-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-11-21 14:21:40 CET. -- Nov 21 14:20:00 volumio-zoli systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 21 14:20:00 volumio-zoli systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 21 14:20:00 volumio-zoli systemd[1]: Failed to start dhcpcd on all interfaces. Nov 21 14:20:00 volumio-zoli wpa_supplicant[618]: Successfully initialized wpa_supplicant Nov 21 14:20:00 volumio-zoli avahi-daemon[588]: Successfully called chroot(). Nov 21 14:20:00 volumio-zoli systemd[1]: Started Raspberry Pi bluetooth helper. Nov 21 14:20:00 volumio-zoli avahi-daemon[588]: Successfully dropped remaining capabilities. Nov 21 14:20:00 volumio-zoli avahi-daemon[588]: Loading service file /services/volumio.service. Nov 21 14:20:00 volumio-zoli avahi-daemon[588]: Network interface enumeration completed. Nov 21 14:20:00 volumio-zoli avahi-daemon[588]: Server startup complete. Host name is volumio-zoli.local. Local service cookie is 2233999362. Nov 21 14:20:00 volumio-zoli avahi-daemon[588]: Service "Volumio Zoli" (/services/volumio.service) successfully established. Nov 21 14:20:00 volumio-zoli systemd[1]: Started Login Service. Nov 21 14:20:00 volumio-zoli systemd[1]: Started WPA supplicant. Nov 21 14:20:00 volumio-zoli volumio-remote-updater[624]: Error: No active session Nov 21 14:20:00 volumio-zoli volumio-remote-updater[624]: [2024-11-21 14:20:00] [info] asio async_connect error: system:111 (Connection refused) Nov 21 14:20:00 volumio-zoli volumio-remote-updater[624]: [2024-11-21 14:20:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 21 14:20:00 volumio-zoli volumio-remote-updater[624]: [2024-11-21 14:20:00] [error] handle_connect error: Underlying Transport Error Nov 21 14:20:00 volumio-zoli systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 21 14:20:00 volumio-zoli volumio[604]: Could not open config: /tmp/upmpdcli.conf Nov 21 14:20:00 volumio-zoli systemd[1]: Listening on mpd.socket. Nov 21 14:20:00 volumio-zoli systemd[1]: Starting Bluetooth service... Nov 21 14:20:00 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Date not found in response Nov 21 14:20:00 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Nov 21 14:20:00 volumio-zoli systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 21 14:20:00 volumio-zoli systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 21 14:20:00 volumio-zoli systemd[1]: iptables.service: Succeeded. Nov 21 14:20:01 volumio-zoli systemd[1]: Started Raise network interfaces. Nov 21 14:20:01 volumio-zoli systemd[1]: Reached target Network. Nov 21 14:20:01 volumio-zoli systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 21 14:20:01 volumio-zoli systemd[1]: Starting Network Time Service... Nov 21 14:20:01 volumio-zoli systemd[1]: Starting Music Player Daemon... Nov 21 14:20:01 volumio-zoli systemd[1]: Starting Permit User Sessions... Nov 21 14:20:01 volumio-zoli systemd[1]: Reached target Network is Online. Nov 21 14:20:01 volumio-zoli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 14:20:01 volumio-zoli systemd[1]: Starting Samba NMB Daemon... Nov 21 14:20:01 volumio-zoli bluetoothd[705]: Bluetooth daemon 5.50 Nov 21 14:20:01 volumio-zoli bluetoothd[705]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 21 14:20:01 volumio-zoli systemd[1]: Starting /etc/rc.local Compatibility... Nov 21 14:20:01 volumio-zoli systemd[1]: Starting LSB: Brings up/down network automatically... Nov 21 14:20:01 volumio-zoli systemd[1]: Started Bluetooth service. Nov 21 14:20:01 volumio-zoli systemd[1]: Reached target Bluetooth. Nov 21 14:20:01 volumio-zoli systemd[1]: Started /etc/rc.local Compatibility. Nov 21 14:20:01 volumio-zoli bluetoothd[705]: Starting SDP server Nov 21 14:20:01 volumio-zoli bluetoothd[705]: Excluding (cli) sap Nov 21 14:20:01 volumio-zoli loadcpufreq[637]: Loading cpufreq kernel modules...done (none). Nov 21 14:20:01 volumio-zoli systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 21 14:20:01 volumio-zoli systemd[1]: Started Permit User Sessions. Nov 21 14:20:01 volumio-zoli systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 21 14:20:01 volumio-zoli systemd[1]: Starting Hold until boot process finishes up... Nov 21 14:20:01 volumio-zoli systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 21 14:20:01 volumio-zoli systemd[1]: Received SIGRTMIN+21 from PID 193 (plymouthd). Nov 21 14:20:01 volumio-zoli systemd[1]: Received SIGRTMIN+21 from PID 193 (plymouthd). Nov 21 14:20:01 volumio-zoli systemd[1]: plymouth-quit.service: Succeeded. Nov 21 14:20:01 volumio-zoli systemd[1]: Started Terminate Plymouth Boot Screen. Nov 21 14:20:01 volumio-zoli systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 21 14:20:01 volumio-zoli systemd[1]: Started Hold until boot process finishes up. Nov 21 14:20:01 volumio-zoli systemd[1]: Started Getty on tty1. Nov 21 14:20:01 volumio-zoli systemd[1]: Reached target Login Prompts. Nov 21 14:20:01 volumio-zoli kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 21 14:20:01 volumio-zoli kernel: Bluetooth: BNEP filters: protocol multicast Nov 21 14:20:01 volumio-zoli kernel: Bluetooth: BNEP socket layer initialized Nov 21 14:20:01 volumio-zoli bluetoothd[705]: Bluetooth management interface 1.22 initialized Nov 21 14:20:01 volumio-zoli dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=705 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 21 14:20:01 volumio-zoli kernel: Bluetooth: MGMT ver 1.22 Nov 21 14:20:01 volumio-zoli ntpd[717]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 21 14:20:01 volumio-zoli ntpd[717]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 21 14:20:01 volumio-zoli ntpd[757]: proto: precision = 2.093 usec (-19) Nov 21 14:20:01 volumio-zoli bluetoothd[705]: Failed to set privacy: Rejected (0x0b) Nov 21 14:20:01 volumio-zoli ntpd[757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 21 14:20:01 volumio-zoli ntpd[757]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 21 14:20:01 volumio-zoli ntpd[757]: Listen and drop on 0 v6wildcard [::]:123 Nov 21 14:20:01 volumio-zoli ntpd[757]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 21 14:20:01 volumio-zoli ntpd[757]: Listen normally on 2 lo 127.0.0.1:123 Nov 21 14:20:01 volumio-zoli ntpd[757]: Listening on routing socket on fd #19 for interface updates Nov 21 14:20:01 volumio-zoli ntpd[757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 21 14:20:01 volumio-zoli ntpd[757]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 21 14:20:01 volumio-zoli cpufrequtils[747]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 21 14:20:02 volumio-zoli systemd[1]: Started Network Time Service. Nov 21 14:20:02 volumio-zoli systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 21 14:20:02 volumio-zoli haveged[540]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 21 14:20:02 volumio-zoli haveged[540]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Nov 21 14:20:02 volumio-zoli haveged[540]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99905 Nov 21 14:20:02 volumio-zoli haveged[540]: haveged: fills: 0, generated: 0 Nov 21 14:20:02 volumio-zoli sudo[730]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 21 14:20:02 volumio-zoli sudo[730]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 14:20:02 volumio-zoli sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:02 volumio-zoli systemd[1]: Starting Hostname Service... Nov 21 14:20:02 volumio-zoli sudo[730]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:02 volumio-zoli ifplugd(eth0)[783]: ifplugd 0.28 initializing. Nov 21 14:20:02 volumio-zoli ifplugd(eth0)[783]: Using interface eth0/D8:3A:DD:E8:03:65 with driver (version: 6.6.56-v7l+) Nov 21 14:20:02 volumio-zoli ifplugd(eth0)[783]: Using detection mode: SIOCETHTOOL Nov 21 14:20:02 volumio-zoli ifplugd(eth0)[783]: Initialization complete, link beat not detected. Nov 21 14:20:02 volumio-zoli ifplugd[741]: Network Interface Plugging Daemon...start eth0...done. Nov 21 14:20:02 volumio-zoli systemd[1]: Started LSB: Brings up/down network automatically. Nov 21 14:20:02 volumio-zoli dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 21 14:20:02 volumio-zoli systemd[1]: Started Hostname Service. Nov 21 14:20:02 volumio-zoli nmbd[742]: [2024/11/21 14:20:02.801431, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 21 14:20:02 volumio-zoli ntpd[757]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 21 14:20:02 volumio-zoli nmbd[742]: started asyncdns process 786 Nov 21 14:20:02 volumio-zoli nmbd[742]: [2024/11/21 14:20:02.804387, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 21 14:20:02 volumio-zoli nmbd[742]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 21 14:20:02 volumio-zoli nmbd[742]: [2024/11/21 14:20:02.804520, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 21 14:20:02 volumio-zoli nmbd[742]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 21 14:20:03 volumio-zoli wireless.js[584]: Cleaning previous... Nov 21 14:20:03 volumio-zoli sudo[794]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 21 14:20:03 volumio-zoli sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:03 volumio-zoli sudo[794]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:03 volumio-zoli sudo[796]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 21 14:20:03 volumio-zoli sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:03 volumio-zoli systemd[1]: systemd-rfkill.service: Succeeded. Nov 21 14:20:03 volumio-zoli ntpd[757]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 21 14:20:03 volumio-zoli sudo[796]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:03 volumio-zoli wireless.js[584]: Stopped aP Nov 21 14:20:03 volumio-zoli sh[506]: eth0: carrier acquired Nov 21 14:20:03 volumio-zoli dhcpcd[544]: eth0: carrier acquired Nov 21 14:20:03 volumio-zoli sh[506]: DUID 00:01:00:01:2e:c1:0d:8a:d8:3a:dd:e8:03:65 Nov 21 14:20:03 volumio-zoli sh[506]: eth0: IAID dd:e8:03:65 Nov 21 14:20:03 volumio-zoli sh[506]: eth0: adding address fe80::da3a:ddff:fee8:365 Nov 21 14:20:03 volumio-zoli sh[506]: ipv6_addaddr1: Permission denied Nov 21 14:20:03 volumio-zoli dhcpcd[544]: DUID 00:01:00:01:2e:c1:0d:8a:d8:3a:dd:e8:03:65 Nov 21 14:20:03 volumio-zoli dhcpcd[544]: eth0: IAID dd:e8:03:65 Nov 21 14:20:03 volumio-zoli dhcpcd[544]: eth0: adding address fe80::da3a:ddff:fee8:365 Nov 21 14:20:03 volumio-zoli dhcpcd[544]: ipv6_addaddr1: Permission denied Nov 21 14:20:03 volumio-zoli kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Nov 21 14:20:03 volumio-zoli wireless.js[584]: Wireless Networking DISABLED, not starting wireless flow Nov 21 14:20:04 volumio-zoli systemd[1]: wireless.service: Succeeded. Nov 21 14:20:04 volumio-zoli systemd[1]: Started Wireless Services. Nov 21 14:20:04 volumio-zoli systemd[1]: Started Volumio Backend Module. Nov 21 14:20:04 volumio-zoli systemd[1]: Started Volumio Cpu Tweaker. Nov 21 14:20:04 volumio-zoli volumio-cpu-tweak[806]: Setting RT Priority for mpd Nov 21 14:20:04 volumio-zoli volumio-cpu-tweak[806]: Setting MPD Affinity Nov 21 14:20:04 volumio-zoli volumio-cpu-tweak[806]: pid 784's current affinity mask: f Nov 21 14:20:04 volumio-zoli volumio-cpu-tweak[806]: pid 784's new affinity mask: 3 Nov 21 14:20:04 volumio-zoli volumio-cpu-tweak[806]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 21 14:20:04 volumio-zoli volumio-cpu-tweak[806]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 21 14:20:04 volumio-zoli systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 21 14:20:04 volumio-zoli ifplugd(eth0)[783]: Link beat detected. Nov 21 14:20:04 volumio-zoli ifplugd(eth0)[783]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 21 14:20:04 volumio-zoli sh[506]: eth0: soliciting a DHCP lease Nov 21 14:20:04 volumio-zoli dhcpcd[544]: eth0: soliciting a DHCP lease Nov 21 14:20:04 volumio-zoli ifplugd(eth0)[783]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Nov 21 14:20:04 volumio-zoli mpd[784]: Nov 21 14:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 14:20:04 volumio-zoli systemd[1]: Started Music Player Daemon. Nov 21 14:20:04 volumio-zoli mpd_monitor.sh[606]: MPD restarted due to no mpc output. Nov 21 14:20:04 volumio-zoli sh[506]: eth0: soliciting an IPv6 router Nov 21 14:20:04 volumio-zoli dhcpcd[544]: eth0: soliciting an IPv6 router Nov 21 14:20:04 volumio-zoli ntpd[757]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 21 14:20:04 volumio-zoli bthelper[631]: Changing power off succeeded Nov 21 14:20:05 volumio-zoli bthelper[631]: [CHG] Controller D8:3A:DD:E8:03:68 Class: 0x0000041c Nov 21 14:20:05 volumio-zoli bthelper[631]: Changing power on succeeded Nov 21 14:20:05 volumio-zoli volumio-remote-updater[624]: [2024-11-21 14:20:05] [connect] Successful connection Nov 21 14:20:05 volumio-zoli ntpd[757]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 21 14:20:05 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Nov 21 14:20:05 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Date not found in response Nov 21 14:20:05 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Nov 21 14:20:06 volumio-zoli volumio[805]: info: ------------------------------------------- Nov 21 14:20:06 volumio-zoli volumio[805]: info: ----- Volumio3 ---- Nov 21 14:20:06 volumio-zoli volumio[805]: info: ------------------------------------------- Nov 21 14:20:06 volumio-zoli volumio[805]: info: ----- System startup ---- Nov 21 14:20:06 volumio-zoli volumio[805]: info: ------------------------------------------- Nov 21 14:20:06 volumio-zoli volumio[805]: info: MYVOLUMIO Environment detected Nov 21 14:20:06 volumio-zoli volumio[805]: info: Plugin folders cleanup Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning into folder /volumio/app/plugins/ Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning category audio_interface Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning category miscellanea Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning category music_service Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning category plugins.json Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning category system_controller Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning category user_interface Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning into folder /data/plugins/ Nov 21 14:20:06 volumio-zoli volumio[805]: info: Scanning category system_hardware Nov 21 14:20:06 volumio-zoli volumio[805]: info: Plugin folders cleanup completed Nov 21 14:20:06 volumio-zoli volumio[805]: info: ------------------------------------------- Nov 21 14:20:06 volumio-zoli volumio[805]: info: ----- Core plugins startup ---- Nov 21 14:20:06 volumio-zoli volumio[805]: info: ------------------------------------------- Nov 21 14:20:06 volumio-zoli volumio[805]: info: Loading plugins from folder /volumio/app/plugins/ Nov 21 14:20:06 volumio-zoli volumio[805]: info: Adding plugin upnp to MyMusic Plugins Nov 21 14:20:06 volumio-zoli volumio[805]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 21 14:20:06 volumio-zoli volumio[805]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 21 14:20:06 volumio-zoli volumio[805]: info: Loading plugins from folder /data/plugins/ Nov 21 14:20:06 volumio-zoli volumio[805]: info: Loading plugin "system"... Nov 21 14:20:06 volumio-zoli volumio[805]: info: Loading plugin "appearance"... Nov 21 14:20:07 volumio-zoli sh[506]: eth0: probing address 192.168.1.238/24 Nov 21 14:20:07 volumio-zoli dhcpcd[544]: eth0: probing address 192.168.1.238/24 Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "network"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Refreshing Cached IP Addresses Nov 21 14:20:08 volumio-zoli sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 14:20:08 volumio-zoli sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:08 volumio-zoli sudo[856]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "services"... Nov 21 14:20:08 volumio-zoli sudo[858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "alsa_controller"... Nov 21 14:20:08 volumio-zoli sudo[858]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:08 volumio-zoli sudo[858]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:08 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "wizard"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "networkfs"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Starting Udev Watcher for removable devices Nov 21 14:20:08 volumio-zoli volumio[805]: info: Ignoring mount for partition: boot Nov 21 14:20:08 volumio-zoli volumio[805]: info: Ignoring mount for partition: volumio Nov 21 14:20:08 volumio-zoli volumio[805]: info: Ignoring mount for partition: volumio_data Nov 21 14:20:08 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "volumio_command_line_client"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "upnp"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: [1732195208198] Starting Upmpd Daemon Nov 21 14:20:08 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "my_music"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "mpd"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "upnp_browser"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "alarm-clock"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "airplay_emulation"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Starting Shairport Sync Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "last_100"... Nov 21 14:20:08 volumio-zoli volumio[805]: info: Loading plugin "webradio"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "i2s_dacs"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "volumiodiscovery"... Nov 21 14:20:09 volumio-zoli volumio[805]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 14:20:09 volumio-zoli volumio[805]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 14:20:09 volumio-zoli node[805]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 21 14:20:09 volumio-zoli volumio[805]: *** WARNING *** For more information see Nov 21 14:20:09 volumio-zoli volumio[805]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 14:20:09 volumio-zoli volumio[805]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 14:20:09 volumio-zoli volumio[805]: *** WARNING *** For more information see Nov 21 14:20:09 volumio-zoli node[805]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 14:20:09 volumio-zoli node[805]: *** WARNING *** For more information see Nov 21 14:20:09 volumio-zoli node[805]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 21 14:20:09 volumio-zoli node[805]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 21 14:20:09 volumio-zoli node[805]: *** WARNING *** For more information see Nov 21 14:20:09 volumio-zoli volumio[805]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 21 14:20:09 volumio-zoli volumio[805]: info: Discovery: Started advertising with name: Volumio Zoli Nov 21 14:20:09 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "outputs"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "albumart"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Plugin example_plugin is not enabled Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "inputs"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "updater_comm"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Plugin mpdemulation is not enabled Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "rest_api"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "websocket"... Nov 21 14:20:09 volumio-zoli volumio[805]: info: Starting Socket.io Server version 2.3.0 Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "audiophonics_evo_sabre"... Nov 21 14:20:09 volumio-zoli volumio[805]: Forking 3 albumart workers Nov 21 14:20:09 volumio-zoli volumio[805]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Nov 21 14:20:09 volumio-zoli volumio[805]: info: Loading plugin "audiophonicsonoff"... Nov 21 14:20:10 volumio-zoli volumio[805]: info: Applying required configuration parameters for plugin audiophonicsonoff Nov 21 14:20:10 volumio-zoli volumio[805]: info: Audiophonics on/off initiated Nov 21 14:20:10 volumio-zoli volumio[805]: Starting albumart workers Nov 21 14:20:10 volumio-zoli volumio[805]: info: Loading i18n strings for locale hu Nov 21 14:20:10 volumio-zoli volumio[805]: Starting albumart workers Nov 21 14:20:10 volumio-zoli volumio[805]: Starting albumart workers Nov 21 14:20:10 volumio-zoli volumio[805]: Updating browse sources language Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::initPlayerControls Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: Express server listening on port 3000 Nov 21 14:20:10 volumio-zoli volumio[805]: [Metrics] WebUI: 5s 9.93ms Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreStateMachine::resetVolumioState Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreStateMachine::getcurrentVolume Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioRetrievevolume Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:20:10 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:20:10 volumio-zoli volumio[805]: info: Cannot read play queue from file Nov 21 14:20:10 volumio-zoli volumio[805]: info: Volumio Network Manager: Network status updated: 0 Nov 21 14:20:10 volumio-zoli volumio-remote-updater[624]: [2024-11-21 14:20:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1732195205 101 Nov 21 14:20:10 volumio-zoli volumio[805]: 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 Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: Setting Device type: Raspberry PI Nov 21 14:20:10 volumio-zoli volumio[805]: info: Completed loading Core Plugins Nov 21 14:20:10 volumio-zoli volumio[805]: info: Preparing to generate the ALSA configuration file Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreStateMachine::setRepeat null single undefined Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:20:10 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreStateMachine::setRandom null Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:20:10 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:20:10 volumio-zoli volumio[805]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 21 14:20:10 volumio-zoli volumio[805]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Nov 21 14:20:10 volumio-zoli volumio[805]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 21 14:20:10 volumio-zoli volumio[805]: info: Asound.conf file unchanged, so no further update is needed Nov 21 14:20:10 volumio-zoli volumio[805]: info: Output device has changed, restarting MPD Nov 21 14:20:10 volumio-zoli volumio[805]: info: Output device has changed, restarting Shairport Sync Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:10 volumio-zoli sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 14:20:10 volumio-zoli sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:10 volumio-zoli sudo[941]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:10 volumio-zoli sudo[943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 14:20:10 volumio-zoli sudo[943]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:10 volumio-zoli systemd[1]: Stopping Music Player Daemon... Nov 21 14:20:10 volumio-zoli volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 14:20:10 volumio-zoli volumio[805]: info: ___________ START PLUGINS ___________ Nov 21 14:20:10 volumio-zoli volumio[805]: info: ControllerMpd::onStart: Initializing MPD Nov 21 14:20:10 volumio-zoli volumio[805]: info: Creating MPD Configuration file Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 14:20:10 volumio-zoli volumio[805]: info: [1732195210696] CoreMusicLibrary::Adding element Médiaszerverek Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 14:20:10 volumio-zoli sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 14:20:10 volumio-zoli sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:10 volumio-zoli sudo[950]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:10 volumio-zoli sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 14:20:10 volumio-zoli sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:10 volumio-zoli volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 14:20:10 volumio-zoli volumio[805]: info: [1732195210787] CoreMusicLibrary::Adding element Last_100 Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 14:20:10 volumio-zoli volumio[805]: info: [1732195210789] CoreMusicLibrary::Adding element Webradio Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 14:20:10 volumio-zoli volumio[805]: info: Initializing BBC Radios Nov 21 14:20:10 volumio-zoli systemd[1]: mpd.service: Succeeded. Nov 21 14:20:10 volumio-zoli systemd[1]: Stopped Music Player Daemon. Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 14:20:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:10 volumio-zoli volumio[805]: info: EVO SABRE : Starting Plugin Nov 21 14:20:10 volumio-zoli volumio[805]: info: Loading i18n strings for locale hu Nov 21 14:20:10 volumio-zoli volumio[805]: info: Configuring GPIO pins Nov 21 14:20:10 volumio-zoli volumio[805]: error: Plugin audiophonicsonoff failed to start! Error: EBUSY: resource busy or locked, write Nov 21 14:20:10 volumio-zoli volumio[805]: info: Volumio Calling Home Nov 21 14:20:10 volumio-zoli systemd[1]: Starting Music Player Daemon... Nov 21 14:20:10 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Nov 21 14:20:10 volumio-zoli sudo[964]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 14:20:10 volumio-zoli sudo[964]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:10 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Date not found in response Nov 21 14:20:10 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Retrying in 5 seconds... Nov 21 14:20:10 volumio-zoli sudo[964]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli volumio[805]: Unhandled rejection Error: No sockets available, cannot start. Nov 21 14:20:11 volumio-zoli volumio[805]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 21 14:20:11 volumio-zoli volumio[805]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 21 14:20:11 volumio-zoli volumio[805]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 21 14:20:11 volumio-zoli volumio[805]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 21 14:20:11 volumio-zoli volumio[805]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 21 14:20:11 volumio-zoli volumio[805]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 21 14:20:11 volumio-zoli volumio[805]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 21 14:20:11 volumio-zoli volumio[805]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 21 14:20:11 volumio-zoli volumio[805]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 21 14:20:11 volumio-zoli volumio[805]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 21 14:20:11 volumio-zoli volumio[805]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 21 14:20:11 volumio-zoli volumio[805]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 21 14:20:11 volumio-zoli volumio[805]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 21 14:20:11 volumio-zoli volumio[805]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 21 14:20:11 volumio-zoli volumio[805]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 21 14:20:11 volumio-zoli volumio[805]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 21 14:20:11 volumio-zoli volumio[805]: info: MPD Permissions set Nov 21 14:20:11 volumio-zoli volumio[805]: info: MPD Permissions set Nov 21 14:20:11 volumio-zoli sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 21 14:20:11 volumio-zoli sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:11 volumio-zoli volumio[805]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli systemd[1]: Reloading. Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:20:11 volumio-zoli volumio[805]: info: Starting Shairport Sync Nov 21 14:20:11 volumio-zoli volumio[805]: info: Starting Shairport Sync Nov 21 14:20:11 volumio-zoli sudo[1014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 14:20:11 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:11 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:11 volumio-zoli sudo[1014]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:11 volumio-zoli volumio[805]: info: Starting Shairport Sync Nov 21 14:20:11 volumio-zoli sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 14:20:11 volumio-zoli sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:11 volumio-zoli 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. Nov 21 14:20:11 volumio-zoli sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 14:20:11 volumio-zoli sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:11 volumio-zoli systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 21 14:20:11 volumio-zoli systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 21 14:20:11 volumio-zoli sudo[990]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli volumio[805]: info: EVO SABRE : systemd cmd daemon-reload : success Nov 21 14:20:11 volumio-zoli systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 14:20:11 volumio-zoli sudo[1023]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Nov 21 14:20:11 volumio-zoli sudo[1023]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:11 volumio-zoli systemd[1]: shairport-sync.service: Succeeded. Nov 21 14:20:11 volumio-zoli systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 21 14:20:11 volumio-zoli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 14:20:11 volumio-zoli sudo[1016]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli sudo[1014]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli volumio[805]: info: Shairport-Sync Started Nov 21 14:20:11 volumio-zoli volumio[805]: Error adding Membership: Error: addMembership EINVAL Nov 21 14:20:11 volumio-zoli sudo[1020]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli volumio[805]: info: Shairport-Sync Started Nov 21 14:20:11 volumio-zoli volumio[805]: info: Shairport-Sync Started Nov 21 14:20:11 volumio-zoli systemd[1]: Started OLED Display Service for EVO SABRE. Nov 21 14:20:11 volumio-zoli sudo[1023]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli volumio[805]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Nov 21 14:20:11 volumio-zoli sudo[1026]: root : TTY=unknown ; PWD=/data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled ; USER=root ; COMMAND=/usr/bin/node /data/plugins/system_hardware/audiophonics_evo_sabre/apps/evo_oled/index.js volumio Nov 21 14:20:11 volumio-zoli sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:11 volumio-zoli sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Nov 21 14:20:11 volumio-zoli sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:11 volumio-zoli mpd[988]: Nov 21 14:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 14:20:11 volumio-zoli systemd[1]: Started Music Player Daemon. Nov 21 14:20:11 volumio-zoli sudo[952]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli sudo[943]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:11 volumio-zoli volumio[805]: error: MPD error: The expression evaluated to a falsy value: Nov 21 14:20:11 volumio-zoli volumio[805]: assert.ok(self.idling) Nov 21 14:20:11 volumio-zoli volumio[805]: error: The expression evaluated to a falsy value: Nov 21 14:20:11 volumio-zoli volumio[805]: assert.ok(self.idling) Nov 21 14:20:11 volumio-zoli systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 14:20:11 volumio-zoli volumio[805]: error: updateQueue error: null Nov 21 14:20:11 volumio-zoli volumio[805]: info: MPD running with PID988 Nov 21 14:20:11 volumio-zoli volumio[805]: ,establishing connection Nov 21 14:20:11 volumio-zoli volumio[805]: error: updateQueue error: null Nov 21 14:20:12 volumio-zoli lircd-setup[1042]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Nov 21 14:20:12 volumio-zoli lircd-setup[1042]: parser = configparser.SafeConfigParser() Nov 21 14:20:12 volumio-zoli systemd[1]: lircd-setup.service: Succeeded. Nov 21 14:20:12 volumio-zoli systemd[1]: Started lircd(8) initialization helper tool. Nov 21 14:20:12 volumio-zoli sh[506]: eth0: leased 192.168.1.238 for 86400 seconds Nov 21 14:20:12 volumio-zoli sh[506]: eth0: adding route to 192.168.1.0/24 Nov 21 14:20:12 volumio-zoli sh[506]: eth0: adding default route via 192.168.1.1 Nov 21 14:20:12 volumio-zoli systemd[1]: Started evo_remote.service. Nov 21 14:20:12 volumio-zoli dhcpcd[544]: eth0: leased 192.168.1.238 for 86400 seconds Nov 21 14:20:12 volumio-zoli dhcpcd[544]: eth0: adding route to 192.168.1.0/24 Nov 21 14:20:12 volumio-zoli dhcpcd[544]: eth0: adding default route via 192.168.1.1 Nov 21 14:20:12 volumio-zoli avahi-daemon[588]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.238. Nov 21 14:20:12 volumio-zoli avahi-daemon[588]: New relevant interface eth0.IPv4 for mDNS. Nov 21 14:20:12 volumio-zoli avahi-daemon[588]: Registering new address record for 192.168.1.238 on eth0.IPv4. Nov 21 14:20:12 volumio-zoli sudo[1030]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:12 volumio-zoli volumio[805]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Info: lircd: Opening log, level: Info Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Version: lircd 0.10.1 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: System info: Linux volumio-zoli 6.6.56-v7l+ #1803 SMP Thu Oct 10 14:10:41 BST 2024 armv7l GNU/Linux Nov 21 14:20:12 volumio-zoli sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Nov 21 14:20:12 volumio-zoli sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:12 volumio-zoli sh[506]: forked to background, child pid 1075 Nov 21 14:20:12 volumio-zoli dhcpcd[544]: forked to background, child pid 1075 Nov 21 14:20:12 volumio-zoli systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 14:20:12 volumio-zoli lircd-setup[1076]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Nov 21 14:20:12 volumio-zoli lircd-setup[1076]: parser = configparser.SafeConfigParser() Nov 21 14:20:12 volumio-zoli systemd[1]: lircd-setup.service: Succeeded. Nov 21 14:20:12 volumio-zoli systemd[1]: Started lircd(8) initialization helper tool. Nov 21 14:20:12 volumio-zoli systemd[1]: Started evo_irexec.service. Nov 21 14:20:12 volumio-zoli sudo[1064]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:12 volumio-zoli volumio[805]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Nov 21 14:20:12 volumio-zoli volumio[805]: info: Completed starting Core Plugins Nov 21 14:20:12 volumio-zoli volumio[805]: info: ------------------------------------------- Nov 21 14:20:12 volumio-zoli volumio[805]: info: ----- MyVolumio plugins startup ---- Nov 21 14:20:12 volumio-zoli volumio[805]: info: ------------------------------------------- Nov 21 14:20:12 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 21 14:20:12 volumio-zoli systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Info: Initial device: /dev/lirc0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Info: Initial device: /dev/lirc0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Info: [lirc] protocol is enabled Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: driver: default Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: output: /var/run/lirc/lircd Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: nodaemon: 1 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: logfile: syslog Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: immediate-init: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: permission: 666 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: driver-options: Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: listen: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: connect: (null) Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: userelease: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: effective_user: (null) Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: release_suffix: _EVUP Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: allow_simulate: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: repeat_max: 600 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Options: dynamic_codes: (null) Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Current driver: default Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Driver API version: 3 Nov 21 14:20:12 volumio-zoli lircd[1044]: lircd-0.10.1[1044]: Info: lircd: Opening log, level: Info Nov 21 14:20:12 volumio-zoli lircd[1044]: lircd-0.10.1[1044]: Warning: Running as root Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Driver version: 0.10.0 Nov 21 14:20:12 volumio-zoli lircd[1044]: lircd-0.10.1[1044]: Info: Using remote: ApEvo. Nov 21 14:20:12 volumio-zoli lircd[1044]: lircd-0.10.1[1044]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Info: lircd: Opening log, level: Info Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Warning: Running as root Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Info: Using remote: ApEvo. Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1044]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 21 14:20:12 volumio-zoli ifplugd(eth0)[783]: client: ifup: interface eth0 already configured Nov 21 14:20:12 volumio-zoli sh[506]: eth0=eth0 Nov 21 14:20:12 volumio-zoli lircd-setup[1100]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Nov 21 14:20:12 volumio-zoli lircd-setup[1100]: parser = configparser.SafeConfigParser() Nov 21 14:20:12 volumio-zoli systemd[1]: lircd-setup.service: Succeeded. Nov 21 14:20:12 volumio-zoli systemd[1]: Started lircd(8) initialization helper tool. Nov 21 14:20:12 volumio-zoli ifplugd(eth0)[783]: Program executed successfully. Nov 21 14:20:12 volumio-zoli nmbd[742]: [2024/11/21 14:20:12.813083, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 21 14:20:12 volumio-zoli nmbd[742]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 21 14:20:12 volumio-zoli systemd[1]: Starting Flexible IR remote input/output application support... Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Info: lircd: Opening log, level: Info Nov 21 14:20:12 volumio-zoli systemd[1]: Started Samba NMB Daemon. Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Version: lircd 0.10.1 Nov 21 14:20:12 volumio-zoli systemd[1]: Starting Samba Winbind Daemon... Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: System info: Linux volumio-zoli 6.6.56-v7l+ #1803 SMP Thu Oct 10 14:10:41 BST 2024 armv7l GNU/Linux Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Info: Initial device: auto Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Info: Initial device: auto Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: driver: devinput Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: output: /var/run/lirc/lircd Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: nodaemon: 1 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: logfile: syslog Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: immediate-init: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: permission: 666 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: driver-options: Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: listen: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: connect: (null) Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: userelease: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: effective_user: (null) Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: release_suffix: _EVUP Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: allow_simulate: 0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: repeat_max: 600 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Options: dynamic_codes: (null) Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Current driver: devinput Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Driver API version: 4 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Driver version: 0.10.0 Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Nov 21 14:20:12 volumio-zoli lircd[1105]: lircd-0.10.1[1105]: Info: lircd: Opening log, level: Info Nov 21 14:20:12 volumio-zoli lircd-0.10.1[1105]: Info: lircd: Opening log, level: Info Nov 21 14:20:12 volumio-zoli lircd[1105]: lircd: there seems to already be a lircd process with pid 1044 Nov 21 14:20:12 volumio-zoli lircd[1105]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 21 14:20:12 volumio-zoli systemd[1]: lircd.service: Main process exited, code=exited, status=1/FAILURE Nov 21 14:20:12 volumio-zoli systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 21 14:20:12 volumio-zoli systemd[1]: Failed to start Flexible IR remote input/output application support. Nov 21 14:20:12 volumio-zoli systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 14:20:12 volumio-zoli lircd-setup[1110]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Nov 21 14:20:12 volumio-zoli lircd-setup[1110]: parser = configparser.SafeConfigParser() Nov 21 14:20:12 volumio-zoli systemd[1]: lircd-setup.service: Succeeded. Nov 21 14:20:12 volumio-zoli systemd[1]: Started lircd(8) initialization helper tool. Nov 21 14:20:13 volumio-zoli winbindd[1107]: [2024/11/21 14:20:13.050694, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 21 14:20:13 volumio-zoli winbindd[1107]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 21 14:20:13 volumio-zoli systemd[1]: Starting Flexible IR remote input/output application support... Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Version: lircd 0.10.1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: System info: Linux volumio-zoli 6.6.56-v7l+ #1803 SMP Thu Oct 10 14:10:41 BST 2024 armv7l GNU/Linux Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: driver: devinput Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: output: /var/run/lirc/lircd Nov 21 14:20:13 volumio-zoli lircd[1111]: lircd-0.10.1[1111]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd[1111]: lircd: there seems to already be a lircd process with pid 1044 Nov 21 14:20:13 volumio-zoli lircd[1111]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: nodaemon: 1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: logfile: syslog Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: immediate-init: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: permission: 666 Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Main process exited, code=exited, status=1/FAILURE Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: driver-options: Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start Flexible IR remote input/output application support. Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: listen: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: connect: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: userelease: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: effective_user: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: release_suffix: _EVUP Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: allow_simulate: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: repeat_max: 600 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Options: dynamic_codes: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Current driver: devinput Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Driver API version: 4 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Driver version: 0.10.0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1111]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli winbindd[1107]: [2024/11/21 14:20:13.081396, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 21 14:20:13 volumio-zoli systemd[1]: Starting lircd(8) initialization helper tool... Nov 21 14:20:13 volumio-zoli winbindd[1107]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 21 14:20:13 volumio-zoli systemd[1]: Started Samba Winbind Daemon. Nov 21 14:20:13 volumio-zoli systemd[1]: Starting Samba SMB Daemon... Nov 21 14:20:13 volumio-zoli lircd-setup[1115]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Nov 21 14:20:13 volumio-zoli lircd-setup[1115]: parser = configparser.SafeConfigParser() Nov 21 14:20:13 volumio-zoli volumio[805]: info: Discovery: adding 1466e939-1190-44eb-b2ad-76f58cb3bbd9 Nov 21 14:20:13 volumio-zoli systemd[1]: lircd-setup.service: Succeeded. Nov 21 14:20:13 volumio-zoli volumio[805]: info: Discovery: Found device Volumio Zoli Nov 21 14:20:13 volumio-zoli systemd[1]: Started lircd(8) initialization helper tool. Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:13 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:13 volumio-zoli systemd[1]: Starting Flexible IR remote input/output application support... Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Version: lircd 0.10.1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: System info: Linux volumio-zoli 6.6.56-v7l+ #1803 SMP Thu Oct 10 14:10:41 BST 2024 armv7l GNU/Linux Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: driver: devinput Nov 21 14:20:13 volumio-zoli lircd[1119]: lircd-0.10.1[1119]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd[1119]: lircd: there seems to already be a lircd process with pid 1044 Nov 21 14:20:13 volumio-zoli lircd[1119]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: output: /var/run/lirc/lircd Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: nodaemon: 1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: logfile: syslog Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: immediate-init: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: permission: 666 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: driver-options: Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: listen: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: connect: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: userelease: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: effective_user: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: release_suffix: _EVUP Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: allow_simulate: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: repeat_max: 600 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Options: dynamic_codes: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Current driver: devinput Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Driver API version: 4 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Driver version: 0.10.0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1119]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Main process exited, code=exited, status=1/FAILURE Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start Flexible IR remote input/output application support. Nov 21 14:20:13 volumio-zoli systemd[1]: lircd-setup.service: Start request repeated too quickly. Nov 21 14:20:13 volumio-zoli systemd[1]: lircd-setup.service: Failed with result 'start-limit-hit'. Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start lircd(8) initialization helper tool. Nov 21 14:20:13 volumio-zoli systemd[1]: Starting Flexible IR remote input/output application support... Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Version: lircd 0.10.1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: System info: Linux volumio-zoli 6.6.56-v7l+ #1803 SMP Thu Oct 10 14:10:41 BST 2024 armv7l GNU/Linux Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 14:20:13 volumio-zoli volumio[805]: info: Discovery: Getting this device information Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:13 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: driver: devinput Nov 21 14:20:13 volumio-zoli lircd[1122]: lircd-0.10.1[1122]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd[1122]: lircd: there seems to already be a lircd process with pid 1044 Nov 21 14:20:13 volumio-zoli lircd[1122]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: output: /var/run/lirc/lircd Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: nodaemon: 1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: logfile: syslog Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: immediate-init: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: permission: 666 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: driver-options: Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: listen: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: connect: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: userelease: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: effective_user: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: release_suffix: _EVUP Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: allow_simulate: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: repeat_max: 600 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Options: dynamic_codes: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Current driver: devinput Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Driver API version: 4 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Driver version: 0.10.0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1122]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Main process exited, code=exited, status=1/FAILURE Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start Flexible IR remote input/output application support. Nov 21 14:20:13 volumio-zoli systemd[1]: lircd-setup.service: Start request repeated too quickly. Nov 21 14:20:13 volumio-zoli systemd[1]: lircd-setup.service: Failed with result 'start-limit-hit'. Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start lircd(8) initialization helper tool. Nov 21 14:20:13 volumio-zoli systemd[1]: Starting Flexible IR remote input/output application support... Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Version: lircd 0.10.1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: System info: Linux volumio-zoli 6.6.56-v7l+ #1803 SMP Thu Oct 10 14:10:41 BST 2024 armv7l GNU/Linux Nov 21 14:20:13 volumio-zoli volumio[805]: verbose: New Socket.io Connection to 192.168.1.238:3000 from 192.168.1.214 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Info: Initial device: auto Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: driver: devinput Nov 21 14:20:13 volumio-zoli lircd[1128]: lircd-0.10.1[1128]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli lircd[1128]: lircd: there seems to already be a lircd process with pid 1044 Nov 21 14:20:13 volumio-zoli lircd[1128]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: output: /var/run/lirc/lircd Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: nodaemon: 1 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: logfile: syslog Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: immediate-init: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: permission: 666 Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: driver-options: Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Main process exited, code=exited, status=1/FAILURE Nov 21 14:20:13 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: listen: 0 Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: connect: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: userelease: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: effective_user: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: release_suffix: _EVUP Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: allow_simulate: 0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: repeat_max: 600 Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start Flexible IR remote input/output application support. Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Options: dynamic_codes: (null) Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Current driver: devinput Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Driver API version: 4 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Driver version: 0.10.0 Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Nov 21 14:20:13 volumio-zoli lircd-0.10.1[1128]: Info: lircd: Opening log, level: Info Nov 21 14:20:13 volumio-zoli systemd[1]: lircd-setup.service: Start request repeated too quickly. Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 21 14:20:13 volumio-zoli systemd[1]: lircd-setup.service: Failed with result 'start-limit-hit'. Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start lircd(8) initialization helper tool. Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Start request repeated too quickly. Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 21 14:20:13 volumio-zoli systemd[1]: Failed to start Flexible IR remote input/output application support. Nov 21 14:20:13 volumio-zoli systemd[1]: lircd.socket: Failed with result 'service-start-limit-hit'. Nov 21 14:20:13 volumio-zoli systemd[1]: evo_irexec.service: Succeeded. Nov 21 14:20:13 volumio-zoli smbd[1118]: [2024/11/21 14:20:13.495931, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 21 14:20:13 volumio-zoli systemd[1]: Started Samba SMB Daemon. Nov 21 14:20:13 volumio-zoli smbd[1118]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 21 14:20:13 volumio-zoli volumio[805]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:13 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetQueue Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreStateMachine::getQueue Nov 21 14:20:13 volumio-zoli volumio[805]: info: CorePlayQueue::getQueue Nov 21 14:20:13 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:13 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:13 volumio-zoli ntpd[757]: Listen normally on 3 eth0 192.168.1.238:123 Nov 21 14:20:13 volumio-zoli ntpd[757]: new interface(s) found: waking up resolver Nov 21 14:20:15 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Fetching time from Volumio... Nov 21 14:20:16 volumio-zoli volumio-time-update[580]: volumio-time-update-util: Setting system time to: 2024-11-21 14:20:46 Nov 21 14:20:16 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 14:20:16 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 14:20:16 volumio-zoli volumio[805]: info: Discovery: Getting this device information Nov 21 14:20:16 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:16 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:16 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 14:20:16 volumio-zoli sudo[1143]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2024-11-21 14:20:46 Nov 21 14:20:16 volumio-zoli sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:16 volumio-zoli dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.14' (uid=0 pid=1144 comm="timedatectl set-time 2024-11-21 14:20:46 ") Nov 21 14:20:16 volumio-zoli systemd[1]: Starting Time & Date Service... Nov 21 14:20:16 volumio-zoli dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 21 14:20:16 volumio-zoli systemd[1]: Started Time & Date Service. Nov 21 14:20:46 volumio-zoli systemd-timedated[1145]: Changed local time to Thu Nov 21 14:20:46 2024 Nov 21 14:20:46 volumio-zoli sudo[1143]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:46 volumio-zoli volumio-time-update[580]: volumio-time-update-util: System time updated successfully. Nov 21 14:20:46 volumio-zoli systemd[1]: Started Volumio Time Update Utility. Nov 21 14:20:46 volumio-zoli systemd[1]: Reached target Multi-User System. Nov 21 14:20:46 volumio-zoli systemd[1]: Reached target Graphical Interface. Nov 21 14:20:46 volumio-zoli systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 21 14:20:46 volumio-zoli systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 21 14:20:46 volumio-zoli systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 21 14:20:46 volumio-zoli systemd[1]: Startup finished in 13.886s (kernel) + 23.167s (userspace) = 37.054s. Nov 21 14:20:46 volumio-zoli volumio[805]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 21 14:20:46 volumio-zoli sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 14:20:46 volumio-zoli sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:46 volumio-zoli sudo[1148]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:46 volumio-zoli sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 14:20:46 volumio-zoli sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:46 volumio-zoli sudo[1150]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:47 volumio-zoli volumio[805]: verbose: New Socket.io Connection to 192.168.1.238 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 14; 23043RP34G Build/UKQ1.230917.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 21 14:20:47 volumio-zoli sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 14:20:47 volumio-zoli sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:47 volumio-zoli sudo[1154]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:47 volumio-zoli sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 14:20:47 volumio-zoli sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:47 volumio-zoli sudo[1156]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:47 volumio-zoli volumio[805]: verbose: New Socket.io Connection to 192.168.1.238 from 192.168.1.214 UA: Mozilla/5.0 (Linux; Android 14; 23043RP34G Build/UKQ1.230917.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/130.0.6723.107 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetVisibleSources Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:47 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 21 14:20:47 volumio-zoli volumio[805]: info: Received Get System Info Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 14:20:47 volumio-zoli volumio[805]: info: Discovery: Getting this device information Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:47 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:47 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:47 volumio-zoli volumio[805]: info: Listing playlists Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 21 14:20:47 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 21 14:20:47 volumio-zoli volumio[805]: info: Volumio Network Manager: Network status updated: 1 Nov 21 14:20:47 volumio-zoli sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 21 14:20:47 volumio-zoli sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:47 volumio-zoli sudo[1174]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:47 volumio-zoli sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 21 14:20:47 volumio-zoli sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:47 volumio-zoli sudo[1176]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:47 volumio-zoli sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 21 14:20:47 volumio-zoli sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:48 volumio-zoli systemd[1]: Started UPnP Renderer front-end to MPD. Nov 21 14:20:48 volumio-zoli sudo[1179]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:48 volumio-zoli volumio[805]: info: Upmpdcli Daemon Started Nov 21 14:20:48 volumio-zoli volumio[1182]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 21 14:20:49 volumio-zoli volumio[805]: info: Received Get System Info Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 14:20:49 volumio-zoli volumio[805]: info: Discovery: Getting this device information Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:49 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 21 14:20:49 volumio-zoli volumio[805]: info: Received Get System Info Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 14:20:49 volumio-zoli volumio[805]: info: Discovery: Getting this device information Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:20:49 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:20:49 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 14:20:50 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 21 14:20:50 volumio-zoli volumio[805]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.779&uuid=f21f2a7ce10bdafe0e0ba9b58d5349ad" http://updates.volumio.org/downloader-v1/track-device Nov 21 14:20:50 volumio-zoli volumio[805]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 21 14:20:50 volumio-zoli volumio[805]: Dload Upload Total Spent Left Speed Nov 21 14:20:50 volumio-zoli volumio[805]: [132B blob data] Nov 21 14:20:50 volumio-zoli volumio[805]: retrying in 5 seconds, trial 0 Nov 21 14:20:50 volumio-zoli volumio[805]: info: Volumio Calling Home Nov 21 14:20:50 volumio-zoli volumio[805]: info: Volumio called home Nov 21 14:20:50 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 21 14:20:53 volumio-zoli volumio[805]: info: Adding plugin bluetooth to MyMusic Plugins Nov 21 14:20:53 volumio-zoli volumio[805]: info: Adding plugin multiroom to MyMusic Plugins Nov 21 14:20:53 volumio-zoli volumio[805]: info: Adding plugin metavolumio to MyMusic Plugins Nov 21 14:20:53 volumio-zoli volumio[805]: info: Adding plugin cd_controller to MyMusic Plugins Nov 21 14:20:53 volumio-zoli volumio[805]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 21 14:20:53 volumio-zoli volumio[805]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 21 14:20:53 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 21 14:20:54 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 21 14:20:54 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 21 14:20:54 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:54 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:20:54 volumio-zoli volumio[805]: info: Starting MyVolumio Remote Streaming Endpoints Nov 21 14:20:54 volumio-zoli volumio[805]: info: MyVolumio login type: Token Nov 21 14:20:54 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 21 14:20:54 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 21 14:20:55 volumio-zoli volumio[805]: info: Starting Streaming Service Transparent Proxy Nov 21 14:20:55 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 21 14:20:55 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 21 14:20:55 volumio-zoli volumio[805]: info: Streaming services startup Nov 21 14:20:55 volumio-zoli volumio[805]: info: Starting Streaming Daemon Nov 21 14:20:55 volumio-zoli sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 14:20:55 volumio-zoli sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:55 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 21 14:20:55 volumio-zoli sudo[1211]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:55 volumio-zoli volumio[805]: error: Cannot start Volumio Streaming Daemon Nov 21 14:20:55 volumio-zoli volumio[805]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 21 14:20:55 volumio-zoli volumio[805]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 21 14:20:55 volumio-zoli volumio[805]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 21 14:20:55 volumio-zoli volumio[805]: STREAMING PROXY: Starting server on port 3245 Nov 21 14:20:55 volumio-zoli volumio[805]: Node JS runtime: 14 Nov 21 14:20:56 volumio-zoli volumio[1182]: .................................................................................................++++ Nov 21 14:20:56 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetQueue Nov 21 14:20:56 volumio-zoli volumio[805]: info: CoreStateMachine::getQueue Nov 21 14:20:56 volumio-zoli volumio[805]: info: CorePlayQueue::getQueue Nov 21 14:20:57 volumio-zoli systemd[1]: systemd-fsckd.service: Succeeded. Nov 21 14:20:57 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 21 14:20:57 volumio-zoli volumio[805]: info: MyVolumio token set successfully Nov 21 14:20:57 volumio-zoli volumio[805]: info: MYVOLUMIO: Adding device Nov 21 14:20:57 volumio-zoli volumio[805]: info: MYVOLUMIO: Evaluating Server Nov 21 14:20:58 volumio-zoli volumio[805]: info: MyVolumio Plan changed: premium Nov 21 14:20:58 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Nov 21 14:20:58 volumio-zoli volumio[805]: info: Removing browser output: myVolumio user plan is not superstar Nov 21 14:20:58 volumio-zoli volumio[805]: info: Removing audio output: Nov 21 14:20:58 volumio-zoli volumio[805]: info: MYVOLUMIO: Adding device Nov 21 14:20:58 volumio-zoli volumio[805]: info: MYVOLUMIO: Evaluating Server Nov 21 14:20:58 volumio-zoli volumio[805]: info: Remote config written successfully Nov 21 14:20:58 volumio-zoli volumio[805]: info: Starting Tunnel 1 Nov 21 14:20:58 volumio-zoli volumio[805]: info: Starting Tunnel Connection Checker Nov 21 14:20:58 volumio-zoli volumio[805]: info: Completed starting MyVolumio Plugin Nov 21 14:20:59 volumio-zoli volumio[805]: info: MYVolumio Device enabled Nov 21 14:20:59 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 21 14:20:59 volumio-zoli volumio[805]: info: MyVolumio status changed Nov 21 14:20:59 volumio-zoli volumio[805]: info: Streaming services startup Nov 21 14:20:59 volumio-zoli volumio[805]: info: Starting Streaming Daemon Nov 21 14:20:59 volumio-zoli sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 21 14:20:59 volumio-zoli sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:20:59 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 21 14:20:59 volumio-zoli sudo[1265]: pam_unix(sudo:session): session closed for user root Nov 21 14:20:59 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 21 14:20:59 volumio-zoli volumio[805]: info: [1732195259258] Starting BluetoothController Nov 21 14:20:59 volumio-zoli volumio[1182]: .........................++++ Nov 21 14:20:59 volumio-zoli volumio[1182]: e is 65537 (0x010001) Nov 21 14:20:59 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 21 14:20:59 volumio-zoli volumio[1182]: writing RSA key Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 21 14:21:00 volumio-zoli sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 21 14:21:00 volumio-zoli sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli sudo[1285]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: MultiRoom plugin initialized Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: STOPPING SNAPCLIENT Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: Snap server stop Nov 21 14:21:00 volumio-zoli sudo[1301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 21 14:21:00 volumio-zoli sudo[1301]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: STOPPING volumioStreaming Nov 21 14:21:00 volumio-zoli sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Nov 21 14:21:00 volumio-zoli sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Nov 21 14:21:00 volumio-zoli sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 21 14:21:00 volumio-zoli sudo[1301]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:00 volumio-zoli sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Nov 21 14:21:00 volumio-zoli sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli sudo[1310]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:00 volumio-zoli sudo[1304]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Nov 21 14:21:00 volumio-zoli sudo[1306]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 21 14:21:00 volumio-zoli volumio[805]: info: Preparing to generate the ALSA configuration file Nov 21 14:21:00 volumio-zoli volumio[805]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 21 14:21:00 volumio-zoli volumio[805]: info: Reading ALSA contributions from plugins. Nov 21 14:21:00 volumio-zoli volumio[805]: info: Setting Geolocation for MyVolumio to eu4 Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:00 volumio-zoli volumio[805]: info: Setting Geolocation for MyVolumio to eu4 Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: Removed streaming files Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: volumioStreaming STOPPED Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: SNAPSERVER STOPPED Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: SNAPCLIENT STOPPED Nov 21 14:21:00 volumio-zoli volumio[805]: error: Cannot start Volumio Streaming Daemon Nov 21 14:21:00 volumio-zoli volumio[805]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 21 14:21:00 volumio-zoli volumio[805]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 21 14:21:00 volumio-zoli volumio[805]: info: Asound.conf file written Nov 21 14:21:00 volumio-zoli sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 21 14:21:00 volumio-zoli sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli sudo[1313]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:00 volumio-zoli volumio[805]: info: Output device has changed, restarting MPD Nov 21 14:21:00 volumio-zoli sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 14:21:00 volumio-zoli volumio[805]: info: Output device has changed, restarting Shairport Sync Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:00 volumio-zoli sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli sudo[1318]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:00 volumio-zoli sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 14:21:00 volumio-zoli sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:00 volumio-zoli systemd[1]: Stopping Music Player Daemon... Nov 21 14:21:00 volumio-zoli volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:00 volumio-zoli systemd[1]: mpd.service: Succeeded. Nov 21 14:21:00 volumio-zoli systemd[1]: Stopped Music Player Daemon. Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 21 14:21:00 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 21 14:21:00 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output for this device Nov 21 14:21:00 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:00 volumio-zoli volumio[805]: info: Adding audio output: Nov 21 14:21:00 volumio-zoli volumio[805]: info: Adding audio output: Nov 21 14:21:00 volumio-zoli sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Zoli Nov 21 14:21:00 volumio-zoli sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:01 volumio-zoli volumio[805]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding METAVOLUMIO REST API Endpoints Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 21 14:21:01 volumio-zoli volumio[805]: info: Preparing CD Folders Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding CD REST API Endpoints Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 21 14:21:01 volumio-zoli volumio[805]: info: Starting UDEV Watcher for CD Nov 21 14:21:01 volumio-zoli volumio[805]: info: Detecting CD presence with UDEV Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 21 14:21:01 volumio-zoli sudo[1330]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:01 volumio-zoli systemd[1]: Starting Music Player Daemon... Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding inputs REST Endpoints Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 21 14:21:01 volumio-zoli volumio[805]: info: Scanning Audio Inputs Nov 21 14:21:01 volumio-zoli volumio[805]: info: Checking against Known Cards name Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding Server instance for streaming Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 21 14:21:01 volumio-zoli volumio[805]: error: Hi Res Audio Failed Login: Missing Login Data Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 21 14:21:01 volumio-zoli volumio[805]: info: Refreshing TIDAL token Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 21 14:21:01 volumio-zoli volumio[805]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 21 14:21:01 volumio-zoli sudo[1327]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 14:21:01 volumio-zoli sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:01 volumio-zoli sudo[1327]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:01 volumio-zoli volumio[805]: info: Stopping AccessToken refresher cron for QOBUZ Nov 21 14:21:01 volumio-zoli sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 21 14:21:01 volumio-zoli sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:01 volumio-zoli sudo[1338]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:01 volumio-zoli volumio[805]: info: AccessToken refresher cron started for QOBUZ Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding QOBUZ REST API Endpoints Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 21 14:21:01 volumio-zoli volumio[805]: info: Bluetooth name changed to Volumio Zoli Nov 21 14:21:01 volumio-zoli volumio[805]: info: MPD Permissions set Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: Starting Shairport Sync Nov 21 14:21:01 volumio-zoli volumio[805]: info: MRS: Found cast device: TelekomTV-STB-7becb231b12e4df5e7420e4543981bda Nov 21 14:21:01 volumio-zoli volumio[805]: info: Adding audio output: Nov 21 14:21:01 volumio-zoli sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 14:21:01 volumio-zoli sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:01 volumio-zoli systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 14:21:01 volumio-zoli systemd[1]: shairport-sync.service: Succeeded. Nov 21 14:21:01 volumio-zoli systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 21 14:21:01 volumio-zoli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 14:21:01 volumio-zoli sudo[1349]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:01 volumio-zoli volumio[805]: info: Shairport-Sync Started Nov 21 14:21:01 volumio-zoli volumio[805]: info: Successfully Added MyVolumio device Nov 21 14:21:01 volumio-zoli volumio[805]: info: Updating MyVolumio device info Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: Updating MyVolumio device info Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:01 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:01 volumio-zoli mpd[1340]: Nov 21 14:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 14:21:01 volumio-zoli systemd[1]: Started Music Player Daemon. Nov 21 14:21:01 volumio-zoli sudo[1320]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:01 volumio-zoli volumio[805]: error: updateQueue error: null Nov 21 14:21:01 volumio-zoli volumio[805]: info: Access Token successfully retrieved Nov 21 14:21:02 volumio-zoli volumio[805]: info: Successfully retrieved User Session From TIDAL Nov 21 14:21:02 volumio-zoli systemd[1]: systemd-hostnamed.service: Succeeded. Nov 21 14:21:02 volumio-zoli volumio[805]: info: Successfully retrieved User Subscription From TIDAL Nov 21 14:21:02 volumio-zoli volumio[805]: info: Adding TIDAL to Browse Sources Nov 21 14:21:02 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 21 14:21:02 volumio-zoli volumio[805]: info: [1732195262224] CoreMusicLibrary::Adding element TIDAL Nov 21 14:21:02 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 21 14:21:02 volumio-zoli volumio[805]: Cannot find translation for source TIDAL Nov 21 14:21:02 volumio-zoli volumio[805]: info: Stopping AccessToken refresher cron Nov 21 14:21:02 volumio-zoli volumio[805]: info: AccessToken refresher cron started Nov 21 14:21:02 volumio-zoli volumio[805]: info: Adding TIDAL REST API Endpoints Nov 21 14:21:02 volumio-zoli volumio[805]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 21 14:21:02 volumio-zoli volumio[805]: info: Successfully Updated MyVolumio device Nov 21 14:21:02 volumio-zoli volumio[805]: info: Successfully Updated MyVolumio device Nov 21 14:21:02 volumio-zoli volumio[805]: info: Successfully Added MyVolumio device Nov 21 14:21:03 volumio-zoli sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 21 14:21:03 volumio-zoli sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:03 volumio-zoli systemd[1]: Started MyVolumio SSH Tunnel. Nov 21 14:21:03 volumio-zoli sudo[1365]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:03 volumio-zoli volumio[805]: info: Remote SSH Started Nov 21 14:21:03 volumio-zoli autossh[1368]: port set to 0, monitoring disabled Nov 21 14:21:03 volumio-zoli autossh[1368]: starting ssh (count 1) Nov 21 14:21:03 volumio-zoli autossh[1368]: ssh child pid is 1371 Nov 21 14:21:04 volumio-zoli sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 21 14:21:04 volumio-zoli sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:04 volumio-zoli volumio[805]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: TidalConnect service stoped! Nov 21 14:21:04 volumio-zoli systemd[1]: Started Volumio Bluetooth Module. Nov 21 14:21:04 volumio-zoli sudo[1373]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:04 volumio-zoli sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 21 14:21:04 volumio-zoli sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:04 volumio-zoli volumiobt[1375]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 21 14:21:04 volumio-zoli sudo[1379]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:04 volumio-zoli volumio[805]: info: Volumio BT Module successfully started Nov 21 14:21:04 volumio-zoli volumio[805]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 21 14:21:04 volumio-zoli volumio[805]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 21 14:21:04 volumio-zoli sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 21 14:21:04 volumio-zoli sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:04 volumio-zoli systemd[1]: Started Volumio Tidal Connect Service. Nov 21 14:21:04 volumio-zoli sudo[1388]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:04 volumio-zoli volumio[805]: info: Executing endpoint tc_getconfig Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 21 14:21:04 volumio-zoli vtcs[1390]: STARTING TidalConnect services, version: 1.4.0.34 Nov 21 14:21:04 volumio-zoli vtcs[1390]: STARTED TidalConnect services. Nov 21 14:21:04 volumio-zoli volumio[805]: info: Executing endpoint tc_connect Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 21 14:21:04 volumio-zoli volumio[805]: info: Connecting to TidalConnect Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::servicePushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 21 14:21:04 volumio-zoli volumio[805]: verbose: CURRENT POSITION 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::syncState stateService stop Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::syncState currentStatus stop Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:04 volumio-zoli volumio[805]: info: No code Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::servicePushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 21 14:21:04 volumio-zoli volumio[805]: verbose: CURRENT POSITION 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::syncState stateService stop Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::syncState currentStatus stop Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:04 volumio-zoli volumio[805]: info: No code Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:04 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:04 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:05 volumio-zoli sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 21 14:21:05 volumio-zoli sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:05 volumio-zoli volumiobt[1375]: pulseaudio: no process found Nov 21 14:21:05 volumio-zoli sudo[1412]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:05 volumio-zoli dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=1416 comm="/usr/bin/pulseaudio --high-priority=true ") Nov 21 14:21:05 volumio-zoli systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 21 14:21:05 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:05 volumio-zoli dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 21 14:21:05 volumio-zoli systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 21 14:21:05 volumio-zoli rtkit-daemon[1417]: Successfully called chroot. Nov 21 14:21:05 volumio-zoli rtkit-daemon[1417]: Successfully dropped privileges. Nov 21 14:21:05 volumio-zoli rtkit-daemon[1417]: Successfully limited resources. Nov 21 14:21:05 volumio-zoli rtkit-daemon[1417]: Running. Nov 21 14:21:05 volumio-zoli rtkit-daemon[1417]: Canary thread running. Nov 21 14:21:05 volumio-zoli rtkit-daemon[1417]: Watchdog thread running. Nov 21 14:21:05 volumio-zoli dbus-daemon[607]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1417 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 21 14:21:05 volumio-zoli systemd[1]: Starting Authorization Manager... Nov 21 14:21:05 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:05 volumio-zoli polkitd[1420]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 21 14:21:05 volumio-zoli dbus-daemon[607]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 21 14:21:05 volumio-zoli systemd[1]: Started Authorization Manager. Nov 21 14:21:05 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 21 14:21:05 volumio-zoli volumio[805]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 21 14:21:05 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 21 14:21:05 volumio-zoli volumio[805]: info: Received Get System Version Nov 21 14:21:05 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 14:21:05 volumio-zoli volumio[805]: info: Received Get System Info Nov 21 14:21:05 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 14:21:05 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 14:21:05 volumio-zoli volumio[805]: info: Discovery: Getting this device information Nov 21 14:21:05 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:21:05 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:05 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 14:21:05 volumio-zoli pulseaudio[1416]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 21 14:21:05 volumio-zoli pulseaudio[1416]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 21 14:21:05 volumio-zoli bluetoothd[705]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Nov 21 14:21:05 volumio-zoli bluetoothd[705]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Applying permissions Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Setting BT discoverable and pairable Nov 21 14:21:05 volumio-zoli pulseaudio[1416]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Nov 21 14:21:05 volumio-zoli kernel: Bluetooth: RFCOMM TTY layer initialized Nov 21 14:21:05 volumio-zoli kernel: Bluetooth: RFCOMM socket layer initialized Nov 21 14:21:05 volumio-zoli kernel: Bluetooth: RFCOMM ver 1.11 Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [CHG] Controller D8:3A:DD:E8:03:68 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [117B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [55B blob data] Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [bluetooth]# -e power on Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Invalid command in menu main: -e Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Use "help" for a list of available commands in a menu. Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Use "menu " if you want to enter any submenu. Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Use "back" if you want to return to menu main. Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [bluetooth]# agent on Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Agent is already registered Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [bluetooth]# discoverable on Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [bluetooth]# pairable on Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [bluetooth]# agent NoInputNoOutput Nov 21 14:21:05 volumio-zoli volumiobt[1375]: Agent is already registered Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [bluetooth]# default-agent Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [bluetooth]# quit Nov 21 14:21:05 volumio-zoli volumiobt[1375]: [59B blob data] Nov 21 14:21:06 volumio-zoli volumio[805]: info: MRS: Getting audio outputs on start Nov 21 14:21:06 volumio-zoli volumio[805]: info: MRS: Requesting all other devices output Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 14:21:06 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 14:21:06 volumio-zoli volumio[805]: info: Getting Alsa Cards List without I2S DAC Nov 21 14:21:06 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:06 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 21 14:21:06 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 21 14:21:07 volumio-zoli volumio[805]: info: TidalConnect service started! Nov 21 14:21:07 volumio-zoli volumio[805]: [Metrics] CommandRouter: 31s 685.67ms Nov 21 14:21:07 volumio-zoli volumio[805]: info: CoreCommandRouter::volumiosetStartupVolume Nov 21 14:21:07 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:07 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:07 volumio-zoli volumio[805]: info: CoreCommandRouter::Close All Modals sent Nov 21 14:21:07 volumio-zoli volumio[805]: info: CoreCommandRouter::Close All Modals sent Nov 21 14:21:08 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 21 14:21:08 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 14:21:08 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 21 14:21:09 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 21 14:21:09 volumio-zoli volumio[805]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 21 14:21:09 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:21:09 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 14:21:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 21 14:21:10 volumio-zoli volumio-remote-updater[624]: No test mode Nov 21 14:21:10 volumio-zoli volumio-remote-updater[624]: No alpha test mode Nov 21 14:21:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 21 14:21:10 volumio-zoli volumio[805]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 21 14:21:10 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 21 14:21:14 volumio-zoli volumio[805]: info: BOOT COMPLETED Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetBrowseSources Nov 21 14:21:14 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 21 14:21:15 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 21 14:21:15 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 21 14:21:15 volumio-zoli volumio[805]: info: Discovery: Getting this device information Nov 21 14:21:15 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:21:15 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:15 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 14:21:16 volumio-zoli systemd[1]: systemd-timedated.service: Succeeded. Nov 21 14:21:29 volumio-zoli volumiologrotate[592]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 21 14:21:29 volumio-zoli volumiologrotate[592]: ls: cannot access 'ZOLI': No such file or directory Nov 21 14:21:36 volumio-zoli ntpd[757]: Soliciting pool server 193.227.197.2 Nov 21 14:21:36 volumio-zoli volumio[805]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 21 14:21:36 volumio-zoli volumio[805]: info: Preparing to save Alsa Options, stopping services first Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioGetState Nov 21 14:21:36 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPause Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreStateMachine::pause Nov 21 14:21:36 volumio-zoli volumio[805]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"allo-boss2-dac","label":"Allo BOSS2"}} Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:36 volumio-zoli volumio[805]: info: Enabling I2S DAC: Allo BOSS2 Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:36 volumio-zoli sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 21 14:21:36 volumio-zoli sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:36 volumio-zoli sudo[1538]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:36 volumio-zoli volumio[805]: info: No Overlays Loaded Nov 21 14:21:36 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Nov 21 14:21:37 volumio-zoli sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-boss2-dac-audio Nov 21 14:21:37 volumio-zoli sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:37 volumio-zoli kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/#sound-dai-cells Nov 21 14:21:37 volumio-zoli kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Nov 21 14:21:37 volumio-zoli kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Nov 21 14:21:37 volumio-zoli kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Nov 21 14:21:37 volumio-zoli kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/label Nov 21 14:21:37 volumio-zoli kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/dais Nov 21 14:21:37 volumio-zoli kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: warn: Unable to locate the device HDMI Out a reboot may be required. Nov 21 14:21:37 volumio-zoli sudo[1541]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli kernel: allo-cs43198 1-0030: Failed to read Chip or wrong Chip id: -5 Nov 21 14:21:37 volumio-zoli kernel: allo-cs43198: probe of 1-0030 failed with error -5 Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: Found match in i2s Card Database: setting mixer Master for card Allo BOSS2 Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 21 14:21:37 volumio-zoli volumio[805]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo BOSS2 Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 21 14:21:37 volumio-zoli volumio[805]: info: Disabling external Volume Control Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Nov 21 14:21:37 volumio-zoli volumio[805]: info: Preparing to generate the ALSA configuration file Nov 21 14:21:37 volumio-zoli volumio[805]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 21 14:21:37 volumio-zoli volumio[805]: info: Reading ALSA contributions from plugins. Nov 21 14:21:37 volumio-zoli volumio[805]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: amixer: Unable to find simple control 'Master',0 Nov 21 14:21:37 volumio-zoli volumio[805]: info: I2S Param [object Object] successfully enabled Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 21 14:21:37 volumio-zoli volumio[805]: info: Getting Alsa Cards List without I2S DAC Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 21 14:21:37 volumio-zoli volumio[805]: info: VolumeController:: Volume=undefined Mute =false Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:37 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:37 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:37 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:37 volumio-zoli volumio[805]: info: Asound.conf file written Nov 21 14:21:37 volumio-zoli sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 21 14:21:37 volumio-zoli sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1576]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli ntpd[757]: Soliciting pool server 195.111.95.4 Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: Output device has changed, restarting MPD Nov 21 14:21:37 volumio-zoli sudo[1581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 14:21:37 volumio-zoli volumio[805]: info: Output device has changed, restarting Shairport Sync Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:37 volumio-zoli sudo[1581]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1581]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 14:21:37 volumio-zoli sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli systemd[1]: Stopping Music Player Daemon... Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:37 volumio-zoli systemd[1]: mpd.service: Succeeded. Nov 21 14:21:37 volumio-zoli systemd[1]: Stopped Music Player Daemon. Nov 21 14:21:37 volumio-zoli vtcs[1390]: [2024-11-21 14:21:37.589] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 21 14:21:37 volumio-zoli vtcs[1390]: [2024-11-21 14:21:37.590] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 21 14:21:37 volumio-zoli systemd[1]: Starting Music Player Daemon... Nov 21 14:21:37 volumio-zoli volumio[805]: info: Output device has changed, restarting MPD Nov 21 14:21:37 volumio-zoli volumio[805]: info: Output device has changed, restarting Shairport Sync Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:37 volumio-zoli sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 21 14:21:37 volumio-zoli sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 14:21:37 volumio-zoli sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 14:21:37 volumio-zoli sudo[1596]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli systemd[1]: Stopping Volumio Tidal Connect Service... Nov 21 14:21:37 volumio-zoli systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 21 14:21:37 volumio-zoli systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 21 14:21:37 volumio-zoli systemd[1]: mpd.service: Succeeded. Nov 21 14:21:37 volumio-zoli systemd[1]: Stopped Music Player Daemon. Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 14:21:37 volumio-zoli volumio[805]: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:37 volumio-zoli systemd[1]: Starting Music Player Daemon... Nov 21 14:21:37 volumio-zoli systemd[1]: vtcs.service: Succeeded. Nov 21 14:21:37 volumio-zoli systemd[1]: Stopped Volumio Tidal Connect Service. Nov 21 14:21:37 volumio-zoli sudo[1593]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 21 14:21:37 volumio-zoli sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1607]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 14:21:37 volumio-zoli sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 21 14:21:37 volumio-zoli sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1607]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 21 14:21:37 volumio-zoli sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:37 volumio-zoli sudo[1612]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli volumio[805]: info: VolumeController::SetAlsaVolume0 Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreStateMachine::pushState Nov 21 14:21:37 volumio-zoli volumio[805]: info: CorePlayQueue::getTrack 0 Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 14:21:37 volumio-zoli volumio[805]: info: CoreCommandRouter::volumioPushState Nov 21 14:21:37 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output update for this device Nov 21 14:21:37 volumio-zoli volumio[805]: info: MRS: Pushing multiroomSync output Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 21 14:21:37 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:37 volumio-zoli volumio[805]: info: MPD Permissions set Nov 21 14:21:37 volumio-zoli volumio[805]: info: MPD Permissions set Nov 21 14:21:37 volumio-zoli sudo[1618]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli sudo[1615]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:37 volumio-zoli volumio[805]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: xcb_connection_has_error() returned true Nov 21 14:21:37 volumio-zoli volumio[805]: amixer: Unable to find simple control 'Master',0 Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 14:21:38 volumio-zoli volumio[805]: info: Starting Shairport Sync Nov 21 14:21:38 volumio-zoli volumio[805]: info: Starting Shairport Sync Nov 21 14:21:38 volumio-zoli sudo[1649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 14:21:38 volumio-zoli sudo[1651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 14:21:38 volumio-zoli sudo[1651]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:38 volumio-zoli sudo[1649]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 14:21:38 volumio-zoli systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 14:21:38 volumio-zoli systemd[1]: shairport-sync.service: Succeeded. Nov 21 14:21:38 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 21 14:21:38 volumio-zoli systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 21 14:21:38 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 21 14:21:38 volumio-zoli volumio[805]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 21 14:21:38 volumio-zoli systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 14:21:38 volumio-zoli sudo[1649]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:38 volumio-zoli volumio[805]: info: Shairport-Sync Started Nov 21 14:21:38 volumio-zoli sudo[1651]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:38 volumio-zoli volumio[805]: info: Shairport-Sync Started Nov 21 14:21:38 volumio-zoli ntpd[757]: Soliciting pool server 80.249.164.129 Nov 21 14:21:38 volumio-zoli mpd[1621]: Nov 21 14:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 14:21:38 volumio-zoli systemd[1]: Started Music Player Daemon. Nov 21 14:21:38 volumio-zoli sudo[1583]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:38 volumio-zoli sudo[1598]: pam_unix(sudo:session): session closed for user root Nov 21 14:21:38 volumio-zoli volumio[805]: error: MPD error: The expression evaluated to a falsy value: Nov 21 14:21:38 volumio-zoli volumio[805]: assert.ok(self.idling) Nov 21 14:21:38 volumio-zoli volumio[805]: error: The expression evaluated to a falsy value: Nov 21 14:21:38 volumio-zoli volumio[805]: assert.ok(self.idling) Nov 21 14:21:38 volumio-zoli volumio[805]: error: updateQueue error: null Nov 21 14:21:39 volumio-zoli ntpd[757]: Soliciting pool server 81.0.124.253 Nov 21 14:21:40 volumio-zoli volumio[805]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Nov 21 14:21:40 volumio-zoli volumio[805]: info: PLUGIN onReboot : networkfs Nov 21 14:21:40 volumio-zoli volumio[805]: info: PLUGIN onReboot : audiophonicsonoff Nov 21 14:21:40 volumio-zoli volumio[805]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 14:21:40 volumio-zoli volumio[805]: TypeError: Cannot read property 'writeSync' of undefined Nov 21 14:21:40 volumio-zoli volumio[805]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Nov 21 14:21:40 volumio-zoli volumio[805]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Nov 21 14:21:40 volumio-zoli volumio[805]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Nov 21 14:21:40 volumio-zoli volumio[805]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 21 14:21:40 volumio-zoli volumio[805]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 21 14:21:40 volumio-zoli volumio[805]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Nov 21 14:21:40 volumio-zoli volumio[805]: at CoreCommandRouter.reboot (/volumio/app/index.js:1345:22) Nov 21 14:21:40 volumio-zoli volumio[805]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Nov 21 14:21:40 volumio-zoli volumio[805]: at Socket.emit (events.js:315:20) Nov 21 14:21:40 volumio-zoli volumio[805]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 21 14:21:40 volumio-zoli volumio[805]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 21 14:21:40 volumio-zoli volumio[805]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 14:21:40 volumio-zoli ntpd[757]: Soliciting pool server 195.111.92.55 Nov 21 14:21:40 volumio-zoli ntpd[757]: Soliciting pool server 213.157.100.66 Nov 21 14:21:40 volumio-zoli ntpd[757]: Soliciting pool server 193.224.163.77 Nov 21 14:21:40 volumio-zoli sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 14:20 Nov 21 14:21:40 volumio-zoli sudo[1668]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"