Thanks for the new version of alarm.py. Unfortunately it didn't change the function. Syslog file is attached. I have tried to include a new syslog message just 2 code lines after the evaluated True messages. Result nothing in the syslog. After that the email facility in Python was test with a positive result. The expected email was received.
I have also tried to change the time_wait constant, but realized that alarm is only reported when archive records are generated. Jesper > >
Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Booting Linux on physical CPU 0x0 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Linux version 4.9.59-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #1047 SMP Sun Oct 29 12:19:23 GMT 2017 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] CPU: div instructions available: patching division code Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] OF: fdt:Machine model: Raspberry Pi 3 Model B Rev 1.2 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] cma: Reserved 8 MiB at 0x3a800000 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Memory policy: Data cache writealloc Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] On node 0 totalpages: 241664 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] free_area_init_node: node 0, pgdat 80c6f380, node_mem_map b9faa000 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Normal zone: 2124 pages used for memmap Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Normal zone: 0 pages reserved Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Normal zone: 241664 pages, LIFO batch:31 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] percpu: Embedded 14 pages/cpu @b9f64000 s25600 r8192 d23552 u57344 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 239540 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=1680 bcm2708_fb.fbheight=1050 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=/dev/mmcblk0p7 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait splash plymouth.ignore-serial-consoles Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Memory: 936304K/966656K available (7168K kernel code, 486K rwdata, 2012K rodata, 1024K init, 770K bss, 22160K reserved, 8192K cma-reserved) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Virtual kernel memory layout: Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] vector : 0xffff0000 - 0xffff1000 ( 4 kB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] fixmap : 0xffc00000 - 0xfff00000 (3072 kB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] vmalloc : 0xbb800000 - 0xff800000 (1088 MB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] lowmem : 0x80000000 - 0xbb000000 ( 944 MB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] modules : 0x7f000000 - 0x80000000 ( 16 MB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] .text : 0x80008000 - 0x80800000 (8160 kB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] .init : 0x80b00000 - 0x80c00000 (1024 kB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] .data : 0x80c00000 - 0x80c79834 ( 487 kB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] .bss : 0x80c7b000 - 0x80d3b964 ( 771 kB) Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Hierarchical RCU implementation. Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] Build-time adjustment of leaf fanout to 32. Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] NR_IRQS:16 nr_irqs:16 16 Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys). Feb 12 13:52:54 raspberrypi kernel: [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 12 13:52:54 raspberrypi kernel: [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 12 13:52:54 raspberrypi kernel: [ 0.000023] Switching to timer-based delay loop, resolution 52ns Feb 12 13:52:54 raspberrypi kernel: [ 0.000303] Console: colour dummy device 80x30 Feb 12 13:52:54 raspberrypi kernel: [ 0.001212] console [tty1] enabled Feb 12 13:52:54 raspberrypi kernel: [ 0.001255] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 12 13:52:54 raspberrypi kernel: [ 0.001323] pid_max: default: 32768 minimum: 301 Feb 12 13:52:54 raspberrypi kernel: [ 0.001653] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.001695] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.002731] Disabling memory control group subsystem Feb 12 13:52:54 raspberrypi kernel: [ 0.002835] CPU: Testing write buffer coherency: ok Feb 12 13:52:54 raspberrypi kernel: [ 0.002898] ftrace: allocating 22392 entries in 66 pages Feb 12 13:52:54 raspberrypi kernel: [ 0.049992] CPU0: update cpu_capacity 1024 Feb 12 13:52:54 raspberrypi kernel: [ 0.050044] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 12 13:52:54 raspberrypi kernel: [ 0.050105] Setting up static identity map for 0x100000 - 0x100034 Feb 12 13:52:54 raspberrypi kernel: [ 0.051989] CPU1: update cpu_capacity 1024 Feb 12 13:52:54 raspberrypi kernel: [ 0.051996] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 12 13:52:54 raspberrypi kernel: [ 0.052683] CPU2: update cpu_capacity 1024 Feb 12 13:52:54 raspberrypi kernel: [ 0.052689] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 12 13:52:54 raspberrypi kernel: [ 0.053361] CPU3: update cpu_capacity 1024 Feb 12 13:52:54 raspberrypi kernel: [ 0.053368] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 12 13:52:54 raspberrypi kernel: [ 0.053455] Brought up 4 CPUs Feb 12 13:52:54 raspberrypi kernel: [ 0.053630] SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 12 13:52:54 raspberrypi kernel: [ 0.053659] CPU: All CPU(s) started in HYP mode. Feb 12 13:52:54 raspberrypi kernel: [ 0.053686] CPU: Virtualization extensions available. Feb 12 13:52:54 raspberrypi kernel: [ 0.054505] devtmpfs: initialized Feb 12 13:52:54 raspberrypi kernel: [ 0.065914] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 12 13:52:54 raspberrypi kernel: [ 0.066229] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 12 13:52:54 raspberrypi kernel: [ 0.066291] futex hash table entries: 1024 (order: 4, 65536 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.066849] pinctrl core: initialized pinctrl subsystem Feb 12 13:52:54 raspberrypi kernel: [ 0.067783] NET: Registered protocol family 16 Feb 12 13:52:54 raspberrypi kernel: [ 0.070131] DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 12 13:52:54 raspberrypi kernel: [ 0.079083] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 12 13:52:54 raspberrypi kernel: [ 0.079132] hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 12 13:52:54 raspberrypi kernel: [ 0.079296] Serial: AMBA PL011 UART driver Feb 12 13:52:54 raspberrypi kernel: [ 0.081224] bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 12 13:52:54 raspberrypi kernel: [ 0.081778] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe Feb 12 13:52:54 raspberrypi kernel: [ 0.150847] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb80f000, dmachans=0x1 Feb 12 13:52:54 raspberrypi kernel: [ 0.152714] SCSI subsystem initialized Feb 12 13:52:54 raspberrypi kernel: [ 0.152891] usbcore: registered new interface driver usbfs Feb 12 13:52:54 raspberrypi kernel: [ 0.152993] usbcore: registered new interface driver hub Feb 12 13:52:54 raspberrypi kernel: [ 0.153109] usbcore: registered new device driver usb Feb 12 13:52:54 raspberrypi kernel: [ 0.159798] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-10-24 17:09 Feb 12 13:52:54 raspberrypi kernel: [ 0.161294] clocksource: Switched to clocksource arch_sys_counter Feb 12 13:52:54 raspberrypi kernel: [ 0.208403] VFS: Disk quotas dquot_6.6.0 Feb 12 13:52:54 raspberrypi kernel: [ 0.208514] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.208744] FS-Cache: Loaded Feb 12 13:52:54 raspberrypi kernel: [ 0.209014] CacheFiles: Loaded Feb 12 13:52:54 raspberrypi kernel: [ 0.221179] NET: Registered protocol family 2 Feb 12 13:52:54 raspberrypi kernel: [ 0.222107] TCP established hash table entries: 8192 (order: 3, 32768 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.222244] TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.222455] TCP: Hash tables configured (established 8192 bind 8192) Feb 12 13:52:54 raspberrypi kernel: [ 0.222567] UDP hash table entries: 512 (order: 2, 16384 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.222634] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 0.222868] NET: Registered protocol family 1 Feb 12 13:52:54 raspberrypi kernel: [ 0.223301] RPC: Registered named UNIX socket transport module. Feb 12 13:52:54 raspberrypi kernel: [ 0.223334] RPC: Registered udp transport module. Feb 12 13:52:54 raspberrypi kernel: [ 0.223362] RPC: Registered tcp transport module. Feb 12 13:52:54 raspberrypi kernel: [ 0.223389] RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 12 13:52:54 raspberrypi kernel: [ 0.224437] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 12 13:52:54 raspberrypi kernel: [ 0.226770] workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 12 13:52:54 raspberrypi kernel: [ 0.242840] FS-Cache: Netfs 'nfs' registered for caching Feb 12 13:52:54 raspberrypi kernel: [ 0.243844] NFS: Registering the id_resolver key type Feb 12 13:52:54 raspberrypi kernel: [ 0.243894] Key type id_resolver registered Feb 12 13:52:54 raspberrypi kernel: [ 0.243922] Key type id_legacy registered Feb 12 13:52:54 raspberrypi kernel: [ 0.246357] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Feb 12 13:52:54 raspberrypi kernel: [ 0.246505] io scheduler noop registered Feb 12 13:52:54 raspberrypi kernel: [ 0.246535] io scheduler deadline registered (default) Feb 12 13:52:54 raspberrypi kernel: [ 0.246832] io scheduler cfq registered Feb 12 13:52:54 raspberrypi kernel: [ 0.252507] BCM2708FB: allocated DMA memory fa910000 Feb 12 13:52:54 raspberrypi kernel: [ 0.252561] BCM2708FB: allocated DMA channel 0 @ bb80f000 Feb 12 13:52:54 raspberrypi kernel: [ 0.302253] Console: switching to colour frame buffer device 210x65 Feb 12 13:52:54 raspberrypi kernel: [ 0.332379] bcm2835-rng 3f104000.rng: hwrng registered Feb 12 13:52:54 raspberrypi kernel: [ 0.332644] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB) Feb 12 13:52:54 raspberrypi kernel: [ 0.333415] vc-sm: Videocore shared memory driver Feb 12 13:52:54 raspberrypi kernel: [ 0.348534] brd: module loaded Feb 12 13:52:54 raspberrypi kernel: [ 0.357792] loop: module loaded Feb 12 13:52:54 raspberrypi kernel: [ 0.357926] Loading iSCSI transport class v2.0-870. Feb 12 13:52:54 raspberrypi kernel: [ 0.358622] usbcore: registered new interface driver smsc95xx Feb 12 13:52:54 raspberrypi kernel: [ 0.358806] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 12 13:52:54 raspberrypi kernel: [ 0.587001] Core Release: 2.80a Feb 12 13:52:54 raspberrypi kernel: [ 0.587105] Setting default values for core params Feb 12 13:52:54 raspberrypi kernel: [ 0.587273] Finished setting default values for core params Feb 12 13:52:54 raspberrypi kernel: [ 0.787816] Using Buffer DMA mode Feb 12 13:52:54 raspberrypi kernel: [ 0.787922] Periodic Transfer Interrupt Enhancement - disabled Feb 12 13:52:54 raspberrypi kernel: [ 0.788088] Multiprocessor Interrupt Enhancement - disabled Feb 12 13:52:54 raspberrypi kernel: [ 0.788247] OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 12 13:52:54 raspberrypi kernel: [ 0.788381] Dedicated Tx FIFOs mode Feb 12 13:52:54 raspberrypi kernel: [ 0.788874] WARN::dwc_otg_hcd_init:1032: FIQ DMA bounce buffers: virt = 0xba904000 dma = 0xfa904000 len=9024 Feb 12 13:52:54 raspberrypi kernel: [ 0.789167] FIQ FSM acceleration enabled for : Feb 12 13:52:54 raspberrypi kernel: [ 0.789167] Non-periodic Split Transactions Feb 12 13:52:54 raspberrypi kernel: [ 0.789167] Periodic Split Transactions Feb 12 13:52:54 raspberrypi kernel: [ 0.789167] High-Speed Isochronous Endpoints Feb 12 13:52:54 raspberrypi kernel: [ 0.789167] Interrupt/Control Split Transaction hack enabled Feb 12 13:52:54 raspberrypi kernel: [ 0.789762] dwc_otg: Microframe scheduler enabled Feb 12 13:52:54 raspberrypi kernel: [ 0.789835] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8058f5b0 Feb 12 13:52:54 raspberrypi kernel: [ 0.790008] WARN::hcd_init_fiq:460: FIQ ASM at 0x8058f920 length 36 Feb 12 13:52:54 raspberrypi kernel: [ 0.790189] WARN::hcd_init_fiq:486: MPHI regs_base at 0xbb87a000 Feb 12 13:52:54 raspberrypi kernel: [ 0.790415] dwc_otg 3f980000.usb: DWC OTG Controller Feb 12 13:52:54 raspberrypi kernel: [ 0.790594] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 12 13:52:54 raspberrypi kernel: [ 0.790827] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000 Feb 12 13:52:54 raspberrypi kernel: [ 0.791032] Init: Port Power? op_state=1 Feb 12 13:52:54 raspberrypi kernel: [ 0.791148] Init: Power Port (0) Feb 12 13:52:54 raspberrypi kernel: [ 0.798562] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Feb 12 13:52:54 raspberrypi kernel: [ 0.805846] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 12 13:52:54 raspberrypi kernel: [ 0.813098] usb usb1: Product: DWC OTG Controller Feb 12 13:52:54 raspberrypi kernel: [ 0.820359] usb usb1: Manufacturer: Linux 4.9.59-v7+ dwc_otg_hcd Feb 12 13:52:54 raspberrypi kernel: [ 0.827679] usb usb1: SerialNumber: 3f980000.usb Feb 12 13:52:54 raspberrypi kernel: [ 0.835770] hub 1-0:1.0: USB hub found Feb 12 13:52:54 raspberrypi kernel: [ 0.842983] hub 1-0:1.0: 1 port detected Feb 12 13:52:54 raspberrypi kernel: [ 0.850779] dwc_otg: FIQ enabled Feb 12 13:52:54 raspberrypi kernel: [ 0.850784] dwc_otg: NAK holdoff enabled Feb 12 13:52:54 raspberrypi kernel: [ 0.850788] dwc_otg: FIQ split-transaction FSM enabled Feb 12 13:52:54 raspberrypi kernel: [ 0.850801] Module dwc_common_port init Feb 12 13:52:54 raspberrypi kernel: [ 0.851060] usbcore: registered new interface driver usb-storage Feb 12 13:52:54 raspberrypi kernel: [ 0.858469] mousedev: PS/2 mouse device common for all mice Feb 12 13:52:54 raspberrypi kernel: [ 0.866621] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer Feb 12 13:52:54 raspberrypi kernel: [ 0.874072] bcm2835-cpufreq: min=600000 max=1200000 Feb 12 13:52:54 raspberrypi kernel: [ 0.881644] sdhci: Secure Digital Host Controller Interface driver Feb 12 13:52:54 raspberrypi kernel: [ 0.888880] sdhci: Copyright(c) Pierre Ossman Feb 12 13:52:54 raspberrypi kernel: [ 0.896468] sdhost-bcm2835 3f202000.sdhost: could not get clk, deferring probe Feb 12 13:52:54 raspberrypi kernel: [ 0.906079] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe Feb 12 13:52:54 raspberrypi kernel: [ 0.913586] sdhci-pltfm: SDHCI platform and OF driver helper Feb 12 13:52:54 raspberrypi kernel: [ 0.923605] ledtrig-cpu: registered to indicate activity on CPUs Feb 12 13:52:54 raspberrypi kernel: [ 0.931128] hidraw: raw HID events driver (C) Jiri Kosina Feb 12 13:52:54 raspberrypi kernel: [ 0.938645] usbcore: registered new interface driver usbhid Feb 12 13:52:54 raspberrypi kernel: [ 0.945978] usbhid: USB HID core driver Feb 12 13:52:54 raspberrypi kernel: [ 0.954243] vchiq: vchiq_init_state: slot_zero = 0xba980000, is_master = 0 Feb 12 13:52:54 raspberrypi kernel: [ 0.963190] [vc_sm_connected_init]: start Feb 12 13:52:54 raspberrypi kernel: [ 0.973233] [vc_sm_connected_init]: end - returning 0 Feb 12 13:52:54 raspberrypi kernel: [ 0.980898] Initializing XFRM netlink socket Feb 12 13:52:54 raspberrypi kernel: [ 0.988243] NET: Registered protocol family 17 Feb 12 13:52:54 raspberrypi kernel: [ 0.995689] Key type dns_resolver registered Feb 12 13:52:54 raspberrypi kernel: [ 1.003527] Registering SWP/SWPB emulation handler Feb 12 13:52:54 raspberrypi kernel: [ 1.011632] registered taskstats version 1 Feb 12 13:52:54 raspberrypi kernel: [ 1.025368] uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 12 13:52:54 raspberrypi kernel: [ 1.032951] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2 Feb 12 13:52:54 raspberrypi kernel: [ 1.042163] sdhost: log_buf @ ba907000 (fa907000) Feb 12 13:52:54 raspberrypi kernel: [ 1.070115] Indeed it is in host mode hprt0 = 00021501 Feb 12 13:52:54 raspberrypi kernel: [ 1.161326] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 12 13:52:54 raspberrypi kernel: [ 1.165054] random: fast init done Feb 12 13:52:54 raspberrypi kernel: [ 1.178420] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0 Feb 12 13:52:54 raspberrypi kernel: [ 1.185945] mmc-bcm2835 3f300000.mmc: DMA channel allocated Feb 12 13:52:54 raspberrypi kernel: [ 1.259574] mmc0: host does not support reading read-only switch, assuming write-enable Feb 12 13:52:54 raspberrypi kernel: [ 1.270280] mmc0: new high speed SDHC card at address 0007 Feb 12 13:52:54 raspberrypi kernel: [ 1.277957] of_cfs_init Feb 12 13:52:54 raspberrypi kernel: [ 1.282089] usb 1-1: new high-speed USB device number 2 using dwc_otg Feb 12 13:52:54 raspberrypi kernel: [ 1.282255] Indeed it is in host mode hprt0 = 00001101 Feb 12 13:52:54 raspberrypi kernel: [ 1.300950] of_cfs_init: OK Feb 12 13:52:54 raspberrypi kernel: [ 1.344608] mmcblk0: mmc0:0007 SDCIT 14.6 GiB Feb 12 13:52:54 raspberrypi kernel: [ 1.356003] mmcblk0: p1 p2 < p5 p6 p7 > Feb 12 13:52:54 raspberrypi kernel: [ 1.369186] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 1.378416] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 1.380922] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null) Feb 12 13:52:54 raspberrypi kernel: [ 1.380974] VFS: Mounted root (ext4 filesystem) readonly on device 179:7. Feb 12 13:52:54 raspberrypi kernel: [ 1.390786] devtmpfs: mounted Feb 12 13:52:54 raspberrypi kernel: [ 1.409799] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 1.410076] Freeing unused kernel memory: 1024K Feb 12 13:52:54 raspberrypi kernel: [ 1.427817] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Feb 12 13:52:54 raspberrypi kernel: [ 1.521642] usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Feb 12 13:52:54 raspberrypi kernel: [ 1.529357] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 12 13:52:54 raspberrypi kernel: [ 1.538205] hub 1-1:1.0: USB hub found Feb 12 13:52:54 raspberrypi kernel: [ 1.546377] hub 1-1:1.0: 5 ports detected Feb 12 13:52:54 raspberrypi kernel: [ 1.559207] mmc1: new high speed SDIO card at address 0001 Feb 12 13:52:54 raspberrypi kernel: [ 1.871343] usb 1-1.1: new high-speed USB device number 3 using dwc_otg Feb 12 13:52:54 raspberrypi kernel: [ 2.001632] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Feb 12 13:52:54 raspberrypi kernel: [ 2.009446] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 12 13:52:54 raspberrypi kernel: [ 2.020246] smsc95xx v1.0.5 Feb 12 13:52:54 raspberrypi kernel: [ 2.061271] NET: Registered protocol family 10 Feb 12 13:52:54 raspberrypi kernel: [ 2.088439] ip_tables: (C) 2000-2006 Netfilter Core Team Feb 12 13:52:54 raspberrypi kernel: [ 2.115463] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:57:76:17 Feb 12 13:52:54 raspberrypi kernel: [ 2.221366] usb 1-1.2: new low-speed USB device number 4 using dwc_otg Feb 12 13:52:54 raspberrypi kernel: [ 2.408989] usb 1-1.2: New USB device found, idVendor=045e, idProduct=0040 Feb 12 13:52:54 raspberrypi kernel: [ 2.417572] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 12 13:52:54 raspberrypi kernel: [ 2.426040] usb 1-1.2: Product: Microsoft Wheel Mouse OpticalĀ® Feb 12 13:52:54 raspberrypi kernel: [ 2.434445] usb 1-1.2: Manufacturer: Microsoft Feb 12 13:52:54 raspberrypi kernel: [ 2.462480] input: Microsoft Microsoft Wheel Mouse OpticalĀ® as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/0003:045E:0040.0001/input/input0 Feb 12 13:52:54 raspberrypi kernel: [ 2.471932] hid-generic 0003:045E:0040.0001: input,hidraw0: USB HID v1.00 Mouse [Microsoft Microsoft Wheel Mouse OpticalĀ®] on usb-3f980000.usb-1.2/input0 Feb 12 13:52:54 raspberrypi kernel: [ 2.581371] usb 1-1.3: new low-speed USB device number 5 using dwc_otg Feb 12 13:52:54 raspberrypi kernel: [ 2.738107] usb 1-1.3: New USB device found, idVendor=0603, idProduct=00f2 Feb 12 13:52:54 raspberrypi kernel: [ 2.747571] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Feb 12 13:52:54 raspberrypi kernel: [ 2.757373] usb 1-1.3: Product: USB Keyboard Feb 12 13:52:54 raspberrypi kernel: [ 2.776147] usb 1-1.3: Manufacturer: NOVATEK Feb 12 13:52:54 raspberrypi kernel: [ 2.796678] input: NOVATEK USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:0603:00F2.0002/input/input1 Feb 12 13:52:54 raspberrypi kernel: [ 2.862517] hid-generic 0003:0603:00F2.0002: input,hidraw1: USB HID v1.10 Keyboard [NOVATEK USB Keyboard] on usb-3f980000.usb-1.3/input0 Feb 12 13:52:54 raspberrypi kernel: [ 2.882749] input: NOVATEK USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.1/0003:0603:00F2.0003/input/input2 Feb 12 13:52:54 raspberrypi kernel: [ 2.951971] hid-generic 0003:0603:00F2.0003: input,hiddev0,hidraw2: USB HID v1.10 Device [NOVATEK USB Keyboard] on usb-3f980000.usb-1.3/input1 Feb 12 13:52:54 raspberrypi kernel: [ 3.014594] i2c /dev entries driver Feb 12 13:52:54 raspberrypi kernel: [ 3.081436] usb 1-1.4: new full-speed USB device number 6 using dwc_otg Feb 12 13:52:54 raspberrypi kernel: [ 3.249499] usb 1-1.4: New USB device found, idVendor=10c4, idProduct=ea61 Feb 12 13:52:54 raspberrypi kernel: [ 3.260561] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 12 13:52:54 raspberrypi kernel: [ 3.260569] usb 1-1.4: Product: CP2102 USB to UART Bridge Controller Feb 12 13:52:54 raspberrypi kernel: [ 3.260576] usb 1-1.4: Manufacturer: Silicon Labs Feb 12 13:52:54 raspberrypi kernel: [ 3.260590] usb 1-1.4: SerialNumber: 1349018539 Feb 12 13:52:54 raspberrypi kernel: [ 4.329671] EXT4-fs (mmcblk0p7): re-mounted. Opts: (null) Feb 12 13:52:54 raspberrypi kernel: [ 4.988512] usbcore: registered new interface driver usbserial Feb 12 13:52:54 raspberrypi kernel: [ 4.988631] usbcore: registered new interface driver usbserial_generic Feb 12 13:52:54 raspberrypi kernel: [ 4.988715] usbserial: USB Serial support registered for generic Feb 12 13:52:54 raspberrypi kernel: [ 4.997457] usbcore: registered new interface driver cp210x Feb 12 13:52:54 raspberrypi kernel: [ 4.997560] usbserial: USB Serial support registered for cp210x Feb 12 13:52:54 raspberrypi kernel: [ 4.997767] cp210x 1-1.4:1.0: cp210x converter detected Feb 12 13:52:54 raspberrypi kernel: [ 5.000166] usb 1-1.4: cp210x converter now attached to ttyUSB0 Feb 12 13:52:54 raspberrypi kernel: [ 5.083272] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Feb 12 13:52:54 raspberrypi kernel: [ 5.368361] brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Feb 12 13:52:54 raspberrypi kernel: [ 5.381976] usbcore: registered new interface driver brcmfmac Feb 12 13:52:54 raspberrypi kernel: [ 5.648844] brcmfmac: Firmware version = wl0: Aug 7 2017 00:46:29 version 7.45.41.46 (r666254 CY) FWID 01-f8a78378 Feb 12 13:52:54 raspberrypi kernel: [ 5.649660] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.41 Inc Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-08-07 00:37:47 Feb 12 13:52:54 raspberrypi kernel: [ 6.871227] uart-pl011 3f201000.serial: no DMA platform data Feb 12 13:52:54 raspberrypi systemd-modules-load[112]: Inserted module 'i2c_dev' Feb 12 13:52:54 raspberrypi fake-hwclock[111]: Mon Feb 12 12:52:50 UTC 2018 Feb 12 13:52:54 raspberrypi systemd-fsck[133]: e2fsck 1.43.4 (31-Jan-2017) Feb 12 13:52:54 raspberrypi systemd[1]: Started Create list of required static device nodes for the current kernel. Feb 12 13:52:54 raspberrypi systemd-fsck[133]: root: clean, 138612/846976 files, 1197818/3383296 blocks Feb 12 13:52:54 raspberrypi systemd[1]: Started Restore / save the current clock. Feb 12 13:52:54 raspberrypi systemd[1]: Started Load Kernel Modules. Feb 12 13:52:54 raspberrypi systemd[1]: Started File System Check on Root Device. Feb 12 13:52:54 raspberrypi systemd[1]: Time has been changed Feb 12 13:52:54 raspberrypi systemd[1]: Started File System Check Daemon to report status. Feb 12 13:52:54 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Apply Kernel Variables... Feb 12 13:52:54 raspberrypi systemd[1]: Mounting Configuration File System... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Create Static Device Nodes in /dev... Feb 12 13:52:54 raspberrypi systemd[1]: Mounted Configuration File System. Feb 12 13:52:54 raspberrypi systemd[1]: Started Set the console keyboard layout. Feb 12 13:52:54 raspberrypi systemd[1]: Started Apply Kernel Variables. Feb 12 13:52:54 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems. Feb 12 13:52:54 raspberrypi systemd[1]: Starting Flush Journal to Persistent Storage... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Load/Save Random Seed... Feb 12 13:52:54 raspberrypi systemd[1]: Starting udev Coldplug all Devices... Feb 12 13:52:54 raspberrypi systemd[1]: Started Load/Save Random Seed. Feb 12 13:52:54 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev. Feb 12 13:52:54 raspberrypi systemd[1]: Starting udev Kernel Device Manager... Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Local File Systems (Pre). Feb 12 13:52:54 raspberrypi systemd[1]: Started Flush Journal to Persistent Storage. Feb 12 13:52:54 raspberrypi systemd[1]: Started udev Kernel Device Manager. Feb 12 13:52:54 raspberrypi systemd[1]: Started udev Coldplug all Devices. Feb 12 13:52:54 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen... Feb 12 13:52:54 raspberrypi systemd[1]: Started Show Plymouth Boot Screen. Feb 12 13:52:54 raspberrypi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Paths. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Encrypted Volumes. Feb 12 13:52:54 raspberrypi mtp-probe: checking bus 1, device 6: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4" Feb 12 13:52:54 raspberrypi mtp-probe: bus: 1, device: 6 was not an MTP device Feb 12 13:52:54 raspberrypi mtp-probe: checking bus 1, device 6: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4" Feb 12 13:52:54 raspberrypi mtp-probe: bus: 1, device: 6 was not an MTP device Feb 12 13:52:54 raspberrypi mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1" Feb 12 13:52:54 raspberrypi mtp-probe: checking bus 1, device 5: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3" Feb 12 13:52:54 raspberrypi mtp-probe: bus: 1, device: 5 was not an MTP device Feb 12 13:52:54 raspberrypi mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2" Feb 12 13:52:54 raspberrypi mtp-probe: bus: 1, device: 4 was not an MTP device Feb 12 13:52:54 raspberrypi systemd[1]: Found device /dev/serial1. Feb 12 13:52:54 raspberrypi mtp-probe: bus: 1, device: 3 was not an MTP device Feb 12 13:52:54 raspberrypi systemd[1]: Found device /dev/mmcblk0p6. Feb 12 13:52:54 raspberrypi systemd[1]: Starting File System Check on /dev/mmcblk0p6... Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Sound Card. Feb 12 13:52:54 raspberrypi systemd-udevd[174]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 12 13:52:54 raspberrypi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 12 13:52:54 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 12 13:52:54 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status. Feb 12 13:52:54 raspberrypi systemd-fsck[245]: fsck.fat 4.1 (2017-01-24) Feb 12 13:52:54 raspberrypi systemd-fsck[245]: /dev/mmcblk0p6: 145 files, 42656/133056 clusters Feb 12 13:52:54 raspberrypi systemd[1]: Started File System Check on /dev/mmcblk0p6. Feb 12 13:52:54 raspberrypi systemd[1]: Mounting /boot... Feb 12 13:52:54 raspberrypi systemd-udevd[178]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 12 13:52:54 raspberrypi systemd[1]: Mounted /boot. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Local File Systems. Feb 12 13:52:54 raspberrypi systemd[1]: Starting Preprocess NFS configuration... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Set console font and keymap... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Raise network interfaces... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Create Volatile Files and Directories... Feb 12 13:52:54 raspberrypi systemd[1]: Started Preprocess NFS configuration. Feb 12 13:52:54 raspberrypi systemd-udevd[177]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 12 13:52:54 raspberrypi systemd[1]: Started Set console font and keymap. Feb 12 13:52:54 raspberrypi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 12 13:52:54 raspberrypi systemd-udevd[166]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target NFS client services. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Remote File Systems (Pre). Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Remote File Systems. Feb 12 13:52:54 raspberrypi systemd[1]: Started Create Volatile Files and Directories. Feb 12 13:52:54 raspberrypi systemd[1]: Starting Network Time Synchronization... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 12 13:52:54 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 12 13:52:54 raspberrypi systemd[1]: Started Network Time Synchronization. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target System Initialization. Feb 12 13:52:54 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 12 13:52:54 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 12 13:52:54 raspberrypi systemd[1]: Listening on triggerhappy.socket. Feb 12 13:52:54 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Sockets. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Basic System. Feb 12 13:52:54 raspberrypi systemd[1]: Starting LSB: Autogenerate and use a swap file... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Check for v3d driver... Feb 12 13:52:54 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Login Service... Feb 12 13:52:54 raspberrypi systemd[1]: Started Regular background program processing daemon. Feb 12 13:52:54 raspberrypi systemd[1]: Started D-Bus System Message Bus. Feb 12 13:52:54 raspberrypi cron[338]: (CRON) INFO (pidfile fd = 3) Feb 12 13:52:54 raspberrypi cron[338]: (CRON) INFO (Running @reboot jobs) Feb 12 13:52:54 raspberrypi dbus[339]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 12 13:52:54 raspberrypi systemd[1]: Starting System Logging Service... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 12 13:52:54 raspberrypi systemd[1]: Starting triggerhappy global hotkey daemon... Feb 12 13:52:54 raspberrypi systemd[1]: Starting dhcpcd on all interfaces... Feb 12 13:52:54 raspberrypi systemd[1]: Starting Save/Restore Sound Card State... Feb 12 13:52:54 raspberrypi systemd[1]: Reached target System Time Synchronized. Feb 12 13:52:54 raspberrypi systemd[1]: apt-daily.timer: Adding 9h 40min 43.321534s random time. Feb 12 13:52:54 raspberrypi systemd[1]: Started Daily apt download activities. Feb 12 13:52:54 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 2min 53.968386s random time. Feb 12 13:52:54 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities. Feb 12 13:52:54 raspberrypi systemd[1]: Reached target Timers. Feb 12 13:52:54 raspberrypi systemd[1]: Started Check for v3d driver. Feb 12 13:52:54 raspberrypi thd[361]: Found socket passed from systemd Feb 12 13:52:54 raspberrypi systemd[1]: Started Save/Restore Sound Card State. Feb 12 13:52:54 raspberrypi dhcpcd[362]: dev: loaded udev Feb 12 13:52:54 raspberrypi avahi-daemon[360]: Found user 'avahi' (UID 108) and group 'avahi' (GID 112). Feb 12 13:52:54 raspberrypi avahi-daemon[360]: Successfully dropped root privileges. Feb 12 13:52:54 raspberrypi avahi-daemon[360]: avahi-daemon 0.6.32 starting up. Feb 12 13:52:54 raspberrypi liblogging-stdlog: [origin software="rsyslogd" swVersion="8.24.0" x-pid="359" x-info="http://www.rsyslog.com"] start Feb 12 13:52:54 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon. Feb 12 13:52:54 raspberrypi systemd[1]: Started System Logging Service. Feb 12 13:52:54 raspberrypi systemd[1]: Started Login Service. Feb 12 13:52:54 raspberrypi dhcpcd[362]: forked to background, child pid 372 Feb 12 13:52:54 raspberrypi systemd[1]: Started dhcpcd on all interfaces. Feb 12 13:52:54 raspberrypi avahi-daemon[360]: Successfully called chroot(). Feb 12 13:52:54 raspberrypi avahi-daemon[360]: Successfully dropped remaining capabilities. Feb 12 13:52:54 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 12 13:52:54 raspberrypi avahi-daemon[360]: No service file found in /etc/avahi/services. Feb 12 13:52:54 raspberrypi avahi-daemon[360]: Network interface enumeration completed. Feb 12 13:52:54 raspberrypi avahi-daemon[360]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 368423806. Feb 12 13:52:54 raspberrypi dhcpcd-run-hooks[387]: wlan0: starting wpa_supplicant Feb 12 13:52:54 raspberrypi kernel: [ 7.391869] random: crng init done Feb 12 13:52:54 raspberrypi systemd[1]: Received SIGRTMIN+20 from PID 183 (plymouthd). Feb 12 13:52:54 raspberrypi kernel: [ 7.586800] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready Feb 12 13:52:54 raspberrypi kernel: [ 7.586865] brcmfmac: power management disabled Feb 12 13:52:54 raspberrypi dhcpcd[372]: eth0: waiting for carrier Feb 12 13:52:54 raspberrypi dhcpcd[372]: wlan0: waiting for carrier Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: carrier acquired Feb 12 13:52:55 raspberrypi kernel: [ 8.083762] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Feb 12 13:52:55 raspberrypi kernel: [ 8.084106] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready Feb 12 13:52:55 raspberrypi dhcpcd[372]: DUID 00:01:00:01:21:b0:de:62:b8:27:eb:02:23:42 Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: IAID eb:02:23:42 Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: adding address fe80::8d26:4a15:cf1b:1ee2 Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: carrier lost Feb 12 13:52:55 raspberrypi dphys-swapfile[325]: Starting dphys-swapfile swapfile setup ... Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: deleting address fe80::8d26:4a15:cf1b:1ee2 Feb 12 13:52:55 raspberrypi dphys-swapfile[325]: want /var/swap=100MByte, checking existing: keeping it Feb 12 13:52:55 raspberrypi systemd[1]: Started Raise network interfaces. Feb 12 13:52:55 raspberrypi systemd[1]: Reached target Network. Feb 12 13:52:55 raspberrypi systemd[1]: Starting The Apache HTTP Server... Feb 12 13:52:55 raspberrypi systemd[1]: Starting Permit User Sessions... Feb 12 13:52:55 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility... Feb 12 13:52:55 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server... Feb 12 13:52:55 raspberrypi systemd[1]: Started Permit User Sessions. Feb 12 13:52:55 raspberrypi systemd[1]: Starting Light Display Manager... Feb 12 13:52:55 raspberrypi systemd[1]: Started /etc/rc.local Compatibility. Feb 12 13:52:55 raspberrypi systemd[1]: Starting Hold until boot process finishes up... Feb 12 13:52:55 raspberrypi systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 12 13:52:55 raspberrypi kernel: [ 8.466684] Adding 102396k swap on /var/swap. Priority:-1 extents:1 across:102396k SSFS Feb 12 13:52:55 raspberrypi kernel: [ 8.472639] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Feb 12 13:52:55 raspberrypi dphys-swapfile[325]: done. Feb 12 13:52:55 raspberrypi systemd[1]: Started LSB: Autogenerate and use a swap file. Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: carrier acquired Feb 12 13:52:55 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 183 (plymouthd). Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: IAID eb:02:23:42 Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: adding address fe80::2280:aaf:95f6:8492 Feb 12 13:52:55 raspberrypi systemd[1]: Started Hold until boot process finishes up. Feb 12 13:52:55 raspberrypi systemd[1]: Started Terminate Plymouth Boot Screen. Feb 12 13:52:55 raspberrypi systemd[1]: Started Getty on tty1. Feb 12 13:52:55 raspberrypi systemd[1]: Reached target Login Prompts. Feb 12 13:52:55 raspberrypi dhcpcd[372]: wlan0: rebinding lease of 192.168.1.65 Feb 12 13:52:56 raspberrypi systemd[1]: Started OpenBSD Secure Shell server. Feb 12 13:52:56 raspberrypi raspi-config[327]: Checking if shift key is held down: No. Switching to ondemand scaling governor. Feb 12 13:52:56 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed). Feb 12 13:52:56 raspberrypi dhcpcd[372]: wlan0: soliciting an IPv6 router Feb 12 13:52:56 raspberrypi lightdm[453]: 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 Feb 12 13:52:56 raspberrypi apachectl[439]: AH00557: apache2: apr_sockaddr_info_get() failed for raspberrypi Feb 12 13:52:56 raspberrypi apachectl[439]: AH00558: apache2: Could not reliably determine the server's fully qualified domain name, using 127.0.0.1. Set the 'ServerName' directive globally to suppress this message Feb 12 13:52:56 raspberrypi systemd[1]: Started Light Display Manager. Feb 12 13:52:56 raspberrypi systemd[1]: Started The Apache HTTP Server. Feb 12 13:52:56 raspberrypi avahi-daemon[360]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::2280:aaf:95f6:8492. Feb 12 13:52:56 raspberrypi avahi-daemon[360]: New relevant interface wlan0.IPv6 for mDNS. Feb 12 13:52:56 raspberrypi avahi-daemon[360]: Registering new address record for fe80::2280:aaf:95f6:8492 on wlan0.*. Feb 12 13:52:56 raspberrypi dhcpcd[372]: wlan0: probing address 192.168.1.65/24 Feb 12 13:52:58 raspberrypi kernel: [ 11.272287] Bluetooth: Core ver 2.22 Feb 12 13:52:58 raspberrypi kernel: [ 11.272333] NET: Registered protocol family 31 Feb 12 13:52:58 raspberrypi kernel: [ 11.272336] Bluetooth: HCI device and connection manager initialized Feb 12 13:52:58 raspberrypi kernel: [ 11.272347] Bluetooth: HCI socket layer initialized Feb 12 13:52:58 raspberrypi kernel: [ 11.272355] Bluetooth: L2CAP socket layer initialized Feb 12 13:52:58 raspberrypi kernel: [ 11.272373] Bluetooth: SCO socket layer initialized Feb 12 13:52:58 raspberrypi kernel: [ 11.282058] Bluetooth: HCI UART driver ver 2.3 Feb 12 13:52:58 raspberrypi kernel: [ 11.282069] Bluetooth: HCI UART protocol H4 registered Feb 12 13:52:58 raspberrypi kernel: [ 11.282074] Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 12 13:52:58 raspberrypi kernel: [ 11.282223] Bluetooth: HCI UART protocol Broadcom registered Feb 12 13:52:58 raspberrypi btuart[328]: bcm43xx_init Feb 12 13:52:58 raspberrypi btuart[328]: Flash firmware /lib/firmware/BCM43430A1.hcd Feb 12 13:52:58 raspberrypi btuart[328]: Set BDADDR UART: b8:27:eb:fd:dc:bd Feb 12 13:52:58 raspberrypi btuart[328]: Set Controller UART speed to 921600 bit/s Feb 12 13:52:58 raspberrypi btuart[328]: Device setup complete Feb 12 13:52:58 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 12 13:52:58 raspberrypi systemd[1]: Reached target Multi-User System. Feb 12 13:52:58 raspberrypi systemd[1]: Starting Bluetooth service... Feb 12 13:52:58 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 12 13:52:58 raspberrypi bluetoothd[555]: Bluetooth daemon 5.43 Feb 12 13:52:58 raspberrypi systemd[1]: Started Bluetooth service. Feb 12 13:52:58 raspberrypi systemd[1]: Started BluezALSA proxy. Feb 12 13:52:58 raspberrypi systemd[1]: Reached target Graphical Interface. Feb 12 13:52:58 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 12 13:52:58 raspberrypi bluetoothd[555]: Starting SDP server Feb 12 13:52:58 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status. Feb 12 13:52:58 raspberrypi systemd[1]: Reached target Bluetooth. Feb 12 13:52:58 raspberrypi kernel: [ 11.434776] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 12 13:52:58 raspberrypi kernel: [ 11.434786] Bluetooth: BNEP filters: protocol multicast Feb 12 13:52:58 raspberrypi kernel: [ 11.434806] Bluetooth: BNEP socket layer initialized Feb 12 13:52:58 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 12 13:52:58 raspberrypi systemd[1]: Startup finished in 1.836s (kernel) + 9.598s (userspace) = 11.434s. Feb 12 13:52:58 raspberrypi dbus[339]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Feb 12 13:52:58 raspberrypi bluetoothd[555]: Bluetooth management interface 1.14 initialized Feb 12 13:52:58 raspberrypi bluetoothd[555]: Failed to obtain handles for "Service Changed" characteristic Feb 12 13:52:58 raspberrypi bluetoothd[555]: Sap driver initialization failed. Feb 12 13:52:58 raspberrypi bluetoothd[555]: sap-server: Operation not permitted (1) Feb 12 13:52:58 raspberrypi systemd[1]: Starting Hostname Service... Feb 12 13:52:58 raspberrypi bluetoothd[555]: Endpoint registered: sender=:1.6 path=/A2DP/SBC/Source/1 Feb 12 13:52:58 raspberrypi bluetoothd[555]: Endpoint registered: sender=:1.6 path=/A2DP/SBC/Sink/1 Feb 12 13:52:58 raspberrypi kernel: [ 11.493855] Bluetooth: RFCOMM TTY layer initialized Feb 12 13:52:58 raspberrypi kernel: [ 11.493879] Bluetooth: RFCOMM socket layer initialized Feb 12 13:52:58 raspberrypi kernel: [ 11.493904] Bluetooth: RFCOMM ver 1.11 Feb 12 13:52:58 raspberrypi systemd[1]: Created slice User Slice of pi. Feb 12 13:52:58 raspberrypi systemd[1]: Starting User Manager for UID 1000... Feb 12 13:52:58 raspberrypi systemd[1]: Started Session c1 of user pi. Feb 12 13:52:58 raspberrypi systemd[582]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Feb 12 13:52:58 raspberrypi systemd[582]: Reached target Paths. Feb 12 13:52:58 raspberrypi systemd[582]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Feb 12 13:52:58 raspberrypi systemd[582]: Listening on GnuPG cryptographic agent (access for web browsers). Feb 12 13:52:58 raspberrypi systemd[582]: Reached target Timers. Feb 12 13:52:58 raspberrypi systemd[582]: Starting D-Bus User Message Bus Socket. Feb 12 13:52:58 raspberrypi systemd[582]: Listening on GnuPG cryptographic agent and passphrase cache. Feb 12 13:52:58 raspberrypi systemd[582]: Listening on D-Bus User Message Bus Socket. Feb 12 13:52:58 raspberrypi systemd[582]: Reached target Sockets. Feb 12 13:52:58 raspberrypi systemd[582]: Reached target Basic System. Feb 12 13:52:58 raspberrypi systemd[582]: Reached target Default. Feb 12 13:52:58 raspberrypi systemd[582]: Startup finished in 96ms. Feb 12 13:52:58 raspberrypi systemd[1]: Started User Manager for UID 1000. Feb 12 13:52:58 raspberrypi dbus[339]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 12 13:52:58 raspberrypi systemd[1]: Started Hostname Service. Feb 12 13:52:58 raspberrypi lightdm[598]: 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 Feb 12 13:52:58 raspberrypi systemd[1]: Started Session c2 of user pi. Feb 12 13:52:58 raspberrypi lightdm[453]: Error opening audit socket: Protocol not supported Feb 12 13:52:58 raspberrypi systemd[582]: Started D-Bus User Message Bus. Feb 12 13:53:01 raspberrypi dhcpcd[372]: wlan0: leased 192.168.1.65 for 86400 seconds Feb 12 13:53:07 raspberrypi avahi-daemon[360]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.65. Feb 12 13:53:07 raspberrypi dhcpcd[372]: wlan0: adding route to 192.168.1.0/24 Feb 12 13:53:07 raspberrypi dhcpcd[372]: wlan0: adding default route via 192.168.1.1 Feb 12 13:53:07 raspberrypi avahi-daemon[360]: New relevant interface wlan0.IPv4 for mDNS. Feb 12 13:53:07 raspberrypi avahi-daemon[360]: Registering new address record for 192.168.1.65 on wlan0.IPv4. Feb 12 13:53:10 raspberrypi dbus-daemon[625]: Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service' Feb 12 13:53:10 raspberrypi systemd[582]: Starting Virtual filesystem service... Feb 12 13:53:10 raspberrypi dbus-daemon[625]: Successfully activated service 'org.gtk.vfs.Daemon' Feb 12 13:53:10 raspberrypi systemd[582]: Started Virtual filesystem service. Feb 12 13:53:10 raspberrypi kernel: [ 23.471867] fuse init (API version 7.26) Feb 12 13:53:10 raspberrypi systemd[1]: Mounting FUSE Control File System... Feb 12 13:53:10 raspberrypi systemd[1]: Mounted FUSE Control File System. Feb 12 13:53:10 raspberrypi dbus[339]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' Feb 12 13:53:10 raspberrypi systemd[1]: Starting Authorization Manager... Feb 12 13:53:10 raspberrypi polkitd[773]: started daemon version 0.105 using authority implementation `local' version `0.105' Feb 12 13:53:10 raspberrypi dbus[339]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Feb 12 13:53:10 raspberrypi systemd[1]: Started Authorization Manager. Feb 12 13:53:11 raspberrypi dbus-daemon[625]: Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service' Feb 12 13:53:11 raspberrypi systemd[582]: Starting Virtual filesystem service - disk device monitor... Feb 12 13:53:12 raspberrypi dbus[339]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service' Feb 12 13:53:12 raspberrypi systemd[1]: Starting Disk Manager... Feb 12 13:53:12 raspberrypi udisksd[808]: udisks daemon version 2.1.8 starting Feb 12 13:53:12 raspberrypi dbus[339]: [system] Successfully activated service 'org.freedesktop.UDisks2' Feb 12 13:53:12 raspberrypi udisksd[808]: Acquired the name org.freedesktop.UDisks2 on the system message bus Feb 12 13:53:12 raspberrypi systemd[1]: Started Disk Manager. Feb 12 13:53:12 raspberrypi udisksd[808]: Cleaning up mount point /media/pi/SETTINGS (device 179:5 is not mounted) Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor' Feb 12 13:53:12 raspberrypi systemd[582]: Started Virtual filesystem service - disk device monitor. Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service' Feb 12 13:53:12 raspberrypi systemd[582]: Starting Virtual filesystem service - digital camera monitor... Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor' Feb 12 13:53:12 raspberrypi systemd[582]: Started Virtual filesystem service - digital camera monitor. Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service' Feb 12 13:53:12 raspberrypi systemd[582]: Starting Virtual filesystem service - Media Transfer Protocol monitor... Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor' Feb 12 13:53:12 raspberrypi systemd[582]: Started Virtual filesystem service - Media Transfer Protocol monitor. Feb 12 13:53:12 raspberrypi dhcpcd[372]: wlan0: no IPv6 Routers available Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service' Feb 12 13:53:12 raspberrypi systemd[582]: Starting Virtual filesystem service - GNOME Online Accounts monitor... Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor' Feb 12 13:53:12 raspberrypi systemd[582]: Started Virtual filesystem service - GNOME Online Accounts monitor. Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service' Feb 12 13:53:12 raspberrypi systemd[582]: Starting Virtual filesystem service - Apple File Conduit monitor... Feb 12 13:53:12 raspberrypi gvfs-afc-volume-monitor[828]: Volume monitor alive Feb 12 13:53:12 raspberrypi dbus-daemon[625]: Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor' Feb 12 13:53:12 raspberrypi systemd[582]: Started Virtual filesystem service - Apple File Conduit monitor. Feb 12 13:53:14 raspberrypi udisksd[808]: Mounted /dev/mmcblk0p5 at /media/pi/SETTINGS on behalf of uid 1000 Feb 12 13:53:14 raspberrypi kernel: [ 27.335573] EXT4-fs (mmcblk0p5): mounted filesystem with ordered data mode. Opts: (null) Feb 12 13:57:16 raspberrypi systemd[582]: Time has been changed Feb 12 13:57:16 raspberrypi systemd-timesyncd[301]: Synchronized to time server 194.239.123.230:123 (2.debian.pool.ntp.org). Feb 12 13:57:16 raspberrypi systemd[1]: Time has been changed Feb 12 13:57:16 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 6min 48.707727s random time. Feb 12 13:57:16 raspberrypi systemd[1]: apt-daily.timer: Adding 1h 48min 28.421867s random time. Feb 12 13:58:15 raspberrypi weewx[912]: engine: Initializing weewx version 3.8.0 Feb 12 13:58:15 raspberrypi weewx[912]: engine: Using Python 2.7.13 (default, Jan 19 2017, 14:48:08) #012[GCC 6.3.0 20170124] Feb 12 13:58:15 raspberrypi weewx[912]: engine: Platform Linux-4.9.59-v7+-armv7l-with-debian-9.1 Feb 12 13:58:15 raspberrypi weewx[912]: engine: Locale is 'en_US.UTF-8' Feb 12 13:58:15 raspberrypi weewx[912]: engine: Using configuration file /home/weewx/weewx.conf Feb 12 13:58:15 raspberrypi weewx[912]: engine: debug is 1 Feb 12 13:58:15 raspberrypi weewx[912]: engine: Initializing engine Feb 12 13:58:15 raspberrypi weewx[912]: engine: Loading station type Vantage (weewx.drivers.vantage) Feb 12 13:58:15 raspberrypi weewx[912]: vantage: Driver version is 3.0.10 Feb 12 13:58:15 raspberrypi weewx[912]: vantage: Opened up serial port /dev/ttyUSB0; baud 19200; timeout 4.00 Feb 12 13:58:15 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 13:58:15 raspberrypi weewx[912]: vantage: Hardware type is 16 Feb 12 13:58:15 raspberrypi weewx[912]: vantage: Hardware name: Vantage Pro2 Feb 12 13:58:15 raspberrypi weewx[912]: engine: Loading service weewx.engine.StdTimeSynch Feb 12 13:58:15 raspberrypi weewx[912]: engine: Finished loading service weewx.engine.StdTimeSynch Feb 12 13:58:15 raspberrypi weewx[912]: engine: Loading service weewx.engine.StdConvert Feb 12 13:58:15 raspberrypi weewx[912]: engine: StdConvert target unit is 0x1 Feb 12 13:58:15 raspberrypi weewx[912]: engine: Finished loading service weewx.engine.StdConvert Feb 12 13:58:15 raspberrypi weewx[912]: engine: Loading service weewx.engine.StdCalibrate Feb 12 13:58:15 raspberrypi weewx[912]: engine: Finished loading service weewx.engine.StdCalibrate Feb 12 13:58:15 raspberrypi weewx[912]: engine: Loading service weewx.engine.StdQC Feb 12 13:58:15 raspberrypi weewx[912]: engine: Finished loading service weewx.engine.StdQC Feb 12 13:58:15 raspberrypi weewx[912]: engine: Loading service weewx.wxservices.StdWXCalculate Feb 12 13:58:15 raspberrypi weewx[912]: 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 Feb 12 13:58:15 raspberrypi weewx[912]: wxcalculate: The following algorithms will be used for calculations: altimeter=aaNOAA, maxSolarRad=RS Feb 12 13:58:15 raspberrypi weewx[912]: engine: Finished loading service weewx.wxservices.StdWXCalculate Feb 12 13:58:15 raspberrypi weewx[912]: engine: Loading service weewx.engine.StdArchive Feb 12 13:58:15 raspberrypi weewx[912]: engine: Archive will use data binding wx_binding Feb 12 13:58:16 raspberrypi weewx[912]: engine: Record generation will be attempted in 'hardware' Feb 12 13:58:16 raspberrypi weewx[912]: engine: The archive interval in the configuration file (300) does not match the station hardware interval (600). Feb 12 13:58:16 raspberrypi weewx[912]: engine: Using archive interval of 600 seconds (specified by hardware) Feb 12 13:58:16 raspberrypi weewx[912]: engine: Use LOOP data in hi/low calculations: 1 Feb 12 13:58:16 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 13:58:16 raspberrypi weewx[912]: engine: Using binding 'wx_binding' to database 'weewx.sdb' Feb 12 13:58:16 raspberrypi weewx[912]: manager: Starting backfill of daily summaries Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.engine.StdArchive Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.restx.StdStationRegistry Feb 12 13:58:16 raspberrypi weewx[912]: restx: StationRegistry: Registration not requested. Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.restx.StdStationRegistry Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.restx.StdWunderground Feb 12 13:58:16 raspberrypi weewx[912]: restx: Wunderground: Posting not enabled. Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.restx.StdWunderground Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.restx.StdPWSweather Feb 12 13:58:16 raspberrypi weewx[912]: restx: PWSweather: Posting not enabled. Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.restx.StdPWSweather Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.restx.StdCWOP Feb 12 13:58:16 raspberrypi weewx[912]: restx: CWOP: Posting not enabled. Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.restx.StdCWOP Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.restx.StdWOW Feb 12 13:58:16 raspberrypi weewx[912]: restx: WOW: Posting not enabled. Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.restx.StdWOW Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.restx.StdAWEKAS Feb 12 13:58:16 raspberrypi weewx[912]: restx: AWEKAS: Posting not enabled. Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.restx.StdAWEKAS Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.engine.StdPrint Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.engine.StdPrint Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service weewx.engine.StdReport Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service weewx.engine.StdReport Feb 12 13:58:16 raspberrypi weewx[912]: engine: Loading service user.alarm.MyAlarm Feb 12 13:58:16 raspberrypi weewx[912]: alarm: Alarm set for expression: 'outTemp < 60.0' Feb 12 13:58:16 raspberrypi weewx[912]: engine: Finished loading service user.alarm.MyAlarm Feb 12 13:58:16 raspberrypi weewx[912]: engine: Starting up weewx version 3.8.0 Feb 12 13:58:16 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 13:58:16 raspberrypi weewx[912]: engine: Clock error is 0.77 seconds (positive is fast) Feb 12 13:58:16 raspberrypi weewx[912]: vantage: Getting archive packets since 2018-02-12 12:20:00 CET (1518434400) Feb 12 13:58:16 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 13:58:16 raspberrypi weewx[912]: vantage: Retrieving 3 page(s); starting index= 4 Feb 12 13:58:16 raspberrypi weewx[912]: manager: Added record 2018-02-12 12:30:00 CET (1518435000) to database 'weewx.sdb' Feb 12 13:58:16 raspberrypi weewx[912]: manager: Added record 2018-02-12 12:30:00 CET (1518435000) to daily summary in 'weewx.sdb' Feb 12 13:58:17 raspberrypi weewx[912]: alarm: Alarm expression "outTemp < 60.0" evaluated True at 2018-02-12 12:30:00 CET (1518435000) Feb 12 13:58:17 raspberrypi weewx[912]: manager: Added record 2018-02-12 12:40:00 CET (1518435600) to database 'weewx.sdb' Feb 12 13:58:17 raspberrypi weewx[912]: manager: Added record 2018-02-12 12:40:00 CET (1518435600) to daily summary in 'weewx.sdb' Feb 12 13:58:20 raspberrypi weewx[912]: manager: Added record 2018-02-12 12:50:00 CET (1518436200) to database 'weewx.sdb' Feb 12 13:58:20 raspberrypi weewx[912]: manager: Added record 2018-02-12 12:50:00 CET (1518436200) to daily summary in 'weewx.sdb' Feb 12 13:58:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:00:00 CET (1518436800) to database 'weewx.sdb' Feb 12 13:58:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:00:00 CET (1518436800) to daily summary in 'weewx.sdb' Feb 12 13:58:24 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:10:00 CET (1518437400) to database 'weewx.sdb' Feb 12 13:58:24 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:10:00 CET (1518437400) to daily summary in 'weewx.sdb' Feb 12 13:58:27 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:20:00 CET (1518438000) to database 'weewx.sdb' Feb 12 13:58:27 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:20:00 CET (1518438000) to daily summary in 'weewx.sdb' Feb 12 13:58:27 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:30:00 CET (1518438600) to database 'weewx.sdb' Feb 12 13:58:27 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:30:00 CET (1518438600) to daily summary in 'weewx.sdb' Feb 12 13:58:30 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:40:00 CET (1518439200) to database 'weewx.sdb' Feb 12 13:58:30 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:40:00 CET (1518439200) to daily summary in 'weewx.sdb' Feb 12 13:58:30 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:50:00 CET (1518439800) to database 'weewx.sdb' Feb 12 13:58:30 raspberrypi weewx[912]: manager: Added record 2018-02-12 13:50:00 CET (1518439800) to daily summary in 'weewx.sdb' Feb 12 13:58:34 raspberrypi weewx[912]: vantage: DMPAFT complete: page timestamp 2018-01-25 20:10:00 CET (1516907400) less than final timestamp 2018-02-12 13:50:00 CET (1518439800) Feb 12 13:58:34 raspberrypi weewx[912]: vantage: Catch up complete. Feb 12 13:58:34 raspberrypi weewx[912]: engine: Starting main packet loop. Feb 12 13:58:34 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 13:58:34 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 13:58:34 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:00:16 raspberrypi weewx[912]: vantage: Getting archive packets since 2018-02-12 13:50:00 CET (1518439800) Feb 12 14:00:16 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:00:16 raspberrypi weewx[912]: vantage: Retrieving 1 page(s); starting index= 3 Feb 12 14:00:16 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:00:00 CET (1518440400) to database 'weewx.sdb' Feb 12 14:00:16 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:00:00 CET (1518440400) to daily summary in 'weewx.sdb' Feb 12 14:00:17 raspberrypi weewx[912]: vantage: DMPAFT complete: page timestamp 2018-01-25 20:20:00 CET (1516908000) less than final timestamp 2018-02-12 14:00:00 CET (1518440400) Feb 12 14:00:17 raspberrypi weewx[912]: vantage: Catch up complete. Feb 12 14:00:17 raspberrypi weewx[912]: reportengine: Running reports for latest time in the database. Feb 12 14:00:17 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:00:17 raspberrypi weewx[912]: reportengine: Running report StandardReport Feb 12 14:00:17 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:00:17 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Standard/skin.conf for report StandardReport Feb 12 14:00:18 raspberrypi weewx[912]: cheetahgenerator: using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras'] Feb 12 14:00:18 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:00:24 raspberrypi weewx[912]: cheetahgenerator: Generated 14 files for report StandardReport in 5.88 seconds Feb 12 14:00:24 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:00:27 raspberrypi weewx[912]: imagegenerator: Generated 24 images for StandardReport in 3.41 seconds Feb 12 14:00:28 raspberrypi weewx[912]: copygenerator: copied 14 files to /home/weewx/public_html Feb 12 14:00:28 raspberrypi weewx[912]: reportengine: Running report FTP Feb 12 14:00:28 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Ftp/skin.conf for report FTP Feb 12 14:00:28 raspberrypi weewx[912]: ftpgenerator: FTP upload not requested. Skipped. Feb 12 14:00:28 raspberrypi weewx[912]: reportengine: Running report RSYNC Feb 12 14:00:28 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Rsync/skin.conf for report RSYNC Feb 12 14:00:28 raspberrypi weewx[912]: rsyncgenerator: rsync upload not requested. Skipped. Feb 12 14:06:54 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:06:54 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:10:16 raspberrypi weewx[912]: vantage: Getting archive packets since 2018-02-12 14:00:00 CET (1518440400) Feb 12 14:10:16 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:10:16 raspberrypi weewx[912]: vantage: Retrieving 1 page(s); starting index= 4 Feb 12 14:10:16 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:10:00 CET (1518441000) to database 'weewx.sdb' Feb 12 14:10:16 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:10:00 CET (1518441000) to daily summary in 'weewx.sdb' Feb 12 14:10:18 raspberrypi weewx[912]: alarm: Alarm expression "outTemp < 60.0" evaluated True at 2018-02-12 14:10:00 CET (1518441000) Feb 12 14:10:18 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:10:18 raspberrypi weewx[912]: reportengine: Running reports for latest time in the database. Feb 12 14:10:18 raspberrypi weewx[912]: reportengine: Running report StandardReport Feb 12 14:10:18 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:10:18 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Standard/skin.conf for report StandardReport Feb 12 14:10:18 raspberrypi weewx[912]: cheetahgenerator: using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras'] Feb 12 14:10:18 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:10:19 raspberrypi weewx[912]: cheetahgenerator: Generated 14 files for report StandardReport in 1.47 seconds Feb 12 14:10:19 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:10:20 raspberrypi weewx[912]: imagegenerator: Generated 12 images for StandardReport in 0.82 seconds Feb 12 14:10:20 raspberrypi weewx[912]: copygenerator: copied 0 files to /home/weewx/public_html Feb 12 14:10:20 raspberrypi weewx[912]: reportengine: Running report FTP Feb 12 14:10:20 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Ftp/skin.conf for report FTP Feb 12 14:10:20 raspberrypi weewx[912]: ftpgenerator: FTP upload not requested. Skipped. Feb 12 14:10:20 raspberrypi weewx[912]: reportengine: Running report RSYNC Feb 12 14:10:20 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Rsync/skin.conf for report RSYNC Feb 12 14:10:20 raspberrypi weewx[912]: rsyncgenerator: rsync upload not requested. Skipped. Feb 12 14:11:38 raspberrypi systemd[1]: Starting Cleanup of Temporary Directories... Feb 12 14:11:39 raspberrypi systemd[1]: Started Cleanup of Temporary Directories. Feb 12 14:16:54 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:16:54 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:17:01 raspberrypi CRON[936]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Feb 12 14:20:16 raspberrypi weewx[912]: vantage: Getting archive packets since 2018-02-12 14:10:00 CET (1518441000) Feb 12 14:20:21 raspberrypi weewx[912]: vantage: Retry #0 failed Feb 12 14:20:21 raspberrypi weewx[912]: vantage: Rude wake up of console successful Feb 12 14:20:21 raspberrypi weewx[912]: vantage: Retrieving 1 page(s); starting index= 0 Feb 12 14:20:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:20:00 CET (1518441600) to database 'weewx.sdb' Feb 12 14:20:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:20:00 CET (1518441600) to daily summary in 'weewx.sdb' Feb 12 14:20:23 raspberrypi weewx[912]: vantage: DMPAFT complete: page timestamp 2018-01-25 20:40:00 CET (1516909200) less than final timestamp 2018-02-12 14:20:00 CET (1518441600) Feb 12 14:20:23 raspberrypi weewx[912]: vantage: Catch up complete. Feb 12 14:20:23 raspberrypi weewx[912]: reportengine: Running reports for latest time in the database. Feb 12 14:20:23 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:20:23 raspberrypi weewx[912]: reportengine: Running report StandardReport Feb 12 14:20:23 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:20:23 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Standard/skin.conf for report StandardReport Feb 12 14:20:23 raspberrypi weewx[912]: cheetahgenerator: using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras'] Feb 12 14:20:23 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:20:24 raspberrypi weewx[912]: cheetahgenerator: Generated 14 files for report StandardReport in 1.54 seconds Feb 12 14:20:24 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:20:25 raspberrypi weewx[912]: imagegenerator: Generated 12 images for StandardReport in 0.80 seconds Feb 12 14:20:25 raspberrypi weewx[912]: copygenerator: copied 0 files to /home/weewx/public_html Feb 12 14:20:25 raspberrypi weewx[912]: reportengine: Running report FTP Feb 12 14:20:25 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Ftp/skin.conf for report FTP Feb 12 14:20:25 raspberrypi weewx[912]: ftpgenerator: FTP upload not requested. Skipped. Feb 12 14:20:25 raspberrypi weewx[912]: reportengine: Running report RSYNC Feb 12 14:20:25 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Rsync/skin.conf for report RSYNC Feb 12 14:20:25 raspberrypi weewx[912]: rsyncgenerator: rsync upload not requested. Skipped. Feb 12 14:26:58 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:26:58 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:30:16 raspberrypi weewx[912]: vantage: Getting archive packets since 2018-02-12 14:20:00 CET (1518441600) Feb 12 14:30:21 raspberrypi weewx[912]: vantage: Retry #0 failed Feb 12 14:30:21 raspberrypi weewx[912]: vantage: Rude wake up of console successful Feb 12 14:30:21 raspberrypi weewx[912]: vantage: Retrieving 1 page(s); starting index= 1 Feb 12 14:30:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:30:00 CET (1518442200) to database 'weewx.sdb' Feb 12 14:30:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:30:00 CET (1518442200) to daily summary in 'weewx.sdb' Feb 12 14:30:22 raspberrypi weewx[912]: alarm: Alarm expression "outTemp < 60.0" evaluated True at 2018-02-12 14:30:00 CET (1518442200) Feb 12 14:30:22 raspberrypi weewx[912]: vantage: DMPAFT complete: page timestamp 2018-01-25 20:50:00 CET (1516909800) less than final timestamp 2018-02-12 14:30:00 CET (1518442200) Feb 12 14:30:22 raspberrypi weewx[912]: vantage: Catch up complete. Feb 12 14:30:22 raspberrypi weewx[912]: reportengine: Running reports for latest time in the database. Feb 12 14:30:22 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:30:22 raspberrypi weewx[912]: reportengine: Running report StandardReport Feb 12 14:30:22 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:30:22 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Standard/skin.conf for report StandardReport Feb 12 14:30:22 raspberrypi weewx[912]: cheetahgenerator: using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras'] Feb 12 14:30:22 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:30:23 raspberrypi weewx[912]: cheetahgenerator: Generated 14 files for report StandardReport in 1.45 seconds Feb 12 14:30:23 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:30:24 raspberrypi weewx[912]: imagegenerator: Generated 12 images for StandardReport in 0.88 seconds Feb 12 14:30:24 raspberrypi weewx[912]: copygenerator: copied 0 files to /home/weewx/public_html Feb 12 14:30:24 raspberrypi weewx[912]: reportengine: Running report FTP Feb 12 14:30:24 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Ftp/skin.conf for report FTP Feb 12 14:30:24 raspberrypi weewx[912]: ftpgenerator: FTP upload not requested. Skipped. Feb 12 14:30:24 raspberrypi weewx[912]: reportengine: Running report RSYNC Feb 12 14:30:24 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Rsync/skin.conf for report RSYNC Feb 12 14:30:24 raspberrypi weewx[912]: rsyncgenerator: rsync upload not requested. Skipped. Feb 12 14:36:58 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:36:58 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:40:16 raspberrypi weewx[912]: vantage: Getting archive packets since 2018-02-12 14:30:00 CET (1518442200) Feb 12 14:40:21 raspberrypi weewx[912]: vantage: Retry #0 failed Feb 12 14:40:21 raspberrypi weewx[912]: vantage: Rude wake up of console successful Feb 12 14:40:21 raspberrypi weewx[912]: vantage: Retrieving 1 page(s); starting index= 2 Feb 12 14:40:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:40:00 CET (1518442800) to database 'weewx.sdb' Feb 12 14:40:21 raspberrypi weewx[912]: manager: Added record 2018-02-12 14:40:00 CET (1518442800) to daily summary in 'weewx.sdb' Feb 12 14:40:22 raspberrypi weewx[912]: vantage: DMPAFT complete: page timestamp 2018-01-25 21:00:00 CET (1516910400) less than final timestamp 2018-02-12 14:40:00 CET (1518442800) Feb 12 14:40:22 raspberrypi weewx[912]: vantage: Catch up complete. Feb 12 14:40:22 raspberrypi weewx[912]: reportengine: Running reports for latest time in the database. Feb 12 14:40:22 raspberrypi weewx[912]: vantage: Requesting 200 LOOP packets. Feb 12 14:40:22 raspberrypi weewx[912]: reportengine: Running report StandardReport Feb 12 14:40:22 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Standard/skin.conf for report StandardReport Feb 12 14:40:22 raspberrypi weewx[912]: cheetahgenerator: using search list ['weewx.cheetahgenerator.Almanac', 'weewx.cheetahgenerator.Station', 'weewx.cheetahgenerator.Current', 'weewx.cheetahgenerator.Stats', 'weewx.cheetahgenerator.UnitInfo', 'weewx.cheetahgenerator.Extras'] Feb 12 14:40:22 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:40:22 raspberrypi weewx[912]: vantage: Gentle wake up of console successful Feb 12 14:40:24 raspberrypi weewx[912]: cheetahgenerator: Generated 14 files for report StandardReport in 1.53 seconds Feb 12 14:40:24 raspberrypi weewx[912]: manager: Daily summary version is 2.0 Feb 12 14:40:25 raspberrypi weewx[912]: imagegenerator: Generated 12 images for StandardReport in 0.84 seconds Feb 12 14:40:25 raspberrypi weewx[912]: copygenerator: copied 0 files to /home/weewx/public_html Feb 12 14:40:25 raspberrypi weewx[912]: reportengine: Running report FTP Feb 12 14:40:25 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Ftp/skin.conf for report FTP Feb 12 14:40:25 raspberrypi weewx[912]: ftpgenerator: FTP upload not requested. Skipped. Feb 12 14:40:25 raspberrypi weewx[912]: reportengine: Running report RSYNC Feb 12 14:40:25 raspberrypi weewx[912]: reportengine: Found configuration file /home/weewx/skins/Rsync/skin.conf for report RSYNC Feb 12 14:40:25 raspberrypi weewx[912]: rsyncgenerator: rsync upload not requested. Skipped. Feb 12 14:44:26 raspberrypi weewx[912]: engine: Main loop exiting. Shutting engine down. Feb 12 14:44:26 raspberrypi weewx[912]: engine: Shutting down StdReport thread Feb 12 14:44:26 raspberrypi weewx[912]: engine: StdReport thread has been terminated Feb 12 14:44:26 raspberrypi weewx[912]: engine: Keyboard interrupt. Feb 12 14:44:42 raspberrypi systemd[1]: Started Session c3 of user pi.