-- Logs begin at Fri 2023-01-20 14:25:15 UTC, end at Mon 2024-10-28 01:10:53 UTC. -- Oct 28 01:09:26 volumio systemd[1]: Time has been changed Oct 28 01:09:26 volumio wireless.js[894]: trying... Oct 28 01:09:26 volumio sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:26 volumio sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:26 volumio sudo[1115]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:27 volumio wireless.js[894]: trying... Oct 28 01:09:27 volumio sudo[1124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:27 volumio sudo[1124]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:27 volumio sudo[1124]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:28 volumio wireless.js[894]: trying... Oct 28 01:09:28 volumio sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:28 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:28 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:29 volumio wireless.js[894]: trying... Oct 28 01:09:29 volumio sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:29 volumio sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:29 volumio sudo[1142]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:30 volumio wireless.js[894]: trying... Oct 28 01:09:30 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:30 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:30 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:31 volumio wireless.js[894]: trying... Oct 28 01:09:31 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:31 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:31 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:32 volumio volumio-remote-updater[738]: [2024-10-28 01:09:32] [info] asio async_connect error: system:111 (Connection refused) Oct 28 01:09:32 volumio volumio-remote-updater[738]: [2024-10-28 01:09:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 28 01:09:32 volumio volumio-remote-updater[738]: [2024-10-28 01:09:32] [error] handle_connect error: Underlying Transport Error Oct 28 01:09:32 volumio wireless.js[894]: trying... Oct 28 01:09:33 volumio sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:33 volumio sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:33 volumio sudo[1169]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:34 volumio wireless.js[894]: trying... Oct 28 01:09:34 volumio sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:34 volumio sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:34 volumio sudo[1178]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:35 volumio wireless.js[894]: trying... Oct 28 01:09:35 volumio sudo[1187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:35 volumio sudo[1187]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:35 volumio sudo[1187]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:36 volumio wireless.js[894]: trying... Oct 28 01:09:36 volumio sudo[1196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:36 volumio sudo[1196]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:36 volumio sudo[1196]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:37 volumio wireless.js[894]: trying... Oct 28 01:09:37 volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:37 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:37 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:38 volumio wireless.js[894]: trying... Oct 28 01:09:38 volumio sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:38 volumio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:38 volumio sudo[1214]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:39 volumio wireless.js[894]: trying... Oct 28 01:09:39 volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:39 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:39 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:40 volumio wireless.js[894]: trying... Oct 28 01:09:40 volumio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:40 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:40 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:41 volumio wireless.js[894]: trying... Oct 28 01:09:41 volumio sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:41 volumio sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:41 volumio sudo[1241]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:42 volumio wireless.js[894]: trying... Oct 28 01:09:42 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:42 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:42 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:42 volumio volumio-remote-updater[738]: [2024-10-28 01:09:42] [info] asio async_connect error: system:111 (Connection refused) Oct 28 01:09:42 volumio volumio-remote-updater[738]: [2024-10-28 01:09:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 28 01:09:42 volumio volumio-remote-updater[738]: [2024-10-28 01:09:42] [error] handle_connect error: Underlying Transport Error Oct 28 01:09:43 volumio wireless.js[894]: trying... Oct 28 01:09:43 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:43 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:43 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:44 volumio wireless.js[894]: trying... Oct 28 01:09:44 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 28 01:09:44 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:44 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:45 volumio wireless.js[894]: Overtime, starting plan B Oct 28 01:09:45 volumio wireless.js[894]: STARTING HOTSPOT Oct 28 01:09:45 volumio wpa_supplicant[1058]: wlan0: CTRL-EVENT-TERMINATING Oct 28 01:09:48 volumio systemd[1]: Stopped hotspot.service. Oct 28 01:09:48 volumio sudo[1283]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 28 01:09:48 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:48 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:48 volumio sudo[1291]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 28 01:09:48 volumio sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:49 volumio sudo[1291]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:49 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 28 01:09:49 volumio systemd[1]: Starting hotspot.service... Oct 28 01:09:49 volumio systemd[1]: Started hotspot.service. Oct 28 01:09:49 volumio systemd[1]: Starting dhcpd.service... Oct 28 01:09:49 volumio systemd[1]: Starting dnsmasq.service... Oct 28 01:09:49 volumio systemd[1]: Started Wireless Services. Oct 28 01:09:49 volumio systemd[1]: Starting Volumio Backend Module... Oct 28 01:09:49 volumio systemd[1]: Started Volumio Backend Module. Oct 28 01:09:49 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 28 01:09:49 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 28 01:09:49 volumio systemd[1]: Starting Multi-User System. Oct 28 01:09:49 volumio systemd[1]: Reached target Multi-User System. Oct 28 01:09:49 volumio systemd[1]: Starting Graphical Interface. Oct 28 01:09:49 volumio systemd[1]: Reached target Graphical Interface. Oct 28 01:09:49 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 28 01:09:49 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 28 01:09:49 volumio dnsmasq[1318]: started, version 2.72 cachesize 150 Oct 28 01:09:49 volumio dnsmasq[1318]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect Oct 28 01:09:49 volumio dnsmasq[1318]: reading /etc/resolv.conf Oct 28 01:09:49 volumio dnsmasq[1318]: using nameserver 210.220.163.82#53 Oct 28 01:09:49 volumio dnsmasq[1318]: using nameserver 219.250.36.130#53 Oct 28 01:09:49 volumio dnsmasq[1318]: using nameserver 208.67.222.222#53 Oct 28 01:09:49 volumio dnsmasq[1318]: using nameserver 208.67.220.220#53 Oct 28 01:09:49 volumio dnsmasq[1318]: read /etc/hosts - 4 addresses Oct 28 01:09:49 volumio systemd[1]: Started dnsmasq.service. Oct 28 01:09:49 volumio systemd[1]: Starting Host and Network Name Lookups. Oct 28 01:09:49 volumio systemd[1]: Reached target Host and Network Name Lookups. Oct 28 01:09:49 volumio dhcpd[1305]: Internet Systems Consortium DHCP Server 4.3.1 Oct 28 01:09:49 volumio hotspot[1305]: Internet Systems Consortium DHCP Server 4.3.1 Oct 28 01:09:49 volumio dhcpd[1305]: Copyright 2004-2014 Internet Systems Consortium. Oct 28 01:09:49 volumio hotspot[1305]: Copyright 2004-2014 Internet Systems Consortium. Oct 28 01:09:49 volumio dhcpd[1305]: All rights reserved. Oct 28 01:09:49 volumio hotspot[1305]: All rights reserved. Oct 28 01:09:49 volumio dhcpd[1305]: For info, please visit https://www.isc.org/software/dhcp/ Oct 28 01:09:49 volumio dhcpd[1305]: Config file: /etc/dhcp/dhcpd.conf Oct 28 01:09:49 volumio dhcpd[1305]: Database file: /var/lib/dhcp/dhcpd.leases Oct 28 01:09:49 volumio dhcpd[1305]: PID file: /var/run/dhcpd.pid Oct 28 01:09:49 volumio hotspot[1305]: For info, please visit https://www.isc.org/software/dhcp/ Oct 28 01:09:49 volumio hotspot[1305]: Config file: /etc/dhcp/dhcpd.conf Oct 28 01:09:49 volumio hotspot[1305]: Database file: /var/lib/dhcp/dhcpd.leases Oct 28 01:09:49 volumio hotspot[1305]: PID file: /var/run/dhcpd.pid Oct 28 01:09:49 volumio dhcpd[1305]: Internet Systems Consortium DHCP Server 4.3.1 Oct 28 01:09:49 volumio dhcpd[1305]: Copyright 2004-2014 Internet Systems Consortium. Oct 28 01:09:49 volumio dhcpd[1305]: All rights reserved. Oct 28 01:09:49 volumio dhcpd[1305]: For info, please visit https://www.isc.org/software/dhcp/ Oct 28 01:09:49 volumio dhcpd[1305]: Wrote 0 leases to leases file. Oct 28 01:09:49 volumio hotspot[1305]: Wrote 0 leases to leases file. Oct 28 01:09:49 volumio dhcpd[1305]: Listening on LPF/wlan0/b8:27:eb:ca:07:41/192.168.211.0/24 Oct 28 01:09:49 volumio dhcpd[1305]: Sending on LPF/wlan0/b8:27:eb:ca:07:41/192.168.211.0/24 Oct 28 01:09:49 volumio dhcpd[1305]: Oct 28 01:09:49 volumio dhcpd[1305]: No subnet declaration for eth0 (192.168.45.194). Oct 28 01:09:49 volumio dhcpd[1305]: ** Ignoring requests on eth0. If this is not what Oct 28 01:09:49 volumio dhcpd[1305]: you want, please write a subnet declaration Oct 28 01:09:49 volumio dhcpd[1305]: in your dhcpd.conf file for the network segment Oct 28 01:09:49 volumio dhcpd[1305]: to which interface eth0 is attached. ** Oct 28 01:09:49 volumio dhcpd[1305]: Oct 28 01:09:49 volumio dhcpd[1305]: Sending on Socket/fallback/fallback-net Oct 28 01:09:49 volumio dhcpd[1324]: Server starting service. Oct 28 01:09:49 volumio systemd[1]: Started dhcpd.service. Oct 28 01:09:49 volumio systemd[1]: Startup finished in 9.620s (kernel) + 39.872s (userspace) = 49.493s. Oct 28 01:09:49 volumio hotspot[1305]: Listening on LPF/wlan0/b8:27:eb:ca:07:41/192.168.211.0/24 Oct 28 01:09:49 volumio hotspot[1305]: Sending on LPF/wlan0/b8:27:eb:ca:07:41/192.168.211.0/24 Oct 28 01:09:49 volumio hotspot[1305]: No subnet declaration for eth0 (192.168.45.194). Oct 28 01:09:49 volumio hotspot[1305]: ** Ignoring requests on eth0. If this is not what Oct 28 01:09:49 volumio hotspot[1305]: you want, please write a subnet declaration Oct 28 01:09:49 volumio hotspot[1305]: in your dhcpd.conf file for the network segment Oct 28 01:09:49 volumio hotspot[1305]: to which interface eth0 is attached. ** Oct 28 01:09:49 volumio hotspot[1305]: Sending on Socket/fallback/fallback-net Oct 28 01:09:49 volumio volumio-streaming-daemon[1308]: ############################ Oct 28 01:09:49 volumio volumio-streaming-daemon[1308]: # Volumio Streaming Daemon # Oct 28 01:09:49 volumio volumio-streaming-daemon[1308]: # Running on port 7777 # Oct 28 01:09:49 volumio volumio-streaming-daemon[1308]: ############################ Oct 28 01:09:49 volumio volumio-streaming-daemon[1308]: Environment: production Oct 28 01:09:49 volumio hotspot[1304]: Launching Hostapd 2.8 Oct 28 01:09:49 volumio hotspot[1304]: Configuration file: /etc/hostapd/hostapd.conf Oct 28 01:09:49 volumio hotspot[1304]: wlan0: Could not connect to kernel driver Oct 28 01:09:49 volumio hotspot[1304]: Using interface wlan0 with hwaddr b8:27:eb:ca:07:41 and ssid "Volumio" Oct 28 01:09:49 volumio kernel: brcmfmac: brcmf_cfg80211_start_ap: Set Channel failed: chspec=4100, -52 Oct 28 01:09:49 volumio hotspot[1304]: Failed to set beacon parameters Oct 28 01:09:49 volumio hotspot[1304]: wlan0: Could not connect to kernel driver Oct 28 01:09:49 volumio hotspot[1304]: Interface initialization failed Oct 28 01:09:49 volumio hotspot[1304]: wlan0: interface state UNINITIALIZED->DISABLED Oct 28 01:09:49 volumio hotspot[1304]: wlan0: AP-DISABLED Oct 28 01:09:49 volumio hotspot[1304]: wlan0: Unable to setup interface. Oct 28 01:09:49 volumio hotspot[1304]: wlan0: interface state DISABLED->DISABLED Oct 28 01:09:49 volumio hotspot[1304]: wlan0: AP-DISABLED Oct 28 01:09:49 volumio hotspot[1304]: wlan0: CTRL-EVENT-TERMINATING Oct 28 01:09:49 volumio hotspot[1304]: hostapd_free_hapd_data: Interface wlan0 wasn't started Oct 28 01:09:49 volumio hotspot[1304]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 28 01:09:49 volumio systemd[1]: hotspot.service: main process exited, code=exited, status=1/FAILURE Oct 28 01:09:49 volumio systemd[1]: Stopping dhcpd.service... Oct 28 01:09:49 volumio systemd[1]: Stopping Host and Network Name Lookups. Oct 28 01:09:49 volumio systemd[1]: Stopped target Host and Network Name Lookups. Oct 28 01:09:49 volumio systemd[1]: Stopping dnsmasq.service... Oct 28 01:09:49 volumio dnsmasq[1318]: exiting on receipt of SIGTERM Oct 28 01:09:49 volumio systemd[1]: Stopped dnsmasq.service. Oct 28 01:09:49 volumio hotspot[1338]: Killing Hostapd Oct 28 01:09:49 volumio sudo[1347]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Oct 28 01:09:49 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:49 volumio hotspot[1338]: hostapd: no process found Oct 28 01:09:49 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:49 volumio systemd[1]: Stopped dhcpd.service. Oct 28 01:09:49 volumio hotspot[1338]: Killing Dhcpd Oct 28 01:09:49 volumio sudo[1356]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Oct 28 01:09:49 volumio sudo[1356]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:49 volumio hotspot[1338]: dhcpd: no process found Oct 28 01:09:49 volumio sudo[1356]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:49 volumio systemd[1]: hotspot.service: control process exited, code=exited status=1 Oct 28 01:09:49 volumio systemd[1]: Unit hotspot.service entered failed state. Oct 28 01:09:51 volumio ntpd[873]: Listen normally on 4 wlan0 192.168.211.1 UDP 123 Oct 28 01:09:51 volumio ntpd[873]: peers refreshed Oct 28 01:09:52 volumio volumio[1307]: info: ------------------------------------------- Oct 28 01:09:52 volumio volumio[1307]: info: ----- Volumio2 ---- Oct 28 01:09:52 volumio volumio[1307]: info: ------------------------------------------- Oct 28 01:09:52 volumio volumio[1307]: info: ----- System startup ---- Oct 28 01:09:52 volumio volumio[1307]: info: ------------------------------------------- Oct 28 01:09:52 volumio volumio-remote-updater[738]: [2024-10-28 01:09:52] [connect] Successful connection Oct 28 01:09:53 volumio volumio[1307]: info: MYVOLUMIO Environment detected Oct 28 01:09:53 volumio volumio[1307]: info: Plugin folders cleanup Oct 28 01:09:53 volumio volumio[1307]: info: Scanning into folder /volumio/app/plugins/ Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category audio_interface Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category miscellanea Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category music_service Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category plugins.json Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category system_controller Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category user_interface Oct 28 01:09:53 volumio volumio[1307]: info: Scanning into folder /data/plugins/ Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category miscellanea Oct 28 01:09:53 volumio volumio[1307]: info: Scanning category music_service Oct 28 01:09:53 volumio volumio[1307]: info: Plugin folders cleanup completed Oct 28 01:09:53 volumio volumio[1307]: info: ------------------------------------------- Oct 28 01:09:53 volumio volumio[1307]: info: ----- Core plugins startup ---- Oct 28 01:09:53 volumio volumio[1307]: info: ------------------------------------------- Oct 28 01:09:53 volumio volumio[1307]: info: Loading plugins from folder /volumio/app/plugins/ Oct 28 01:09:53 volumio volumio[1307]: info: Adding plugin upnp to MyMusic Plugins Oct 28 01:09:53 volumio volumio[1307]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 28 01:09:53 volumio volumio[1307]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 28 01:09:53 volumio volumio[1307]: info: Loading plugins from folder /data/plugins/ Oct 28 01:09:53 volumio volumio[1307]: info: Loading plugin "system"... Oct 28 01:09:53 volumio volumio[1307]: info: Loading plugin "appearance"... Oct 28 01:09:56 volumio volumio[1307]: info: Loading plugin "network"... Oct 28 01:09:56 volumio volumio[1307]: info: Refreshing Cached IP Addresses Oct 28 01:09:56 volumio sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 01:09:56 volumio volumio[1307]: info: Loading plugin "touch_display"... Oct 28 01:09:56 volumio sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:56 volumio sudo[1380]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:56 volumio sudo[1383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 01:09:56 volumio sudo[1383]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:09:56 volumio sudo[1383]: pam_unix(sudo:session): session closed for user root Oct 28 01:09:57 volumio volumio[1307]: info: Applying required configuration parameters for plugin touch_display Oct 28 01:09:57 volumio volumio[1307]: info: Loading plugin "services"... Oct 28 01:09:57 volumio volumio[1307]: info: Loading plugin "alsa_controller"... Oct 28 01:09:57 volumio volumio[1307]: info: Loading plugin "wizard"... Oct 28 01:09:57 volumio volumio[1307]: info: Loading plugin "volumio_command_line_client"... Oct 28 01:09:57 volumio volumio[1307]: info: Loading plugin "upnp"... Oct 28 01:09:57 volumio volumio[1307]: info: [1730077797441] Starting Upmpd Daemon Oct 28 01:09:57 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 01:09:57 volumio volumio[1307]: info: Loading plugin "my_music"... Oct 28 01:09:57 volumio volumio[1307]: info: Loading plugin "mpd"... Oct 28 01:09:58 volumio volumio[1307]: info: Loading plugin "upnp_browser"... Oct 28 01:09:58 volumio volumio[1307]: info: Loading plugin "networkfs"... Oct 28 01:09:58 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 01:09:58 volumio volumio[1307]: info: Loading plugin "alarm-clock"... Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "airplay_emulation"... Oct 28 01:09:59 volumio volumio[1307]: info: Starting Shairport Sync Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "last_100"... Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "webradio"... Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "i2s_dacs"... Oct 28 01:09:59 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "volumiodiscovery"... Oct 28 01:09:59 volumio volumio[1307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 01:09:59 volumio node[1307]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 28 01:09:59 volumio node[1307]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 01:09:59 volumio node[1307]: *** WARNING *** For more information see Oct 28 01:09:59 volumio node[1307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 01:09:59 volumio node[1307]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 01:09:59 volumio node[1307]: *** WARNING *** For more information see Oct 28 01:09:59 volumio volumio[1307]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 01:09:59 volumio volumio[1307]: *** WARNING *** For more information see Oct 28 01:09:59 volumio volumio[1307]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 28 01:09:59 volumio volumio[1307]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 28 01:09:59 volumio volumio[1307]: *** WARNING *** For more information see Oct 28 01:09:59 volumio volumio[1307]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 28 01:09:59 volumio volumio[1307]: Discovery: StartAdv! undefined Oct 28 01:09:59 volumio volumio[1307]: Discovery: Started advertising... Volumio - undefined Oct 28 01:09:59 volumio dbus[813]: [system] Activating via systemd: service name='org.freedesktop.Avahi' unit='dbus-org.freedesktop.Avahi.service' Oct 28 01:09:59 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 28 01:09:59 volumio avahi-daemon[1404]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Oct 28 01:09:59 volumio avahi-daemon[1404]: Successfully dropped root privileges. Oct 28 01:09:59 volumio avahi-daemon[1404]: avahi-daemon 0.6.31 starting up. Oct 28 01:09:59 volumio dbus[813]: [system] Successfully activated service 'org.freedesktop.Avahi' Oct 28 01:09:59 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 28 01:09:59 volumio avahi-daemon[1404]: Successfully called chroot(). Oct 28 01:09:59 volumio avahi-daemon[1404]: Successfully dropped remaining capabilities. Oct 28 01:09:59 volumio avahi-daemon[1404]: No service file found in /etc/avahi/services. Oct 28 01:09:59 volumio avahi-daemon[1404]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 28 01:09:59 volumio avahi-daemon[1404]: New relevant interface wlan0.IPv4 for mDNS. Oct 28 01:09:59 volumio avahi-daemon[1404]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.45.194. Oct 28 01:09:59 volumio avahi-daemon[1404]: New relevant interface eth0.IPv4 for mDNS. Oct 28 01:09:59 volumio avahi-daemon[1404]: Network interface enumeration completed. Oct 28 01:09:59 volumio avahi-daemon[1404]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Oct 28 01:09:59 volumio avahi-daemon[1404]: Registering new address record for 192.168.45.194 on eth0.IPv4. Oct 28 01:09:59 volumio avahi-daemon[1404]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Oct 28 01:09:59 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "outputs"... Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "albumart"... Oct 28 01:09:59 volumio volumio[1307]: info: Plugin example_plugin is not enabled Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "inputs"... Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "updater_comm"... Oct 28 01:09:59 volumio volumio[1307]: info: Plugin mpdemulation is not enabled Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "rest_api"... Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "websocket"... Oct 28 01:09:59 volumio volumio[1307]: info: Loading plugin "personal_radio"... Oct 28 01:09:59 volumio volumio[1307]: Forking 3 albumart workers Oct 28 01:10:00 volumio avahi-daemon[1404]: Server startup complete. Host name is volumio.local. Local service cookie is 3953259742. Oct 28 01:10:00 volumio volumio[1307]: Starting albumart workers Oct 28 01:10:00 volumio volumio[1307]: Starting albumart workers Oct 28 01:10:00 volumio volumio[1307]: Starting albumart workers Oct 28 01:10:01 volumio volumio[1307]: info: ControllerPersonalRadio::constructor Oct 28 01:10:01 volumio volumio[1307]: info: ___________ START PLUGINS ___________ Oct 28 01:10:01 volumio volumio[1307]: info: Loading i18n strings for locale ko Oct 28 01:10:01 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 01:10:01 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 01:10:01 volumio volumio[1307]: info: [1730077801894] CoreMusicLibrary::Adding element Media Servers Oct 28 01:10:01 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:01 volumio sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 28 01:10:01 volumio sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:01 volumio systemd[1]: Reloading. Oct 28 01:10:01 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Oct 28 01:10:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Oct 28 01:10:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Oct 28 01:10:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Oct 28 01:10:02 volumio volumio[1307]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 01:10:02 volumio volumio[1307]: info: [1730077802079] CoreMusicLibrary::Adding element Last_100 Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 01:10:02 volumio volumio[1307]: info: [1730077802086] CoreMusicLibrary::Adding element Webradio Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 01:10:02 volumio sudo[1456]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 01:10:02 volumio volumio[1307]: info: [1730077802147] CoreMusicLibrary::Adding element 개인 라디오 Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:02 volumio volumio[1307]: info: ------------------------------------------- Oct 28 01:10:02 volumio volumio[1307]: info: ----- MyVolumio plugins startup ---- Oct 28 01:10:02 volumio volumio[1307]: info: ------------------------------------------- Oct 28 01:10:02 volumio volumio[1307]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 28 01:10:02 volumio volumio[1307]: info: Loading i18n strings for locale ko Oct 28 01:10:02 volumio volumio[1307]: Updating browse sources language Oct 28 01:10:02 volumio volumio[1307]: Cannot find translation for sourceMedia Servers Oct 28 01:10:02 volumio volumio[1307]: Cannot find translation for source개인 라디오 Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::initPlayerControls Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 28 01:10:02 volumio volumio[1307]: info: BOOT COMPLETED Oct 28 01:10:02 volumio volumio[1307]: [Metrics] CommandRouter: 9s 999.04ms Oct 28 01:10:02 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:07 volumio volumio-remote-updater[738]: [2024-10-28 01:10:07] [connect] Successful connection Oct 28 01:10:07 volumio volumio[1307]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 28 01:10:07 volumio volumio[1307]: info: CoreCommandRouter::Close All Modals sent Oct 28 01:10:07 volumio volumio[1307]: info: CoreCommandRouter::Close All Modals sent Oct 28 01:10:07 volumio volumio[1307]: Express server listening on port 3000 Oct 28 01:10:07 volumio volumio[1307]: [Metrics] WebUI: 16s 124.24ms Oct 28 01:10:07 volumio volumio[1307]: Volumio Calling Home Oct 28 01:10:07 volumio volumio[1307]: info: CoreStateMachine::resetVolumioState Oct 28 01:10:07 volumio volumio[1307]: info: CoreStateMachine::getcurrentVolume Oct 28 01:10:07 volumio volumio[1307]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 01:10:07 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:07 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:07 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 28 01:10:07 volumio sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 28 01:10:07 volumio sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:07 volumio sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 28 01:10:07 volumio sudo[1499]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:07 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:07 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::updateTrackBlock Oct 28 01:10:08 volumio volumio[1307]: info: CorePlayQueue::getTrackBlock Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::volumioRetrievevolume Oct 28 01:10:08 volumio volumio[1307]: info: Setting Device type: Raspberry PI Oct 28 01:10:08 volumio volumio[1307]: info: touch_display: systemctl daemon-reload succeeded. Oct 28 01:10:08 volumio volumio[1307]: info: MPD running with PID810 Oct 28 01:10:08 volumio volumio[1307]: ,establishing connection Oct 28 01:10:08 volumio sudo[1523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 28 01:10:08 volumio sudo[1523]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:08 volumio systemd[1]: Starting Volumio Kiosk... Oct 28 01:10:08 volumio systemd[1]: Started Volumio Kiosk. Oct 28 01:10:08 volumio volumio-remote-updater[738]: [2024-10-28 01:10:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1730077807 101 Oct 28 01:10:08 volumio sudo[1523]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:08 volumio volumio[1307]: info: Oct 28 01:10:08 volumio volumio[1307]: ---------------------------- Client requests Volume 100 Oct 28 01:10:08 volumio volumio[1307]: info: VolumeController::SetAlsaVolume100 Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:08 volumio startx[1528]: X.Org X Server 1.18.4 Oct 28 01:10:08 volumio startx[1528]: Release Date: 2016-07-19 Oct 28 01:10:08 volumio startx[1528]: X Protocol Version 11, Revision 0 Oct 28 01:10:08 volumio startx[1528]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Oct 28 01:10:08 volumio startx[1528]: Current Operating System: Linux volumio 4.19.118-v7+ #1311 SMP Mon Apr 27 14:21:24 BST 2020 armv7l Oct 28 01:10:08 volumio startx[1528]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 snd-bcm2835.enable_compat_alsa=1 snd_bcm2835.enable_headphones=1 Oct 28 01:10:08 volumio startx[1528]: Build Date: 11 November 2016 11:59:59AM Oct 28 01:10:08 volumio startx[1528]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Oct 28 01:10:08 volumio startx[1528]: Current version of pixman: 0.32.6 Oct 28 01:10:08 volumio startx[1528]: Before reporting problems, check http://wiki.x.org Oct 28 01:10:08 volumio startx[1528]: to make sure that you have the latest version. Oct 28 01:10:08 volumio startx[1528]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 28 01:10:08 volumio startx[1528]: (++) from command line, (!!) notice, (II) informational, Oct 28 01:10:08 volumio startx[1528]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 28 01:10:08 volumio startx[1528]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Oct 28 01:10:08 2024 Oct 28 01:10:08 volumio startx[1528]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 28 01:10:08 volumio startx[1528]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 28 01:10:08 volumio volumio[1307]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 28 01:10:08 volumio volumio[1307]: info: touch_display: Volumio Kiosk started Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:08 volumio volumio[1307]: info: Volumio called home Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 28 01:10:08 volumio volumio[1307]: info: Reloading queue from file Oct 28 01:10:08 volumio startx[1528]: modprobe: FATAL: Module g2d_23 not found. Oct 28 01:10:08 volumio volumio[1307]: error: updateQueue error: null Oct 28 01:10:08 volumio volumio[1307]: error: Failed LSINFO: Error: [50@0] {} No such directory Oct 28 01:10:08 volumio volumio[1307]: info: Setting volume on startup at 100 Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::setRepeat false single undefined Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::setRandom null Oct 28 01:10:08 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:08 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:08 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:08 volumio volumio[1307]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 28 01:10:08 volumio volumio[1307]: info: Starting Shairport Sync Oct 28 01:10:08 volumio volumio[1307]: info: Starting Shairport Sync Oct 28 01:10:08 volumio sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 01:10:08 volumio sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:08 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 28 01:10:08 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:08 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 28 01:10:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 28 01:10:08 volumio sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 28 01:10:08 volumio sudo[1559]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:08 volumio sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 28 01:10:08 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 28 01:10:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 28 01:10:08 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:08 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 28 01:10:08 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 28 01:10:08 volumio sudo[1568]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:08 volumio volumio[1307]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 28 01:10:08 volumio volumio[1307]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Oct 28 01:10:09 volumio volumio[1307]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Oct 28 01:10:09 volumio volumio[1307]: info: Shairport-Sync Started Oct 28 01:10:09 volumio volumio[1307]: Error adding Membership: Error: addMembership EINVAL Oct 28 01:10:09 volumio sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 28 01:10:09 volumio sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:09 volumio sudo[1602]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:09 volumio volumio[1307]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 28 01:10:09 volumio volumio[1307]: info: Upmpdcli Daemon Started Oct 28 01:10:09 volumio volumio[1307]: info: Shairport-Sync Started Oct 28 01:10:09 volumio volumio[1307]: info: touch_display: Touchscreen transformation matrix removed. Oct 28 01:10:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:09 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:09 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:09 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:10 volumio volumio[1307]: Discovery: adding 6e0d1c1a-7325-4f30-b7c8-2d8fef1ca40a Oct 28 01:10:10 volumio volumio[1307]: info: mDNS: Found device Volumio Oct 28 01:10:10 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:10 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:10 volumio volumio[1307]: Discovery: this is already registered, 6e0d1c1a-7325-4f30-b7c8-2d8fef1ca40a Oct 28 01:10:10 volumio volumio[1307]: info: mDNS: Found device Volumio Oct 28 01:10:10 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:10 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:10 volumio volumio[1307]: info: touch_display: Setting screensaver timeout to 120 seconds. Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 28 01:10:11 volumio volumio[1307]: info: Adding plugin bluetooth to MyMusic Plugins Oct 28 01:10:11 volumio volumio[1307]: info: Adding plugin cd_controller to MyMusic Plugins Oct 28 01:10:11 volumio volumio[1307]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 28 01:10:11 volumio volumio[1307]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 28 01:10:11 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 28 01:10:12 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 28 01:10:12 volumio dbus[813]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Oct 28 01:10:12 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 28 01:10:12 volumio dbus[813]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 28 01:10:12 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 28 01:10:12 volumio rtkit-daemon[1688]: Successfully called chroot. Oct 28 01:10:12 volumio rtkit-daemon[1688]: Successfully dropped privileges. Oct 28 01:10:12 volumio rtkit-daemon[1688]: Successfully limited resources. Oct 28 01:10:12 volumio rtkit-daemon[1688]: Running. Oct 28 01:10:12 volumio rtkit-daemon[1688]: Watchdog thread running. Oct 28 01:10:12 volumio rtkit-daemon[1688]: Canary thread running. Oct 28 01:10:12 volumio volumio[1307]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 28 01:10:12 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 28 01:10:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:12 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:12 volumio volumio[1307]: info: Starting MyVolumio Remote Streaming Endpoints Oct 28 01:10:12 volumio volumio[1307]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 28 01:10:12 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 28 01:10:12 volumio volumio[1307]: info: Streaming services startup Oct 28 01:10:12 volumio volumio[1307]: info: Starting Streaming Daemon Oct 28 01:10:12 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 28 01:10:12 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:12 volumio volumio[1307]: info: MyVolumio login type: Token Oct 28 01:10:12 volumio volumio[1307]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 28 01:10:12 volumio pulseaudio[1687]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message Oct 28 01:10:12 volumio pulseaudio[1687]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message Oct 28 01:10:12 volumio pulseaudio[1687]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Oct 28 01:10:12 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 28 01:10:12 volumio bluetoothd[967]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSource Oct 28 01:10:12 volumio bluetoothd[967]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSink Oct 28 01:10:12 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 28 01:10:12 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 28 01:10:12 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:12 volumio volumio-streaming-daemon[1706]: ############################ Oct 28 01:10:12 volumio volumio-streaming-daemon[1706]: # Volumio Streaming Daemon # Oct 28 01:10:12 volumio volumio-streaming-daemon[1706]: # Running on port 7777 # Oct 28 01:10:12 volumio volumio-streaming-daemon[1706]: ############################ Oct 28 01:10:12 volumio volumio-streaming-daemon[1706]: Environment: production Oct 28 01:10:13 volumio volumio[1307]: error: MyVolumio Custom Token format not valid, refreshing it Oct 28 01:10:14 volumio volumio[1307]: info: MyVolumio login type: Token Oct 28 01:10:15 volumio volumio-streaming-daemon[1706]: Environment: production Oct 28 01:10:15 volumio volumio[1307]: info: Fetching Streaming Services browse cache Oct 28 01:10:15 volumio volumio[1307]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 28 01:10:15 volumio volumio[1307]: info: MYVOLUMIO: Adding device Oct 28 01:10:15 volumio volumio[1307]: info: MYVOLUMIO: Evaluating Server Oct 28 01:10:17 volumio volumio[1307]: info: [MyVolumio PluginManager] Subscribed plan changed to virtuoso Oct 28 01:10:17 volumio volumio[1307]: info: Removing browser output: myVolumio user plan is not superstar Oct 28 01:10:17 volumio volumio[1307]: info: Removing audio output: Oct 28 01:10:17 volumio volumio[1307]: info: MYVOLUMIO: Adding device Oct 28 01:10:17 volumio volumio[1307]: info: MYVOLUMIO: Evaluating Server Oct 28 01:10:17 volumio volumio[1307]: info: Remote config written successfully Oct 28 01:10:17 volumio volumio[1307]: info: Starting Tunnel 1 Oct 28 01:10:17 volumio volumio[1307]: info: Starting Tunnel Connection Checker Oct 28 01:10:17 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 28 01:10:17 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:17 volumio systemd[1]: Starting MyVolumio SSH Tunnel... Oct 28 01:10:17 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 28 01:10:17 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:17 volumio autossh[1816]: port set to 0, monitoring disabled Oct 28 01:10:17 volumio autossh[1816]: starting ssh (count 1) Oct 28 01:10:17 volumio autossh[1816]: ssh child pid is 1822 Oct 28 01:10:17 volumio volumio[1307]: info: Remote SSH Started Oct 28 01:10:17 volumio volumio[1307]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 28 01:10:17 volumio volumio[1307]: info: MyVolumio status changed Oct 28 01:10:17 volumio volumio[1307]: info: Streaming services startup Oct 28 01:10:17 volumio volumio[1307]: info: Re-Starting Streaming Daemon Oct 28 01:10:18 volumio volumio-streaming-daemon[1706]: Restarting daemon Oct 28 01:10:18 volumio volumio-streaming-daemon[1706]: Environment: production Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 28 01:10:18 volumio volumio[1307]: info: [1730077818383] Starting BluetoothController Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 28 01:10:18 volumio volumiossh-tunnel[1815]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[68.183.69.189]:2222' (RSA) to the list of known hosts. Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 28 01:10:18 volumio volumio-streaming-daemon[1706]: Enabled services: Oct 28 01:10:18 volumio volumio-streaming-daemon[1706]: 1) tidal Oct 28 01:10:18 volumio volumio-streaming-daemon[1706]: 2) qobuz Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 28 01:10:18 volumio volumio[1307]: info: Preparing CD Folders Oct 28 01:10:18 volumio volumio[1307]: info: Adding CD REST API Endpoints Oct 28 01:10:18 volumio volumio[1307]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Oct 28 01:10:18 volumio volumio[1307]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Oct 28 01:10:18 volumio volumio[1307]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Oct 28 01:10:18 volumio volumio[1307]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 28 01:10:18 volumio volumio[1307]: info: Detecting CD presence Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 28 01:10:18 volumio volumio[1307]: info: Refreshing TIDAL token Oct 28 01:10:18 volumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 28 01:10:18 volumio volumio[1307]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 28 01:10:18 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:18 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:18 volumio volumio[1307]: info: Stopping AccessToken refresher cron for QOBUZ Oct 28 01:10:18 volumio sudo[1870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 28 01:10:18 volumio sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:18 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 28 01:10:18 volumio sudo[1870]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:18 volumio volumio[1307]: info: AccessToken refresher cron started for QOBUZ Oct 28 01:10:18 volumio volumio[1307]: info: Setting Geolocation for MyVolumio to as1 Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:18 volumio volumio[1307]: info: Setting Geolocation for MyVolumio to as1 Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:18 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:18 volumio volumio[1307]: info: Bluetooth name changed to Volumio Oct 28 01:10:19 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:19 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:19 volumio volumio[1307]: info: Updating MyVolumio device info Oct 28 01:10:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:19 volumio volumio[1307]: info: Updating MyVolumio device info Oct 28 01:10:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:19 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:20 volumio volumio-streaming-daemon[1706]: Environment: production Oct 28 01:10:20 volumio volumio-streaming-daemon[1706]: Environment: production Oct 28 01:10:20 volumio volumio[1307]: info: Fetching Streaming Services browse cache Oct 28 01:10:20 volumio volumio[1307]: info: Access Token successfully retrieved Oct 28 01:10:21 volumio volumio[1307]: info: Successfully retrieved User Session From TIDAL Oct 28 01:10:21 volumio volumio[1307]: info: Successfully retrieved User Subscription From TIDAL Oct 28 01:10:21 volumio volumio[1307]: info: Adding TIDAL to Browse Sources Oct 28 01:10:21 volumio volumio[1307]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 28 01:10:21 volumio volumio[1307]: info: [1730077821711] CoreMusicLibrary::Adding element TIDAL Oct 28 01:10:21 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:21 volumio volumio[1307]: info: Stopping AccessToken refresher cron Oct 28 01:10:21 volumio volumio[1307]: info: AccessToken refresher cron started Oct 28 01:10:21 volumio sudo[1893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 28 01:10:21 volumio sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:21 volumio systemd[1]: Starting Volumio Bluetooth Module... Oct 28 01:10:21 volumio systemd[1]: Started Volumio Bluetooth Module. Oct 28 01:10:21 volumio sudo[1893]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:21 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 28 01:10:21 volumio volumio[1307]: info: TidalConnect service stoped! Oct 28 01:10:21 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:21 volumio volumiobt[1898]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 28 01:10:21 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:21 volumio volumio[1307]: info: Volumio BT Module successfully started Oct 28 01:10:21 volumio volumio[1307]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 28 01:10:21 volumio volumio[1307]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 28 01:10:22 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 28 01:10:22 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:22 volumio systemd[1]: Starting Volumio Tidal Connect Service... Oct 28 01:10:22 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 28 01:10:22 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:22 volumio volumio[1307]: info: Executing endpoint tc_getconfig Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 28 01:10:22 volumio vtcs[1921]: STARTING TidalConnect services, version: 1.1.7.5 Oct 28 01:10:22 volumio vtcs[1921]: STARTED TidalConnect services. Oct 28 01:10:22 volumio volumio[1307]: info: Executing endpoint tc_connect Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 28 01:10:22 volumio volumio[1307]: Connecting to TidalConnect. Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Oct 28 01:10:22 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:22 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:22 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:22 volumio volumio[1307]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Oct 28 01:10:22 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:22 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:22 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:22 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:22 volumio volumio[1307]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 28 01:10:22 volumio sudo[1944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 28 01:10:22 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 28 01:10:22 volumio bluetoothd[967]: Endpoint unregistered: sender=:1.20 path=/MediaEndpoint/A2DPSource Oct 28 01:10:22 volumio bluetoothd[967]: Endpoint unregistered: sender=:1.20 path=/MediaEndpoint/A2DPSink Oct 28 01:10:22 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Oct 28 01:10:23 volumio pulseaudio[1954]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 28 01:10:23 volumio pulseaudio[1954]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 28 01:10:23 volumio pulseaudio[1954]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Oct 28 01:10:23 volumio bluetoothd[967]: Endpoint registered: sender=:1.26 path=/MediaEndpoint/A2DPSource Oct 28 01:10:23 volumio bluetoothd[967]: Endpoint registered: sender=:1.26 path=/MediaEndpoint/A2DPSink Oct 28 01:10:23 volumio volumiobt[1898]: Applying permissions Oct 28 01:10:23 volumio volumiobt[1898]: Setting BT discoverable and pairable Oct 28 01:10:23 volumio volumiobt[1898]: [105B blob data] Oct 28 01:10:23 volumio volumiobt[1898]: [bluetooth]# -e power on Oct 28 01:10:23 volumio volumiobt[1898]: Invalid command Oct 28 01:10:23 volumio volumiobt[1898]: [bluetooth]# agent on Oct 28 01:10:23 volumio volumiobt[1898]: [bluetooth]# discoverable on Oct 28 01:10:23 volumio volumiobt[1898]: [bluetooth]# pairable on Oct 28 01:10:23 volumio volumiobt[1898]: [bluetooth]# agent NoInputNoOutput Oct 28 01:10:23 volumio volumiobt[1898]: Failed to register agent object Oct 28 01:10:23 volumio volumiobt[1898]: [bluetooth]# default-agent Oct 28 01:10:23 volumio volumiobt[1898]: No agent is registered Oct 28 01:10:23 volumio volumiobt[1898]: [bluetooth]# quit Oct 28 01:10:23 volumio volumiobt[1898]: [61B blob data] Oct 28 01:10:23 volumio volumiobt[1898]: [91B blob data] Oct 28 01:10:23 volumio volumiobt[1898]: [62B blob data] Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:24 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::volumioGetQueue Oct 28 01:10:24 volumio volumio[1307]: info: CoreStateMachine::getQueue Oct 28 01:10:24 volumio volumio[1307]: info: CorePlayQueue::getQueue Oct 28 01:10:24 volumio volumio[1307]: info: Listing playlists Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 01:10:24 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 01:10:25 volumio volumio[1307]: info: TidalConnect service started! Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:26 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 28 01:10:26 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 28 01:10:28 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 28 01:10:28 volumio volumio[1307]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 28 01:10:28 volumio volumio[1307]: info: CoreStateMachine::ClearQueue Oct 28 01:10:28 volumio volumio[1307]: info: CoreStateMachine::stop Oct 28 01:10:28 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 01:10:28 volumio volumio[1307]: verbose: UNSET VOLATILE Oct 28 01:10:28 volumio volumio[1307]: info: CorePlayQueue::clearPlayQueue Oct 28 01:10:28 volumio volumio[1307]: info: CorePlayQueue::saveQueue Oct 28 01:10:28 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:28 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:29 volumio volumio[1307]: info: CoreCommandRouter::volumioPushQueue Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::addQueueItems Oct 28 01:10:29 volumio volumio[1307]: info: CorePlayQueue::addQueueItems Oct 28 01:10:29 volumio volumio[1307]: info: Adding Item to queue: http://serpent0.duckdns.org:8088/kbsfm.pls Oct 28 01:10:29 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 28 01:10:29 volumio volumio[1307]: info: CorePlayQueue::saveQueue Oct 28 01:10:29 volumio volumio[1307]: info: CoreCommandRouter::volumioPushQueue Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::updateTrackBlock Oct 28 01:10:29 volumio volumio[1307]: info: CorePlayQueue::getTrackBlock Oct 28 01:10:29 volumio volumio[1307]: info: CoreCommandRouter::volumioPlay Oct 28 01:10:29 volumio volumio[1307]: verbose: UNSET VOLATILE Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::play index 0 Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::stop Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 01:10:29 volumio volumio[1307]: verbose: UNSET VOLATILE Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::play index undefined Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 01:10:29 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:29 volumio volumio[1307]: info: CoreStateMachine::startPlaybackTimer Oct 28 01:10:29 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:29 volumio volumio[1307]: info: [1730077829039] ControllerWebradio::clearAddPlayTrack Oct 28 01:10:29 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand stop Oct 28 01:10:29 volumio volumio[1307]: info: sendMpdCommand stop took 28 milliseconds Oct 28 01:10:29 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand clear Oct 28 01:10:29 volumio volumio[1307]: info: Oct 28 01:10:29 volumio volumio[1307]: ---------------------------- MPD announces system playlist update Oct 28 01:10:29 volumio volumio[1307]: info: Ignoring MPD Status Update Oct 28 01:10:29 volumio volumio[1307]: info: sendMpdCommand clear took 6 milliseconds Oct 28 01:10:29 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" Oct 28 01:10:29 volumio volumio[1307]: error: updateQueue error: null Oct 28 01:10:29 volumio volumio[1307]: info: ------------------------------ 9ms Oct 28 01:10:29 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 01:10:29 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 01:10:30 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:30 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:30 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 01:10:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 01:10:31 volumio volumio[1307]: info: Listing playlists Oct 28 01:10:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 01:10:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 01:10:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 01:10:31 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 01:10:32 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 01:10:32 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 28 01:10:35 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 01:10:39 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand add "http://serpent0.duckdns.org:8088/kbsfm.pls" Oct 28 01:10:39 volumio volumio[1307]: info: Oct 28 01:10:39 volumio volumio[1307]: ---------------------------- MPD announces system playlist update Oct 28 01:10:39 volumio volumio[1307]: info: Ignoring MPD Status Update Oct 28 01:10:39 volumio volumio[1307]: info: sendMpdCommand add "http://serpent0.duckdns.org:8088/kbsfm.pls" took 4 milliseconds Oct 28 01:10:39 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 28 01:10:39 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand play Oct 28 01:10:39 volumio volumio[1307]: info: ------------------------------ 10ms Oct 28 01:10:39 volumio volumio[1307]: info: sendMpdCommand play took 5 milliseconds Oct 28 01:10:40 volumio volumio[1307]: info: Oct 28 01:10:40 volumio volumio[1307]: ---------------------------- MPD announces state update: player Oct 28 01:10:40 volumio volumio[1307]: info: ControllerMpd::getState Oct 28 01:10:40 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand status Oct 28 01:10:40 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 28 01:10:40 volumio volumio[1307]: info: sendMpdCommand status took 8 milliseconds Oct 28 01:10:40 volumio volumio[1307]: verbose: ControllerMpd::parseState Oct 28 01:10:40 volumio volumio[1307]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 28 01:10:40 volumio volumio[1307]: info: sendMpdCommand clearerror took 7 milliseconds Oct 28 01:10:40 volumio volumio[1307]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 28 01:10:40 volumio volumio[1307]: verbose: ControllerMpd::parseTrackInfo Oct 28 01:10:40 volumio volumio[1307]: info: ControllerMpd::pushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreCommandRouter::servicePushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:40 volumio volumio[1307]: info: CorePlayQueue::getTrack 0 Oct 28 01:10:40 volumio volumio[1307]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"kbsfm.pls","artist":null,"album":null,"uri":"http://serpent0.duckdns.org:8088/kbsfm.pls","trackType":"pls"} Oct 28 01:10:40 volumio volumio[1307]: verbose: CURRENT POSITION 0 Oct 28 01:10:40 volumio volumio[1307]: info: CoreStateMachine::syncState stateService stop Oct 28 01:10:40 volumio volumio[1307]: info: CoreStateMachine::syncState currentStatus stop Oct 28 01:10:40 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:40 volumio volumio[1307]: info: No code Oct 28 01:10:40 volumio volumio[1307]: info: CoreStateMachine::pushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:40 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:40 volumio volumio[1307]: info: ------------------------------ 62ms Oct 28 01:10:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 01:10:43 volumio volumio[1307]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 28 01:10:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 28 01:10:43 volumio volumio[1307]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 28 01:10:43 volumio volumio[1307]: info: Received Get System Version Oct 28 01:10:43 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 01:10:45 volumio volumio[1307]: info: CoreCommandRouter::volumioGetState Oct 28 01:10:45 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 28 01:10:45 volumio volumio[1307]: info: Listing playlists Oct 28 01:10:45 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 28 01:10:45 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 28 01:10:45 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 28 01:10:45 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 01:10:46 volumio volumio[1307]: info: CoreCommandRouter::volumioGetVisibleSources Oct 28 01:10:46 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 28 01:10:46 volumio volumio[1307]: info: CoreCommandRouter::volumioGetQueue Oct 28 01:10:46 volumio volumio[1307]: info: CoreStateMachine::getQueue Oct 28 01:10:46 volumio volumio[1307]: info: CorePlayQueue::getQueue Oct 28 01:10:46 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 01:10:47 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 28 01:10:47 volumio volumio[1307]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 28 01:10:47 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 28 01:10:47 volumio volumio[1307]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Oct 28 01:10:47 volumio volumio[1307]: info: Received Get System Version Oct 28 01:10:47 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 28 01:10:47 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 28 01:10:47 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 28 01:10:48 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 28 01:10:50 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Oct 28 01:10:52 volumio volumio[1307]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 28 01:10:52 volumio volumio[1307]: info: CoreStateMachine::ClearQueue Oct 28 01:10:52 volumio volumio[1307]: info: CoreStateMachine::stop Oct 28 01:10:52 volumio volumio[1307]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 28 01:10:52 volumio volumio[1307]: verbose: UNSET VOLATILE Oct 28 01:10:52 volumio volumio[1307]: info: CorePlayQueue::clearPlayQueue Oct 28 01:10:52 volumio volumio[1307]: info: CorePlayQueue::saveQueue Oct 28 01:10:52 volumio volumio[1307]: info: CoreCommandRouter::volumioPushState Oct 28 01:10:52 volumio volumio[1307]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 28 01:10:52 volumio volumio[1307]: info: CoreCommandRouter::volumioPushQueue Oct 28 01:10:52 volumio volumio[1307]: info: CoreStateMachine::addQueueItems Oct 28 01:10:52 volumio volumio[1307]: info: CorePlayQueue::addQueueItems Oct 28 01:10:52 volumio volumio[1307]: info: Adding Item to queue: webkbs/0 Oct 28 01:10:52 volumio volumio[1307]: info: Exploding uri webkbs/0 in service personal_radio Oct 28 01:10:52 volumio volumio[1307]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 01:10:52 volumio volumio[1307]: ReferenceError: self is not defined Oct 28 01:10:52 volumio volumio[1307]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Oct 28 01:10:52 volumio volumio[1307]: at /data/plugins/music_service/personal_radio/index.js:487:12 Oct 28 01:10:52 volumio volumio[1307]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Oct 28 01:10:52 volumio volumio[1307]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Oct 28 01:10:52 volumio volumio[1307]: at emitOne (events.js:116:13) Oct 28 01:10:52 volumio volumio[1307]: at Request.emit (events.js:211:7) Oct 28 01:10:52 volumio volumio[1307]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Oct 28 01:10:52 volumio volumio[1307]: at emitOne (events.js:116:13) Oct 28 01:10:52 volumio volumio[1307]: at ClientRequest.emit (events.js:211:7) Oct 28 01:10:52 volumio volumio[1307]: at Socket.socketErrorListener (_http_client.js:387:9) Oct 28 01:10:52 volumio volumio[1307]: at emitOne (events.js:116:13) Oct 28 01:10:52 volumio volumio[1307]: at Socket.emit (events.js:211:7) Oct 28 01:10:52 volumio volumio[1307]: at emitErrorNT (internal/streams/destroy.js:64:8) Oct 28 01:10:52 volumio volumio[1307]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Oct 28 01:10:52 volumio volumio[1307]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 28 01:10:52 volumio volumio[1307]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 28 01:10:53 volumio sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-28 01:09 Oct 28 01:10:53 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 30 22:42:09 CEST 2021" VOLUMIO_VERSION="2.915" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="02ee61bb422c79b6e5c0b6d1847a78d9"