Hi vince, thank's a lot for your fast reply! I enabled debugging, restarted Pi with only WLAN (ethernet not connected). I'm not sure what you mean, with when "it" starts to boot. Are you refering to Pi or to weewex? Below the boot process of weewx:
Feb 14 11:12:03 raspberrypi systemd[1]: Starting LSB: weewx weather system... Feb 14 11:12:03 raspberrypi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 14 11:12:03 raspberrypi cron[274]: (CRON) INFO (pidfile fd = 3) Feb 14 11:12:03 raspberrypi alsactl[278]: alsactl 1.1.8 daemon started Feb 14 11:12:03 raspberrypi systemd[1]: Starting dhcpcd on all interfaces... Feb 14 11:12:03 raspberrypi systemd[1]: man-db.timer: Not using persistent file timestamp Wed 2019-11-06 20:14:03 CET as it is in the future. Feb 14 11:12:03 raspberrypi systemd[1]: Started Daily man-db regeneration. Feb 14 11:12:03 raspberrypi systemd[1]: Starting Check for Raspberry Pi EEPROM updates... Feb 14 11:12:03 raspberrypi systemd[1]: Started D-Bus System Message Bus. Feb 14 11:12:03 raspberrypi thd[267]: Found socket passed from systemd Feb 14 11:12:03 raspberrypi cron[274]: (CRON) INFO (Running @reboot jobs) Feb 14 11:12:03 raspberrypi systemd[1]: Starting WPA supplicant... Feb 14 11:12:03 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 14 11:12:03 raspberrypi systemd[1]: logrotate.timer: Not using persistent file timestamp Wed 2019-11-06 20:14:03 CET as it is in the future. Feb 14 11:12:03 raspberrypi systemd[1]: Started Daily rotation of log files. Feb 14 11:12:03 raspberrypi systemd[1]: Starting rng-tools.service... Feb 14 11:12:03 raspberrypi systemd[1]: apt-daily.timer: Not using persistent file timestamp Wed 2019-11-06 20:14:03 CET as it is in the future. Feb 14 11:12:03 raspberrypi systemd[1]: Started Daily apt download activities. Feb 14 11:12:03 raspberrypi systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Wed 2019-11-06 20:14:03 CET as it is in the future. Feb 14 11:12:03 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities. Feb 14 11:12:03 raspberrypi systemd[1]: Reached target Timers. Feb 14 11:12:03 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon. Feb 14 11:12:03 raspberrypi systemd[1]: Started Save/Restore Sound Card State. Feb 14 11:12:03 raspberrypi systemd[1]: Reached target Sound Card. Feb 14 11:12:03 raspberrypi rng-tools[318]: Starting Hardware RNG entropy gatherer daemon: rngd. Feb 14 11:12:03 raspberrypi rngd[348]: rngd 2-unofficial-mt.14 starting up... Feb 14 11:12:03 raspberrypi dhcpcd[289]: dev: loaded udev Feb 14 11:12:03 raspberrypi rngd[348]: entropy feed to the kernel ready Feb 14 11:12:03 raspberrypi rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd. [v8.1901.0] Feb 14 11:12:03 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.1901.0" x-pid="268" x-info="https://www.rsyslog.com";] start Feb 14 11:12:03 raspberrypi avahi-daemon[316]: Found user 'avahi' (UID 108) and group 'avahi' (GID 113). Feb 14 11:12:03 raspberrypi avahi-daemon[316]: Successfully dropped root privileges. Feb 14 11:12:03 raspberrypi avahi-daemon[316]: avahi-daemon 0.7 starting up. Feb 14 11:12:03 raspberrypi kernel: [ 9.578224] 8021q: 802.1Q VLAN Support v1.8 Feb 14 11:12:03 raspberrypi dhcpcd[289]: forked to background, child pid 369 Feb 14 11:12:03 raspberrypi udisksd[276]: udisks daemon version 2.8.1 starting Feb 14 11:12:03 raspberrypi dphys-swapfile[273]: want /var/swap=100MByte, checking existing: keeping it Feb 14 11:12:03 raspberrypi systemd[1]: Started System Logging Service. Feb 14 11:12:03 raspberrypi systemd[1]: Started Raise network interfaces. Feb 14 11:12:03 raspberrypi systemd[1]: Started Check for v3d driver. Feb 14 11:12:03 raspberrypi systemd[1]: Started dhcpcd on all interfaces. Feb 14 11:12:03 raspberrypi systemd[1]: Started Check for Raspberry Pi EEPROM updates. Feb 14 11:12:03 raspberrypi systemd[1]: Started rng-tools.service. Feb 14 11:12:03 raspberrypi avahi-daemon[316]: Successfully called chroot(). Feb 14 11:12:03 raspberrypi avahi-daemon[316]: Successfully dropped remaining capabilities. Feb 14 11:12:03 raspberrypi avahi-daemon[316]: No service file found in /etc/avahi/services. Feb 14 11:12:03 raspberrypi avahi-daemon[316]: Network interface enumeration completed. Feb 14 11:12:03 raspberrypi avahi-daemon[316]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 2066605770. Feb 14 11:12:03 raspberrypi wpa_supplicant[315]: Successfully initialized wpa_supplicant Feb 14 11:12:03 raspberrypi udisksd[276]: failed to load module crypto: libbd_crypto.so.2: cannot open shared object file: No such file or directory Feb 14 11:12:03 raspberrypi udisksd[276]: failed to load module mdraid: libbd_mdraid.so.2: cannot open shared object file: No such file or directory Feb 14 11:12:03 raspberrypi dhcpcd-run-hooks[397]: wlan0: starting wpa_supplicant Feb 14 11:12:03 raspberrypi udisksd[276]: Failed to load the 'mdraid' libblockdev plugin Feb 14 11:12:03 raspberrypi udisksd[276]: Failed to load the 'crypto' libblockdev plugin Feb 14 11:12:03 raspberrypi kernel: [ 9.919713] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k FS Feb 14 11:12:03 raspberrypi dbus-daemon[298]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.4' (uid=0 pid=276 comm="/usr/lib/udisks2/udisksd ") Feb 14 11:12:03 raspberrypi systemd[1]: Started dphys-swapfile - set up, mount/unmount, and delete a swap file. Feb 14 11:12:03 raspberrypi systemd[1]: Started Login Service. Feb 14 11:12:03 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 14 11:12:03 raspberrypi systemd[1]: Started WPA supplicant. Feb 14 11:12:03 raspberrypi raspi-config[277]: Checking if shift key is held down:Error opening '/dev/input/event*': No such file or directory Feb 14 11:12:03 raspberrypi raspi-config[277]: No. Switching to ondemand scaling governor. Feb 14 11:12:03 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed). Feb 14 11:12:03 raspberrypi systemd[1]: Starting Authorization Manager... Feb 14 11:12:03 raspberrypi systemd[1]: Reached target Network. Feb 14 11:12:03 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server... Feb 14 11:12:04 raspberrypi systemd[1]: Starting A high performance web server and a reverse proxy server... Feb 14 11:12:04 raspberrypi systemd[1]: Starting Permit User Sessions... Feb 14 11:12:04 raspberrypi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 14 11:12:04 raspberrypi systemd[1]: Starting Network Time Service... Feb 14 11:12:04 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility... Feb 14 11:12:04 raspberrypi kernel: [ 10.075327] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Feb 14 11:12:04 raspberrypi kernel: [ 10.075343] brcmfmac: power management disabled Feb 14 11:12:04 raspberrypi systemd[1]: Started Permit User Sessions. Feb 14 11:12:04 raspberrypi systemd[1]: Starting Light Display Manager... Feb 14 11:12:04 raspberrypi systemd[1]: Started /etc/rc.local Compatibility. Feb 14 11:12:04 raspberrypi systemd[1]: Starting Hold until boot process finishes up... Feb 14 11:12:04 raspberrypi ntpd[415]: ntpd [email protected] (1): Starting Feb 14 11:12:04 raspberrypi ntpd[415]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 110:115 Feb 14 11:12:04 raspberrypi systemd[1]: Started Network Time Service. Feb 14 11:12:04 raspberrypi ntpd[435]: proto: precision = 0.729 usec (-20) Feb 14 11:12:04 raspberrypi ntpd[435]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 14 11:12:04 raspberrypi ntpd[435]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2020-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 14 11:12:04 raspberrypi ntpd[435]: Listen and drop on 0 v6wildcard [::]:123 Feb 14 11:12:04 raspberrypi ntpd[435]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 14 11:12:04 raspberrypi ntpd[435]: Listen normally on 2 lo 127.0.0.1:123 Feb 14 11:12:04 raspberrypi ntpd[435]: Listen normally on 3 lo [::1]:123 Feb 14 11:12:04 raspberrypi ntpd[435]: Listening on routing socket on fd #20 for interface updates Feb 14 11:12:04 raspberrypi ntpd[435]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 11:12:04 raspberrypi ntpd[435]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 14 11:12:04 raspberrypi systemd[1]: Started OpenBSD Secure Shell server. Feb 14 11:12:04 raspberrypi dhcpcd[369]: eth0: waiting for carrier Feb 14 11:12:04 raspberrypi dhcpcd[369]: wlan0: waiting for carrier Feb 14 11:12:04 raspberrypi dhcpcd[369]: wlan0: carrier acquired Feb 14 11:12:04 raspberrypi kernel: [ 10.578109] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Feb 14 11:12:04 raspberrypi kernel: [ 10.578474] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Feb 14 11:12:04 raspberrypi dhcpcd[369]: DUID 00:01:00:01:25:1e:c3:69:b8:27:eb:2f:cf:9a Feb 14 11:12:04 raspberrypi dhcpcd[369]: wlan0: IAID eb:2f:cf:9a Feb 14 11:12:04 raspberrypi dhcpcd[369]: wlan0: adding address fe80::bc93:b7e:c8b5:47f Feb 14 11:12:04 raspberrypi dhcpcd[369]: wlan0: carrier lost Feb 14 11:12:04 raspberrypi avahi-daemon[316]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::bc93:b7e:c8b5:47f. Feb 14 11:12:04 raspberrypi avahi-daemon[316]: New relevant interface wlan0.IPv6 for mDNS. Feb 14 11:12:04 raspberrypi avahi-daemon[316]: Registering new address record for fe80::bc93:b7e:c8b5:47f on wlan0.*. Feb 14 11:12:04 raspberrypi dhcpcd[369]: wlan0: deleting address fe80::bc93:b7e:c8b5:47f Feb 14 11:12:04 raspberrypi avahi-daemon[316]: Withdrawing address record for fe80::bc93:b7e:c8b5:47f on wlan0. Feb 14 11:12:04 raspberrypi avahi-daemon[316]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::bc93:b7e:c8b5:47f. Feb 14 11:12:04 raspberrypi avahi-daemon[316]: Interface wlan0.IPv6 no longer relevant for mDNS. Feb 14 11:12:05 raspberrypi dhcpcd[369]: wlan0: carrier acquired Feb 14 11:12:05 raspberrypi kernel: [ 11.634258] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Feb 14 11:12:05 raspberrypi dhcpcd[369]: wlan0: IAID eb:2f:cf:9a Feb 14 11:12:05 raspberrypi dhcpcd[369]: wlan0: adding address fe80::77f1:c446:55bf:f3a Feb 14 11:12:05 raspberrypi avahi-daemon[316]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::77f1:c446:55bf:f3a. Feb 14 11:12:05 raspberrypi avahi-daemon[316]: New relevant interface wlan0.IPv6 for mDNS. Feb 14 11:12:05 raspberrypi avahi-daemon[316]: Registering new address record for fe80::77f1:c446:55bf:f3a on wlan0.*. Feb 14 11:12:05 raspberrypi dhcpcd[369]: wlan0: probing address 192.168.0.46/24 Feb 14 11:12:06 raspberrypi dhcpcd[369]: wlan0: soliciting an IPv6 router Feb 14 11:12:06 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded. Feb 14 11:12:07 raspberrypi ntpd[435]: bind(23) AF_INET6 fe80::77f1:c446:55bf:f3a%3#123 flags 0x11 failed: Cannot assign requested address Feb 14 11:12:07 raspberrypi ntpd[435]: unable to create socket on wlan0 (4) for fe80::77f1:c446:55bf:f3a%3#123 Feb 14 11:12:07 raspberrypi ntpd[435]: failed to init interface for address fe80::77f1:c446:55bf:f3a%3 Feb 14 11:12:09 raspberrypi ntpd[435]: Listen normally on 5 wlan0 [fe80::77f1:c446:55bf:f3a%3]:123 Feb 14 11:12:09 raspberrypi ntpd[435]: new interface(s) found: waking up resolver Feb 14 11:12:11 raspberrypi dhcpcd[369]: wlan0: using static address 192.168.0.46/24 Feb 14 11:12:11 raspberrypi avahi-daemon[316]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.46. Feb 14 11:12:11 raspberrypi avahi-daemon[316]: New relevant interface wlan0.IPv4 for mDNS. Feb 14 11:12:11 raspberrypi dhcpcd[369]: wlan0: adding route to 192.168.0.0/24 Feb 14 11:12:11 raspberrypi avahi-daemon[316]: Registering new address record for 192.168.0.46 on wlan0.IPv4. Feb 14 11:12:11 raspberrypi dhcpcd[369]: wlan0: adding default route via 192.168.0.1 Feb 14 11:12:12 raspberrypi ntpd[435]: Listen normally on 6 wlan0 192.168.0.46:123 Feb 14 11:12:12 raspberrypi ntpd[435]: new interface(s) found: waking up resolver Feb 14 11:12:12 raspberrypi ntpd[435]: Soliciting pool server 81.94.123.17 Feb 14 11:12:12 raspberrypi ntpd[435]: Soliciting pool server 81.94.123.16 Feb 14 11:12:12 raspberrypi ntpd[435]: Soliciting pool server 62.202.141.23 Feb 14 11:12:12 raspberrypi ntpd[435]: Soliciting pool server 192.33.96.102 Feb 14 11:12:13 raspberrypi ntpd[435]: Soliciting pool server 195.186.4.101 Feb 14 11:12:13 raspberrypi ntpd[435]: Soliciting pool server 46.22.26.12 Feb 14 11:12:13 raspberrypi ntpd[435]: Soliciting pool server 212.51.144.44 Feb 14 11:12:13 raspberrypi ntpd[435]: Soliciting pool server 192.33.214.47 Feb 14 11:12:14 raspberrypi ntpd[435]: Soliciting pool server 185.134.196.169 Feb 14 11:12:14 raspberrypi ntpd[435]: Soliciting pool server 5.148.175.134 Feb 14 11:12:14 raspberrypi ntpd[435]: Soliciting pool server 31.3.135.232 Feb 14 11:12:14 raspberrypi ntpd[435]: Soliciting pool server 162.159.200.1 Feb 14 11:12:15 raspberrypi ntpd[435]: Soliciting pool server 213.251.52.217 Feb 14 11:12:15 raspberrypi ntpd[435]: Soliciting pool server 162.159.200.123 Feb 14 11:12:15 raspberrypi ntpd[435]: Soliciting pool server 217.147.208.1 Feb 14 11:12:16 raspberrypi ntpd[435]: Soliciting pool server 2606:4700:f1::1 Feb 14 11:12:16 raspberrypi ntpd[435]: Soliciting pool server 213.251.53.234 Feb 14 11:12:17 raspberrypi ntpd[435]: Soliciting pool server 130.60.204.10 Feb 14 11:12:18 raspberrypi ntpd[435]: Soliciting pool server 178.209.50.229 Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.4618f930 does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.550feae8 Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.46292162 does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.55cc064a Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.46272391 does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.554f42ae Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.462b1aba does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.55bcecae Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.460fabb4 does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.54255c58 Nov 6 22:52:00 raspberrypi systemd[1]: Starting Daily man-db regeneration... Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.46232cc7 does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.54a16ac5 Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.461cb86a does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.549d2aa6 Nov 6 22:52:00 raspberrypi ntpd[435]: receive: Unexpected origin timestamp 0xe00fbd83.46212997 does not match aorg 0000000000.00000000 from [email protected] xmt 0xe16dbf00.549e47bf Nov 6 22:52:00 raspberrypi dhcpcd[369]: wlan0: no IPv6 Routers available Nov 6 22:52:03 raspberrypi systemd[1]: Starting Rotate log files... Nov 6 22:52:03 raspberrypi systemd[1]: Starting Daily apt download activities... Nov 6 22:52:07 raspberrypi kernel: [ 32.614805] Bluetooth: Core ver 2.22 Nov 6 22:52:07 raspberrypi kernel: [ 32.614919] NET: Registered protocol family 31 Nov 6 22:52:07 raspberrypi kernel: [ 32.614925] Bluetooth: HCI device and connection manager initialized Nov 6 22:52:07 raspberrypi kernel: [ 32.616064] Bluetooth: HCI socket layer initialized Nov 6 22:52:07 raspberrypi kernel: [ 32.616080] Bluetooth: L2CAP socket layer initialized Nov 6 22:52:07 raspberrypi kernel: [ 32.616121] Bluetooth: SCO socket layer initialized Nov 6 22:52:09 raspberrypi udisksd[276]: Error initializing polkit authority: Error initializing authority: Error calling StartServiceByName for org.freedesktop.PolicyKit1: GDBus.Error:org.freedesktop.DBus.Error.TimedOut: Failed to activate service 'org.freedesktop.PolicyKit1': timed out (service_start_timeout=25000ms) (g-dbus-error-quark, 20) Nov 6 22:52:11 raspberrypi btuart[265]: bcm43xx_init Nov 6 22:52:11 raspberrypi btuart[265]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Nov 6 22:52:11 raspberrypi kernel: [ 36.613918] Bluetooth: HCI UART driver ver 2.3 Nov 6 22:52:11 raspberrypi kernel: [ 36.613928] Bluetooth: HCI UART protocol H4 registered Nov 6 22:52:11 raspberrypi kernel: [ 36.613999] Bluetooth: HCI UART protocol Three-wire (H5) registered Nov 6 22:52:11 raspberrypi kernel: [ 36.614134] Bluetooth: HCI UART protocol Broadcom registered Nov 6 22:52:11 raspberrypi btuart[265]: Set BDADDR UART: b8:27:eb:d0:30:65 Nov 6 22:52:11 raspberrypi btuart[265]: Set Controller UART speed to 921600 bit/s Nov 6 22:52:11 raspberrypi btuart[265]: Device setup complete Nov 6 22:52:11 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART. Nov 6 22:52:11 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 6 22:52:15 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status. Nov 6 22:52:15 raspberrypi systemd[1]: systemd-fsckd.service: Succeeded. Nov 6 22:52:15 raspberrypi systemd[1]: Created slice system-bthelper.slice. Nov 6 22:52:15 raspberrypi systemd[1]: Starting Bluetooth service... Nov 6 22:52:15 raspberrypi lightdm[429]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files Nov 6 22:52:15 raspberrypi polkitd[405]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 6 22:52:15 raspberrypi systemd[1]: Started Authorization Manager. Nov 6 22:52:20 raspberrypi systemd[1]: plymouth-start.service: Succeeded. Nov 6 22:52:20 raspberrypi systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 6 22:52:20 raspberrypi systemd[1]: Started Hold until boot process finishes up. Nov 6 22:52:20 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded. Nov 6 22:52:20 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 144 (n/a). Nov 6 22:52:20 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 144 (n/a). Nov 6 22:52:20 raspberrypi systemd[1]: Started Light Display Manager. Nov 6 22:52:20 raspberrypi systemd[1]: Started Getty on tty1. Nov 6 22:52:20 raspberrypi systemd[1]: Reached target Login Prompts. Nov 6 22:52:20 raspberrypi systemd[1]: Created slice User Slice of UID 1000. Nov 6 22:52:20 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000... Nov 6 22:52:22 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000. Nov 6 22:52:22 raspberrypi systemd[1]: Starting User Manager for UID 1000... Nov 6 22:52:36 raspberrypi systemd[1]: Started A high performance web server and a reverse proxy server. Nov 6 22:52:48 raspberrypi systemd[1]: Started Disk Manager. Nov 6 22:52:48 raspberrypi bluetoothd[488]: Bluetooth daemon 5.50 Nov 6 22:52:48 raspberrypi udisksd[276]: Acquired the name org.freedesktop.UDisks2 on the system message bus Nov 6 22:52:54 raspberrypi systemd[1]: Started Bluetooth service. Nov 6 22:52:54 raspberrypi systemd[1]: Reached target Bluetooth. Nov 6 22:52:54 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper. Nov 6 22:52:54 raspberrypi systemd[1]: Started BluezALSA proxy. Nov 6 22:52:56 raspberrypi systemd[1]: logrotate.service: Succeeded. Nov 6 22:52:56 raspberrypi systemd[1]: Started Rotate log files. Nov 6 22:52:59 raspberrypi bluetoothd[488]: Starting SDP server Nov 6 22:53:00 raspberrypi kernel: [ 85.050019] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 6 22:53:00 raspberrypi kernel: [ 85.050028] Bluetooth: BNEP filters: protocol multicast Nov 6 22:53:00 raspberrypi kernel: [ 85.050043] Bluetooth: BNEP socket layer initialized Nov 6 22:53:00 raspberrypi dbus-daemon[298]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.10' (uid=0 pid=488 comm="/usr/lib/bluetooth/bluetoothd ") Nov 6 22:53:00 raspberrypi bluetoothd[488]: Bluetooth management interface 1.14 initialized Nov 6 22:53:00 raspberrypi bluetoothd[488]: Sap driver initialization failed. Nov 6 22:53:00 raspberrypi bluetoothd[488]: sap-server: Operation not permitted (1) Nov 6 22:53:00 raspberrypi systemd[1]: [email protected]: Succeeded. Nov 6 22:53:00 raspberrypi bluetoothd[488]: Endpoint registered: sender=:1.12 path=/org/bluez/hci0/A2DP/SBC/Source/1 Nov 6 22:53:00 raspberrypi bluetoothd[488]: Endpoint registered: sender=:1.12 path=/org/bluez/hci0/A2DP/SBC/Source/2 Nov 6 22:53:00 raspberrypi systemd[1]: Starting Hostname Service... Nov 6 22:53:00 raspberrypi weewx[427]: engine: Initializing weewx version 3.9.2 Nov 6 22:53:00 raspberrypi weewx[427]: engine: Using Python 2.7.16 (default, Apr 6 2019, 01:42:57) #012[GCC 8.2.0] Nov 6 22:53:00 raspberrypi kernel: [ 85.166844] Bluetooth: RFCOMM TTY layer initialized Nov 6 22:53:00 raspberrypi kernel: [ 85.166866] Bluetooth: RFCOMM socket layer initialized Nov 6 22:53:00 raspberrypi kernel: [ 85.166889] Bluetooth: RFCOMM ver 1.11 Nov 6 22:53:00 raspberrypi weewx[427]: engine: Platform Linux-4.19.75-v7+-armv7l-with-debian-10.1 Nov 6 22:53:00 raspberrypi weewx[427]: engine: Locale is 'de_CH.UTF-8' Nov 6 22:53:00 raspberrypi weewx[427]: engine: pid file is /var/run/weewx.pid Nov 6 22:53:00 raspberrypi bluetoothd[488]: Failed to set privacy: Rejected (0x0b) Nov 6 22:53:00 raspberrypi weewx[280]: Starting weewx weather system: weewx. Nov 6 22:53:00 raspberrypi systemd[1]: Started LSB: weewx weather system. Nov 6 22:53:00 raspberrypi systemd[1]: Reached target Multi-User System. Nov 6 22:53:00 raspberrypi systemd[1]: Reached target Graphical Interface. Nov 6 22:53:00 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 6 22:53:00 raspberrypi weewx[576]: engine: Using configuration file /home/weewx/weewx.conf Nov 6 22:53:00 raspberrypi weewx[576]: engine: Debug is 1 Nov 6 22:53:00 raspberrypi weewx[576]: engine: Initializing engine Nov 6 22:53:00 raspberrypi weewx[576]: engine: Loading station type KlimaLogg (user.kl) Nov 6 22:53:00 raspberrypi systemd[502]: Reached target Paths. Nov 6 22:53:00 raspberrypi systemd[502]: Listening on GnuPG network certificate management daemon. Nov 6 22:53:00 raspberrypi systemd[502]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Nov 6 22:53:00 raspberrypi systemd[502]: Listening on GnuPG cryptographic agent and passphrase cache. Nov 6 22:53:00 raspberrypi systemd[502]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Nov 6 22:53:00 raspberrypi systemd[502]: Starting D-Bus User Message Bus Socket. Nov 6 22:53:00 raspberrypi systemd[502]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Nov 6 22:53:00 raspberrypi systemd[502]: Reached target Timers. Nov 6 22:53:00 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 6 22:53:00 raspberrypi systemd[502]: Listening on D-Bus User Message Bus Socket. Nov 6 22:53:00 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 6 22:53:00 raspberrypi systemd[502]: Reached target Sockets. Nov 6 22:53:00 raspberrypi systemd[502]: Reached target Basic System. Nov 6 22:53:00 raspberrypi systemd[502]: Reached target Default. Nov 6 22:53:00 raspberrypi systemd[502]: Startup finished in 28.346s. Nov 6 22:53:00 raspberrypi systemd[1]: Started User Manager for UID 1000. Nov 6 22:53:00 raspberrypi systemd[1]: Started Session c2 of user pi. Nov 6 22:53:00 raspberrypi systemd[1]: Started Session c1 of user pi. Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: driver version is 1.3.7 Nov 6 22:53:00 raspberrypi dbus-daemon[298]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 6 22:53:00 raspberrypi systemd[1]: Started Hostname Service. Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: channel is 1 Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: frequency is EU Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: using sensor map for kl schema Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: sensor map is: {'temp3': 'Temp3', 'temp2': 'Temp2', 'temp1': 'Temp1', 'temp0': 'Temp0', 'temp7': 'Temp7', 'temp6': 'Temp6', 'temp5': 'Temp5', 'temp4': 'Temp4', 'temp8': 'Temp8', 'batteryStatus8': 'BatteryStatus8', 'batteryStatus1': 'BatteryStatus1', 'batteryStatus0': 'BatteryStatus0', 'batteryStatus3': 'BatteryStatus3', 'batteryStatus2': 'BatteryStatus2', 'batteryStatus5': 'BatteryStatus5', 'batteryStatus4': 'BatteryStatus4', 'batteryStatus7': 'BatteryStatus7', 'batteryStatus6': 'BatteryStatus6', 'humidity8': 'Humidity8', 'humidity4': 'Humidity4', 'humidity5': 'Humidity5', 'humidity6': 'Humidity6', 'humidity7': 'Humidity7', 'humidity0': 'Humidity0', 'humidity1': 'Humidity1', 'humidity2': 'Humidity2', 'humidity3': 'Humidity3', 'rxCheckPercent': 'SignalQuality'} Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: catchup limited to 51200 records Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: timing is 300 ms (0.300 s) Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: CommunicationService.init Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: comm_interval is 8 Nov 6 22:53:00 raspberrypi weewx[576]: KlimaLogg: MainThread: logger_channel is 1 Nov 6 22:53:30 raspberrypi systemd[1]: systemd-hostnamed.service: Succeeded. Nov 6 22:53:34 raspberrypi weewx[576]: KlimaLogg: MainThread: found transceiver at bus= device= Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: manufacturer: LA CROSSE TECHNOLOGY Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: product: Weather Direct Light Wireless Device Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: interface: 0 Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: claiming USB interface 0 Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: frequency standard: EU Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: base frequency: 868300000 Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: frequency correction: 94320 (0x17070) Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: adjusted frequency: 910572861 (0x36463d3d) Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: frequency registers: 36 46 3d 3d Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: transceiver identifier: 91 (0x005b) Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: transceiver serial: 01012703140091 Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: MainThread: startRFThread: spawning RF thread Nov 6 22:53:36 raspberrypi weewx[576]: KlimaLogg: RFComm: setting up rf communication Nov 6 22:53:36 raspberrypi weewx[576]: engine: Loading service weewx.engine.StdTimeSynch Nov 6 22:53:36 raspberrypi weewx[576]: engine: Finished loading service weewx.engine.StdTimeSynch Nov 6 22:53:36 raspberrypi weewx[576]: engine: Loading service weewx.engine.StdConvert Nov 6 22:53:36 raspberrypi weewx[576]: engine: StdConvert target unit is 0x11 Nov 6 22:53:36 raspberrypi weewx[576]: engine: Finished loading service weewx.engine.StdConvert Nov 6 22:53:36 raspberrypi weewx[576]: engine: Loading service weewx.engine.StdCalibrate Nov 6 22:53:36 raspberrypi weewx[576]: engine: Finished loading service weewx.engine.StdCalibrate Nov 6 22:53:36 raspberrypi weewx[576]: engine: Loading service weewx.engine.StdQC Nov 6 22:53:36 raspberrypi weewx[576]: engine: Finished loading service weewx.engine.StdQC Nov 6 22:53:36 raspberrypi weewx[576]: engine: Loading service weewx.wxservices.StdWXCalculate Nov 6 22:53:38 raspberrypi weewx[576]: wxcalculate: The following values will be calculated: barometer=prefer_hardware, windchill=prefer_hardware, dewpoint=prefer_hardware, appTemp=prefer_hardware, rainRate=prefer_hardware, windrun=prefer_hardware, heatindex=prefer_hardware, maxSolarRad=prefer_hardware, humidex=prefer_hardware, pressure=prefer_hardware, inDewpoint=prefer_hardware, ET=prefer_hardware, altimeter=prefer_hardware, cloudbase=prefer_hardware Nov 6 22:53:38 raspberrypi weewx[576]: wxcalculate: The following algorithms will be used for calculations: altimeter=aaNOAA, maxSolarRad=RS Nov 6 22:53:38 raspberrypi weewx[576]: engine: Finished loading service weewx.wxservices.StdWXCalculate Nov 6 22:53:38 raspberrypi weewx[576]: engine: Loading service weewx.engine.StdArchive Nov 6 22:53:38 raspberrypi weewx[576]: engine: Archive will use data binding kl_binding Nov 6 22:53:38 raspberrypi weewx[576]: engine: Record generation will be attempted in 'hardware' Nov 6 22:53:38 raspberrypi weewx[576]: engine: Using archive interval of 60 seconds (specified in weewx configuration) Nov 6 22:53:38 raspberrypi weewx[576]: engine: Use LOOP data in hi/low calculations: 1 Nov 6 22:54:23 raspberrypi systemd[1]: man-db.service: Succeeded. Nov 6 22:54:23 raspberrypi systemd[1]: Started Daily man-db regeneration. Nov 6 22:54:24 raspberrypi lightdm[639]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files Nov 6 22:54:24 raspberrypi systemd[1]: Started Session c3 of user pi. Nov 6 22:54:26 raspberrypi lightdm[429]: Error opening audit socket: Protocol not supported Nov 6 22:54:26 raspberrypi systemd[502]: Started D-Bus User Message Bus. Nov 6 22:54:26 raspberrypi systemd[1]: apt-daily.service: Succeeded. Nov 6 22:54:26 raspberrypi systemd[1]: Started Daily apt download activities. Nov 6 22:54:26 raspberrypi systemd[1]: Starting Daily apt upgrade and clean activities... Nov 6 22:54:30 raspberrypi systemd[1]: apt-daily-upgrade.service: Succeeded. Nov 6 22:54:30 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities. Nov 6 22:54:30 raspberrypi systemd[1]: Startup finished in 3.979s (kernel) + 2min 51.593s (userspace) = 2min 55.573s. Nov 6 22:55:08 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' requested by ':1.3' (uid=1000 pid=648 comm="/usr/bin/lxsession -s LXDE-pi -e LXDE ") Nov 6 22:55:08 raspberrypi systemd[502]: Starting Virtual filesystem service... Nov 6 22:55:08 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Successfully activated service 'org.gtk.vfs.Daemon' Nov 6 22:55:08 raspberrypi systemd[502]: Started Virtual filesystem service. Nov 6 22:55:08 raspberrypi kernel: [ 213.682100] fuse init (API version 7.27) Nov 6 22:55:08 raspberrypi systemd[1]: Mounting FUSE Control File System... Nov 6 22:55:08 raspberrypi systemd[1]: Mounted FUSE Control File System. Nov 6 22:55:09 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=795 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 6 22:55:09 raspberrypi systemd[502]: Starting Virtual filesystem service - disk device monitor... Nov 6 22:55:09 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor' Nov 6 22:55:09 raspberrypi systemd[502]: Started Virtual filesystem service - disk device monitor. Nov 6 22:55:09 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' requested by ':1.7' (uid=1000 pid=795 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 6 22:55:09 raspberrypi systemd[502]: Starting Virtual filesystem service - digital camera monitor... Nov 6 22:55:09 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor' Nov 6 22:55:09 raspberrypi systemd[502]: Started Virtual filesystem service - digital camera monitor. Nov 6 22:55:09 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' requested by ':1.7' (uid=1000 pid=795 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 6 22:55:09 raspberrypi systemd[502]: Starting Virtual filesystem service - GNOME Online Accounts monitor... Nov 6 22:55:10 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor' Nov 6 22:55:10 raspberrypi systemd[502]: Started Virtual filesystem service - GNOME Online Accounts monitor. Nov 6 22:55:10 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' requested by ':1.7' (uid=1000 pid=795 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 6 22:55:10 raspberrypi systemd[502]: Starting Virtual filesystem service - Apple File Conduit monitor... Nov 6 22:55:10 raspberrypi gvfs-afc-volume-monitor[894]: Volume monitor alive Nov 6 22:55:10 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor' Nov 6 22:55:10 raspberrypi systemd[502]: Started Virtual filesystem service - Apple File Conduit monitor. Nov 6 22:55:10 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' requested by ':1.7' (uid=1000 pid=795 comm="pcmanfm --desktop --profile LXDE-pi ") Nov 6 22:55:10 raspberrypi systemd[502]: Starting Virtual filesystem service - Media Transfer Protocol monitor... Nov 6 22:55:10 raspberrypi dbus-daemon[659]: [session uid=1000 pid=659] Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor' Nov 6 22:55:10 raspberrypi systemd[502]: Started Virtual filesystem service - Media Transfer Protocol monitor. Nov 6 22:55:54 raspberrypi weewx[576]: KlimaLogg: MainThread: stopRFThread: waiting for RF thread to terminate Nov 6 22:56:54 raspberrypi weewx[576]: KlimaLogg: MainThread: unable to terminate RF thread after 60 seconds Nov 6 22:56:54 raspberrypi weewx[576]: KlimaLogg: MainThread: releasing USB interface Nov 6 22:56:54 raspberrypi weewx[576]: engine: Caught unrecoverable exception in engine: Nov 6 22:56:54 raspberrypi weewx[576]: **** (1007, "Can't create database 'weewx_kl'; database exists") Nov 6 22:56:54 raspberrypi weewx[576]: **** Traceback (most recent call last): Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/engine.py", line 888, in main Nov 6 22:56:54 raspberrypi weewx[576]: **** engine = engine_class(config_dict) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/engine.py", line 78, in __init__ Nov 6 22:56:54 raspberrypi weewx[576]: **** self.loadServices(config_dict) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/engine.py", line 142, in loadServices Nov 6 22:56:54 raspberrypi weewx[576]: **** self.service_obj.append(weeutil.weeutil._get_object(svc)(self, config_dict)) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/engine.py", line 500, in __init__ Nov 6 22:56:54 raspberrypi weewx[576]: **** self.setup_database(config_dict) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/engine.py", line 608, in setup_database Nov 6 22:56:54 raspberrypi weewx[576]: **** dbmanager = self.engine.db_binder.get_manager(self.data_binding, initialize=True) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/manager.py", line 872, in get_manager Nov 6 22:56:54 raspberrypi weewx[576]: **** self.manager_cache[data_binding] = open_manager(manager_dict, initialize) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/manager.py", line 1021, in open_manager Nov 6 22:56:54 raspberrypi weewx[576]: **** manager_dict['schema']) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weewx/manager.py", line 136, in open_with_create Nov 6 22:56:54 raspberrypi weewx[576]: **** weedb.create(database_dict) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weedb/__init__.py", line 80, in create Nov 6 22:56:54 raspberrypi weewx[576]: **** return driver_mod.create(**db_dict) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weedb/mysql.py", line 75, in create Nov 6 22:56:54 raspberrypi weewx[576]: **** cursor.execute("CREATE DATABASE %s" % (database_name,)) Nov 6 22:56:54 raspberrypi weewx[576]: **** File "/home/weewx/bin/weedb/mysql.py", line 49, in guarded_fn Nov 6 22:56:54 raspberrypi weewx[576]: **** raise klass(e) Nov 6 22:56:54 raspberrypi weewx[576]: **** DatabaseExistsError: (1007, "Can't create database 'weewx_kl'; database exists") Nov 6 22:56:54 raspberrypi weewx[576]: **** Exiting. -- You received this message because you are subscribed to the Google Groups "weewx-user" group. To unsubscribe from this group and stop receiving emails from it, send an email to [email protected]. To view this discussion on the web visit https://groups.google.com/d/msgid/weewx-user/30597a72-f808-4854-a124-5f48e927da98%40googlegroups.com.
