-- Logs begin at Wed 2021-10-06 10:34:49 UTC, end at Wed 2021-10-06 10:41:15 UTC. -- Oct 06 10:40:18 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Oct 06 10:40:18 volumio volumio[747]: info: Saving new wireless network Oct 06 10:40:18 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 06 10:40:18 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:18 volumio systemd[1]: Starting Wireless Services... Oct 06 10:40:18 volumio sudo[2386]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Oct 06 10:40:18 volumio sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:18 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:20 volumio wireless.js[2396]: Cleaning previous... Oct 06 10:40:21 volumio systemd[1]: Stopping dhcpd.service... Oct 06 10:40:21 volumio systemd[1]: Stopping Host and Network Name Lookups. Oct 06 10:40:21 volumio systemd[1]: Stopped target Host and Network Name Lookups. Oct 06 10:40:21 volumio dnsmasq[1931]: exiting on receipt of SIGTERM Oct 06 10:40:21 volumio systemd[1]: Stopping dnsmasq.service... Oct 06 10:40:21 volumio systemd[1]: Stopped dnsmasq.service. Oct 06 10:40:21 volumio systemd[1]: Stopped dhcpd.service. Oct 06 10:40:21 volumio systemd[1]: Stopping hotspot.service... Oct 06 10:40:26 volumio hotspot[2417]: Killing Hostapd Oct 06 10:40:26 volumio sudo[2434]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Oct 06 10:40:26 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:26 volumio hotspot[2417]: hostapd: no process found Oct 06 10:40:26 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:26 volumio hotspot[2417]: Killing Dhcpd Oct 06 10:40:26 volumio sudo[2443]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Oct 06 10:40:26 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:26 volumio hotspot[2417]: dhcpd: no process found Oct 06 10:40:26 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:26 volumio systemd[1]: hotspot.service: control process exited, code=exited status=1 Oct 06 10:40:26 volumio systemd[1]: Stopped hotspot.service. Oct 06 10:40:26 volumio systemd[1]: Unit hotspot.service entered failed state. Oct 06 10:40:26 volumio sudo[2454]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 06 10:40:26 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:26 volumio avahi-daemon[543]: Withdrawing address record for 192.168.211.1 on wlan0. Oct 06 10:40:26 volumio avahi-daemon[543]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 06 10:40:26 volumio avahi-daemon[543]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 06 10:40:26 volumio volumio[747]: info: mDNS: A device disapperared from network Oct 06 10:40:26 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:26 volumio volumio[747]: info: mDNS: Device volumio disapperared from network Oct 06 10:40:27 volumio sudo[2462]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 06 10:40:27 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:28 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:28 volumio wireless.js[2396]: Stopped aP Oct 06 10:40:28 volumio wireless.js[2396]: Start wireless flow Oct 06 10:40:28 volumio wireless.js[2396]: Stopped hotspot (if there).. Oct 06 10:40:28 volumio sudo[2479]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 06 10:40:28 volumio sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:28 volumio sudo[2479]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:28 volumio sudo[2489]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 06 10:40:28 volumio sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:28 volumio sudo[2489]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:28 volumio wireless.js[2396]: DHCP IP Oct 06 10:40:28 volumio wireless.js[2396]: Start ap Oct 06 10:40:28 volumio wpa_supplicant[2497]: Successfully initialized wpa_supplicant Oct 06 10:40:28 volumio sudo[2498]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 06 10:40:28 volumio sudo[2498]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:28 volumio dhcpcd[2503]: version 6.0.5 starting Oct 06 10:40:28 volumio dhcpcd[2503]: wlan0: waiting for carrier Oct 06 10:40:29 volumio wpa_supplicant[2500]: wlan0: Trying to associate with SSID 'SPWH_H33_058D1E' Oct 06 10:40:29 volumio wireless.js[2396]: trying... Oct 06 10:40:29 volumio sudo[2517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:40:29 volumio sudo[2517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:29 volumio sudo[2517]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:29 volumio wpa_supplicant[2500]: wlan0: Associated with 4c:50:77:05:8d:1e Oct 06 10:40:30 volumio wpa_supplicant[2500]: wlan0: WPA: Key negotiation completed with 4c:50:77:05:8d:1e [PTK=CCMP GTK=TKIP] Oct 06 10:40:30 volumio wpa_supplicant[2500]: wlan0: CTRL-EVENT-CONNECTED - Connection to 4c:50:77:05:8d:1e completed [id=0 id_str=] Oct 06 10:40:30 volumio dhcpcd[2503]: wlan0: carrier acquired Oct 06 10:40:30 volumio dhcpcd[2503]: wlan0: soliciting a DHCP lease Oct 06 10:40:30 volumio dhcpcd[2503]: wlan0: offered 192.168.100.166 from 192.168.100.1 Oct 06 10:40:30 volumio dhcpcd[2503]: wlan0: leased 192.168.100.166 for 604800 seconds Oct 06 10:40:30 volumio avahi-daemon[543]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.166. Oct 06 10:40:30 volumio avahi-daemon[543]: New relevant interface wlan0.IPv4 for mDNS. Oct 06 10:40:30 volumio avahi-daemon[543]: Registering new address record for 192.168.100.166 on wlan0.IPv4. Oct 06 10:40:30 volumio dhcpcd[2503]: wlan0: adding host route to 192.168.100.166 via 127.0.0.1 Oct 06 10:40:30 volumio dhcpcd[2503]: wlan0: adding route to 192.168.100.0/24 Oct 06 10:40:30 volumio dhcpcd[2503]: wlan0: adding default route via 192.168.100.1 Oct 06 10:40:30 volumio dhcpcd[2503]: forked to background, child pid 2557 Oct 06 10:40:30 volumio sudo[2498]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:30 volumio wireless.js[2396]: trying... Oct 06 10:40:31 volumio sudo[2564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:40:31 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:31 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:31 volumio wireless.js[2396]: Connected to: ----SPWH_H33_058D1E Oct 06 10:40:31 volumio wireless.js[2396]: ---- Oct 06 10:40:31 volumio volumio[747]: Discovery: adding 8d773884-e3c1-4708-9643-f0e0f1e352df Oct 06 10:40:31 volumio volumio[747]: info: mDNS: Found device Volumio Oct 06 10:40:31 volumio volumio[747]: info: CoreCommandRouter::volumioGetState Oct 06 10:40:31 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:40:31 volumio sudo[2573]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:40:31 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:31 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:31 volumio wireless.js[2396]: ... joined AP, wlan0 IPv4 is 192.168.100.166, ipV6 is undefined Oct 06 10:40:31 volumio wireless.js[2396]: It's done! AP Oct 06 10:40:31 volumio systemd[1]: Started Wireless Services. Oct 06 10:40:31 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:31 volumio ntpd[597]: Listen normally on 5 wlan0 192.168.100.166 UDP 123 Oct 06 10:40:31 volumio ntpd[597]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=2, sent=2, dropped=0, active_time=100 secs Oct 06 10:40:31 volumio ntpd[597]: 192.168.211.1 interface 192.168.211.1 -> (none) Oct 06 10:40:31 volumio ntpd[597]: peers refreshed Oct 06 10:40:36 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Oct 06 10:40:36 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Oct 06 10:40:36 volumio sudo[2596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:40:36 volumio sudo[2596]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:36 volumio sudo[2596]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:36 volumio sudo[2605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 10:40:36 volumio sudo[2605]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:36 volumio sudo[2605]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:41 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Oct 06 10:40:41 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Oct 06 10:40:41 volumio volumio[747]: info: Refreshing Cached IP Addresses Oct 06 10:40:41 volumio sudo[2626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Oct 06 10:40:41 volumio sudo[2626]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:41 volumio sudo[2628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 10:40:41 volumio sudo[2626]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:41 volumio sudo[2628]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:42 volumio sudo[2634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:40:42 volumio sudo[2634]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:42 volumio sudo[2628]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:42 volumio sudo[2634]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:52 volumio sudo[2658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 10:40:52 volumio sudo[2658]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:52 volumio sudo[2660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:40:52 volumio sudo[2658]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:52 volumio sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:52 volumio sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 06 10:40:52 volumio sudo[2660]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:52 volumio sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:52 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 06 10:40:52 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 06 10:40:52 volumio sudo[2666]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:52 volumio volumio[747]: info: Upmpdcli Daemon Started Oct 06 10:40:53 volumio nmbd[714]: [2021/10/06 10:40:53.119776, 0] ../source3/libsmb/nmblib.c:873(send_udp) Oct 06 10:40:53 volumio nmbd[714]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Oct 06 10:40:54 volumio sudo[2703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 10:40:55 volumio sudo[2705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:40:55 volumio sudo[2703]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:55 volumio sudo[2705]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:55 volumio sudo[2705]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:55 volumio sudo[2703]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::volumioGetState Oct 06 10:40:56 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::volumioGetVisibleSources Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 10:40:56 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 06 10:40:56 volumio sudo[2733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 06 10:40:56 volumio sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:57 volumio sudo[2738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 10:40:57 volumio sudo[2738]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:57 volumio sudo[2733]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:57 volumio sudo[2738]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:57 volumio sudo[2745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 10:40:57 volumio sudo[2745]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:57 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 06 10:40:57 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 06 10:40:57 volumio sudo[2745]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:57 volumio sudo[2760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 06 10:40:57 volumio sudo[2760]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:57 volumio sudo[2760]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:57 volumio sudo[2777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 10:40:57 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:57 volumio sudo[2783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:40:57 volumio sudo[2777]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:57 volumio sudo[2783]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:57 volumio sudo[2783]: pam_unix(sudo:session): session closed for user root Oct 06 10:40:58 volumio sudo[2794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 06 10:40:58 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:40:58 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root Oct 06 10:41:00 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Oct 06 10:41:06 volumio volumio[747]: verbose: CURRENT POSITION 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState stateService play Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState currentStatus stop Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Oct 06 10:41:06 volumio volumio[747]: verbose: CURRENT POSITION 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState stateService play Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:41:06 volumio volumio[747]: info: Received an update from plugin. extracting info from payload Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Oct 06 10:41:06 volumio volumio[747]: verbose: CURRENT POSITION 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState stateService play Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:41:06 volumio volumio[747]: info: Received an update from plugin. extracting info from payload Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":0,"duration":0,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} Oct 06 10:41:06 volumio volumio[747]: verbose: CURRENT POSITION 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState stateService play Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::syncState currentStatus play Oct 06 10:41:06 volumio volumio[747]: info: Received an update from plugin. extracting info from payload Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:06 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:06 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:06 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:07 volumio shairport-sync[1366]: Failed to create secure directory (/home/shairport-sync/.config/pulse): No such file or directory Oct 06 10:41:07 volumio shairport-sync[1366]: % Tot al% T o t%al R e c e%i vReed c%e Xferd Average Speed Time Time Time Current Oct 06 10:41:07 volumio shairport-sync[1366]: Dload Upload Total Spent Left Speed Oct 06 10:41:07 volumio shairport-sync[1366]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0ived % Xferd Average Speed Time Time Time Current Oct 06 10:41:07 volumio shairport-sync[1366]: Dload Upload Total Spent Left Speed Oct 06 10:41:07 volumio volumio[747]: info: Airplay started streaming, receiving metadatas Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::volumioStop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::stop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: verbose: UNSET VOLATILE Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::stPlaybackTimer Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::updateTrackBlock Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrackBlock Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::serviceStop Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:07 volumio volumio[747]: info: ControllerMpd::stop Oct 06 10:41:07 volumio volumio[747]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: info: Airplay started streaming, receiving metadatas Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::volumioStop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::stop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: verbose: UNSET VOLATILE Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: info: Airplay started streaming, receiving metadatas Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::volumioStop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::stop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: verbose: UNSET VOLATILE Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: info: Airplay started streaming, receiving metadatas Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::volumioStop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::stop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: verbose: UNSET VOLATILE Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: info: sendMpdCommand stop took 174 milliseconds Oct 06 10:41:07 volumio volumio[747]: info: Oct 06 10:41:07 volumio volumio[747]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 06 10:41:07 volumio volumio[747]: info: Airplay playback start Oct 06 10:41:07 volumio volumio[747]: info: CorePlayQueue::getTrack 0 Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::volumioStop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::stop Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio volumio[747]: verbose: UNSET VOLATILE Oct 06 10:41:07 volumio volumio[747]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 10:41:07 volumio shairport-sync[1366]: [155B blob data] Oct 06 10:41:07 volumio volumio[747]: info: Oct 06 10:41:07 volumio volumio[747]: ---------------------------- Client requests AirplayActive Oct 06 10:41:07 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Oct 06 10:41:07 volumio shairport-sync[1366]: [143B blob data] Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:08 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:08 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:08 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:08 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:08 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:08 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::servicePushState Oct 06 10:41:09 volumio volumio[747]: info: CoreStateMachine::pushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::volumioPushState Oct 06 10:41:09 volumio volumio[747]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 10:41:10 volumio volumio[747]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 10:41:10 volumio volumio[747]: { Error: certificate is not yet valid Oct 06 10:41:10 volumio volumio[747]: at TLSSocket. (_tls_wrap.js:1105:38) Oct 06 10:41:10 volumio volumio[747]: at emitNone (events.js:106:13) Oct 06 10:41:10 volumio volumio[747]: at TLSSocket.emit (events.js:208:7) Oct 06 10:41:10 volumio volumio[747]: at TLSSocket._finishInit (_tls_wrap.js:639:8) Oct 06 10:41:10 volumio volumio[747]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:469:38) code: 'CERT_NOT_YET_VALID' } Oct 06 10:41:10 volumio volumio[747]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 10:41:15 volumio sudo[2892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2021-10-06 10:40 Oct 06 10:41:15 volumio sudo[2892]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"