-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2024-09-23 12:59:25 UTC. -- Sep 23 12:58:00 one-dac systemd[1]: systemd-rfkill.service: Succeeded. Sep 23 12:58:00 one-dac ntpd[763]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Sep 23 12:58:01 one-dac volumio-remote-updater[603]: [2024-09-23 12:58:01] [info] asio async_connect error: system:111 (Connection refused) Sep 23 12:58:01 one-dac volumio-remote-updater[603]: [2024-09-23 12:58:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 23 12:58:01 one-dac volumio-remote-updater[603]: [2024-09-23 12:58:01] [error] handle_connect error: Underlying Transport Error Sep 23 12:58:01 one-dac ntpd[763]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Sep 23 12:58:01 one-dac bthelper[672]: Changing power off succeeded Sep 23 12:58:01 one-dac bthelper[672]: [CHG] Controller D8:3A:DD:9B:32:05 Class: 0x0000041c Sep 23 12:58:01 one-dac bthelper[672]: Changing power on succeeded Sep 23 12:58:02 one-dac sudo[834]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:02 one-dac wireless.js[621]: SETTING APPROPRIATE REG DOMAIN: US Sep 23 12:58:02 one-dac sudo[846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 23 12:58:02 one-dac sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:02 one-dac sudo[846]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:02 one-dac sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Sep 23 12:58:02 one-dac sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:02 one-dac sudo[848]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:02 one-dac wireless.js[621]: SUCCESSFULLY SET NEW REGDOMAIN: US Sep 23 12:58:02 one-dac sudo[855]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 23 12:58:02 one-dac sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:02 one-dac sudo[855]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:02 one-dac sudo[857]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 23 12:58:02 one-dac sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:03 one-dac sudo[857]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:03 one-dac avahi-daemon[627]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Sep 23 12:58:03 one-dac kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 23 12:58:03 one-dac avahi-daemon[627]: New relevant interface wlan0.IPv4 for mDNS. Sep 23 12:58:03 one-dac avahi-daemon[627]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Sep 23 12:58:03 one-dac systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Sep 23 12:58:03 one-dac systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 23 12:58:03 one-dac dnsmasq[863]: dnsmasq: syntax check OK. Sep 23 12:58:03 one-dac hostapd[864]: Configuration file: /etc/hostapd/hostapd.conf Sep 23 12:58:03 one-dac hostapd[864]: wlan0: Could not connect to kernel driver Sep 23 12:58:03 one-dac hostapd[864]: Using interface wlan0 with hwaddr d8:3a:dd:9b:32:04 and ssid "Volumio-F3AEF" Sep 23 12:58:03 one-dac dnsmasq[870]: started, version 2.80 cachesize 150 Sep 23 12:58:03 one-dac dnsmasq[870]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Sep 23 12:58:03 one-dac dnsmasq-dhcp[870]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 23 12:58:03 one-dac dnsmasq[870]: reading /etc/resolv.conf Sep 23 12:58:03 one-dac dnsmasq[870]: using nameserver 208.67.222.222#53 Sep 23 12:58:03 one-dac dnsmasq[870]: using nameserver 208.67.220.220#53 Sep 23 12:58:03 one-dac dnsmasq[870]: read /etc/hosts - 1 addresses Sep 23 12:58:03 one-dac systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Sep 23 12:58:03 one-dac systemd[1]: Reached target Host and Network Name Lookups. Sep 23 12:58:03 one-dac hostapd[864]: wlan0: interface state UNINITIALIZED->ENABLED Sep 23 12:58:03 one-dac hostapd[864]: wlan0: AP-ENABLED Sep 23 12:58:03 one-dac systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 23 12:58:03 one-dac nmbd[758]: [2024/09/23 12:58:03.458661, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 23 12:58:03 one-dac nmbd[758]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 23 12:58:03 one-dac systemd[1]: Started Samba NMB Daemon. Sep 23 12:58:03 one-dac systemd[1]: Starting Samba Winbind Daemon... Sep 23 12:58:03 one-dac iwconfig[880]: Error for wireless request "Set Power Management" (8B2C) : Sep 23 12:58:03 one-dac iwconfig[880]: SET failed on device wlan0 ; Invalid argument. Sep 23 12:58:03 one-dac systemd[1]: wireless.service: Succeeded. Sep 23 12:58:03 one-dac systemd[1]: Started Wireless Services. Sep 23 12:58:03 one-dac systemd[1]: Started Volumio Backend Module. Sep 23 12:58:03 one-dac systemd[1]: Started Volumio Cpu Tweaker. Sep 23 12:58:03 one-dac volumio-cpu-tweak[883]: Setting RT Priority for mpd Sep 23 12:58:03 one-dac volumio-cpu-tweak[883]: pid 35's current scheduling policy: SCHED_OTHER Sep 23 12:58:03 one-dac volumio-cpu-tweak[883]: pid 35's current scheduling priority: 0 Sep 23 12:58:03 one-dac volumio-cpu-tweak[883]: Setting MPD Affinity Sep 23 12:58:03 one-dac volumio-cpu-tweak[883]: pid 3's current affinity mask: f Sep 23 12:58:03 one-dac volumio-cpu-tweak[883]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Sep 23 12:58:03 one-dac volumio-cpu-tweak[883]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Sep 23 12:58:03 one-dac systemd[1]: volumio_cpu_tweak.service: Succeeded. Sep 23 12:58:03 one-dac winbindd[879]: [2024/09/23 12:58:03.725737, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 23 12:58:03 one-dac winbindd[879]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 23 12:58:03 one-dac winbindd[879]: [2024/09/23 12:58:03.745993, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 23 12:58:03 one-dac winbindd[879]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 23 12:58:03 one-dac systemd[1]: Started Samba Winbind Daemon. Sep 23 12:58:03 one-dac systemd[1]: Starting Samba SMB Daemon... Sep 23 12:58:04 one-dac sh[530]: eth0: leased 192.168.0.115 for 7200 seconds Sep 23 12:58:04 one-dac dhcpcd[562]: eth0: leased 192.168.0.115 for 7200 seconds Sep 23 12:58:04 one-dac sh[530]: eth0: adding route to 192.168.0.0/24 Sep 23 12:58:04 one-dac sh[530]: eth0: adding default route via 192.168.0.1 Sep 23 12:58:04 one-dac dhcpcd[562]: eth0: adding route to 192.168.0.0/24 Sep 23 12:58:04 one-dac dhcpcd[562]: eth0: adding default route via 192.168.0.1 Sep 23 12:58:04 one-dac avahi-daemon[627]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.115. Sep 23 12:58:04 one-dac avahi-daemon[627]: New relevant interface eth0.IPv4 for mDNS. Sep 23 12:58:04 one-dac avahi-daemon[627]: Registering new address record for 192.168.0.115 on eth0.IPv4. Sep 23 12:58:04 one-dac dnsmasq[870]: reading /etc/resolv.conf Sep 23 12:58:04 one-dac dnsmasq[870]: using nameserver 192.168.0.1#53 Sep 23 12:58:04 one-dac dnsmasq[870]: using nameserver 208.67.222.222#53 Sep 23 12:58:04 one-dac dnsmasq[870]: using nameserver 208.67.220.220#53 Sep 23 12:58:04 one-dac sh[530]: forked to background, child pid 923 Sep 23 12:58:04 one-dac dhcpcd[562]: forked to background, child pid 923 Sep 23 12:58:04 one-dac winbindd[879]: [2024/09/23 12:58:04.139910, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Sep 23 12:58:04 one-dac winbindd[879]: res_names->count = 1, expected 2 Sep 23 12:58:04 one-dac smbd[902]: [2024/09/23 12:58:04.159321, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 23 12:58:04 one-dac smbd[902]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 23 12:58:04 one-dac systemd[1]: Started Samba SMB Daemon. Sep 23 12:58:04 one-dac systemd[1]: Reached target Multi-User System. Sep 23 12:58:04 one-dac systemd[1]: Reached target Graphical Interface. Sep 23 12:58:04 one-dac systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 23 12:58:04 one-dac systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 23 12:58:04 one-dac systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 23 12:58:04 one-dac systemd[1]: Startup finished in 14.458s (kernel) + 15.282s (userspace) = 29.740s. Sep 23 12:58:04 one-dac ifplugd(eth0)[790]: client: ifup: interface eth0 already configured Sep 23 12:58:04 one-dac sh[530]: eth0=eth0 Sep 23 12:58:04 one-dac ifplugd(eth0)[790]: Program executed successfully. Sep 23 12:58:05 one-dac volumio[882]: info: ------------------------------------------- Sep 23 12:58:05 one-dac volumio[882]: info: ----- Volumio3 ---- Sep 23 12:58:05 one-dac volumio[882]: info: ------------------------------------------- Sep 23 12:58:05 one-dac volumio[882]: info: ----- System startup ---- Sep 23 12:58:05 one-dac volumio[882]: info: ------------------------------------------- Sep 23 12:58:05 one-dac ntpd[763]: Listen normally on 3 eth0 192.168.0.115:123 Sep 23 12:58:05 one-dac ntpd[763]: Listen normally on 4 wlan0 192.168.211.1:123 Sep 23 12:58:05 one-dac ntpd[763]: new interface(s) found: waking up resolver Sep 23 12:58:06 one-dac volumio[882]: info: MYVOLUMIO Environment detected Sep 23 12:58:06 one-dac volumio[882]: info: Plugin folders cleanup Sep 23 12:58:06 one-dac volumio[882]: info: Scanning into folder /volumio/app/plugins/ Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category audio_interface Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category miscellanea Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category music_service Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category plugins.json Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category system_controller Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category user_interface Sep 23 12:58:06 one-dac volumio[882]: info: Scanning into folder /data/plugins/ Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category music_service Sep 23 12:58:06 one-dac volumio[882]: info: Scanning category user_interface Sep 23 12:58:06 one-dac volumio[882]: info: Plugin folders cleanup completed Sep 23 12:58:06 one-dac volumio[882]: info: ------------------------------------------- Sep 23 12:58:06 one-dac volumio[882]: info: ----- Core plugins startup ---- Sep 23 12:58:06 one-dac volumio[882]: info: ------------------------------------------- Sep 23 12:58:06 one-dac volumio[882]: info: Loading plugins from folder /volumio/app/plugins/ Sep 23 12:58:06 one-dac volumio[882]: info: Adding plugin upnp to MyMusic Plugins Sep 23 12:58:06 one-dac volumio[882]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 23 12:58:06 one-dac volumio[882]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 23 12:58:06 one-dac volumio[882]: info: Loading plugins from folder /data/plugins/ Sep 23 12:58:06 one-dac volumio[882]: info: Loading plugin "system"... Sep 23 12:58:06 one-dac volumio[882]: info: Loading plugin "appearance"... Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "network"... Sep 23 12:58:07 one-dac volumio[882]: info: Refreshing Cached IP Addresses Sep 23 12:58:07 one-dac sudo[971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 23 12:58:07 one-dac sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "services"... Sep 23 12:58:07 one-dac sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 23 12:58:07 one-dac sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:07 one-dac sudo[971]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "alsa_controller"... Sep 23 12:58:07 one-dac sudo[973]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:07 one-dac sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 23 12:58:07 one-dac sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:07 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "wizard"... Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "networkfs"... Sep 23 12:58:07 one-dac volumio[882]: info: Starting Udev Watcher for removable devices Sep 23 12:58:07 one-dac volumio[882]: info: Ignoring mount for partition: boot Sep 23 12:58:07 one-dac volumio[882]: info: Ignoring mount for partition: volumio Sep 23 12:58:07 one-dac volumio[882]: info: Ignoring mount for partition: volumio_data Sep 23 12:58:07 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "volumio_command_line_client"... Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "upnp"... Sep 23 12:58:07 one-dac volumio[882]: info: [1727096287502] Starting Upmpd Daemon Sep 23 12:58:07 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "my_music"... Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "mpd"... Sep 23 12:58:07 one-dac volumio[882]: info: Loading plugin "upnp_browser"... Sep 23 12:58:08 one-dac volumio[882]: info: Loading plugin "alarm-clock"... Sep 23 12:58:08 one-dac volumio[882]: info: Loading plugin "airplay_emulation"... Sep 23 12:58:08 one-dac volumio[882]: info: Starting Shairport Sync Sep 23 12:58:08 one-dac volumio[882]: info: Loading plugin "last_100"... Sep 23 12:58:08 one-dac volumio[882]: info: Loading plugin "webradio"... Sep 23 12:58:08 one-dac volumio[882]: info: Loading plugin "i2s_dacs"... Sep 23 12:58:08 one-dac volumio[882]: info: I2S DAC not set, start Auto-detection Sep 23 12:58:08 one-dac volumio[882]: info: Loading plugin "volumiodiscovery"... Sep 23 12:58:08 one-dac volumio[882]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 23 12:58:08 one-dac volumio[882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 23 12:58:08 one-dac volumio[882]: *** WARNING *** For more information see Sep 23 12:58:08 one-dac node[882]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 23 12:58:08 one-dac volumio[882]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 23 12:58:08 one-dac volumio[882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 23 12:58:08 one-dac volumio[882]: *** WARNING *** For more information see Sep 23 12:58:08 one-dac node[882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 23 12:58:08 one-dac node[882]: *** WARNING *** For more information see Sep 23 12:58:08 one-dac node[882]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 23 12:58:08 one-dac node[882]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 23 12:58:08 one-dac node[882]: *** WARNING *** For more information see Sep 23 12:58:08 one-dac volumio[882]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 23 12:58:08 one-dac volumio[882]: info: Discovery: Started advertising with name: ONE DAC Sep 23 12:58:08 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 23 12:58:08 one-dac volumio[882]: info: Loading plugin "spop"... Sep 23 12:58:08 one-dac volumio-remote-updater[603]: [2024-09-23 12:58:08] [connect] Successful connection Sep 23 12:58:09 one-dac volumio[882]: info: Loading plugin "outputs"... Sep 23 12:58:09 one-dac volumio[882]: info: Loading plugin "albumart"... Sep 23 12:58:09 one-dac volumio[882]: info: Plugin example_plugin is not enabled Sep 23 12:58:09 one-dac volumio[882]: info: Loading plugin "inputs"... Sep 23 12:58:09 one-dac volumio[882]: info: Loading plugin "updater_comm"... Sep 23 12:58:09 one-dac volumio[882]: info: Plugin mpdemulation is not enabled Sep 23 12:58:09 one-dac volumio[882]: info: Loading plugin "rest_api"... Sep 23 12:58:09 one-dac volumio[882]: info: Loading plugin "websocket"... Sep 23 12:58:09 one-dac volumio[882]: info: Starting Socket.io Server version 2.3.0 Sep 23 12:58:09 one-dac volumio[882]: info: Loading plugin "RoonBridge"... Sep 23 12:58:09 one-dac volumio[882]: Forking 3 albumart workers Sep 23 12:58:10 one-dac volumio[882]: info: Applying required configuration parameters for plugin RoonBridge Sep 23 12:58:10 one-dac volumio[882]: info: Loading plugin "touch_display"... Sep 23 12:58:10 one-dac volumio[882]: Starting albumart workers Sep 23 12:58:10 one-dac volumio[882]: Starting albumart workers Sep 23 12:58:10 one-dac volumio[882]: Starting albumart workers Sep 23 12:58:10 one-dac volumio[882]: info: Applying required configuration parameters for plugin touch_display Sep 23 12:58:10 one-dac volumio[882]: info: Loading i18n strings for locale en Sep 23 12:58:10 one-dac volumio[882]: Updating browse sources language Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::initPlayerControls Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 23 12:58:10 one-dac volumio[882]: Express server listening on port 3000 Sep 23 12:58:10 one-dac volumio[882]: [Metrics] WebUI: 6s 159.30ms Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::resetVolumioState Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::getcurrentVolume Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioRetrievevolume Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:11 one-dac volumio-remote-updater[603]: [2024-09-23 12:58:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1727096288 101 Sep 23 12:58:11 one-dac volumio[882]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Sep 23 12:58:11 one-dac volumio[882]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 23 12:58:11 one-dac volumio[882]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Sep 23 12:58:11 one-dac volumio[882]: info: Reloading queue from file Sep 23 12:58:11 one-dac volumio[882]: info: VolumeController:: Volume=undefined Mute =false Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:58:11 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::updateTrackBlock Sep 23 12:58:11 one-dac volumio[882]: info: CorePlayQueue::getTrackBlock Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioRetrievevolume Sep 23 12:58:11 one-dac volumio[882]: info: Setting Device type: Raspberry PI Sep 23 12:58:11 one-dac volumio[882]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Sep 23 12:58:11 one-dac volumio[882]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Sep 23 12:58:11 one-dac volumio[882]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::setRepeat false single undefined Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:58:11 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::setRandom false Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:58:11 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:58:11 one-dac volumio[882]: info: Completed loading Core Plugins Sep 23 12:58:11 one-dac volumio[882]: info: Preparing to generate the ALSA configuration file Sep 23 12:58:11 one-dac volumio[882]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Sep 23 12:58:11 one-dac volumio[882]: info: Discovery: adding b11fedb2-0369-44e1-9c5c-eb3e453a6a04 Sep 23 12:58:11 one-dac volumio[882]: info: Discovery: Found device ONE DAC Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:11 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:11 one-dac volumio[882]: info: Asound.conf file unchanged, so no further update is needed Sep 23 12:58:11 one-dac volumio[882]: info: Output device has changed, restarting MPD Sep 23 12:58:11 one-dac volumio[882]: info: Output device has changed, restarting Shairport Sync Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 23 12:58:11 one-dac sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:11 one-dac sudo[1049]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:11 one-dac sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 23 12:58:11 one-dac sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac volumio[882]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 23 12:58:11 one-dac volumio[882]: info: ___________ START PLUGINS ___________ Sep 23 12:58:11 one-dac volumio[882]: info: ControllerMpd::onStart: Initializing MPD Sep 23 12:58:11 one-dac volumio[882]: info: Creating MPD Configuration file Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 23 12:58:11 one-dac sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 23 12:58:11 one-dac sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 23 12:58:11 one-dac volumio[882]: info: [1727096291410] CoreMusicLibrary::Adding element Media Servers Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 23 12:58:11 one-dac systemd[1]: Listening on mpd.socket. Sep 23 12:58:11 one-dac sudo[1059]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:11 one-dac systemd[1]: Starting Music Player Daemon... Sep 23 12:58:11 one-dac sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 23 12:58:11 one-dac sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:11 one-dac systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 23 12:58:11 one-dac systemd[1]: mpd.service: Succeeded. Sep 23 12:58:11 one-dac systemd[1]: Stopped Music Player Daemon. Sep 23 12:58:11 one-dac systemd[1]: Starting Music Player Daemon... Sep 23 12:58:11 one-dac volumio[882]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:11 one-dac sudo[1066]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 23 12:58:11 one-dac sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac volumio[882]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 23 12:58:11 one-dac volumio[882]: info: [1727096291516] CoreMusicLibrary::Adding element Last_100 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 23 12:58:11 one-dac volumio[882]: info: [1727096291518] CoreMusicLibrary::Adding element Webradio Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 23 12:58:11 one-dac sudo[1066]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Sep 23 12:58:11 one-dac sudo[1066]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 23 12:58:11 one-dac volumio[882]: info: Initializing BBC Radios Sep 23 12:58:11 one-dac sudo[976]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: Creating Spotify config file Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: Loading i18n strings for locale en Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 23 12:58:11 one-dac volumio[882]: info: Volumio Calling Home Sep 23 12:58:11 one-dac sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Sep 23 12:58:11 one-dac sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Sep 23 12:58:11 one-dac sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac systemd[1]: Started RoonBridge. Sep 23 12:58:11 one-dac sudo[1085]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:11 one-dac systemd[1]: Reloading. Sep 23 12:58:11 one-dac volumio[882]: info: VolumeController:: Volume=undefined Mute =false Sep 23 12:58:11 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:58:11 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:58:11 one-dac volumio[882]: info: Discovery: this is already registered, b11fedb2-0369-44e1-9c5c-eb3e453a6a04 Sep 23 12:58:11 one-dac volumio[882]: info: Discovery: Found device ONE DAC Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:11 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:11 one-dac volumio[882]: info: MPD Permissions set Sep 23 12:58:11 one-dac volumio[882]: info: MPD Permissions set Sep 23 12:58:11 one-dac volumio[882]: info: Spotify config file written Sep 23 12:58:11 one-dac volumio[882]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:11 one-dac sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 23 12:58:11 one-dac sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:11 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:12 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:12 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:12 one-dac volumio[882]: info: No need to fix Spotify hosts Sep 23 12:58:12 one-dac volumio[882]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Sep 23 12:58:12 one-dac volumio[882]: info: Starting Shairport Sync Sep 23 12:58:12 one-dac volumio[882]: info: Starting Shairport Sync Sep 23 12:58:12 one-dac sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 23 12:58:12 one-dac volumio[882]: info: Starting Shairport Sync Sep 23 12:58:12 one-dac sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:12 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:12 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:12 one-dac sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 23 12:58:12 one-dac sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:12 one-dac sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 23 12:58:12 one-dac sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:12 one-dac systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 23 12:58:12 one-dac volumio[882]: info: Volumio called home Sep 23 12:58:12 one-dac systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 23 12:58:12 one-dac sudo[1092]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:12 one-dac volumio[882]: info: touch_display: systemctl daemon-reload succeeded. Sep 23 12:58:12 one-dac sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Sep 23 12:58:12 one-dac sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:12 one-dac systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 23 12:58:12 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:12 one-dac go-librespot[1139]: Librespot-go daemon starting... Sep 23 12:58:12 one-dac sudo[1102]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:12 one-dac systemd[1]: shairport-sync.service: Succeeded. Sep 23 12:58:12 one-dac systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 23 12:58:12 one-dac systemd[1]: Started Volumio Kiosk. Sep 23 12:58:12 one-dac systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 23 12:58:12 one-dac sudo[1129]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:12 one-dac sudo[1127]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:12 one-dac volumio[882]: info: Shairport-Sync Started Sep 23 12:58:12 one-dac volumio[882]: Error adding Membership: Error: addMembership EINVAL Sep 23 12:58:12 one-dac volumio[882]: info: Shairport-Sync Started Sep 23 12:58:12 one-dac sudo[1132]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:12 one-dac volumio[882]: info: Shairport-Sync Started Sep 23 12:58:12 one-dac sudo[1137]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:12 one-dac volumio[882]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Sep 23 12:58:12 one-dac volumio[882]: info: touch_display: Volumio Kiosk started Sep 23 12:58:12 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:12 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:12 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:12 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:12 one-dac volumio[882]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Sep 23 12:58:13 one-dac volumio[882]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.014 Warn: get lock file path: /tmp/.rnbgem0- Sep 23 12:58:13 one-dac startx[1141]: X.Org X Server 1.20.4 Sep 23 12:58:13 one-dac startx[1141]: X Protocol Version 11, Revision 0 Sep 23 12:58:13 one-dac startx[1141]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Sep 23 12:58:13 one-dac startx[1141]: Current Operating System: Linux one-dac 6.1.69-v7l+ #1710 SMP Thu Dec 21 13:17:38 GMT 2023 armv7l Sep 23 12:58:13 one-dac startx[1141]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=D8:3A:DD:9B:32:02 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash 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 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=59add7c1-f5c7-4787-a0bf-7fec952d5a50 imgfile=/volumio_current.sqsh bootpart=UUID=E8CB-9B11 datapart=UUID=c7049a41-b6fb-4f8f-807b-264845b2b35c uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Sep 23 12:58:13 one-dac startx[1141]: Build Date: 04 April 2023 07:50:56AM Sep 23 12:58:13 one-dac startx[1141]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Sep 23 12:58:13 one-dac startx[1141]: Current version of pixman: 0.36.0 Sep 23 12:58:13 one-dac startx[1141]: Before reporting problems, check http://wiki.x.org Sep 23 12:58:13 one-dac startx[1141]: to make sure that you have the latest version. Sep 23 12:58:13 one-dac startx[1141]: Markers: (--) probed, (**) from config file, (==) default setting, Sep 23 12:58:13 one-dac startx[1141]: (++) from command line, (!!) notice, (II) informational, Sep 23 12:58:13 one-dac startx[1141]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Sep 23 12:58:13 one-dac startx[1141]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Sep 23 12:58:13 2024 Sep 23 12:58:13 one-dac go-librespot[1139]: time="2024-09-23T12:58:13Z" level=info msg="generated new device id: 8033a4c15f81ef432ccf8e1e5d4cc1c14f28ffa0" Sep 23 12:58:13 one-dac startx[1141]: (==) Using config directory: "/etc/X11/xorg.conf.d" Sep 23 12:58:13 one-dac startx[1141]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Sep 23 12:58:13 one-dac volumio[882]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 23 12:58:13 one-dac go-librespot[1139]: time="2024-09-23T12:58:13Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:13Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:13 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:13 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.506 Trace: [childprocess] using unix child process Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.577 Debug: PathForResource, filename: ../.update Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.673 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.680 Debug: PathForResource, filename: RAATServer Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.681 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.682 Debug: PathForResource, filename: RoonBridgeHelper Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.682 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 23 12:58:13 one-dac RoonBridge[1090]: Initializing Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.721 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Sep 23 12:58:13 one-dac RoonBridge[1090]: 00:00:00.758 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Sep 23 12:58:13 one-dac RoonBridge[1090]: Not Running (.o) Sep 23 12:58:13 one-dac mpd[1073]: Sep 23 12:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 23 12:58:13 one-dac systemd[1]: Started Music Player Daemon. Sep 23 12:58:13 one-dac sudo[1061]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:13 one-dac sudo[1051]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:14 one-dac volumio[882]: info: Completed starting Core Plugins Sep 23 12:58:14 one-dac volumio[882]: info: ------------------------------------------- Sep 23 12:58:14 one-dac volumio[882]: info: ----- MyVolumio plugins startup ---- Sep 23 12:58:14 one-dac volumio[882]: info: ------------------------------------------- Sep 23 12:58:14 one-dac volumio[882]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 23 12:58:14 one-dac RoonBridge[1090]: Running Sep 23 12:58:14 one-dac volumio[882]: error: MPD error: The expression evaluated to a falsy value: Sep 23 12:58:14 one-dac volumio[882]: assert.ok(self.idling) Sep 23 12:58:14 one-dac volumio[882]: error: The expression evaluated to a falsy value: Sep 23 12:58:14 one-dac volumio[882]: assert.ok(self.idling) Sep 23 12:58:14 one-dac volumio[882]: info: MPD running with PID1073 Sep 23 12:58:14 one-dac volumio[882]: ,establishing connection Sep 23 12:58:14 one-dac volumio[882]: error: updateQueue error: null Sep 23 12:58:14 one-dac volumio[882]: error: updateQueue error: null Sep 23 12:58:14 one-dac RoonBridge[1090]: 00:00:00.025 Warn: get lock file path: /tmp/.rnbhgem0- Sep 23 12:58:14 one-dac volumio[882]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 23 12:58:15 one-dac volumio[882]: info: go-librespot daemon successfully initialized Sep 23 12:58:16 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:16 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Sep 23 12:58:16 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:16 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:16 one-dac go-librespot[1304]: Librespot-go daemon starting... Sep 23 12:58:16 one-dac go-librespot[1304]: time="2024-09-23T12:58:16Z" level=info msg="generated new device id: 356dd47f0ed0ed3d43bf1a4151faacb4442e8345" Sep 23 12:58:16 one-dac go-librespot[1304]: time="2024-09-23T12:58:16Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:16Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:16 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:16 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:17 one-dac sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 23 12:58:17 one-dac sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:17 one-dac sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 23 12:58:17 one-dac sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:17 one-dac sudo[1334]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:17 one-dac sudo[1332]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:17 one-dac sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 23 12:58:17 one-dac sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 23 12:58:17 one-dac systemd[1]: Started UPnP Renderer front-end to MPD. Sep 23 12:58:17 one-dac sudo[1338]: pam_unix(sudo:session): session closed for user root Sep 23 12:58:17 one-dac volumio[882]: info: Upmpdcli Daemon Started Sep 23 12:58:17 one-dac volumio[1343]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 23 12:58:18 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:18 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:20 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:20 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Sep 23 12:58:20 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:20 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:20 one-dac go-librespot[1412]: Librespot-go daemon starting... Sep 23 12:58:20 one-dac go-librespot[1412]: time="2024-09-23T12:58:20Z" level=info msg="generated new device id: 870e0d640fa863244d8f73e9874b32b3a0a70e51" Sep 23 12:58:20 one-dac go-librespot[1412]: time="2024-09-23T12:58:20Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:20Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:20 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:20 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:21 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:21 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:23 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:23 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Sep 23 12:58:23 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:23 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:23 one-dac go-librespot[1473]: Librespot-go daemon starting... Sep 23 12:58:23 one-dac go-librespot[1473]: time="2024-09-23T12:58:23Z" level=info msg="generated new device id: d92788dc7854dfc75551cc72793d7f236966890f" Sep 23 12:58:23 one-dac go-librespot[1473]: time="2024-09-23T12:58:23Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:23Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:23 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:23 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:23 one-dac volumio[882]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 23 12:58:24 one-dac volumio[882]: info: CoreCommandRouter::volumioGetVisibleSources Sep 23 12:58:24 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 23 12:58:24 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:24 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:24 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 23 12:58:24 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 23 12:58:24 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 23 12:58:24 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 23 12:58:24 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:24 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:25 one-dac volumio[882]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 23 12:58:25 one-dac systemd[1]: systemd-fsckd.service: Succeeded. Sep 23 12:58:25 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:25 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:25 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 23 12:58:25 one-dac volumio[882]: info: Listing playlists Sep 23 12:58:25 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 23 12:58:25 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 23 12:58:25 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 23 12:58:25 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 23 12:58:26 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:26 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Sep 23 12:58:26 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:26 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:26 one-dac go-librespot[1481]: Librespot-go daemon starting... Sep 23 12:58:26 one-dac go-librespot[1481]: time="2024-09-23T12:58:26Z" level=info msg="generated new device id: d8b7f84309a105b5e0cb5d1cc1116f0cfdb688c6" Sep 23 12:58:26 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 23 12:58:26 one-dac volumio[882]: info: Received Get System Info Sep 23 12:58:26 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 23 12:58:26 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 23 12:58:26 one-dac volumio[882]: info: Discovery: Getting this device information Sep 23 12:58:26 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:58:26 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:58:26 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 23 12:58:26 one-dac go-librespot[1481]: time="2024-09-23T12:58:26Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:26Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:26 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:26 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:27 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:27 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:28 one-dac systemd[1]: systemd-hostnamed.service: Succeeded. Sep 23 12:58:30 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:30 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Sep 23 12:58:30 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:30 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:30 one-dac go-librespot[1673]: Librespot-go daemon starting... Sep 23 12:58:30 one-dac go-librespot[1673]: time="2024-09-23T12:58:30Z" level=info msg="generated new device id: a75aabf6d6108f540171cd8d7085b3d2abde5582" Sep 23 12:58:30 one-dac go-librespot[1673]: time="2024-09-23T12:58:30Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:30Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:30 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:30 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:30 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:30 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:33 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:33 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Sep 23 12:58:33 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:33 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:33 one-dac go-librespot[1681]: Librespot-go daemon starting... Sep 23 12:58:33 one-dac go-librespot[1681]: time="2024-09-23T12:58:33Z" level=info msg="generated new device id: 066cc18db31a756dd1a6c2839f3484bc92ae73c1" Sep 23 12:58:33 one-dac go-librespot[1681]: time="2024-09-23T12:58:33Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:33Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:33 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:33 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:33 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:33 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:34 one-dac volumio[882]: error: MyVolumio Plugin failed to start in a timely fashion Sep 23 12:58:34 one-dac volumio[882]: info: BOOT COMPLETED Sep 23 12:58:34 one-dac volumio[882]: [Metrics] CommandRouter: 28s 604.19ms Sep 23 12:58:34 one-dac volumio[882]: info: CoreCommandRouter::volumiosetStartupVolume Sep 23 12:58:34 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 23 12:58:34 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:34 one-dac volumio[882]: info: CoreCommandRouter::Close All Modals sent Sep 23 12:58:34 one-dac volumio[882]: info: CoreCommandRouter::Close All Modals sent Sep 23 12:58:34 one-dac volumio[1343]: ...........................................................................................................................................................................................++++ Sep 23 12:58:35 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 23 12:58:35 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 23 12:58:35 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 23 12:58:36 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:36 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Sep 23 12:58:36 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:36 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:36 one-dac go-librespot[1693]: Librespot-go daemon starting... Sep 23 12:58:36 one-dac go-librespot[1693]: time="2024-09-23T12:58:36Z" level=info msg="generated new device id: 3c34f7b4d2c7c69f19616a79fdad1d6084b1e82e" Sep 23 12:58:36 one-dac go-librespot[1693]: time="2024-09-23T12:58:36Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:36Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:36 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:36 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:36 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:36 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:37 one-dac volumio[1343]: ..........................++++ Sep 23 12:58:37 one-dac volumio[1343]: e is 65537 (0x010001) Sep 23 12:58:37 one-dac volumio[1343]: writing RSA key Sep 23 12:58:39 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:39 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Sep 23 12:58:39 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:39 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:39 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:39 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:39 one-dac go-librespot[1719]: Librespot-go daemon starting... Sep 23 12:58:39 one-dac go-librespot[1719]: time="2024-09-23T12:58:39Z" level=info msg="generated new device id: 41847016485ba55aaddc9f792b6490777f4d3f7a" Sep 23 12:58:39 one-dac go-librespot[1719]: time="2024-09-23T12:58:39Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:39Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:39 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:39 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:41 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 23 12:58:41 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 23 12:58:41 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 23 12:58:42 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:42 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:43 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:43 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Sep 23 12:58:43 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:43 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:43 one-dac go-librespot[1732]: Librespot-go daemon starting... Sep 23 12:58:43 one-dac go-librespot[1732]: time="2024-09-23T12:58:43Z" level=info msg="generated new device id: 4b589c49b82a3e412666ab03ecb6613981d65f39" Sep 23 12:58:43 one-dac go-librespot[1732]: time="2024-09-23T12:58:43Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:43Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:43 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:43 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:45 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:45 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:46 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:46 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Sep 23 12:58:46 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:46 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:46 one-dac go-librespot[1743]: Librespot-go daemon starting... Sep 23 12:58:46 one-dac go-librespot[1743]: time="2024-09-23T12:58:46Z" level=info msg="generated new device id: 7297bb50448c8ca1ab8a9b93dbdb8803505a192e" Sep 23 12:58:46 one-dac go-librespot[1743]: time="2024-09-23T12:58:46Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:46Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:46 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:46 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:48 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:48 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:49 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:49 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Sep 23 12:58:49 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:49 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:49 one-dac go-librespot[1751]: Librespot-go daemon starting... Sep 23 12:58:49 one-dac go-librespot[1751]: time="2024-09-23T12:58:49Z" level=info msg="generated new device id: d17f1d9e4c1f1b4cf300f9ec85b6cc9b172a22e3" Sep 23 12:58:49 one-dac go-librespot[1751]: time="2024-09-23T12:58:49Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:49Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:49 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:49 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:51 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:51 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:52 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:52 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Sep 23 12:58:52 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:52 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:52 one-dac go-librespot[1759]: Librespot-go daemon starting... Sep 23 12:58:52 one-dac go-librespot[1759]: time="2024-09-23T12:58:52Z" level=info msg="generated new device id: 02abba7313d0e62021de90f84a0b17aa0347121e" Sep 23 12:58:52 one-dac go-librespot[1759]: time="2024-09-23T12:58:52Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:52Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:52 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:52 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:54 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:54 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:56 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:56 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Sep 23 12:58:56 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:56 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:56 one-dac go-librespot[1767]: Librespot-go daemon starting... Sep 23 12:58:56 one-dac go-librespot[1767]: time="2024-09-23T12:58:56Z" level=info msg="generated new device id: 57e0255e11c787f89c10c9d5814fb66ae26a5116" Sep 23 12:58:56 one-dac go-librespot[1767]: time="2024-09-23T12:58:56Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:56Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:56 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:56 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:58:56 one-dac volumiologrotate[628]: ls: cannot access '/var/log/samba/log.wb-ONE': No such file or directory Sep 23 12:58:56 one-dac volumiologrotate[628]: ls: cannot access 'DAC': No such file or directory Sep 23 12:58:57 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:58:57 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:58:59 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:58:59 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Sep 23 12:58:59 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:58:59 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:58:59 one-dac go-librespot[1819]: Librespot-go daemon starting... Sep 23 12:58:59 one-dac go-librespot[1819]: time="2024-09-23T12:58:59Z" level=info msg="generated new device id: 59dfeeb036ecd0204fc6a0b32d445d2eefcceaba" Sep 23 12:58:59 one-dac go-librespot[1819]: time="2024-09-23T12:58:59Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:58:59Z is before 2026-07-09T00:00:00Z" Sep 23 12:58:59 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:58:59 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:00 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:00 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:02 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:59:02 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Sep 23 12:59:02 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:59:02 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:59:02 one-dac go-librespot[1827]: Librespot-go daemon starting... Sep 23 12:59:02 one-dac go-librespot[1827]: time="2024-09-23T12:59:02Z" level=info msg="generated new device id: 2173d3f5e312297bd10f5894f35d7d4067dd778f" Sep 23 12:59:02 one-dac go-librespot[1827]: time="2024-09-23T12:59:02Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:59:02Z is before 2026-07-09T00:00:00Z" Sep 23 12:59:02 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:59:02 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:03 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:03 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:04 one-dac ntpd[763]: Soliciting pool server 45.252.250.189 Sep 23 12:59:05 one-dac ntpd[763]: Soliciting pool server 45.252.250.189 Sep 23 12:59:05 one-dac ntpd[763]: Soliciting pool server 115.165.161.155 Sep 23 12:59:05 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:59:05 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Sep 23 12:59:05 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:59:05 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:59:05 one-dac go-librespot[1835]: Librespot-go daemon starting... Sep 23 12:59:05 one-dac go-librespot[1835]: time="2024-09-23T12:59:05Z" level=info msg="generated new device id: 385ec006e317c82ffc31fa0d8e7bca536b96818c" Sep 23 12:59:05 one-dac go-librespot[1835]: time="2024-09-23T12:59:05Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:59:05Z is before 2026-07-09T00:00:00Z" Sep 23 12:59:05 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:59:05 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:06 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:06 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:07 one-dac ntpd[763]: Soliciting pool server 115.165.161.155 Sep 23 12:59:09 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:59:09 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Sep 23 12:59:09 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:59:09 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:59:09 one-dac go-librespot[1844]: Librespot-go daemon starting... Sep 23 12:59:09 one-dac go-librespot[1844]: time="2024-09-23T12:59:09Z" level=info msg="generated new device id: 02c9da3958da2d421bffa6d697462604edb3afaa" Sep 23 12:59:09 one-dac go-librespot[1844]: time="2024-09-23T12:59:09Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:59:09Z is before 2026-07-09T00:00:00Z" Sep 23 12:59:09 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:59:09 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:09 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:09 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:12 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:59:12 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Sep 23 12:59:12 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:59:12 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:59:12 one-dac go-librespot[1853]: Librespot-go daemon starting... Sep 23 12:59:12 one-dac go-librespot[1853]: time="2024-09-23T12:59:12Z" level=info msg="generated new device id: 93ca9a746f4adf29800c19cd39179d56ba0da019" Sep 23 12:59:12 one-dac go-librespot[1853]: time="2024-09-23T12:59:12Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:59:12Z is before 2026-07-09T00:00:00Z" Sep 23 12:59:12 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:59:12 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:12 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:12 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:15 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:59:15 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 19. Sep 23 12:59:15 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:59:15 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:59:15 one-dac go-librespot[1862]: Librespot-go daemon starting... Sep 23 12:59:15 one-dac go-librespot[1862]: time="2024-09-23T12:59:15Z" level=info msg="generated new device id: 7658df45b24c8664f0cbc86e9368899adb181174" Sep 23 12:59:15 one-dac go-librespot[1862]: time="2024-09-23T12:59:15Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:59:15Z is before 2026-07-09T00:00:00Z" Sep 23 12:59:15 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:59:15 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:15 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:15 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:18 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:59:18 one-dac volumio[882]: info: Clearing queue after UPNP request Sep 23 12:59:18 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 20. Sep 23 12:59:18 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:59:18 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:59:18 one-dac go-librespot[1874]: Librespot-go daemon starting... Sep 23 12:59:18 one-dac go-librespot[1874]: time="2024-09-23T12:59:18Z" level=info msg="generated new device id: bb68dbf7d030a79bb3fc924e068812a9d11b1458" Sep 23 12:59:18 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:18 one-dac go-librespot[1874]: time="2024-09-23T12:59:18Z" level=debug msg="new websocket client" Sep 23 12:59:18 one-dac volumio[882]: info: Connection to go-librespot Websocket established Sep 23 12:59:18 one-dac go-librespot[1874]: time="2024-09-23T12:59:18Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:59:18Z is before 2026-07-09T00:00:00Z" Sep 23 12:59:18 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:59:18 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:18 one-dac volumio[882]: info: Connection to go-librespot Websocket closed Sep 23 12:59:19 one-dac volumio[882]: info: CoreStateMachine::ClearQueue Sep 23 12:59:19 one-dac volumio[882]: info: CoreStateMachine::stop Sep 23 12:59:19 one-dac volumio[882]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 23 12:59:19 one-dac volumio[882]: info: CorePlayQueue::clearPlayQueue Sep 23 12:59:19 one-dac volumio[882]: info: CorePlayQueue::saveQueue Sep 23 12:59:19 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:19 one-dac volumio[882]: info: CoreCommandRouter::volumioPushQueue Sep 23 12:59:19 one-dac volumio[882]: error: Upnp client error: Error: This socket has been ended by the other party Sep 23 12:59:20 one-dac volumio[882]: info: Starting UPNP Playback Sep 23 12:59:20 one-dac volumio[882]: info: Preparing playback through UPNP Sep 23 12:59:20 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:59:20 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:59:20 one-dac volumio[882]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 7ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 5ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 4ms Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 5ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 2ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 2ms Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 4ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 3ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 2ms Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 3ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 3ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 2ms Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 4ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 3ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 2ms Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces system playlist update Sep 23 12:59:21 one-dac volumio[882]: info: Ignoring MPD Status Update Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 3ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 2ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 1ms Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand status took 6 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand status took 5 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand status took 6 milliseconds Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand status took 9 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand status took 2 milliseconds Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:21 one-dac volumio[882]: verbose: In UPNP mode Sep 23 12:59:21 one-dac volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:21 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState stateService play Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus stop Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:21 one-dac volumio[882]: verbose: In UPNP mode Sep 23 12:59:21 one-dac volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:21 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState stateService play Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus play Sep 23 12:59:21 one-dac volumio[882]: info: Received an update from plugin. extracting info from payload Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:21 one-dac volumio[882]: verbose: In UPNP mode Sep 23 12:59:21 one-dac volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:21 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState stateService play Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus play Sep 23 12:59:21 one-dac volumio[882]: info: Received an update from plugin. extracting info from payload Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 40ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 55ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 54ms Sep 23 12:59:21 one-dac volumio[882]: info: Sep 23 12:59:21 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 45 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 44 milliseconds Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand status took 3 milliseconds Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:21 one-dac volumio[882]: verbose: In UPNP mode Sep 23 12:59:21 one-dac volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:21 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState stateService play Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus play Sep 23 12:59:21 one-dac volumio[882]: info: Received an update from plugin. extracting info from payload Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:21 one-dac volumio[882]: verbose: In UPNP mode Sep 23 12:59:21 one-dac volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:21 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState stateService play Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus play Sep 23 12:59:21 one-dac volumio[882]: info: Received an update from plugin. extracting info from payload Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 97ms Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 89ms Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 77 milliseconds Sep 23 12:59:21 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:21 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:21 one-dac volumio[882]: verbose: In UPNP mode Sep 23 12:59:21 one-dac volumio[882]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:21 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState stateService play Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus play Sep 23 12:59:21 one-dac volumio[882]: info: Received an update from plugin. extracting info from payload Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:21 one-dac volumio[882]: info: ------------------------------ 117ms Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:21 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:21 one-dac volumio[882]: info: Getting Spotify volume Sep 23 12:59:21 one-dac volumio[882]: (node:882) UnhandledPromiseRejectionWarning: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:21 one-dac volumio[882]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) Sep 23 12:59:21 one-dac volumio[882]: (Use `node --trace-warnings ...` to show where the warning was created) Sep 23 12:59:21 one-dac volumio[882]: (node:882) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Sep 23 12:59:21 one-dac volumio[882]: (node:882) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Sep 23 12:59:21 one-dac volumio[882]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 23 12:59:21 one-dac volumio[882]: info: CoreCommandRouter::volumioGetState Sep 23 12:59:21 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:21 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:22 one-dac systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Sep 23 12:59:22 one-dac systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 21. Sep 23 12:59:22 one-dac systemd[1]: Stopped go-librespot Daemon. Sep 23 12:59:22 one-dac systemd[1]: Started go-librespot Daemon. Sep 23 12:59:22 one-dac go-librespot[1897]: Librespot-go daemon starting... Sep 23 12:59:22 one-dac go-librespot[1897]: time="2024-09-23T12:59:22Z" level=info msg="generated new device id: efe7ed3d508c17d1bee6f22bef503fd66be9e44a" Sep 23 12:59:24 one-dac volumio[882]: info: Sep 23 12:59:24 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:24 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:24 one-dac volumio[882]: info: Sep 23 12:59:24 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:24 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:24 one-dac volumio[882]: info: Sep 23 12:59:24 one-dac volumio[882]: ---------------------------- MPD announces state update: player Sep 23 12:59:24 one-dac volumio[882]: info: ControllerMpd::getState Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand status Sep 23 12:59:24 one-dac volumio[882]: info: sendMpdCommand status took 4 milliseconds Sep 23 12:59:24 one-dac volumio[882]: info: sendMpdCommand status took 3 milliseconds Sep 23 12:59:24 one-dac volumio[882]: info: sendMpdCommand status took 1 milliseconds Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::parseState Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 23 12:59:24 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 23 12:59:24 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 23 12:59:24 one-dac volumio[882]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:24 one-dac volumio[882]: verbose: ControllerMpd::parseTrackInfo Sep 23 12:59:24 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:59:24 one-dac volumio[882]: 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":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:24 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::syncState stateService stop Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus play Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::stPlaybackTimer Sep 23 12:59:24 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:59:24 one-dac volumio[882]: 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":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:24 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::syncState stateService stop Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus stop Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: No code Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: ControllerMpd::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::servicePushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: CorePlayQueue::getTrack 0 Sep 23 12:59:24 one-dac volumio[882]: 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":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","uri":"http://192.168.0.100:14916/mconnect/tidal/105048155.flac","trackType":"tidal"} Sep 23 12:59:24 one-dac volumio[882]: verbose: CURRENT POSITION 0 Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::syncState stateService stop Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::syncState currentStatus stop Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: No code Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::pushState Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioPushState Sep 23 12:59:24 one-dac volumio[882]: info: ------------------------------ 84ms Sep 23 12:59:24 one-dac volumio[882]: info: ------------------------------ 86ms Sep 23 12:59:24 one-dac volumio[882]: info: ------------------------------ 92ms Sep 23 12:59:24 one-dac volumio[882]: info: CoreCommandRouter::volumioGetQueue Sep 23 12:59:24 one-dac volumio[882]: info: CoreStateMachine::getQueue Sep 23 12:59:24 one-dac volumio[882]: info: CorePlayQueue::getQueue Sep 23 12:59:24 one-dac volumio[882]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 23 12:59:24 one-dac go-librespot[1897]: time="2024-09-23T12:59:24Z" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": tls: failed to verify certificate: x509: certificate has expired or is not yet valid: current time 2024-09-23T12:59:24Z is before 2026-07-09T00:00:00Z" Sep 23 12:59:24 one-dac systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Sep 23 12:59:24 one-dac systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Sep 23 12:59:24 one-dac volumio[882]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Sep 23 12:59:24 one-dac volumio[882]: info: touch_display: Setting screensaver timeout to 120 seconds. Sep 23 12:59:24 one-dac volumio[882]: info: Initializing connection to go-librespot Websocket Sep 23 12:59:24 one-dac volumio[882]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Sep 23 12:59:25 one-dac volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 23 12:59:25 one-dac volumio[882]: Error: certificate is not yet valid Sep 23 12:59:25 one-dac volumio[882]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Sep 23 12:59:25 one-dac volumio[882]: at TLSSocket.emit (events.js:315:20) Sep 23 12:59:25 one-dac volumio[882]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Sep 23 12:59:25 one-dac volumio[882]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Sep 23 12:59:25 one-dac volumio[882]: code: 'CERT_NOT_YET_VALID' Sep 23 12:59:25 one-dac volumio[882]: } Sep 23 12:59:25 one-dac volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 23 12:59:25 one-dac sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-23 12:58 Sep 23 12:59:25 one-dac sudo[1943]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"