-- Logs begin at Sat 2025-02-15 19:22:51 UTC, end at Sat 2025-02-15 20:05:59 UTC. -- Feb 15 20:04:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:00 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:00 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:01 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:01 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:02 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:02 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:03 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:03 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:04 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:04 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:05 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:05 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:06 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:06 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:07 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:07 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:08 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:08 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:09 volumio-primo volumio[1100]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] Feb 15 20:04:09 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings Feb 15 20:04:09 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 15 20:04:09 volumio-primo volumio[1100]: info: Discovery: Restarting Advertising due to device name change Feb 15 20:04:09 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:09 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:09 volumio-primo sudo[8737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /etc/resolv.conf.tail.tmpl /etc/resolv.conf.tail Feb 15 20:04:09 volumio-primo sudo[8737]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:09 volumio-primo sudo[8740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 15 20:04:09 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 15 20:04:09 volumio-primo volumio[1100]: info: Discovery: Restarting Advertising due to device name change Feb 15 20:04:09 volumio-primo sudo[8737]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:09 volumio-primo sudo[8745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Feb 15 20:04:09 volumio-primo sudo[8740]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:09 volumio-primo sudo[8745]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:09 volumio-primo systemd[1]: Stopping Wireless Services... Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: wlan0: CTRL-EVENT-DISCONNECTED bssid=6a:f0:bc:bb:1e:10 reason=3 locally_generated=1 Feb 15 20:04:09 volumio-primo avahi-daemon[549]: Withdrawing address record for 192.168.11.246 on eth0. Feb 15 20:04:09 volumio-primo avahi-daemon[549]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.11.246. Feb 15 20:04:09 volumio-primo dhcpcd[1043]: eth0: pid 8759 deleted IP address 192.168.11.246/24 Feb 15 20:04:09 volumio-primo dhcpcd[1194]: eth0: pid 8759 deleted IP address 192.168.11.246/24 Feb 15 20:04:09 volumio-primo dhcpcd[1194]: eth0: deleting route to 192.168.11.0/24 Feb 15 20:04:09 volumio-primo dhcpcd[1043]: eth0: deleting route to 192.168.11.0/24 Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: nl80211: Failed to set IPv4 unicast in multicast filter Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: nl80211: Failed to set IPv4 unicast in multicast filter Feb 15 20:04:09 volumio-primo sudo[8745]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:09 volumio-primo avahi-daemon[549]: Interface eth0.IPv4 no longer relevant for mDNS. Feb 15 20:04:09 volumio-primo dhcpcd[1194]: eth0: deleting default route via 192.168.11.1 Feb 15 20:04:09 volumio-primo dhcpcd[1043]: eth0: deleting default route via 192.168.11.1 Feb 15 20:04:09 volumio-primo volumio[1100]: info: Discovery: A device disappeared from network Feb 15 20:04:09 volumio-primo volumio[1100]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Feb 15 20:04:09 volumio-primo kernel: cfg80211: World regulatory domain updated: Feb 15 20:04:09 volumio-primo kernel: cfg80211: DFS Master region: unset Feb 15 20:04:09 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Feb 15 20:04:09 volumio-primo dhcpcd[1194]: eth0: rebinding lease of 192.168.11.246 Feb 15 20:04:09 volumio-primo kernel: cfg80211: Regulatory domain changed to country: IT Feb 15 20:04:09 volumio-primo kernel: cfg80211: DFS Master region: ETSI Feb 15 20:04:09 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (5490000 KHz - 5710000 KHz @ 160000 KHz), (N/A, 2700 mBm), (0 s) Feb 15 20:04:09 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Feb 15 20:04:09 volumio-primo dhcpcd[1194]: eth0: probing address 192.168.11.246/24 Feb 15 20:04:09 volumio-primo dhcpcd[1043]: eth0: rebinding lease of 192.168.11.246 Feb 15 20:04:09 volumio-primo dhcpcd[1043]: wlan0: carrier lost Feb 15 20:04:09 volumio-primo sudo[8786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Feb 15 20:04:09 volumio-primo sudo[8786]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:09 volumio-primo avahi-daemon[549]: Withdrawing address record for 192.168.11.170 on wlan0. Feb 15 20:04:09 volumio-primo avahi-daemon[549]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.170. Feb 15 20:04:09 volumio-primo avahi-daemon[549]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 15 20:04:09 volumio-primo sudo[8786]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: nl80211: Failed to set IPv4 unicast in multicast filter Feb 15 20:04:09 volumio-primo volumio[1100]: info: Discovery: A device disappeared from network Feb 15 20:04:09 volumio-primo wpa_supplicant[919]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Feb 15 20:04:09 volumio-primo volumio[1100]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Feb 15 20:04:09 volumio-primo sudo[8826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Feb 15 20:04:09 volumio-primo dhcpcd[1194]: arp_probe1: Network is down Feb 15 20:04:09 volumio-primo dhcpcd[1194]: dhcp_readpacket: eth0: Network is down Feb 15 20:04:09 volumio-primo dhcpcd[1043]: wlan0: deleting route to 192.168.11.0/24 Feb 15 20:04:09 volumio-primo dhcpcd[1043]: wlan0: deleting default route via 192.168.11.1 Feb 15 20:04:09 volumio-primo sudo[8826]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:10 volumio-primo dhcpcd[1043]: dhcp_readpacket: eth0: Network is down Feb 15 20:04:10 volumio-primo dhcpcd[1043]: eth0: carrier lost Feb 15 20:04:10 volumio-primo dhcpcd[1194]: eth0: carrier lost Feb 15 20:04:10 volumio-primo wpa_supplicant[919]: wlan0: CTRL-EVENT-TERMINATING Feb 15 20:04:10 volumio-primo sudo[8826]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:10 volumio-primo systemd[1]: wireless.service: Succeeded. Feb 15 20:04:10 volumio-primo systemd[1]: Stopped Wireless Services. Feb 15 20:04:10 volumio-primo systemd[1]: Starting Wireless Services... Feb 15 20:04:10 volumio-primo dhcpcd[1043]: eth0: carrier acquired Feb 15 20:04:10 volumio-primo dhcpcd[1043]: eth0: IAID 64:d8:1c:c9 Feb 15 20:04:10 volumio-primo dhcpcd[1194]: eth0: carrier acquired Feb 15 20:04:10 volumio-primo dhcpcd[1194]: eth0: IAID 64:d8:1c:c9 Feb 15 20:04:10 volumio-primo ifplugd(eth0)[817]: Link beat lost. Feb 15 20:04:10 volumio-primo wireless.js[8861]: Cleaning previous... Feb 15 20:04:10 volumio-primo dhcpcd[1043]: eth0: rebinding lease of 192.168.11.246 Feb 15 20:04:10 volumio-primo sudo[8897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 15 20:04:10 volumio-primo sudo[8897]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:10 volumio-primo sudo[8897]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:10 volumio-primo sudo[8906]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 15 20:04:10 volumio-primo sudo[8906]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:10 volumio-primo sudo[8906]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:10 volumio-primo dhcpcd[1043]: eth0: soliciting an IPv6 router Feb 15 20:04:10 volumio-primo wireless.js[8861]: Stopped aP Feb 15 20:04:10 volumio-primo sudo[8920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 15 20:04:10 volumio-primo sudo[8920]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:10 volumio-primo sudo[8920]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:10 volumio-primo sudo[8929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 15 20:04:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:10 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:10 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:10 volumio-primo sudo[8929]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:10 volumio-primo sudo[8929]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:10 volumio-primo dhcpcd[1043]: eth0: carrier lost Feb 15 20:04:10 volumio-primo dhcpcd[1194]: eth0: carrier lost Feb 15 20:04:10 volumio-primo sudo[8943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 15 20:04:10 volumio-primo sudo[8943]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:10 volumio-primo sudo[8943]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:10 volumio-primo sudo[8976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 15 20:04:10 volumio-primo sudo[8976]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:11 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:11 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:12 volumio-primo dhcpcd[1194]: eth0: carrier acquired Feb 15 20:04:12 volumio-primo dhcpcd[1043]: eth0: carrier acquired Feb 15 20:04:12 volumio-primo dhcpcd[1194]: eth0: IAID 64:d8:1c:c9 Feb 15 20:04:12 volumio-primo dhcpcd[1043]: eth0: IAID 64:d8:1c:c9 Feb 15 20:04:12 volumio-primo kernel: setLedConfiguration: #### before setting led, Reg26 = 0x91 , Reg28 = 0x9200 Feb 15 20:04:12 volumio-primo kernel: setLedConfiguration: #### after setting led, Reg26 = 0x91 , Reg28 = 0x9200 Feb 15 20:04:12 volumio-primo kernel: rk_gmac-dwmac ff290000.ethernet eth0: Link is Up - 100Mbps/Full - flow control off Feb 15 20:04:12 volumio-primo sudo[8976]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:12 volumio-primo wireless.js[8861]: Start wireless flow Feb 15 20:04:12 volumio-primo wireless.js[8861]: Stopped hotspot (if there).. Feb 15 20:04:12 volumio-primo sudo[8990]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 15 20:04:12 volumio-primo sudo[8990]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:12 volumio-primo sudo[8990]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:12 volumio-primo sudo[8999]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 15 20:04:12 volumio-primo sudo[8999]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:12 volumio-primo sudo[8999]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:12 volumio-primo dhcpcd[1194]: eth0: rebinding lease of 192.168.11.246 Feb 15 20:04:12 volumio-primo wireless.js[8861]: DHCP IP Feb 15 20:04:12 volumio-primo wireless.js[8861]: Start ap Feb 15 20:04:12 volumio-primo wpa_supplicant[9008]: Successfully initialized wpa_supplicant Feb 15 20:04:12 volumio-primo sudo[9009]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 15 20:04:12 volumio-primo sudo[9009]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:12 volumio-primo dhcpcd[9014]: sending commands to master dhcpcd process Feb 15 20:04:12 volumio-primo dhcpcd[1043]: control command: /sbin/dhcpcd Feb 15 20:04:12 volumio-primo sudo[9009]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:12 volumio-primo ifplugd(eth0)[817]: Link beat detected. Feb 15 20:04:12 volumio-primo dhcpcd[1043]: eth0: soliciting an IPv6 router Feb 15 20:04:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:12 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:12 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:13 volumio-primo dhcpcd[1194]: eth0: soliciting an IPv6 router Feb 15 20:04:13 volumio-primo dhcpcd[1043]: eth0: rebinding lease of 192.168.11.246 Feb 15 20:04:13 volumio-primo dhcpcd[1043]: eth0: probing address 192.168.11.246/24 Feb 15 20:04:13 volumio-primo volumio[1100]: info: Volumio Network Manager: Network status updated: 0 Feb 15 20:04:13 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:13 volumio-primo sudo[9034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:13 volumio-primo sudo[9034]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:13 volumio-primo sudo[9034]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:13 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:13 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:13 volumio-primo ntpd[1193]: Deleting interface #3 eth0, 192.168.11.246#123, interface stats: received=609, sent=609, dropped=1, active_time=2469 secs Feb 15 20:04:13 volumio-primo ntpd[1193]: 45.135.69.197 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 95.110.254.234 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 129.152.16.145 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 93.148.164.6 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 162.159.200.1 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 212.45.144.3 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 31.14.133.122 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 188.213.165.209 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 162.159.200.123 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 151.22.209.90 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 93.94.88.50 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 185.157.229.254 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 193.204.114.105 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 37.247.53.178 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: 89.46.74.148 local addr 192.168.11.246 -> Feb 15 20:04:13 volumio-primo ntpd[1193]: Deleting interface #4 wlan0, 192.168.11.170#123, interface stats: received=0, sent=0, dropped=0, active_time=2469 secs Feb 15 20:04:13 volumio-primo wpa_supplicant[9018]: wlan0: Trying to associate with 6a:f0:bc:bb:1e:10 (SSID='WINDTRE-9CA160-2.4G-ext' freq=2472 MHz) Feb 15 20:04:13 volumio-primo wpa_supplicant[9018]: wlan0: Associated with 6a:f0:bc:bb:1e:10 Feb 15 20:04:13 volumio-primo wpa_supplicant[9018]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 15 20:04:13 volumio-primo wpa_supplicant[9018]: wlan0: WPA: Key negotiation completed with 6a:f0:bc:bb:1e:10 [PTK=CCMP GTK=CCMP] Feb 15 20:04:13 volumio-primo wpa_supplicant[9018]: wlan0: CTRL-EVENT-CONNECTED - Connection to 6a:f0:bc:bb:1e:10 completed [id=0 id_str=] Feb 15 20:04:13 volumio-primo dhcpcd[1043]: wlan0: carrier acquired Feb 15 20:04:13 volumio-primo wpa_supplicant[9018]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Feb 15 20:04:13 volumio-primo dhcpcd[1043]: wlan0: IAID 55:76:48:e5 Feb 15 20:04:13 volumio-primo kernel: cfg80211: Regulatory domain changed to country: CN Feb 15 20:04:13 volumio-primo kernel: cfg80211: DFS Master region: FCC Feb 15 20:04:13 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Feb 15 20:04:13 volumio-primo kernel: cfg80211: (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:13 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (N/A) Feb 15 20:04:13 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s) Feb 15 20:04:13 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A) Feb 15 20:04:13 volumio-primo kernel: cfg80211: (57240000 KHz - 59400000 KHz @ 2160000 KHz), (N/A, 2800 mBm), (N/A) Feb 15 20:04:13 volumio-primo kernel: cfg80211: (59400000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4400 mBm), (N/A) Feb 15 20:04:13 volumio-primo kernel: cfg80211: (63720000 KHz - 65880000 KHz @ 2160000 KHz), (N/A, 2800 mBm), (N/A) Feb 15 20:04:14 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:14 volumio-primo sudo[9047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:14 volumio-primo sudo[9047]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:14 volumio-primo sudo[9047]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:14 volumio-primo dhcpcd[1043]: wlan0: rebinding lease of 192.168.11.170 Feb 15 20:04:14 volumio-primo dhcpcd[1043]: wlan0: probing address 192.168.11.170/24 Feb 15 20:04:14 volumio-primo dhcpcd[1043]: wlan0: soliciting an IPv6 router Feb 15 20:04:14 volumio-primo volumio[1100]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:04:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:14 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:14 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:14 volumio-primo volumio[1100]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:04:15 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:15 volumio-primo sudo[9060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:15 volumio-primo sudo[9060]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:15 volumio-primo sudo[9060]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:15 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:15 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:15 volumio-primo dhcpcd[1194]: eth0: probing address 192.168.11.246/24 Feb 15 20:04:16 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:16 volumio-primo sudo[9070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:16 volumio-primo sudo[9070]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:16 volumio-primo sudo[9070]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:16 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:16 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:17 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:17 volumio-primo sudo[9080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:17 volumio-primo sudo[9080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:17 volumio-primo sudo[9080]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:17 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:17 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:18 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:18 volumio-primo sudo[9090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:18 volumio-primo sudo[9090]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:18 volumio-primo sudo[9090]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:18 volumio-primo dhcpcd[1043]: eth0: leased 192.168.11.246 for 43200 seconds Feb 15 20:04:18 volumio-primo dhcpcd[1043]: eth0: adding route to 192.168.11.0/24 Feb 15 20:04:18 volumio-primo avahi-daemon[549]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.11.246. Feb 15 20:04:18 volumio-primo dhcpcd[1043]: eth0: adding default route via 192.168.11.1 Feb 15 20:04:18 volumio-primo avahi-daemon[549]: New relevant interface eth0.IPv4 for mDNS. Feb 15 20:04:18 volumio-primo avahi-daemon[549]: Registering new address record for 192.168.11.246 on eth0.IPv4. Feb 15 20:04:18 volumio-primo ntpd[1193]: ntpd exiting on signal 15 (Terminated) Feb 15 20:04:18 volumio-primo systemd[1]: Stopping Network Time Service... Feb 15 20:04:18 volumio-primo systemd[1]: ntp.service: Succeeded. Feb 15 20:04:18 volumio-primo systemd[1]: Stopped Network Time Service. Feb 15 20:04:18 volumio-primo systemd[1]: Starting Network Time Service... Feb 15 20:04:18 volumio-primo ntpd[9132]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 15 20:04:18 volumio-primo ntpd[9132]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 15 20:04:18 volumio-primo systemd[1]: Started Network Time Service. Feb 15 20:04:18 volumio-primo ntpd[9138]: proto: precision = 0.583 usec (-21) Feb 15 20:04:18 volumio-primo ntpd[9138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 15 20:04:18 volumio-primo ntpd[9138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 15 20:04:18 volumio-primo ntpd[9138]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 781 days ago Feb 15 20:04:18 volumio-primo ntpd[9138]: Listen and drop on 0 v6wildcard [::]:123 Feb 15 20:04:18 volumio-primo ntpd[9138]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 15 20:04:18 volumio-primo ntpd[9138]: Listen normally on 2 lo 127.0.0.1:123 Feb 15 20:04:18 volumio-primo ntpd[9138]: Listen normally on 3 eth0 192.168.11.246:123 Feb 15 20:04:18 volumio-primo ntpd[9138]: Listening on routing socket on fd #20 for interface updates Feb 15 20:04:18 volumio-primo ntpd[9138]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:18 volumio-primo ntpd[9138]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:18 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:18 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:19 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:19 volumio-primo sudo[9142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:19 volumio-primo sudo[9142]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:19 volumio-primo sudo[9142]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:19 volumio-primo dhcpcd[1043]: wlan0: leased 192.168.11.170 for 43200 seconds Feb 15 20:04:19 volumio-primo dhcpcd[1043]: wlan0: adding route to 192.168.11.0/24 Feb 15 20:04:19 volumio-primo dhcpcd[1043]: wlan0: adding default route via 192.168.11.1 Feb 15 20:04:19 volumio-primo avahi-daemon[549]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.170. Feb 15 20:04:19 volumio-primo avahi-daemon[549]: New relevant interface wlan0.IPv4 for mDNS. Feb 15 20:04:19 volumio-primo avahi-daemon[549]: Registering new address record for 192.168.11.170 on wlan0.IPv4. Feb 15 20:04:19 volumio-primo volumio[1100]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:04:19 volumio-primo ntpd[9138]: ntpd exiting on signal 15 (Terminated) Feb 15 20:04:19 volumio-primo systemd[1]: Stopping Network Time Service... Feb 15 20:04:19 volumio-primo systemd[1]: ntp.service: Succeeded. Feb 15 20:04:19 volumio-primo systemd[1]: Stopped Network Time Service. Feb 15 20:04:19 volumio-primo systemd[1]: Starting Network Time Service... Feb 15 20:04:19 volumio-primo ntpd[9184]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 15 20:04:19 volumio-primo ntpd[9184]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 15 20:04:19 volumio-primo systemd[1]: Started Network Time Service. Feb 15 20:04:19 volumio-primo ntpd[9190]: proto: precision = 0.583 usec (-21) Feb 15 20:04:19 volumio-primo ntpd[9190]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 15 20:04:19 volumio-primo ntpd[9190]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 15 20:04:19 volumio-primo ntpd[9190]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 781 days ago Feb 15 20:04:19 volumio-primo ntpd[9190]: Listen and drop on 0 v6wildcard [::]:123 Feb 15 20:04:19 volumio-primo ntpd[9190]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 15 20:04:19 volumio-primo ntpd[9190]: Listen normally on 2 lo 127.0.0.1:123 Feb 15 20:04:19 volumio-primo ntpd[9190]: Listen normally on 3 eth0 192.168.11.246:123 Feb 15 20:04:19 volumio-primo ntpd[9190]: Listen normally on 4 wlan0 192.168.11.170:123 Feb 15 20:04:19 volumio-primo ntpd[9190]: Listening on routing socket on fd #21 for interface updates Feb 15 20:04:19 volumio-primo ntpd[9190]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:19 volumio-primo ntpd[9190]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:19 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:19 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 15 20:04:20 volumio-primo volumio[1100]: info: Refreshing Cached IP Addresses Feb 15 20:04:20 volumio-primo sudo[9199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 15 20:04:20 volumio-primo sudo[9202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:04:20 volumio-primo sudo[9199]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:20 volumio-primo sudo[9202]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:20 volumio-primo sudo[9202]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:20 volumio-primo volumio[1100]: error: Upnp client error: Error: This socket has been ended by the other party Feb 15 20:04:20 volumio-primo sudo[9199]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:20 volumio-primo sudo[9210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:20 volumio-primo sudo[9210]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:20 volumio-primo sudo[9210]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:20 volumio-primo wireless.js[8861]: trying... Feb 15 20:04:20 volumio-primo sudo[9229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:20 volumio-primo sudo[9229]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:20 volumio-primo sudo[9229]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:20 volumio-primo wireless.js[8861]: Connected to: ----WINDTRE-9CA160-2.4G-ext Feb 15 20:04:20 volumio-primo wireless.js[8861]: ---- Feb 15 20:04:20 volumio-primo sudo[9239]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:20 volumio-primo sudo[9239]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:20 volumio-primo sudo[9239]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:20 volumio-primo wireless.js[8861]: ... joined AP, wlan0 IPv4 is 192.168.11.170, ipV6 is undefined Feb 15 20:04:20 volumio-primo wireless.js[8861]: It's done! AP Feb 15 20:04:20 volumio-primo iwconfig[9250]: Error for wireless request "Set Power Management" (8B2C) : Feb 15 20:04:20 volumio-primo iwconfig[9250]: SET failed on device wlan0 ; Operation not permitted. Feb 15 20:04:20 volumio-primo systemd[1]: Started Wireless Services. Feb 15 20:04:20 volumio-primo sudo[8740]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 20:04:20 volumio-primo volumio[1100]: info: Received Get System Info Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 15 20:04:20 volumio-primo volumio[1100]: info: Discovery: Getting this device information Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioGetState Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 15 20:04:20 volumio-primo volumio[1100]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:04:20 volumio-primo volumio[1100]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Feb 15 20:04:20 volumio-primo ntpd[9190]: Soliciting pool server 185.157.229.254 Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 20:04:20 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 15 20:04:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:20 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:20 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:21 volumio-primo dhcpcd[1194]: eth0: leased 192.168.11.246 for 43200 seconds Feb 15 20:04:21 volumio-primo dhcpcd[1194]: eth0: adding route to 192.168.11.0/24 Feb 15 20:04:21 volumio-primo dhcpcd[1194]: eth0: adding default route via 192.168.11.1 Feb 15 20:04:21 volumio-primo ntpd[9190]: ntpd exiting on signal 15 (Terminated) Feb 15 20:04:21 volumio-primo ntpd[9190]: 185.157.229.254 local addr 192.168.11.246 -> Feb 15 20:04:21 volumio-primo systemd[1]: Stopping Network Time Service... Feb 15 20:04:21 volumio-primo systemd[1]: ntp.service: Succeeded. Feb 15 20:04:21 volumio-primo systemd[1]: Stopped Network Time Service. Feb 15 20:04:21 volumio-primo systemd[1]: Starting Network Time Service... Feb 15 20:04:21 volumio-primo ntpd[9286]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 15 20:04:21 volumio-primo ntpd[9286]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 15 20:04:21 volumio-primo systemd[1]: Started Network Time Service. Feb 15 20:04:21 volumio-primo ntpd[9292]: proto: precision = 0.583 usec (-21) Feb 15 20:04:21 volumio-primo ntpd[9292]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 15 20:04:21 volumio-primo ntpd[9292]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 15 20:04:21 volumio-primo ntpd[9292]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 781 days ago Feb 15 20:04:21 volumio-primo ntpd[9292]: Listen and drop on 0 v6wildcard [::]:123 Feb 15 20:04:21 volumio-primo ntpd[9292]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 15 20:04:21 volumio-primo ntpd[9292]: Listen normally on 2 lo 127.0.0.1:123 Feb 15 20:04:21 volumio-primo ntpd[9292]: Listen normally on 3 eth0 192.168.11.246:123 Feb 15 20:04:21 volumio-primo ntpd[9292]: Listen normally on 4 wlan0 192.168.11.170:123 Feb 15 20:04:21 volumio-primo ntpd[9292]: Listening on routing socket on fd #21 for interface updates Feb 15 20:04:21 volumio-primo ntpd[9292]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:21 volumio-primo ntpd[9292]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:21 volumio-primo sudo[9297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Feb 15 20:04:21 volumio-primo sudo[9297]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:21 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:21 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:21 volumio-primo sudo[9297]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:21 volumio-primo volumio[1100]: info: Permissions for /etc/hostapd/hostapd.conf Feb 15 20:04:21 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Feb 15 20:04:21 volumio-primo volumio[1100]: info: Discovery: Restarting Advertising due to device name change Feb 15 20:04:21 volumio-primo sudo[9307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Feb 15 20:04:21 volumio-primo sudo[9307]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:21 volumio-primo systemd[1]: Stopping Wireless Services... Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: wlan0: CTRL-EVENT-DISCONNECTED bssid=6a:f0:bc:bb:1e:10 reason=3 locally_generated=1 Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: nl80211: Failed to set IPv4 unicast in multicast filter Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: nl80211: Failed to set IPv4 unicast in multicast filter Feb 15 20:04:21 volumio-primo dhcpcd[1043]: wlan0: carrier lost Feb 15 20:04:21 volumio-primo kernel: cfg80211: World regulatory domain updated: Feb 15 20:04:21 volumio-primo kernel: cfg80211: DFS Master region: unset Feb 15 20:04:21 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Feb 15 20:04:21 volumio-primo avahi-daemon[549]: Withdrawing address record for 192.168.11.170 on wlan0. Feb 15 20:04:21 volumio-primo avahi-daemon[549]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.170. Feb 15 20:04:21 volumio-primo avahi-daemon[549]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 15 20:04:21 volumio-primo volumio[1100]: info: Discovery: A device disappeared from network Feb 15 20:04:21 volumio-primo volumio[1100]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Feb 15 20:04:21 volumio-primo kernel: cfg80211: Regulatory domain changed to country: IT Feb 15 20:04:21 volumio-primo kernel: cfg80211: DFS Master region: ETSI Feb 15 20:04:21 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (5490000 KHz - 5710000 KHz @ 160000 KHz), (N/A, 2700 mBm), (0 s) Feb 15 20:04:21 volumio-primo kernel: cfg80211: (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Feb 15 20:04:21 volumio-primo dhcpcd[1043]: wlan0: deleting route to 192.168.11.0/24 Feb 15 20:04:21 volumio-primo dhcpcd[1043]: wlan0: deleting default route via 192.168.11.1 Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: nl80211: Failed to open /proc/sys/net/ipv4/conf/wlan0/drop_unicast_in_l2_multicast: No such file or directory Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: nl80211: Failed to set IPv4 unicast in multicast filter Feb 15 20:04:21 volumio-primo wpa_supplicant[9018]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Feb 15 20:04:22 volumio-primo wpa_supplicant[9018]: wlan0: CTRL-EVENT-TERMINATING Feb 15 20:04:22 volumio-primo systemd[1]: wireless.service: Succeeded. Feb 15 20:04:22 volumio-primo systemd[1]: Stopped Wireless Services. Feb 15 20:04:22 volumio-primo systemd[1]: Starting Wireless Services... Feb 15 20:04:22 volumio-primo wireless.js[9349]: Cleaning previous... Feb 15 20:04:22 volumio-primo sudo[9363]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 15 20:04:22 volumio-primo sudo[9363]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:22 volumio-primo sudo[9363]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:22 volumio-primo sudo[9372]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 15 20:04:22 volumio-primo sudo[9372]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:22 volumio-primo sudo[9372]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:22 volumio-primo ntpd[9292]: Soliciting pool server 185.157.229.254 Feb 15 20:04:22 volumio-primo wireless.js[9349]: Stopped aP Feb 15 20:04:22 volumio-primo sudo[9386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 15 20:04:22 volumio-primo sudo[9386]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:22 volumio-primo sudo[9386]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:22 volumio-primo sudo[9395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Feb 15 20:04:22 volumio-primo sudo[9395]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:22 volumio-primo sudo[9395]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:22 volumio-primo sudo[9409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Feb 15 20:04:22 volumio-primo sudo[9409]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:22 volumio-primo sudo[9409]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:22 volumio-primo sudo[9418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Feb 15 20:04:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:22 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:22 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:22 volumio-primo sudo[9418]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:23 volumio-primo volumio[1100]: info: Volumio Network Manager: Network status updated: 1 Feb 15 20:04:23 volumio-primo ntpd[9292]: Soliciting pool server 162.159.200.123 Feb 15 20:04:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:23 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:23 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:23 volumio-primo ntpd[9292]: Soliciting pool server 5.249.144.95 Feb 15 20:04:24 volumio-primo sudo[9418]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:24 volumio-primo wireless.js[9349]: Start wireless flow Feb 15 20:04:24 volumio-primo wireless.js[9349]: Stopped hotspot (if there).. Feb 15 20:04:24 volumio-primo sudo[9444]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 15 20:04:24 volumio-primo sudo[9444]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:24 volumio-primo sudo[9444]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:24 volumio-primo sudo[9453]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 15 20:04:24 volumio-primo sudo[9453]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:24 volumio-primo sudo[9453]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:24 volumio-primo wireless.js[9349]: DHCP IP Feb 15 20:04:24 volumio-primo wireless.js[9349]: Start ap Feb 15 20:04:24 volumio-primo wpa_supplicant[9462]: Successfully initialized wpa_supplicant Feb 15 20:04:24 volumio-primo sudo[9463]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Feb 15 20:04:24 volumio-primo sudo[9463]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:24 volumio-primo dhcpcd[9468]: sending commands to master dhcpcd process Feb 15 20:04:24 volumio-primo dhcpcd[1043]: control command: /sbin/dhcpcd Feb 15 20:04:24 volumio-primo sudo[9463]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:24 volumio-primo ntpd[9292]: Soliciting pool server 85.199.214.99 Feb 15 20:04:24 volumio-primo ntpd[9292]: Soliciting pool server 89.46.74.148 Feb 15 20:04:24 volumio-primo ntpd[9292]: Soliciting pool server 162.159.200.1 Feb 15 20:04:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:24 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:24 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:25 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:25 volumio-primo sudo[9474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:25 volumio-primo sudo[9474]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:25 volumio-primo sudo[9474]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:25 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Feb 15 20:04:25 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Feb 15 20:04:25 volumio-primo sudo[9484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:25 volumio-primo sudo[9484]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:25 volumio-primo sudo[9484]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:25 volumio-primo ntpd[9292]: Soliciting pool server 185.19.184.35 Feb 15 20:04:25 volumio-primo ntpd[9292]: Soliciting pool server 95.110.254.234 Feb 15 20:04:25 volumio-primo ntpd[9292]: Soliciting pool server 93.94.88.51 Feb 15 20:04:25 volumio-primo ntpd[9292]: Deleting interface #4 wlan0, 192.168.11.170#123, interface stats: received=0, sent=0, dropped=0, active_time=4 secs Feb 15 20:04:25 volumio-primo ntpd[9292]: Soliciting pool server 212.45.144.3 Feb 15 20:04:25 volumio-primo wpa_supplicant[9472]: wlan0: Trying to associate with 6a:f0:bc:bb:1e:10 (SSID='WINDTRE-9CA160-2.4G-ext' freq=2472 MHz) Feb 15 20:04:25 volumio-primo wpa_supplicant[9472]: wlan0: Associated with 6a:f0:bc:bb:1e:10 Feb 15 20:04:25 volumio-primo wpa_supplicant[9472]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 15 20:04:25 volumio-primo wpa_supplicant[9472]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Feb 15 20:04:25 volumio-primo wpa_supplicant[9472]: wlan0: WPA: Key negotiation completed with 6a:f0:bc:bb:1e:10 [PTK=CCMP GTK=CCMP] Feb 15 20:04:25 volumio-primo wpa_supplicant[9472]: wlan0: CTRL-EVENT-CONNECTED - Connection to 6a:f0:bc:bb:1e:10 completed [id=0 id_str=] Feb 15 20:04:25 volumio-primo dhcpcd[1043]: wlan0: carrier acquired Feb 15 20:04:25 volumio-primo kernel: cfg80211: Regulatory domain changed to country: CN Feb 15 20:04:25 volumio-primo kernel: cfg80211: DFS Master region: FCC Feb 15 20:04:25 volumio-primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) Feb 15 20:04:25 volumio-primo kernel: cfg80211: (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Feb 15 20:04:25 volumio-primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (N/A) Feb 15 20:04:25 volumio-primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s) Feb 15 20:04:25 volumio-primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A) Feb 15 20:04:25 volumio-primo kernel: cfg80211: (57240000 KHz - 59400000 KHz @ 2160000 KHz), (N/A, 2800 mBm), (N/A) Feb 15 20:04:25 volumio-primo kernel: cfg80211: (59400000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4400 mBm), (N/A) Feb 15 20:04:25 volumio-primo kernel: cfg80211: (63720000 KHz - 65880000 KHz @ 2160000 KHz), (N/A, 2800 mBm), (N/A) Feb 15 20:04:25 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:25 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:25 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:25 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:25 volumio-primo dhcpcd[1043]: wlan0: IAID 55:76:48:e5 Feb 15 20:04:25 volumio-primo dhcpcd[1043]: wlan0: soliciting an IPv6 router Feb 15 20:04:26 volumio-primo dhcpcd[1043]: wlan0: rebinding lease of 192.168.11.170 Feb 15 20:04:26 volumio-primo dhcpcd[1043]: wlan0: probing address 192.168.11.170/24 Feb 15 20:04:26 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:26 volumio-primo sudo[9496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:26 volumio-primo sudo[9496]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:26 volumio-primo sudo[9496]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:26 volumio-primo ntpd[9292]: Soliciting pool server 45.135.69.197 Feb 15 20:04:26 volumio-primo ntpd[9292]: Soliciting pool server 151.22.209.90 Feb 15 20:04:26 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:26 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:26 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:26 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:26 volumio-primo volumio[1100]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:04:27 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:27 volumio-primo sudo[9507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:27 volumio-primo sudo[9507]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:27 volumio-primo sudo[9507]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:27 volumio-primo ntpd[9292]: Soliciting pool server 79.10.113.170 Feb 15 20:04:27 volumio-primo ntpd[9292]: Soliciting pool server 31.14.133.122 Feb 15 20:04:27 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:27 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:27 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:27 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:28 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:28 volumio-primo sudo[9517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:28 volumio-primo sudo[9517]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:28 volumio-primo sudo[9517]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:28 volumio-primo ntpd[9292]: Soliciting pool server 2a0d:3341:ccbc:c800::123 Feb 15 20:04:28 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:28 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:28 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:28 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:29 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:29 volumio-primo sudo[9527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:29 volumio-primo sudo[9527]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:29 volumio-primo sudo[9527]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:29 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:29 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:29 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:29 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:30 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:30 volumio-primo sudo[9542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:04:30 volumio-primo sudo[9543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:30 volumio-primo sudo[9544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:30 volumio-primo sudo[9542]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:30 volumio-primo sudo[9543]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:30 volumio-primo sudo[9542]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:30 volumio-primo sudo[9544]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:30 volumio-primo sudo[9543]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:30 volumio-primo sudo[9554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 15 20:04:30 volumio-primo sudo[9544]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:30 volumio-primo sudo[9554]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:30 volumio-primo sudo[9554]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:30 volumio-primo volumio[1100]: info: Upmpdcli Daemon Started Feb 15 20:04:30 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 15 20:04:30 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 15 20:04:30 volumio-primo volumio[1100]: info: Refreshing Cached IP Addresses Feb 15 20:04:30 volumio-primo sudo[9580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 15 20:04:30 volumio-primo sudo[9583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:04:30 volumio-primo sudo[9580]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:30 volumio-primo sudo[9583]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:30 volumio-primo sudo[9588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:30 volumio-primo volumio[1100]: error: Upnp client error: Error: This socket has been ended by the other party Feb 15 20:04:30 volumio-primo sudo[9580]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:30 volumio-primo sudo[9583]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:30 volumio-primo sudo[9588]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:30 volumio-primo sudo[9588]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:30 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:30 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:30 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:30 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:31 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:31 volumio-primo sudo[9610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:31 volumio-primo sudo[9610]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:31 volumio-primo sudo[9610]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:31 volumio-primo dhcpcd[1043]: wlan0: leased 192.168.11.170 for 43200 seconds Feb 15 20:04:31 volumio-primo dhcpcd[1043]: wlan0: adding route to 192.168.11.0/24 Feb 15 20:04:31 volumio-primo avahi-daemon[549]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.170. Feb 15 20:04:31 volumio-primo avahi-daemon[549]: New relevant interface wlan0.IPv4 for mDNS. Feb 15 20:04:31 volumio-primo dhcpcd[1043]: wlan0: adding default route via 192.168.11.1 Feb 15 20:04:31 volumio-primo avahi-daemon[549]: Registering new address record for 192.168.11.170 on wlan0.IPv4. Feb 15 20:04:31 volumio-primo ntpd[9292]: ntpd exiting on signal 15 (Terminated) Feb 15 20:04:31 volumio-primo ntpd[9292]: 185.157.229.254 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 162.159.200.123 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo systemd[1]: Stopping Network Time Service... Feb 15 20:04:31 volumio-primo ntpd[9292]: 5.249.144.95 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 89.46.74.148 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 85.199.214.99 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 162.159.200.1 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 185.19.184.35 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 95.110.254.234 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 93.94.88.51 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 212.45.144.3 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 45.135.69.197 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 151.22.209.90 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 31.14.133.122 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo ntpd[9292]: 79.10.113.170 local addr 192.168.11.246 -> Feb 15 20:04:31 volumio-primo systemd[1]: ntp.service: Succeeded. Feb 15 20:04:31 volumio-primo systemd[1]: Stopped Network Time Service. Feb 15 20:04:31 volumio-primo systemd[1]: Starting Network Time Service... Feb 15 20:04:31 volumio-primo ntpd[9653]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 15 20:04:31 volumio-primo ntpd[9653]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Feb 15 20:04:31 volumio-primo systemd[1]: Started Network Time Service. Feb 15 20:04:31 volumio-primo ntpd[9659]: proto: precision = 0.583 usec (-21) Feb 15 20:04:31 volumio-primo ntpd[9659]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 15 20:04:31 volumio-primo ntpd[9659]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 15 20:04:31 volumio-primo ntpd[9659]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 781 days ago Feb 15 20:04:31 volumio-primo ntpd[9659]: Listen and drop on 0 v6wildcard [::]:123 Feb 15 20:04:31 volumio-primo ntpd[9659]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 15 20:04:31 volumio-primo ntpd[9659]: Listen normally on 2 lo 127.0.0.1:123 Feb 15 20:04:31 volumio-primo ntpd[9659]: Listen normally on 3 eth0 192.168.11.246:123 Feb 15 20:04:31 volumio-primo ntpd[9659]: Listen normally on 4 wlan0 192.168.11.170:123 Feb 15 20:04:31 volumio-primo ntpd[9659]: Listening on routing socket on fd #21 for interface updates Feb 15 20:04:31 volumio-primo ntpd[9659]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:31 volumio-primo ntpd[9659]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 15 20:04:31 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:31 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:31 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:31 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:32 volumio-primo wireless.js[9349]: trying... Feb 15 20:04:32 volumio-primo volumio[1100]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:04:32 volumio-primo sudo[9662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 20:04:32 volumio-primo sudo[9662]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:32 volumio-primo sudo[9662]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:32 volumio-primo wireless.js[9349]: Connected to: ----WINDTRE-9CA160-2.4G-ext Feb 15 20:04:32 volumio-primo wireless.js[9349]: ---- Feb 15 20:04:32 volumio-primo sudo[9672]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:32 volumio-primo sudo[9672]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:32 volumio-primo sudo[9672]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:32 volumio-primo wireless.js[9349]: ... joined AP, wlan0 IPv4 is 192.168.11.170, ipV6 is undefined Feb 15 20:04:32 volumio-primo wireless.js[9349]: It's done! AP Feb 15 20:04:32 volumio-primo iwconfig[9683]: Error for wireless request "Set Power Management" (8B2C) : Feb 15 20:04:32 volumio-primo iwconfig[9683]: SET failed on device wlan0 ; Operation not permitted. Feb 15 20:04:32 volumio-primo systemd[1]: Started Wireless Services. Feb 15 20:04:32 volumio-primo sudo[9307]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:32 volumio-primo ntpd[9659]: Soliciting pool server 162.159.200.123 Feb 15 20:04:32 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:32 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:32 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:32 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:33 volumio-primo volumio[1100]: info: Volumio Network Manager: Network status updated: 3 Feb 15 20:04:33 volumio-primo ntpd[9659]: Soliciting pool server 185.157.229.254 Feb 15 20:04:33 volumio-primo ntpd[9659]: Soliciting pool server 93.94.88.51 Feb 15 20:04:33 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:33 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:33 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:33 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:34 volumio-primo ntpd[9659]: Soliciting pool server 95.110.254.234 Feb 15 20:04:34 volumio-primo ntpd[9659]: Soliciting pool server 89.46.74.148 Feb 15 20:04:34 volumio-primo ntpd[9659]: Soliciting pool server 151.22.209.90 Feb 15 20:04:34 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:34 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:34 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:34 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:35 volumio-primo ntpd[9659]: Soliciting pool server 185.19.184.35 Feb 15 20:04:35 volumio-primo ntpd[9659]: Soliciting pool server 85.199.214.99 Feb 15 20:04:35 volumio-primo ntpd[9659]: Soliciting pool server 212.45.144.3 Feb 15 20:04:35 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:35 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:35 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:35 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:36 volumio-primo ntpd[9659]: Soliciting pool server 5.249.144.95 Feb 15 20:04:36 volumio-primo ntpd[9659]: Soliciting pool server 162.159.200.1 Feb 15 20:04:36 volumio-primo ntpd[9659]: Soliciting pool server 5.249.144.95 Feb 15 20:04:36 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:36 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:36 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:36 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:37 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Feb 15 20:04:37 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Feb 15 20:04:37 volumio-primo sudo[9704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:37 volumio-primo sudo[9704]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:37 volumio-primo sudo[9704]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:37 volumio-primo sudo[9714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 15 20:04:37 volumio-primo sudo[9714]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:37 volumio-primo sudo[9714]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:37 volumio-primo ntpd[9659]: Soliciting pool server 79.10.113.170 Feb 15 20:04:37 volumio-primo ntpd[9659]: Soliciting pool server 31.14.133.122 Feb 15 20:04:37 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:37 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:37 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:37 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:38 volumio-primo ntpd[9659]: Soliciting pool server 45.135.69.197 Feb 15 20:04:38 volumio-primo ntpd[9659]: Soliciting pool server 2a00:6d41:10:1194::5 Feb 15 20:04:38 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:38 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:38 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:38 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:39 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:39 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:39 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:39 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:40 volumio-primo sudo[9729]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:04:40 volumio-primo sudo[9732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:40 volumio-primo sudo[9729]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:40 volumio-primo sudo[9729]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:40 volumio-primo sudo[9732]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:40 volumio-primo sudo[9737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 15 20:04:40 volumio-primo sudo[9732]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:40 volumio-primo sudo[9737]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:40 volumio-primo sudo[9737]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:40 volumio-primo volumio[1100]: info: Upmpdcli Daemon Started Feb 15 20:04:40 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:40 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:40 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:40 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:41 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:41 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:41 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:41 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:42 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Feb 15 20:04:42 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Feb 15 20:04:42 volumio-primo volumio[1100]: info: Refreshing Cached IP Addresses Feb 15 20:04:42 volumio-primo sudo[9759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Feb 15 20:04:42 volumio-primo sudo[9759]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:42 volumio-primo sudo[9762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:04:42 volumio-primo sudo[9762]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:42 volumio-primo volumio[1100]: error: Upnp client error: Error: This socket has been ended by the other party Feb 15 20:04:42 volumio-primo sudo[9759]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:42 volumio-primo sudo[9762]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:42 volumio-primo sudo[9770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:42 volumio-primo sudo[9770]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:42 volumio-primo sudo[9770]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:42 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:42 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:42 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:42 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:43 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:43 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:43 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:43 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:44 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:44 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:44 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:44 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:45 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:45 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:45 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:45 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:46 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:46 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:46 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:46 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:46 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:04:47 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:47 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:47 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:47 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:48 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:48 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:48 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:48 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:49 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:49 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:49 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:49 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:50 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:50 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:50 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:50 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:51 volumio-primo volumio[1100]: info: CALLMETHOD: user_interface Systeminfo getsysteminfo undefined Feb 15 20:04:51 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , getsysteminfo Feb 15 20:04:51 volumio-primo sudo[9806]: pam_unix(sudo:auth): conversation failed Feb 15 20:04:51 volumio-primo sudo[9806]: pam_unix(sudo:auth): auth could not identify password for [volumio] Feb 15 20:04:51 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:51 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:51 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:51 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:52 volumio-primo sudo[10121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:04:52 volumio-primo sudo[10121]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:52 volumio-primo sudo[10124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:04:52 volumio-primo sudo[10124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:52 volumio-primo sudo[10124]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:52 volumio-primo sudo[10121]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:52 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:52 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:52 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:52 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:53 volumio-primo volumio[1100]: info: board is Feb 15 20:04:53 volumio-primo volumio[1100]: Storage info 12265M 687M 10937M Feb 15 20:04:53 volumio-primo volumio[1100]: Storage info 12265M 687M 10937M Feb 15 20:04:53 volumio-primo volumio[1100]: BBBBBBBBBBBBBB-CPU Temp 55 °C Feb 15 20:04:53 volumio-primo volumio[1100]: info: Firmware detection failed: Error: Command failed: sudo vcgencmd version Feb 15 20:04:53 volumio-primo volumio[1100]: sudo: no tty present and no askpass program specified Feb 15 20:04:53 volumio-primo volumio[1100]: info: failed Error: Command failed: /data/plugins/user_interface/Systeminfo/hw_params hw:0 >/data/configuration/user_interface/Systeminfo/config.json Feb 15 20:04:53 volumio-primo volumio[1100]: cannot open device 'hw:0': Device or resource busy Feb 15 20:04:53 volumio-primo sudo[10211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 15 20:04:53 volumio-primo sudo[10211]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:04:53 volumio-primo sudo[10211]: pam_unix(sudo:session): session closed for user root Feb 15 20:04:53 volumio-primo volumio[1100]: info: Upmpdcli Daemon Started Feb 15 20:04:53 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:53 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:53 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:53 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:54 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:54 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:54 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:54 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:55 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:55 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:55 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:55 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:56 volumio-primo volumiologrotate[541]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 15 20:04:56 volumio-primo volumiologrotate[541]: ls: cannot access 'PRIMO': No such file or directory Feb 15 20:04:56 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:56 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:56 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:56 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:57 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:04:57 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:57 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:57 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:57 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:58 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:58 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:58 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:58 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:59 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:59 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:04:59 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:04:59 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:00 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:00 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:00 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:01 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:01 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:01 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:02 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:02 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:02 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:03 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:03 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:03 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:04 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:04 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:04 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:05 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:05 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:05 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:06 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:06 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:06 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:07 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:07 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:07 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:08 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:08 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:08 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:09 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:09 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:09 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:10 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:10 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:10 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:11 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:11 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:11 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:12 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:12 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:12 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:13 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:13 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:13 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:14 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:14 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:14 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:15 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:15 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:15 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:16 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:16 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:16 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:17 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:17 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:17 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:18 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:18 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:18 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:19 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:19 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:19 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:20 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:20 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:20 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:21 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:21 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:21 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:22 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:22 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:22 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:23 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:23 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:23 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:24 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:24 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:24 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:25 volumio-primo volumio[1100]: info: CoreCommandRouter::Close All Modals sent Feb 15 20:05:25 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:25 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:25 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:25 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:26 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:26 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:26 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:26 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:27 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:27 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:27 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:27 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:28 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 15 20:05:28 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 15 20:05:28 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:28 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:28 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:28 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:29 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:29 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:29 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:29 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:30 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:30 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:30 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:30 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:31 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:31 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:31 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:31 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:32 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:32 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:32 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:32 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:33 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:33 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:33 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:33 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:34 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:34 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:34 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:34 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:35 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:35 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:35 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:35 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 15 20:05:36 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:36 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:36 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:36 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Feb 15 20:05:36 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Feb 15 20:05:37 volumio-primo volumio[1100]: info: Received Get System Version Feb 15 20:05:37 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:37 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: appearance , getBackgrounds Feb 15 20:05:37 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:37 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:37 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:37 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:38 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:38 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:38 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:38 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:39 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:39 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:39 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:39 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:40 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:40 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:40 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:40 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Retrieving Cloud Streaming UI Feb 15 20:05:41 volumio-primo volumio[1100]: info: Getting Tidal Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Getting Qobuz Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: Asking plugin for UI Config Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Getting Spotify Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: Asking plugin for UI Config Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: Saving Spotify Acccount Feb 15 20:05:41 volumio-primo volumio[1100]: info: Got it Feb 15 20:05:41 volumio-primo volumio[1100]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 15 20:05:41 volumio-primo volumio[1100]: info: Got Tidal Cloud Configuration Feb 15 20:05:41 volumio-primo volumio[1100]: info: Got it Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 20:05:41 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioGetBrowseSources Feb 15 20:05:41 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:41 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:41 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:41 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:42 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:42 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 15 20:05:42 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:42 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:42 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:42 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:43 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:43 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:43 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:43 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand update Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: Feb 15 20:05:44 volumio-primo volumio[1100]: ---------------------------- MPD announces state update: update Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand update took 4 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::getState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 4 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 1 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: info: Command Router : Notfying DB Updatetrue Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: Feb 15 20:05:44 volumio-primo volumio[1100]: ---------------------------- MPD announces state update: update Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::getState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand status Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::servicePushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2139606,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: CURRENT POSITION 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState stateService play Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:05:44 volumio-primo volumio[1100]: info: Received an update from plugin. extracting info from payload Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: ------------------------------ 55ms Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 33 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand status took 31 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: info: Command Router : Notfying DB Updatefalse Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::Close All Modals sent Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseState Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: ControllerMpd::parseTrackInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: ControllerMpd::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::servicePushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2139606,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"jazz-flac.flac","artist":"Naim Jazz","album":null,"uri":"http://mscp3.live-streams.nl:8340/jazz-flac.flac","trackType":"flac"} Feb 15 20:05:44 volumio-primo volumio[1100]: verbose: CURRENT POSITION 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState stateService play Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::syncState currentStatus play Feb 15 20:05:44 volumio-primo volumio[1100]: info: Received an update from plugin. extracting info from payload Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreStateMachine::pushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:44 volumio-primo volumio[1100]: info: ------------------------------ 122ms Feb 15 20:05:44 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:44 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:44 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:44 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:45 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:45 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:45 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:45 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:46 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:46 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:46 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:46 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:46 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:47 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:47 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:47 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:47 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:48 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:48 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:48 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:48 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:49 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:49 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:49 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:49 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:50 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 15 20:05:50 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 15 20:05:50 volumio-primo volumio[1100]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:50 volumio-primo volumio[1100]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:50 volumio-primo volumio[1100]: Error: getaddrinfo -3007 Feb 15 20:05:50 volumio-primo volumio[1100]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Feb 15 20:05:50 volumio-primo volumio[1100]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Feb 15 20:05:50 volumio-primo volumio[1100]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Feb 15 20:05:50 volumio-primo volumio[1100]: code: -3007, Feb 15 20:05:50 volumio-primo volumio[1100]: errno: -3007, Feb 15 20:05:50 volumio-primo volumio[1100]: syscall: 'getaddrinfo' Feb 15 20:05:50 volumio-primo volumio[1100]: } Feb 15 20:05:50 volumio-primo volumio[1100]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:50 volumio-primo sudo[10681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:04 Feb 15 20:05:50 volumio-primo sudo[10681]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:50 volumio-primo sudo[10681]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:50 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:50 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:50 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:50 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:51 volumio-primo vtcs[2217]: [2025-02-15 20:05:51.482] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Feb 15 20:05:51 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:51] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Feb 15 20:05:51 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:51] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Feb 15 20:05:51 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:51] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 15 20:05:51 volumio-primo systemd[1]: upmpdcli.service: Succeeded. Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 15 20:05:51 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:05:51 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 15 20:05:51 volumio-primo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 15 20:05:51 volumio-primo systemd[1]: Started dynamicswap service. Feb 15 20:05:51 volumio-primo systemd[1]: Stopped Volumio Backend Module. Feb 15 20:05:51 volumio-primo systemd[1]: Started Volumio Backend Module. Feb 15 20:05:51 volumio-primo systemd[1]: dynamicswap.service: Succeeded. Feb 15 20:05:51 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:51 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:51 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:51 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:52 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ----- Volumio3 ---- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ----- System startup ---- Feb 15 20:05:52 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:52 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:52 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:52 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:52 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:53 volumio-primo volumio[10703]: info: MYVOLUMIO Environment detected Feb 15 20:05:53 volumio-primo volumio[10703]: info: Plugin folders cleanup Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning into folder /volumio/app/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category audio_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category miscellanea Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category music_service Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category plugins.json Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category system_controller Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category user_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning into folder /data/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category RoonBridge Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category audio_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category music_service Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category system_controller Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category system_hardware Feb 15 20:05:53 volumio-primo volumio[10703]: info: Scanning category user_interface Feb 15 20:05:53 volumio-primo volumio[10703]: info: Plugin folders cleanup completed Feb 15 20:05:53 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:53 volumio-primo volumio[10703]: info: ----- Core plugins startup ---- Feb 15 20:05:53 volumio-primo volumio[10703]: info: ------------------------------------------- Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugins from folder /volumio/app/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Adding plugin upnp to MyMusic Plugins Feb 15 20:05:53 volumio-primo volumio[10703]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 15 20:05:53 volumio-primo volumio[10703]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugins from folder /data/plugins/ Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "system"... Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "ampswitch"... Feb 15 20:05:53 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin ampswitch Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "appearance"... Feb 15 20:05:53 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:53 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:53 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:53 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:53 volumio-primo volumio[10703]: info: Loading plugin "network"... Feb 15 20:05:53 volumio-primo volumio[10703]: info: Refreshing Cached IP Addresses Feb 15 20:05:54 volumio-primo sudo[10730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 20:05:54 volumio-primo sudo[10732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "services"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "alsa_controller"... Feb 15 20:05:54 volumio-primo sudo[10730]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:54 volumio-primo sudo[10732]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:54 volumio-primo sudo[10732]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:54 volumio-primo sudo[10730]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:54 volumio-primo sudo[10744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 15 20:05:54 volumio-primo sudo[10744]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "wizard"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "networkfs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Starting Udev Watcher for removable devices Feb 15 20:05:54 volumio-primo volumio[10703]: info: Ignoring mount for partition: BOOT Feb 15 20:05:54 volumio-primo volumio[10703]: info: Ignoring mount for partition: volumio Feb 15 20:05:54 volumio-primo volumio[10703]: info: Ignoring mount for partition: volumio_data Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "volumio_command_line_client"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "upnp"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: [1739649954187] Starting Upmpd Daemon Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "my_music"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "mpd"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "upnp_browser"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "alarm-clock"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "airplay_emulation"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "last_100"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "webradio"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "i2s_dacs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: I2S DAC not set, start Auto-detection Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "volumiodiscovery"... Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo volumio[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 20:05:54 volumio-primo node[10703]: *** WARNING *** For more information see Feb 15 20:05:54 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 15 20:05:54 volumio-primo volumio[10703]: info: Discovery: Started advertising with name: Volumio primo Feb 15 20:05:54 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "outputs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "albumart"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Plugin example_plugin is not enabled Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "inputs"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "updater_comm"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Plugin mpdemulation is not enabled Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "rest_api"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "websocket"... Feb 15 20:05:54 volumio-primo volumio[10703]: info: Starting Socket.io Server version 2.3.0 Feb 15 20:05:54 volumio-primo volumio[10703]: info: Loading plugin "fusiondsp"... Feb 15 20:05:54 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:54 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:54 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:54 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:55 volumio-primo volumio[10703]: Forking 3 albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin fusiondsp Feb 15 20:05:55 volumio-primo volumio[10703]: info: Loading plugin "pandora"... Feb 15 20:05:55 volumio-primo sudo[10744]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:55 volumio-primo volumio[10703]: Starting albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: Starting albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: Starting albumart workers Feb 15 20:05:55 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin pandora Feb 15 20:05:55 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:55 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:55 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:55 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:55 volumio-primo volumio[10703]: info: Loading plugin "ir_controller"... Feb 15 20:05:55 volumio-primo volumio[10703]: info: Applying required configuration parameters for plugin ir_controller Feb 15 20:05:56 volumio-primo volumio[10703]: info: Loading plugin "rotaryencoder2"... Feb 15 20:05:56 volumio-primo volumiologrotate[541]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 15 20:05:56 volumio-primo volumiologrotate[541]: ls: cannot access 'PRIMO': No such file or directory Feb 15 20:05:56 volumio-primo volumio[10703]: info: Loading plugin "Systeminfo"... Feb 15 20:05:56 volumio-primo volumio[10703]: info: Loading i18n strings for locale it Feb 15 20:05:56 volumio-primo volumio[10703]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:05:56 volumio-primo volumio[10703]: Updating browse sources language Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:56 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:56] [connect] Successful connection Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::initPlayerControls Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: Express server listening on port 3000 Feb 15 20:05:56 volumio-primo volumio[10703]: [Metrics] WebUI: 4s 25.87ms Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::resetVolumioState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::getcurrentVolume Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:56 volumio-primo volumio[10703]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 15 20:05:56 volumio-primo volumio[10703]: info: Completed loading Core Plugins Feb 15 20:05:56 volumio-primo volumio[10703]: info: Preparing to generate the ALSA configuration file Feb 15 20:05:56 volumio-primo volumio[10703]: info: Volumio Network Manager: Network status updated: 3 Feb 15 20:05:56 volumio-primo volumio[10703]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:05:56 volumio-primo volumio[10703]: info: Reading ALSA contributions from plugins. Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 1 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 2 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 4 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.246 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Feb 15 20:05:56 volumio-primo volumio-remote-updater[539]: [2025-02-15 20:05:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739649956 101 Feb 15 20:05:56 volumio-primo volumio[10703]: 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: 9 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to 192.168.11.170 from 192.168.11.225 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 15 20:05:56 volumio-primo volumio[10703]: info: Reloading queue from file Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetQueue Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::getQueue Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getQueue Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:56 volumio-primo volumio[10703]: info: Listing playlists Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 20:05:56 volumio-primo volumio[10703]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:05:56 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:56 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:56 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:56 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::updateTrackBlock Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrackBlock Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::setRepeat true single undefined Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::setRandom false Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 20:05:56 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:56 volumio-primo volumio[10703]: info: Setting Device type: Tinkerboard Feb 15 20:05:57 volumio-primo volumio[10703]: info: VolumeController:: Volume=65 Mute =false Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreStateMachine::pushState Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushState Feb 15 20:05:57 volumio-primo volumio[10703]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:05:57 volumio-primo volumio[10703]: info: Output device has changed, restarting MPD Feb 15 20:05:57 volumio-primo volumio[10703]: info: Output device has changed, restarting Shairport Sync Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo sudo[10899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:05:57 volumio-primo sudo[10899]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo sudo[10902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:05:57 volumio-primo sudo[10899]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo sudo[10902]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo volumio[10703]: info: ___________ START PLUGINS ___________ Feb 15 20:05:57 volumio-primo systemd[1]: Stopping Music Player Daemon... Feb 15 20:05:57 volumio-primo kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Feb 15 20:05:57 volumio-primo volumio[10703]: info: ControllerMpd::onStart: Initializing MPD Feb 15 20:05:57 volumio-primo volumio[10703]: info: Creating MPD Configuration file Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957421] CoreMusicLibrary::Adding element Server multimediali Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo sudo[10925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:05:57 volumio-primo sudo[10925]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo sudo[10928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo sudo[10925]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:05:57 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:05:57 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:05:57 volumio-primo sudo[10928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957507] CoreMusicLibrary::Adding element Last_100 Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 15 20:05:57 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:05:57 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957511] CoreMusicLibrary::Adding element Webradio Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:57 volumio-primo volumio[10703]: info: Initializing BBC Radios Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: Loading i18n strings for locale it Feb 15 20:05:57 volumio-primo sudo[10948]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:05:57 volumio-primo volumio[10703]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: FusionDsp - mixtype--------------------- Hardware Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957580] CoreMusicLibrary::Adding element Pandora Radio Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 20:05:57 volumio-primo volumio[10703]: Cannot find translation for source Pandora Radio Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957583] ControllerPandora::initializeMQTT Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957583] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Feb 15 20:05:57 volumio-primo sudo[10948]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo volumio[10703]: info: Loading i18n strings for locale it Feb 15 20:05:57 volumio-primo volumio[10703]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Feb 15 20:05:57 volumio-primo sudo[10948]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 20:05:57 volumio-primo volumio[10703]: info: Volumio Calling Home Feb 15 20:05:57 volumio-primo volumio[10703]: info: Preparing to generate the ALSA configuration file Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957684] ControllerPandora::initialSetup Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957686] ControllerPandora::PandoraHandler::init Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957687] ControllerPandora::PandoraHandler::loadErrorCodes Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957749] [Pandora] PandoraHandler::setMaxStationTracks: 16 Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957750] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957752] ControllerPandora::flushPandora Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::getQueue Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::clearAddPlayQueue Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::saveQueue Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::serviceClearAddPlayTracks Feb 15 20:05:57 volumio-primo volumio[10703]: error: WARNING: No clearAddPlayTracks method for service webradio Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioPushQueue Feb 15 20:05:57 volumio-primo sudo[10974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Feb 15 20:05:57 volumio-primo volumio[10703]: info: [1739649957760] ControllerPandora::validateAndSetAccountOptions Feb 15 20:05:57 volumio-primo volumio[10703]: error: [1739649957761] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Feb 15 20:05:57 volumio-primo sudo[10974]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:57 volumio-primo sudo[10974]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:57 volumio-primo volumio[10703]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:05:57 volumio-primo volumio[10703]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Feb 15 20:05:57 volumio-primo volumio[10703]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 15 20:05:57 volumio-primo volumio[10703]: info: Reading ALSA contributions from plugins. Feb 15 20:05:57 volumio-primo volumio[10703]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Feb 15 20:05:57 volumio-primo volumio[10703]: info: MPD Permissions set Feb 15 20:05:57 volumio-primo volumio[10703]: info: MPD Permissions set Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:57 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:57 volumio-primo volumio[10703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 15 20:05:57 volumio-primo volumio[10703]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:05:57 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:57 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:57 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:57 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:57 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] CurState: stop PrevState: na Feb 15 20:05:57 volumio-primo volumio[10703]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Feb 15 20:05:57 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:57 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:58 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:58 volumio-primo sudo[10996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo sudo[10998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:58 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:58 volumio-primo sudo[10996]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::volumioGetState Feb 15 20:05:58 volumio-primo volumio[10703]: info: CorePlayQueue::getTrack 0 Feb 15 20:05:58 volumio-primo sudo[10998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo volumio[10703]: info: ir_controller: LIRC correctly updated. Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 20:05:58 volumio-primo sudo[10996]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11004]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo volumio[10703]: info: Asound.conf file unchanged, so no further update is needed Feb 15 20:05:58 volumio-primo volumio[10703]: info: Output device has changed, restarting MPD Feb 15 20:05:58 volumio-primo sudo[10998]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo volumio[10703]: info: Output device has changed, restarting Shairport Sync Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11004]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo sudo[11025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 15 20:05:58 volumio-primo sudo[11027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 15 20:05:58 volumio-primo sudo[11025]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo sudo[11025]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo sudo[11027]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo volumio[10703]: info: Volumio called home Feb 15 20:05:58 volumio-primo volumio[10703]: info: MPD Permissions set Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: Error adding Membership: Error: addMembership EINVAL Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 15 20:05:58 volumio-primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 15 20:05:58 volumio-primo systemd[1]: mpd.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Music Player Daemon. Feb 15 20:05:58 volumio-primo systemd[1]: Starting Music Player Daemon... Feb 15 20:05:58 volumio-primo volumio[10703]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 20:05:58 volumio-primo volumio[10703]: info: Starting Shairport Sync Feb 15 20:05:58 volumio-primo sudo[11059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 20:05:58 volumio-primo sudo[11059]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 20:05:58 volumio-primo systemd[1]: shairport-sync.service: Succeeded. Feb 15 20:05:58 volumio-primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11056]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 15 20:05:58 volumio-primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 20:05:58 volumio-primo sudo[11059]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo sudo[11056]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 20:05:58 volumio-primo sudo[11056]: pam_unix(sudo:session): session closed for user root Feb 15 20:05:58 volumio-primo volumio[10703]: info: Shairport-Sync Started Feb 15 20:05:58 volumio-primo volumio[10703]: info: [ASDebug] Togle GPIO: OFF Feb 15 20:05:58 volumio-primo volumio[10703]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:58 volumio-primo volumio[10703]: Error: EPERM: operation not permitted, write Feb 15 20:05:58 volumio-primo volumio[10703]: at Object.writeSync (fs.js:737:3) Feb 15 20:05:58 volumio-primo volumio[10703]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Feb 15 20:05:58 volumio-primo volumio[10703]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Feb 15 20:05:58 volumio-primo volumio[10703]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Feb 15 20:05:58 volumio-primo volumio[10703]: at listOnTimeout (internal/timers.js:557:17) Feb 15 20:05:58 volumio-primo volumio[10703]: at processTimers (internal/timers.js:500:7) { Feb 15 20:05:58 volumio-primo volumio[10703]: errno: -1, Feb 15 20:05:58 volumio-primo volumio[10703]: syscall: 'write', Feb 15 20:05:58 volumio-primo volumio[10703]: code: 'EPERM' Feb 15 20:05:58 volumio-primo volumio[10703]: } Feb 15 20:05:58 volumio-primo volumio[10703]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 20:05:58 volumio-primo lircd[1837]: lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:58 volumio-primo lircd[1837]: lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:58 volumio-primo lircd-0.10.1[1837]: Error: could not get file information for /dev/lirc0 Feb 15 20:05:58 volumio-primo lircd-0.10.1[1837]: default_init(): No such file or directory Feb 15 20:05:59 volumio-primo sudo[11090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 20:04 Feb 15 20:05:59 volumio-primo sudo[11090]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:11:38 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="932fc3546f7cc8faa2b4d349036752dd"