Uhrzeit verstellt sich im laufenden Betrieb (Raspberry)

[bassman]

Hallo zusammen,

ich habe hier ein Phänomen, das ich nicht erklären kann.
Bei meinem Raspberry verstellt sich ab und zu (meist 1x täglich) die Uhr im laufenden Betrieb.
Manchmal läuft das ganze aber auch 4 Tage ohne Uhrzeitwechsel durch.

Da ein Watchdog auf Filebasis (letztes Änderungsdatum) läuft, startet die Kiste neu.

ntp läuft und bekommt auch die richtigen Daten.

Hat jemand ein ähnliches Verhalten beobachtet?

System:
- Raspberry 2 mit Jessie
- FHEM 5.8
- ngnix als Reverse Proxy
- pi-hole

[Hollo]

...ntp läuft und bekommt auch die richtigen Daten...

Dann lass uns doch mal an der config teilhaben bzw. gib uns mehr Infos.

[bassman]

Auszug aus  der /etc/ntp.conf

Code Auswählen Erweitern

# /etc/ntp.conf, configuration for ntpd; see ntp.conf(5) for help

driftfile /var/lib/ntp/ntp.drift

# Enable this if you want statistics to be logged.
#statsdir /var/log/ntpstats/

statistics loopstats peerstats clockstats
filegen loopstats file loopstats type day enable
filegen peerstats file peerstats type day enable
filegen clockstats file clockstats type day enable


# You do need to talk to an NTP server or two (or three).
#server ntp.your-provider.example

# pool.ntp.org maps to about 1000 low-stratum NTP servers.  Your server will
# pick a different set every time it starts up.  Please consider joining the
# pool: <http://www.pool.ntp.org/join.html>
server rustime01.rus.uni-stuttgart.de
server ptbtime1.ptb.de
server ptbtime2.ptb.de
server ptbtimer.ptb.de
server 0.de.pool.ntp.org

#server 192.168.178.1


# By default, exchange time with everybody, but don't allow configuration.
restrict -4 default kod notrap nomodify nopeer noquery
restrict -6 default kod notrap nomodify nopeer noquery

# Local users may interrogate the ntp server more closely.
restrict 127.0.0.1
restrict ::1

# Clients from this (example!) subnet have unlimited access, but only if
# cryptographically authenticated.
#restrict 192.168.123.0 mask 255.255.255.0 notrust


[moskito]

Hi,

Code Auswählen Erweitern

server ptbtimer.ptb.de
Den Server gibts in Braunschweig nicht, sollte allerdings nicht dein Fehlerbild verursachen.

Hast du evtl. mal etwas aus den Systemlogs so um einen Zeitsprung herum?

[amenomade]

Ich würde min. 2 max. 3 Server nutzen, und möglicherweise in der gleiche Domäne und möglichst nah von meinem Wohnort.
Mit pool.ntp.org kriegst Du jedes mal (neuboot) neue Server, ohne zu wissen, wo sie liegen. Abhängig von der Netzwerkverbindung, kann es zu Millisekunden Verzögerung führen.

Ich glaube aber nicht, dass es die einzige Ursache ist.

Gruß

[Wernieman]

Vorallem auch, da NTP die Zeit nichts sprunghaft ändert. Hast Du eventuell zusätzlich ein ntpdate per Cron laufen?

[Hollo]

Schmeiss die PTB aus der config raus und nehm nur einen Eintrag von de.pool.ntp.org .
Wie Werniemann schon schrieb, gibt es bei ntp keine "großen" Zeitsprünge, da müsste dann noch was anderes aktiv sein.

Woran erkennst Du denn, dass die Zeit (und um wie viel) geändert wurde?
Kann es evtl. auch ein "Fehler" im watchdog sein?

[kadettilac89]

Wie von den Vorrednern schon gesagt ...

- Cron?
- Daemon?
- rc.local ...
--> Hast du iregndwo noch andere Programme laufen die Zeiten ändern? Vielleicht siehst du mit "ps -ef | grep -i time" oder ".... -i ntp" mehrere Prozesse die "komisch" aussehen

Zusätzlich, hast du irgendwelche Fehler im Syslog oder generell in den Logs? Führe auch mal Raspi-Config nochmal durch und setze die locals auf Deutschland und CET oder welche Zeitzonde du haben willst. Hast du irgendwo Zeitzonen pro User festgelegt und es wird verstellt wenn du dich mit unterschiedlichen User einloggst?

[bassman]

Hier ein Besipiel der Zeitverstellung (sprang von 2:55 zurück auf 2:17, dadurch Reboot ausgelöst, Watchdog)

Auszug aus dem FileLog:

Code Auswählen Erweitern

2017-05-19_02:53:20 RaspberryStat 0.06 0.05 0.05
2017-05-19_02:53:20 RaspberryStat load: 0.06
2017-05-19_02:53:20 RaspberryStat temperature: 32.552
2017-05-19_02:53:20 RaspberryStat uptime: 0 days, 19:47
2017-05-19_02:55:20 RaspberryStat 0.09 0.06 0.05
2017-05-19_02:55:20 RaspberryStat load: 0.09
2017-05-19_02:55:20 RaspberryStat temperature: 33.628
2017-05-19_02:55:20 RaspberryStat uptime: 0 days, 19:49
2017-05-19_02:19:43 RaspberryStat 0.48 0.64 0.28
2017-05-19_02:19:43 RaspberryStat load: 0.48
2017-05-19_02:19:43 RaspberryStat temperature: 34.166
2017-05-19_02:19:43 RaspberryStat uptime: 0 days, 0:02
2017-05-19_03:06:53 RaspberryStat 0.32 0.57 0.28
2017-05-19_03:06:53 RaspberryStat load: 0.32
2017-05-19_03:06:53 RaspberryStat temperature: 33.628
2017-05-19_03:06:53 RaspberryStat uptime: 0 days, 0:03
2017-05-19_03:08:54 RaspberryStat 0.17 0.44 0.26
2017-05-19_03:08:54 RaspberryStat load: 0.17
2017-05-19_03:08:54 RaspberryStat temperature: 34.166
2017-05-19_03:08:54 RaspberryStat uptime: 0 days, 0:05


Auszug aus dem Syslog:

Code Auswählen Erweitern

May 18 07:05:27 raspberrypi avahi-daemon[328]: avahi-daemon 0.6.31 exiting.
May 18 07:05:27 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="331" x-info="http://www.rsyslog.com"] exiting on signal 15.
May 19 02:17:04 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="331" x-info="http://www.rsyslog.com"] start
May 19 02:17:04 raspberrypi systemd-modules-load[108]: Module 'bcm2835_wdt' is builtin
May 19 02:17:04 raspberrypi systemd[1]: Mounted Debug File System.
May 19 02:17:04 raspberrypi systemd[1]: Mounted POSIX Message Queue File System.
May 19 02:17:04 raspberrypi systemd[1]: Mounted Configuration File System.
May 19 02:17:04 raspberrypi systemd[1]: Started Create list of required static device nodes for the current kernel.
May 19 02:17:04 raspberrypi systemd[1]: Started Apply Kernel Variables.
May 19 02:17:04 raspberrypi systemd[1]: Time has been changed
May 19 02:17:04 raspberrypi systemd[1]: Started Restore / save the current clock.
May 19 02:17:04 raspberrypi systemd[1]: Started udev Coldplug all Devices.
May 19 02:17:04 raspberrypi systemd[1]: Started File System Check on Root Device.
May 19 02:17:04 raspberrypi systemd[1]: Starting udev Wait for Complete Device Initialization...
May 19 02:17:04 raspberrypi systemd[1]: Starting Create Static Device Nodes in /dev...
May 19 02:17:04 raspberrypi fake-hwclock[105]: Fr 19. Mai 00:17:01 UTC 2017
May 19 02:17:04 raspberrypi systemd-fsck[113]: e2fsck 1.42.12 (29-Aug-2014)
May 19 02:17:04 raspberrypi systemd-fsck[113]: root0: sauber, 109321/472352 Dateien, 897703/1888256 Blöcke
May 19 02:17:04 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
May 19 02:17:04 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
May 19 02:17:04 raspberrypi systemd[1]: Started udev Kernel Device Manager.
May 19 02:17:04 raspberrypi systemd[1]: Starting LSB: Set preliminary keymap...
May 19 02:17:04 raspberrypi nfs-common[114]: Starting NFS common utilities: statd
May 19 02:17:04 raspberrypi nfs-common[114]: Not starting: portmapper is not running ... (warning).
May 19 02:17:04 raspberrypi systemd[1]: Started LSB: NFS support files common to client and server.
May 19 02:17:04 raspberrypi systemd[1]: Found device /dev/ttyAMA0.
May 19 02:17:04 raspberrypi keyboard-setup[148]: Setting preliminary keymap...done.
May 19 02:17:04 raspberrypi systemd[1]: Started LSB: Set preliminary keymap.
May 19 02:17:04 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen...
May 19 02:17:04 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
May 19 02:17:04 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
May 19 02:17:04 raspberrypi systemd[1]: Found device /dev/mmcblk0p6.
May 19 02:17:04 raspberrypi systemd[1]: Received SIGRTMIN+20 from PID 220 (plymouthd).
May 19 02:17:04 raspberrypi systemd[1]: Started Show Plymouth Boot Screen.
May 19 02:17:04 raspberrypi systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch.
May 19 02:17:04 raspberrypi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
May 19 02:17:04 raspberrypi systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
May 19 02:17:04 raspberrypi systemd[1]: Starting Paths.
May 19 02:17:04 raspberrypi systemd[1]: Reached target Paths.
May 19 02:17:04 raspberrypi systemd[1]: Starting Sound Card.
May 19 02:17:04 raspberrypi systemd[1]: Reached target Sound Card.
May 19 02:17:04 raspberrypi systemd[1]: Starting File System Check on /dev/mmcblk0p6...
May 19 02:17:04 raspberrypi systemd[1]: Started Various fixups to make systemd work better on Debian.
May 19 02:17:04 raspberrypi systemd[1]: Starting Local File Systems (Pre).
May 19 02:17:04 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
May 19 02:17:04 raspberrypi systemd[1]: Mounting /usr/local/vpnserver/server_log...
May 19 02:17:04 raspberrypi systemd[1]: Mounting /var/www/html/smartVISU/temp...
May 19 02:17:04 raspberrypi systemd[1]: var-www-html-smartVISU-temp.mount: Directory /var/www/html/smartVISU/temp to mount over is not empty, mounting anyway.
May 19 02:17:04 raspberrypi systemd[1]: Mounting /var/www/html/sv/temp...
May 19 02:17:04 raspberrypi systemd[1]: var-www-html-sv-temp.mount: Directory /var/www/html/sv/temp to mount over is not empty, mounting anyway.
May 19 02:17:04 raspberrypi systemd[1]: Mounting /var/log...
May 19 02:17:04 raspberrypi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
May 19 02:17:04 raspberrypi systemd[1]: Starting Load/Save Random Seed...
May 19 02:17:04 raspberrypi systemd[1]: Mounted /var/www/html/sv/temp.
May 19 02:17:04 raspberrypi systemd[1]: Mounted /var/www/html/smartVISU/temp.
May 19 02:17:04 raspberrypi systemd[1]: Mounted /usr/local/vpnserver/server_log.
May 19 02:17:04 raspberrypi systemd[1]: Mounted /var/log.
May 19 02:17:04 raspberrypi systemd[1]: Started Load/Save Random Seed.
May 19 02:17:04 raspberrypi systemd-fsck[224]: fsck.fat 3.0.27 (2014-11-12)
May 19 02:17:04 raspberrypi systemd-fsck[224]: /dev/mmcblk0p6: 137 files, 10744/33717 clusters
May 19 02:17:04 raspberrypi systemd[1]: Started File System Check on /dev/mmcblk0p6.
May 19 02:17:04 raspberrypi systemd[1]: Mounting /boot...
May 19 02:17:04 raspberrypi systemd[1]: Mounting /var/log/nginx...
May 19 02:17:04 raspberrypi systemd[1]: Mounted /var/log/nginx.
May 19 02:17:04 raspberrypi systemd[1]: Mounted /boot.
May 19 02:17:04 raspberrypi systemd[1]: Starting Local File Systems.
May 19 02:17:04 raspberrypi systemd[1]: Reached target Local File Systems.
May 19 02:17:04 raspberrypi systemd[1]: Starting Start/stop tmpfs logfile saving...
May 19 02:17:04 raspberrypi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
May 19 02:17:04 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
May 19 02:17:04 raspberrypi systemd[1]: Starting LSB: Raise network interfaces....
May 19 02:17:04 raspberrypi varlog[244]: *** Starting tmpfs file restore: varlog.
May 19 02:17:04 raspberrypi systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
May 19 02:17:04 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
May 19 02:17:04 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
May 19 02:17:04 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
May 19 02:17:04 raspberrypi systemd[1]: Started udev Wait for Complete Device Initialization.
May 19 02:17:04 raspberrypi systemd[1]: Starting Copy rules generated while the root was ro...
May 19 02:17:04 raspberrypi systemd[1]: Started Copy rules generated while the root was ro.
May 19 02:17:04 raspberrypi networking[247]: Configuring network interfaces...done.
May 19 02:17:04 raspberrypi systemd[1]: Started LSB: Raise network interfaces..
May 19 02:17:04 raspberrypi systemd[1]: Starting System Initialization.
May 19 02:17:04 raspberrypi systemd[1]: Reached target System Initialization.
May 19 02:17:04 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket.
May 19 02:17:04 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
May 19 02:17:04 raspberrypi systemd[1]: Starting D-Bus System Message Bus Socket.
May 19 02:17:04 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
May 19 02:17:04 raspberrypi systemd[1]: Starting Sockets.
May 19 02:17:04 raspberrypi systemd[1]: Reached target Sockets.
May 19 02:17:04 raspberrypi systemd[1]: Starting Run certbot twice daily.
May 19 02:17:04 raspberrypi systemd[1]: Started Run certbot twice daily.
May 19 02:17:04 raspberrypi systemd[1]: Starting Daily Cleanup of Temporary Directories.
May 19 02:17:04 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
May 19 02:17:04 raspberrypi systemd[1]: Starting Timers.
May 19 02:17:04 raspberrypi systemd[1]: Reached target Timers.
May 19 02:17:04 raspberrypi systemd[1]: Started Manage Sound Card State (restore and store).
May 19 02:17:04 raspberrypi systemd[1]: Starting Restore Sound Card State...
May 19 02:17:04 raspberrypi systemd[1]: Started Restore Sound Card State.
May 19 02:17:04 raspberrypi ntpdate[316]: Can't find host rustime01.rus.uni-stuttgart.de: Name or service not known (-2)
May 19 02:17:04 raspberrypi ntpdate[316]: Can't find host 0.de.pool.ntp.org: Name or service not known (-2)
May 19 02:17:04 raspberrypi ntpdate[316]: no servers can be used, exiting
May 19 02:17:04 raspberrypi systemd[1]: Started Start/stop tmpfs logfile saving.
May 19 02:17:04 raspberrypi systemd[1]: Starting Basic System.
May 19 02:17:04 raspberrypi systemd[1]: Reached target Basic System.
May 19 02:17:04 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
May 19 02:17:04 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART.
May 19 02:17:04 raspberrypi systemd[1]: Started Turn on SSH if /boot/ssh is present.
May 19 02:17:04 raspberrypi systemd[1]: Starting Regular background program processing daemon...
May 19 02:17:04 raspberrypi systemd[1]: Started Regular background program processing daemon.
May 19 02:17:04 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
May 19 02:17:04 raspberrypi systemd[1]: Starting System Logging Service...
May 19 02:17:04 raspberrypi systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
May 19 02:17:04 raspberrypi systemd[1]: Starting D-Bus System Message Bus...
May 19 02:17:04 raspberrypi systemd[1]: Started D-Bus System Message Bus.
May 19 02:17:04 raspberrypi cron[329]: (CRON) INFO (pidfile fd = 3)
May 19 02:17:04 raspberrypi dhcpcd[330]: version 6.7.1 starting
May 19 02:17:04 raspberrypi cron[329]: (CRON) INFO (Running @reboot jobs)
May 19 02:17:04 raspberrypi dhcpcd[330]: dev: loaded udev
May 19 02:17:04 raspberrypi avahi-daemon[328]: Found user 'avahi' (UID 105) and group 'avahi' (GID 110).
May 19 02:17:04 raspberrypi avahi-daemon[328]: Successfully dropped root privileges.
May 19 02:17:04 raspberrypi avahi-daemon[328]: avahi-daemon 0.6.31 starting up.
May 19 02:17:04 raspberrypi dhcpcd[330]: eth0: adding address fe80::2391:5b86:313e:659a
May 19 02:17:04 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0xf00
May 19 02:17:04 raspberrypi kernel: [    0.000000] Linux version 4.9.24-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #993 SMP Wed Apr 26 18:01:23 BST 2017
May 19 02:17:04 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fc075] revision 5 (ARMv7), cr=10c5387d
May 19 02:17:04 raspberrypi kernel: [    0.000000] CPU: div instructions available: patching division code
May 19 02:17:04 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
May 19 02:17:04 raspberrypi kernel: [    0.000000] OF: fdt:Machine model: Raspberry Pi 2 Model B Rev 1.1
May 19 02:17:04 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x3d800000
May 19 02:17:04 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
May 19 02:17:04 raspberrypi kernel: [    0.000000] On node 0 totalpages: 253952
May 19 02:17:04 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c6edc0, node_mem_map bcf3e000
May 19 02:17:04 raspberrypi kernel: [    0.000000]   Normal zone: 2232 pages used for memmap
May 19 02:17:04 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
May 19 02:17:04 raspberrypi kernel: [    0.000000]   Normal zone: 253952 pages, LIFO batch:31
May 19 02:17:04 raspberrypi kernel: [    0.000000] percpu: Embedded 14 pages/cpu @bcef9000 s25600 r8192 d23552 u57344
May 19 02:17:04 raspberrypi kernel: [    0.000000] pcpu-alloc: s25600 r8192 d23552 u57344 alloc=14*4096
May 19 02:17:04 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
May 19 02:17:04 raspberrypi kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 251720
May 19 02:17:04 raspberrypi kernel: [    0.000000] Kernel command line: bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ea00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 root=/dev/mmcblk0p7 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
May 19 02:17:04 raspberrypi kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.000000] Memory: 985028K/1015808K available (7168K kernel code, 483K rwdata, 2012K rodata, 1024K init, 778K bss, 22588K reserved, 8192K cma-reserved)
May 19 02:17:04 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
May 19 02:17:04 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]     vmalloc : 0xbe800000 - 0xff800000   (1040 MB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]     lowmem  : 0x80000000 - 0xbe000000   ( 992 MB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]       .text : 0x80008000 - 0x80800000   (8160 kB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]       .init : 0x80b00000 - 0x80c00000   (1024 kB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]       .data : 0x80c00000 - 0x80c78f94   ( 484 kB)
May 19 02:17:04 raspberrypi kernel: [    0.000000]        .bss : 0x80c7a000 - 0x80d3ca64   ( 779 kB)
May 19 02:17:04 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
May 19 02:17:04 raspberrypi kernel: [    0.000000] Hierarchical RCU implementation.
May 19 02:17:04 raspberrypi kernel: [    0.000000] Build-time adjustment of leaf fanout to 32.
May 19 02:17:04 raspberrypi kernel: [    0.000000] NR_IRQS:16 nr_irqs:16 16
May 19 02:17:04 raspberrypi kernel: [    0.000000] arm_arch_timer: Architected cp15 timer(s) running at 19.20MHz (phys).
May 19 02:17:04 raspberrypi kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
May 19 02:17:04 raspberrypi kernel: [    0.000010] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
May 19 02:17:04 raspberrypi kernel: [    0.000031] Switching to timer-based delay loop, resolution 52ns
May 19 02:17:04 raspberrypi kernel: [    0.000385] Console: colour dummy device 80x30
May 19 02:17:04 raspberrypi kernel: [    0.001362] console [tty1] enabled
May 19 02:17:04 raspberrypi kernel: [    0.001422] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
May 19 02:17:04 raspberrypi kernel: [    0.001501] pid_max: default: 32768 minimum: 301
May 19 02:17:04 raspberrypi kernel: [    0.001952] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.002005] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.003336] Disabling cpuset control group subsystem
May 19 02:17:04 raspberrypi kernel: [    0.003611] CPU: Testing write buffer coherency: ok
May 19 02:17:04 raspberrypi kernel: [    0.003711] ftrace: allocating 22386 entries in 66 pages
May 19 02:17:04 raspberrypi kernel: [    0.059097] CPU0: update cpu_capacity 1024
May 19 02:17:04 raspberrypi kernel: [    0.059177] CPU0: thread -1, cpu 0, socket 15, mpidr 80000f00
May 19 02:17:04 raspberrypi kernel: [    0.059275] Setting up static identity map for 0x100000 - 0x100034
May 19 02:17:04 raspberrypi kernel: [    0.062523] CPU1: update cpu_capacity 1024
May 19 02:17:04 raspberrypi kernel: [    0.062535] CPU1: thread -1, cpu 1, socket 15, mpidr 80000f01
May 19 02:17:04 raspberrypi kernel: [    0.063614] CPU2: update cpu_capacity 1024
May 19 02:17:04 raspberrypi kernel: [    0.063625] CPU2: thread -1, cpu 2, socket 15, mpidr 80000f02
May 19 02:17:04 raspberrypi kernel: [    0.064608] CPU3: update cpu_capacity 1024
May 19 02:17:04 raspberrypi kernel: [    0.064618] CPU3: thread -1, cpu 3, socket 15, mpidr 80000f03
May 19 02:17:04 raspberrypi kernel: [    0.064737] Brought up 4 CPUs
May 19 02:17:04 raspberrypi kernel: [    0.064939] SMP: Total of 4 processors activated (153.60 BogoMIPS).
May 19 02:17:04 raspberrypi kernel: [    0.064972] CPU: All CPU(s) started in HYP mode.
May 19 02:17:04 raspberrypi kernel: [    0.065002] CPU: Virtualization extensions available.
May 19 02:17:04 raspberrypi kernel: [    0.066175] devtmpfs: initialized
May 19 02:17:04 raspberrypi kernel: [    0.079575] VFP support v0.3: implementor 41 architecture 2 part 30 variant 7 rev 5
May 19 02:17:04 raspberrypi kernel: [    0.080031] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
May 19 02:17:04 raspberrypi kernel: [    0.080110] futex hash table entries: 1024 (order: 4, 65536 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.080926] pinctrl core: initialized pinctrl subsystem
May 19 02:17:04 raspberrypi kernel: [    0.082256] NET: Registered protocol family 16
May 19 02:17:04 raspberrypi kernel: [    0.084864] DMA: preallocated 1024 KiB pool for atomic coherent allocations
May 19 02:17:04 raspberrypi kernel: [    0.095237] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
May 19 02:17:04 raspberrypi kernel: [    0.095298] hw-breakpoint: maximum watchpoint size is 8 bytes.
May 19 02:17:04 raspberrypi kernel: [    0.095533] Serial: AMBA PL011 UART driver
May 19 02:17:04 raspberrypi kernel: [    0.097929] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
May 19 02:17:04 raspberrypi kernel: [    0.180267] bcm2835-dma 3f007000.dma: DMA legacy API manager at be80f000, dmachans=0x1
May 19 02:17:04 raspberrypi kernel: [    0.182736] SCSI subsystem initialized
May 19 02:17:04 raspberrypi kernel: [    0.182984] usbcore: registered new interface driver usbfs
May 19 02:17:04 raspberrypi kernel: [    0.183115] usbcore: registered new interface driver hub
May 19 02:17:04 raspberrypi kernel: [    0.183299] usbcore: registered new device driver usb
May 19 02:17:04 raspberrypi kernel: [    0.188734] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-04-27 17:21
May 19 02:17:04 raspberrypi kernel: [    0.190515] clocksource: Switched to clocksource arch_sys_counter
May 19 02:17:04 raspberrypi kernel: [    0.245629] VFS: Disk quotas dquot_6.6.0
May 19 02:17:04 raspberrypi kernel: [    0.245784] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.246106] FS-Cache: Loaded
May 19 02:17:04 raspberrypi kernel: [    0.246485] CacheFiles: Loaded
May 19 02:17:04 raspberrypi kernel: [    0.262945] NET: Registered protocol family 2
May 19 02:17:04 raspberrypi kernel: [    0.264227] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.264399] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.264618] TCP: Hash tables configured (established 8192 bind 8192)
May 19 02:17:04 raspberrypi kernel: [    0.264763] UDP hash table entries: 512 (order: 2, 16384 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.264851] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
May 19 02:17:04 raspberrypi kernel: [    0.265208] NET: Registered protocol family 1
May 19 02:17:04 raspberrypi kernel: [    0.265879] RPC: Registered named UNIX socket transport module.
May 19 02:17:04 raspberrypi kernel: [    0.265927] RPC: Registered udp transport module.
May 19 02:17:04 raspberrypi kernel: [    0.265959] RPC: Registered tcp transport module.
May 19 02:17:04 raspberrypi kernel: [    0.265990] RPC: Registered tcp NFSv4.1 backchannel transport module.
May 19 02:17:04 raspberrypi kernel: [    0.267075] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 5 counters available
May 19 02:17:04 raspberrypi kernel: [    0.270132] workingset: timestamp_bits=14 max_order=18 bucket_order=4
May 19 02:17:04 raspberrypi kernel: [    0.289065] FS-Cache: Netfs 'nfs' registered for caching
May 19 02:17:04 raspberrypi kernel: [    0.290390] NFS: Registering the id_resolver key type
May 19 02:17:04 raspberrypi kernel: [    0.290468] Key type id_resolver registered
May 19 02:17:04 raspberrypi kernel: [    0.290620] Key type id_legacy registered
May 19 02:17:04 raspberrypi kernel: [    0.293899] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
May 19 02:17:04 raspberrypi kernel: [    0.294120] io scheduler noop registered
May 19 02:17:04 raspberrypi kernel: [    0.294158] io scheduler deadline registered (default)
May 19 02:17:04 raspberrypi kernel: [    0.294553] io scheduler cfq registered
May 19 02:17:04 raspberrypi kernel: [    0.300290] BCM2708FB: allocated DMA memory fd900000
May 19 02:17:04 raspberrypi kernel: [    0.300368] BCM2708FB: allocated DMA channel 0 @ be80f000
May 19 02:17:04 raspberrypi kernel: [    0.306697] Console: switching to colour frame buffer device 80x30
May 19 02:17:04 raspberrypi kernel: [    0.314546] bcm2835-rng 3f104000.rng: hwrng registered
May 19 02:17:04 raspberrypi kernel: [    0.316549] vc-cma: Videocore CMA driver
May 19 02:17:04 raspberrypi kernel: [    0.318374] vc-cma: vc_cma_base      = 0x00000000
May 19 02:17:04 raspberrypi kernel: [    0.320173] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
May 19 02:17:04 raspberrypi kernel: [    0.321989] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
May 19 02:17:04 raspberrypi kernel: [    0.324006] vc-mem: phys_addr:0x00000000 mem_base=0x3ea00000 mem_size:0x3f000000(1008 MiB)
May 19 02:17:04 raspberrypi kernel: [    0.348180] brd: module loaded
May 19 02:17:04 raspberrypi kernel: [    0.361468] loop: module loaded
May 19 02:17:04 raspberrypi kernel: [    0.363295] Loading iSCSI transport class v2.0-870.
May 19 02:17:04 raspberrypi kernel: [    0.365778] usbcore: registered new interface driver smsc95xx
May 19 02:17:04 raspberrypi kernel: [    0.367547] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
May 19 02:17:04 raspberrypi kernel: [    0.595488] Core Release: 2.80a
May 19 02:17:04 raspberrypi kernel: [    0.597181] Setting default values for core params
May 19 02:17:04 raspberrypi kernel: [    0.598895] Finished setting default values for core params
May 19 02:17:04 raspberrypi kernel: [    0.801094] Using Buffer DMA mode
May 19 02:17:04 raspberrypi kernel: [    0.802810] Periodic Transfer Interrupt Enhancement - disabled
May 19 02:17:04 raspberrypi kernel: [    0.804532] Multiprocessor Interrupt Enhancement - disabled
May 19 02:17:04 raspberrypi kernel: [    0.806216] OTG VER PARAM: 0, OTG VER FLAG: 0
May 19 02:17:04 raspberrypi kernel: [    0.807904] Dedicated Tx FIFOs mode
May 19 02:17:04 raspberrypi kernel: [    0.810053] WARN::dwc_otg_hcd_init:1053: FIQ DMA bounce buffers: virt = 0xbd914000 dma = 0xfd914000 len=9024
May 19 02:17:04 raspberrypi kernel: [    0.813644] FIQ FSM acceleration enabled for :
May 19 02:17:04 raspberrypi kernel: [    0.813644] Non-periodic Split Transactions
May 19 02:17:04 raspberrypi kernel: [    0.813644] Periodic Split Transactions
May 19 02:17:04 raspberrypi kernel: [    0.813644] High-Speed Isochronous Endpoints
May 19 02:17:04 raspberrypi kernel: [    0.813644] Interrupt/Control Split Transaction hack enabled
May 19 02:17:04 raspberrypi kernel: [    0.822388] dwc_otg: Microframe scheduler enabled
May 19 02:17:04 raspberrypi kernel: [    0.822462] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x8058e4c4
May 19 02:17:04 raspberrypi kernel: [    0.824297] WARN::hcd_init_fiq:460: FIQ ASM at 0x8058e834 length 36
May 19 02:17:04 raspberrypi kernel: [    0.826158] WARN::hcd_init_fiq:486: MPHI regs_base at 0xbe87a000
May 19 02:17:04 raspberrypi kernel: [    0.828041] dwc_otg 3f980000.usb: DWC OTG Controller
May 19 02:17:04 raspberrypi kernel: [    0.829859] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
May 19 02:17:04 raspberrypi kernel: [    0.833518] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
May 19 02:17:04 raspberrypi kernel: [    0.835454] Init: Port Power? op_state=1
May 19 02:17:04 raspberrypi kernel: [    0.837311] Init: Power Port (0)
May 19 02:17:04 raspberrypi kernel: [    0.839429] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
May 19 02:17:04 raspberrypi kernel: [    0.841363] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
May 19 02:17:04 raspberrypi kernel: [    0.845047] usb usb1: Product: DWC OTG Controller
May 19 02:17:04 raspberrypi kernel: [    0.846927] usb usb1: Manufacturer: Linux 4.9.24-v7+ dwc_otg_hcd
May 19 02:17:04 raspberrypi kernel: [    0.848815] usb usb1: SerialNumber: 3f980000.usb
May 19 02:17:04 raspberrypi kernel: [    0.851738] hub 1-0:1.0: USB hub found
May 19 02:17:04 raspberrypi kernel: [    0.853636] hub 1-0:1.0: 1 port detected
May 19 02:17:04 raspberrypi kernel: [    0.856332] dwc_otg: FIQ enabled
May 19 02:17:04 raspberrypi kernel: [    0.856339] dwc_otg: NAK holdoff enabled
May 19 02:17:04 raspberrypi kernel: [    0.856345] dwc_otg: FIQ split-transaction FSM enabled
May 19 02:17:04 raspberrypi kernel: [    0.856363] Module dwc_common_port init
May 19 02:17:04 raspberrypi kernel: [    0.856685] usbcore: registered new interface driver usb-storage
May 19 02:17:04 raspberrypi kernel: [    0.858888] mousedev: PS/2 mouse device common for all mice
May 19 02:17:04 raspberrypi kernel: [    0.861993] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
May 19 02:17:04 raspberrypi kernel: [    0.864184] bcm2835-cpufreq: min=600000 max=900000
May 19 02:17:04 raspberrypi kernel: [    0.866535] sdhci: Secure Digital Host Controller Interface driver
May 19 02:17:04 raspberrypi kernel: [    0.868427] sdhci: Copyright(c) Pierre Ossman
May 19 02:17:04 raspberrypi kernel: [    0.870699] sdhost-bcm2835 3f202000.sdhost: could not get clk, deferring probe
May 19 02:17:04 raspberrypi kernel: [    0.874750] sdhci-pltfm: SDHCI platform and OF driver helper
May 19 02:17:04 raspberrypi kernel: [    0.877529] ledtrig-cpu: registered to indicate activity on CPUs
May 19 02:17:04 raspberrypi kernel: [    0.879720] hidraw: raw HID events driver (C) Jiri Kosina
May 19 02:17:04 raspberrypi kernel: [    0.882053] usbcore: registered new interface driver usbhid
May 19 02:17:04 raspberrypi kernel: [    0.884073] usbhid: USB HID core driver
May 19 02:17:04 raspberrypi kernel: [    0.887032] vchiq: vchiq_init_state: slot_zero = 0xbd980000, is_master = 0
May 19 02:17:04 raspberrypi kernel: [    0.891051] Initializing XFRM netlink socket
May 19 02:17:04 raspberrypi kernel: [    0.893085] NET: Registered protocol family 17
May 19 02:17:04 raspberrypi kernel: [    0.895299] Key type dns_resolver registered
May 19 02:17:04 raspberrypi kernel: [    0.897654] Registering SWP/SWPB emulation handler
May 19 02:17:04 raspberrypi kernel: [    0.900642] registered taskstats version 1
May 19 02:17:04 raspberrypi kernel: [    0.903031] vc-sm: Videocore shared memory driver
May 19 02:17:04 raspberrypi kernel: [    0.904955] [vc_sm_connected_init]: start
May 19 02:17:04 raspberrypi kernel: [    0.907169] vc_vchi_sm_init: failed to open VCHI service (-1)
May 19 02:17:04 raspberrypi kernel: [    0.907290] [vc_sm_connected_init]: failed to initialize shared memory service
May 19 02:17:04 raspberrypi kernel: [    0.911214] [vc_sm_connected_init]: end - returning -1
May 19 02:17:04 raspberrypi kernel: [    0.919488] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
May 19 02:17:04 raspberrypi kernel: [    1.873464] console [ttyAMA0] enabled
May 19 02:17:04 raspberrypi kernel: [    1.881348] sdhost: log_buf @ bd913000 (fd913000)
May 19 02:17:04 raspberrypi kernel: [    1.960558] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
May 19 02:17:04 raspberrypi kernel: [    1.968254] of_cfs_init
May 19 02:17:04 raspberrypi kernel: [    1.972874] of_cfs_init: OK
May 19 02:17:04 raspberrypi kernel: [    1.978306] Waiting for root device /dev/mmcblk0p7...
May 19 02:17:04 raspberrypi kernel: [    2.010662] Indeed it is in host mode hprt0 = 00021501
May 19 02:17:04 raspberrypi kernel: [    2.085071] mmc0: host does not support reading read-only switch, assuming write-enable
May 19 02:17:04 raspberrypi kernel: [    2.098909] mmc0: new high speed SDHC card at address 59b4
May 19 02:17:04 raspberrypi kernel: [    2.107312] mmcblk0: mmc0:59b4 USD   29.5 GiB
May 19 02:17:04 raspberrypi kernel: [    2.109116] random: fast init done
May 19 02:17:04 raspberrypi kernel: [    2.123228]  mmcblk0: p1 p2 < p5 p6 p7 >
May 19 02:17:04 raspberrypi kernel: [    2.210560] usb 1-1: new high-speed USB device number 2 using dwc_otg
May 19 02:17:04 raspberrypi kernel: [    2.219128] Indeed it is in host mode hprt0 = 00001101
May 19 02:17:04 raspberrypi kernel: [    2.263845] EXT4-fs (mmcblk0p7): mounted filesystem without journal. Opts: (null)
May 19 02:17:04 raspberrypi kernel: [    2.275365] VFS: Mounted root (ext4 filesystem) readonly on device 179:7.
May 19 02:17:04 raspberrypi kernel: [    2.323267] devtmpfs: mounted
May 19 02:17:04 raspberrypi kernel: [    2.330187] Freeing unused kernel memory: 1024K (80b00000 - 80c00000)
May 19 02:17:04 raspberrypi kernel: [    2.451008] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
May 19 02:17:04 raspberrypi kernel: [    2.459890] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 19 02:17:04 raspberrypi kernel: [    2.470231] hub 1-1:1.0: USB hub found
May 19 02:17:04 raspberrypi kernel: [    2.476191] hub 1-1:1.0: 5 ports detected
May 19 02:17:04 raspberrypi kernel: [    2.800579] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
May 19 02:17:04 raspberrypi kernel: [    2.931043] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
May 19 02:17:04 raspberrypi kernel: [    2.940153] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
May 19 02:17:04 raspberrypi kernel: [    2.954706] smsc95xx v1.0.5
May 19 02:17:04 raspberrypi kernel: [    2.993575] NET: Registered protocol family 10
May 19 02:17:04 raspberrypi kernel: [    3.047085] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:8c:21:80
May 19 02:17:04 raspberrypi kernel: [    3.076428] uart-pl011 3f201000.serial: no DMA platform data
May 19 02:17:04 raspberrypi kernel: [    3.360584] usb 1-1.3: new low-speed USB device number 4 using dwc_otg
May 19 02:17:04 raspberrypi kernel: [    3.538852] usb 1-1.3: New USB device found, idVendor=051d, idProduct=0002
May 19 02:17:04 raspberrypi kernel: [    3.547922] usb 1-1.3: New USB device strings: Mfr=3, Product=1, SerialNumber=2
May 19 02:17:04 raspberrypi kernel: [    3.559260] usb 1-1.3: Product: Back-UPS XS 700U   FW:924.Z3 .I USB FW:Z3
May 19 02:17:04 raspberrypi kernel: [    3.568292] usb 1-1.3: Manufacturer: American Power Conversion
May 19 02:17:04 raspberrypi kernel: [    3.576246] usb 1-1.3: SerialNumber: 3B1615X28994 
May 19 02:17:04 raspberrypi kernel: [    4.069234] hid-generic 0003:051D:0002.0001: hiddev0,hidraw0: USB HID v1.10 Device [American Power Conversion Back-UPS XS 700U   FW:924.Z3 .I USB FW:Z3 ] on usb-3f980000.usb-1.3/input0
May 19 02:17:04 raspberrypi kernel: [    4.900283] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
May 19 02:17:04 raspberrypi kernel: [    5.238499] EXT4-fs (mmcblk0p7): re-mounted. Opts: (null)
May 19 02:17:05 raspberrypi CRON[355]: (root) CMD (/usr/sbin/logrotate /etc/pihole/logrotate)
May 19 02:17:05 raspberrypi CRON[356]: (root) CMD (/usr/bin/touch /var/log/fhem.heartbeat; /usr/bin/touch /var/log/pihole.log; /bin/chmod 666 /var/log/fhem.heartbeat; /bin/chmod/ 666 /var/log/pihole.log; /bin/sleep 120; /etc/init.d/watchdog start )
May 19 02:17:05 raspberrypi avahi-daemon[328]: Successfully called chroot().
May 19 02:17:05 raspberrypi avahi-daemon[328]: Successfully dropped remaining capabilities.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Loading service file /services/nut.service.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Network interface enumeration completed.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Registering HINFO record with values 'ARMV7L'/'LINUX'.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 3644478692.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Service "raspberrypi" (/services/nut.service) successfully established.
May 19 02:17:05 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
May 19 02:17:05 raspberrypi systemd[1]: Starting Login Service...
May 19 02:17:05 raspberrypi systemd[1]: Started System Logging Service.
May 19 02:17:05 raspberrypi systemd[1]: Started Login Service.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::2391:5b86:313e:659a.
May 19 02:17:05 raspberrypi avahi-daemon[328]: New relevant interface eth0.IPv6 for mDNS.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Registering new address record for fe80::2391:5b86:313e:659a on eth0.*.
May 19 02:17:05 raspberrypi kernel: [    8.663343] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
May 19 02:17:05 raspberrypi dhcpcd[330]: eth0: waiting for carrier
May 19 02:17:05 raspberrypi dhcpcd[330]: eth0: carrier acquired
May 19 02:17:05 raspberrypi dhcpcd[330]: DUID 00:01:00:01:1f:77:63:ce:b8:27:eb:8c:21:80
May 19 02:17:05 raspberrypi dhcpcd[330]: eth0: IAID eb:8c:21:80
May 19 02:17:05 raspberrypi dhcpcd[330]: eth0: using static address 192.168.178.42/24
May 19 02:17:05 raspberrypi dhcpcd[330]: eth0: adding route to 192.168.178.0/24
May 19 02:17:05 raspberrypi avahi-daemon[328]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.42.
May 19 02:17:05 raspberrypi dhcpcd[330]: eth0: adding default route via 192.168.178.1
May 19 02:17:05 raspberrypi avahi-daemon[328]: New relevant interface eth0.IPv4 for mDNS.
May 19 02:17:05 raspberrypi avahi-daemon[328]: Registering new address record for 192.168.178.42 on eth0.IPv4.
May 19 02:17:06 raspberrypi dhcpcd[330]: forked to background, child pid 522
May 19 02:17:06 raspberrypi dhcpcd[522]: eth0: carrier lost
May 19 02:17:06 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
May 19 02:17:06 raspberrypi systemd[1]: Starting Network.
May 19 02:17:06 raspberrypi systemd[1]: Reached target Network.
May 19 02:17:06 raspberrypi systemd[1]: Starting A high performance web server and a reverse proxy server...
May 19 02:17:06 raspberrypi systemd[1]: Starting The PHP FastCGI Process Manager...
May 19 02:17:06 raspberrypi systemd[1]: Starting Network UPS Tools - power device driver controller...
May 19 02:17:06 raspberrypi systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
May 19 02:17:06 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
May 19 02:17:06 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
May 19 02:17:06 raspberrypi systemd[1]: Starting Lighttpd Daemon...
May 19 02:17:06 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
May 19 02:17:06 raspberrypi systemd[1]: Starting Network is Online.
May 19 02:17:06 raspberrypi systemd[1]: Reached target Network is Online.
May 19 02:17:06 raspberrypi systemd[1]: Mounting /media/nas-backup...
May 19 02:17:06 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
May 19 02:17:06 raspberrypi rpc.statd[591]: Version 1.2.8 starting
May 19 02:17:06 raspberrypi rpc.statd[591]: Flags: TI-RPC
May 19 02:17:06 raspberrypi rpc.statd[591]: failed to create RPC listeners, exiting
May 19 02:17:06 raspberrypi mount[550]: mount.nfs: rpc.statd is not running but is required for remote locking.
May 19 02:17:06 raspberrypi mount[550]: mount.nfs: Either use '-o nolock' to keep locks local, or start statd.
May 19 02:17:06 raspberrypi mount[550]: mount.nfs: an incorrect mount option was specified
May 19 02:17:06 raspberrypi systemd[1]: media-nas\x2dbackup.mount mount process exited, code=exited status=32
May 19 02:17:06 raspberrypi systemd[1]: Failed to mount /media/nas-backup.
May 19 02:17:06 raspberrypi systemd[1]: Dependency failed for Remote File Systems.
May 19 02:17:06 raspberrypi systemd[1]: Unit media-nas\x2dbackup.mount entered failed state.
May 19 02:17:06 raspberrypi upsdrvctl[527]: Using subdriver: APC HID 0.95
May 19 02:17:06 raspberrypi upsdrvctl[527]: Network UPS Tools - Generic HID driver 0.38 (2.7.2)
May 19 02:17:06 raspberrypi upsdrvctl[527]: USB communication driver 0.32
May 19 02:17:06 raspberrypi systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
May 19 02:17:06 raspberrypi systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)...
May 19 02:17:06 raspberrypi systemd[1]: Starting LSB: start Samba daemons for the AD DC...
May 19 02:17:06 raspberrypi systemd[1]: Starting LSB: triggerhappy hotkey daemon...
May 19 02:17:06 raspberrypi systemd[1]: Starting LSB: Start daemon at boot time for homebridge...
May 19 02:17:06 raspberrypi systemd[1]: Starting LSB: Start/stop fail2ban...
May 19 02:17:06 raspberrypi systemd[1]: Starting LSB: FHEM server...
May 19 02:17:06 raspberrypi systemd[1]: Starting LSB: Start NTP daemon...
May 19 02:17:06 raspberrypi dnsmasq[528]: dnsmasq: Syntaxprüfung OK.
May 19 02:17:06 raspberrypi usbhid-ups[637]: Startup successful
May 19 02:17:06 raspberrypi upsdrvctl[527]: Network UPS Tools - UPS driver controller 2.7.2
May 19 02:17:06 raspberrypi systemd[1]: Started Network UPS Tools - power device driver controller.
May 19 02:17:07 raspberrypi systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
May 19 02:17:07 raspberrypi dhcpcd[522]: eth0: deleting address fe80::2391:5b86:313e:659a
May 19 02:17:07 raspberrypi dhcpcd[522]: eth0: deleting route to 192.168.178.0/24
May 19 02:17:07 raspberrypi dhcpcd[522]: eth0: deleting default route via 192.168.178.1
May 19 02:17:07 raspberrypi avahi-daemon[328]: Withdrawing address record for fe80::2391:5b86:313e:659a on eth0.
May 19 02:17:07 raspberrypi avahi-daemon[328]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::2391:5b86:313e:659a.
May 19 02:17:07 raspberrypi avahi-daemon[328]: Interface eth0.IPv6 no longer relevant for mDNS.
May 19 02:17:07 raspberrypi avahi-daemon[328]: Withdrawing address record for 192.168.178.42 on eth0.
May 19 02:17:07 raspberrypi avahi-daemon[328]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.178.42.
May 19 02:17:07 raspberrypi avahi-daemon[328]: Interface eth0.IPv4 no longer relevant for mDNS.
May 19 02:17:07 raspberrypi systemd[1]: Starting Permit User Sessions...
May 19 02:17:07 raspberrypi systemd[1]: Starting Network UPS Tools - power devices information server...
May 19 02:17:07 raspberrypi kernel: [   10.197560] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
May 19 02:17:07 raspberrypi systemd[1]: Started Permit User Sessions.
May 19 02:17:07 raspberrypi systemd[1]: Starting Hold until boot process finishes up...
May 19 02:17:07 raspberrypi systemd[1]: Starting Terminate Plymouth Boot Screen...
May 19 02:17:07 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 220 (plymouthd).
May 19 02:17:07 raspberrypi systemd[1]: Started Hold until boot process finishes up.
May 19 02:17:07 raspberrypi systemd[1]: Started Terminate Plymouth Boot Screen.
May 19 02:17:07 raspberrypi triggerhappy[616]: Error opening '/dev/input/event*': No such file or directory
May 19 02:17:07 raspberrypi systemd[1]: Started LSB: triggerhappy hotkey daemon.
May 19 02:17:07 raspberrypi ntpd[685]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1)
May 19 02:17:07 raspberrypi ntpd[730]: proto: precision = 1.145 usec
May 19 02:17:07 raspberrypi ntpd[730]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
May 19 02:17:07 raspberrypi ntpd[730]: Listen and drop on 1 v6wildcard :: UDP 123
May 19 02:17:07 raspberrypi ntpd[730]: Listen normally on 2 lo 127.0.0.1 UDP 123
May 19 02:17:07 raspberrypi ntpd[730]: Listen normally on 3 lo ::1 UDP 123
May 19 02:17:07 raspberrypi ntpd[730]: peers refreshed
May 19 02:17:07 raspberrypi ntpd[730]: Listening on routing socket on fd #20 for interface updates
May 19 02:17:07 raspberrypi systemd[1]: Started LSB: Start NTP daemon.
May 19 02:17:07 raspberrypi ntp[623]: Starting NTP server: ntpd.
May 19 02:17:07 raspberrypi dhcpcd[522]: eth0: carrier acquired
May 19 02:17:07 raspberrypi dhcpcd[522]: eth0: adding address fe80::2391:5b86:313e:659a
May 19 02:17:07 raspberrypi systemd[1]: Starting Getty on tty1...
May 19 02:17:07 raspberrypi systemd[1]: Started Getty on tty1.
May 19 02:17:07 raspberrypi systemd[1]: Starting Serial Getty on ttyAMA0...
May 19 02:17:07 raspberrypi systemd[1]: Started Serial Getty on ttyAMA0.
May 19 02:17:07 raspberrypi systemd[1]: Starting Login Prompts.
May 19 02:17:07 raspberrypi systemd[1]: Reached target Login Prompts.
May 19 02:17:07 raspberrypi dhcpcd[522]: eth0: IAID eb:8c:21:80
May 19 02:17:07 raspberrypi homebridge[618]: Homebridge starting
May 19 02:17:07 raspberrypi upsd[665]: fopen /var/run/nut/upsd.pid: No such file or directory
May 19 02:17:07 raspberrypi homebridge[618]: Homebridge is not running
May 19 02:17:07 raspberrypi systemd[1]: Started LSB: Start daemon at boot time for homebridge.
May 19 02:17:07 raspberrypi upsd[665]: not listening on 192.168.178.42 port 3493
May 19 02:17:07 raspberrypi upsd[665]: listening on 127.0.0.1 port 3493
May 19 02:17:07 raspberrypi upsd[665]: no listening interface available
May 19 02:17:07 raspberrypi upsd[665]: not listening on 192.168.178.42 port 3493
May 19 02:17:07 raspberrypi upsd[665]: listening on 127.0.0.1 port 3493
May 19 02:17:07 raspberrypi upsd[665]: no listening interface available
May 19 02:17:07 raspberrypi systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server.
May 19 02:17:07 raspberrypi systemd[1]: nut-server.service: control process exited, code=exited status=1
May 19 02:17:07 raspberrypi systemd[1]: Failed to start Network UPS Tools - power devices information server.
May 19 02:17:07 raspberrypi systemd[1]: Service nut-driver.service is not needed anymore. Stopping.
May 19 02:17:07 raspberrypi systemd[1]: Unit nut-server.service entered failed state.
May 19 02:17:07 raspberrypi upsd[665]: Network UPS Tools upsd 2.7.2
May 19 02:17:07 raspberrypi systemd[1]: Stopping Network UPS Tools - power device driver controller...
May 19 02:17:07 raspberrypi systemd[1]: Starting Network UPS Tools - power device monitor and shutdown controller...
May 19 02:17:07 raspberrypi systemd[1]: Starting Host and Network Name Lookups.
May 19 02:17:07 raspberrypi systemd[1]: Reached target Host and Network Name Lookups.
May 19 02:17:07 raspberrypi usbhid-ups[637]: Signal 15: exiting
May 19 02:17:08 raspberrypi systemd[1]: Stopped Network UPS Tools - power device driver controller.
May 19 02:17:08 raspberrypi upsdrvctl[805]: Network UPS Tools - UPS driver controller 2.7.2
May 19 02:17:08 raspberrypi upsmon[806]: fopen /var/run/nut/upsmon.pid: No such file or directory
May 19 02:17:08 raspberrypi upsmon[806]: UPS: ups@localhost (master) (power value 1)
May 19 02:17:08 raspberrypi upsmon[806]: Using power down flag file /etc/killpower
May 19 02:17:08 raspberrypi upsmon[810]: Startup successful
May 19 02:17:08 raspberrypi upsmon[811]: Init SSL without certificate database
May 19 02:17:08 raspberrypi systemd[1]: nut-monitor.service: Supervising process 811 which is not our child. We'll most likely not notice when it exits.
May 19 02:17:08 raspberrypi systemd[1]: Started Network UPS Tools - power device monitor and shutdown controller.
May 19 02:17:08 raspberrypi dhcpcd[522]: eth0: soliciting an IPv6 router
May 19 02:17:08 raspberrypi dhcpcd[522]: eth0: using static address 192.168.178.42/24
May 19 02:17:08 raspberrypi dhcpcd[522]: eth0: adding route to 192.168.178.0/24
May 19 02:17:08 raspberrypi dhcpcd[522]: eth0: adding default route via 192.168.178.1
May 19 02:17:08 raspberrypi avahi-daemon[328]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.42.
May 19 02:17:08 raspberrypi avahi-daemon[328]: New relevant interface eth0.IPv4 for mDNS.
May 19 02:17:08 raspberrypi avahi-daemon[328]: Registering new address record for 192.168.178.42 on eth0.IPv4.
May 19 02:17:08 raspberrypi fhem[620]: Starting fhem...
May 19 02:17:08 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:08 raspberrypi upsmon[811]: Communications with UPS ups@localhost lost
May 19 02:17:09 raspberrypi systemd[1]: Started A high performance web server and a reverse proxy server.
May 19 02:17:09 raspberrypi lighttpd[536]: Syntax OK
May 19 02:17:09 raspberrypi systemd[1]: Started Lighttpd Daemon.
May 19 02:17:09 raspberrypi systemd[1]: Started The PHP FastCGI Process Manager.
May 19 02:17:09 raspberrypi avahi-daemon[328]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::2391:5b86:313e:659a.
May 19 02:17:09 raspberrypi avahi-daemon[328]: New relevant interface eth0.IPv6 for mDNS.
May 19 02:17:09 raspberrypi avahi-daemon[328]: Registering new address record for fe80::2391:5b86:313e:659a on eth0.*.
May 19 02:17:10 raspberrypi lighttpd[899]: 2017-05-19 02:17:09: (network.c.409) can't bind to port:  80 Address already in use
May 19 02:17:10 raspberrypi systemd[1]: lighttpd.service: main process exited, code=exited, status=255/n/a
May 19 02:17:10 raspberrypi systemd[1]: Unit lighttpd.service entered failed state.
May 19 02:17:12 raspberrypi kernel: [   15.221328] random: crng init done
May 19 02:17:12 raspberrypi systemd[1]: Started LSB: FHEM server.
May 19 02:17:13 raspberrypi nodejs[782]: *** WARNING *** The program 'nodejs' uses the Apple Bonjour compatibility layer of Avahi.
May 19 02:17:13 raspberrypi nodejs[782]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 19 02:17:13 raspberrypi nodejs[782]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=nodejs>
May 19 02:17:13 raspberrypi nodejs[782]: *** WARNING *** The program 'nodejs' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 19 02:17:13 raspberrypi nodejs[782]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 19 02:17:13 raspberrypi nodejs[782]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=nodejs&f=DNSServiceRegister>
May 19 02:17:13 raspberrypi systemd[1]: Started LSB: start Samba daemons for the AD DC.
May 19 02:17:13 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:13 raspberrypi upsmon[811]: UPS ups@localhost is unavailable
May 19 02:17:14 raspberrypi nmbd[614]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: Datei oder Verzeichnis nicht gefunden
May 19 02:17:14 raspberrypi nmbd[614]: Failed to create /var/log/samba/cores for user 0 with mode 0700
May 19 02:17:14 raspberrypi nmbd[614]: Unable to setup corepath for nmbd: Datei oder Verzeichnis nicht gefunden
May 19 02:17:14 raspberrypi nmbd[614]: [2017/05/19 02:17:14,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:14 raspberrypi nmbd[614]: Unable to open new log file '/var/log/samba/log.nmbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:14 raspberrypi nmbd[614]: [2017/05/19 02:17:14,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:14 raspberrypi nmbd[614]: Unable to open new log file '/var/log/samba/log.nmbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:14 raspberrypi nmbd[614]: [2017/05/19 02:17:14,  0] ../source3/nmbd/nmbd.c:908(main)
May 19 02:17:14 raspberrypi nmbd[614]: nmbd version 4.2.14-Debian started.
May 19 02:17:14 raspberrypi nmbd[614]: Copyright Andrew Tridgell and the Samba Team 1992-2014
May 19 02:17:14 raspberrypi nmbd[614]: [2017/05/19 02:17:14.322737,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:14 raspberrypi nmbd[614]: Unable to open new log file '/var/log/samba/log.': Datei oder Verzeichnis nicht gefunden
May 19 02:17:14 raspberrypi nmbd[614]: [2017/05/19 02:17:14.362348,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:14 raspberrypi nmbd[614]: Unable to open new log file '/var/log/samba/log.nmbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:14 raspberrypi systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd).
May 19 02:17:14 raspberrypi systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)...
May 19 02:17:14 raspberrypi ntpd[730]: Listen normally on 4 eth0 192.168.178.42 UDP 123
May 19 02:17:14 raspberrypi ntpd[730]: Listen normally on 5 eth0 fe80::2391:5b86:313e:659a UDP 123
May 19 02:17:14 raspberrypi ntpd[730]: peers refreshed
May 19 02:17:14 raspberrypi nmbd[614]: .
May 19 02:17:16 raspberrypi smbd[933]: Starting SMB/CIFS daemon: smbd[2017/05/19 02:17:16,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:16 raspberrypi smbd[933]: Unable to open new log file '/var/log/samba/log.smbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16,  0] ../lib/util/util.c:220(directory_create_or_exist)
May 19 02:17:16 raspberrypi smbd[933]: mkdir failed on directory /var/log/samba/cores: Datei oder Verzeichnis nicht gefunden
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16,  0] ../source3/lib/dumpcore.c:59(get_default_corepath)
May 19 02:17:16 raspberrypi smbd[933]: Failed to create /var/log/samba/cores for user 0 with mode 0700
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16,  0] ../source3/lib/dumpcore.c:250(dump_core_setup)
May 19 02:17:16 raspberrypi smbd[933]: Unable to setup corepath for smbd: Datei oder Verzeichnis nicht gefunden
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:16 raspberrypi smbd[933]: Unable to open new log file '/var/log/samba/log.smbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16,  0] ../source3/smbd/server.c:1241(main)
May 19 02:17:16 raspberrypi smbd[933]: smbd version 4.2.14-Debian started.
May 19 02:17:16 raspberrypi smbd[933]: Copyright Andrew Tridgell and the Samba Team 1992-2014
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16.645637,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:16 raspberrypi smbd[933]: Unable to open new log file '/var/log/samba/log.smbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16.652096,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:16 raspberrypi smbd[933]: Unable to open new log file '/var/log/samba/log.smbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:16 raspberrypi smbd[933]: [2017/05/19 02:17:16.652434,  0] ../lib/util/debug.c:603(reopen_logs_internal)
May 19 02:17:16 raspberrypi smbd[933]: Unable to open new log file '/var/log/samba/log.smbd': Datei oder Verzeichnis nicht gefunden
May 19 02:17:16 raspberrypi smbd[933]: .
May 19 02:17:16 raspberrypi systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd).
May 19 02:17:18 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:19 raspberrypi fail2ban[619]: Starting authentication failure monitor: fail2banWARNING 'ignoreregex' not defined in 'Definition'. Using default one: ''
May 19 02:17:21 raspberrypi dhcpcd[522]: eth0: no IPv6 Routers available
May 19 02:17:23 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:24 raspberrypi kernel: [   27.253978] ip_tables: (C) 2000-2006 Netfilter Core Team
May 19 02:17:24 raspberrypi fail2ban[619]: .
May 19 02:17:24 raspberrypi systemd[1]: Started LSB: Start/stop fail2ban.
May 19 02:17:24 raspberrypi systemd[1]: Starting Multi-User System.
May 19 02:17:24 raspberrypi systemd[1]: Reached target Multi-User System.
May 19 02:17:24 raspberrypi systemd[1]: Starting Graphical Interface.
May 19 02:17:24 raspberrypi systemd[1]: Reached target Graphical Interface.
May 19 02:17:24 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
May 19 02:17:24 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
May 19 02:17:24 raspberrypi systemd[1]: Startup finished in 2.743s (kernel) + 24.691s (userspace) = 27.435s.
May 19 02:17:28 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:33 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:38 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:43 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:48 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:53 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:17:58 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:03 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:08 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:13 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:18 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:23 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:28 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:33 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:33 raspberrypi rsyslogd-2007: action 'action 17' suspended, next retry is Fri May 19 02:19:03 2017 [try http://www.rsyslog.com/e/2007 ]
May 19 02:18:38 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:43 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:48 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:53 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:18:58 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:03 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:03 raspberrypi rsyslogd-2007: action 'action 17' suspended, next retry is Fri May 19 02:19:33 2017 [try http://www.rsyslog.com/e/2007 ]
May 19 02:19:05 raspberrypi systemd[1]: Starting LSB: RPC portmapper replacement...
May 19 02:19:05 raspberrypi systemd[1]: Starting watchdog daemon...
May 19 02:19:05 raspberrypi watchdog[1137]: starting daemon (5.14):
May 19 02:19:05 raspberrypi watchdog[1137]: int=1s realtime=yes sync=no soft=no mla=24 mem=0
May 19 02:19:05 raspberrypi watchdog[1137]: ping: no machine to check
May 19 02:19:05 raspberrypi watchdog[1137]: file: /var/log/fhem.heartbeat:300
May 19 02:19:05 raspberrypi watchdog[1137]: pidfile: no server process to check
May 19 02:19:05 raspberrypi watchdog[1137]: interface: no interface to check
May 19 02:19:05 raspberrypi watchdog[1137]: temperature: no sensors to check
May 19 02:19:05 raspberrypi watchdog[1137]: test=none(0) repair=none(0) alive=/dev/watchdog heartbeat=none to=root no_act=no force=no
May 19 02:19:05 raspberrypi watchdog[1137]: cannot set timeout 60 (errno = 22 = 'Invalid argument')
May 19 02:19:05 raspberrypi watchdog[1137]: hardware watchdog identity: Broadcom BCM2835 Watchdog timer
May 19 02:19:05 raspberrypi CRON[334]: (CRON) info (No MTA installed, discarding output)
May 19 02:19:05 raspberrypi systemd[1]: Started watchdog daemon.
May 19 02:19:05 raspberrypi rpcbind[1128]: Starting rpcbind daemon....
May 19 02:19:05 raspberrypi systemd[1]: Started LSB: RPC portmapper replacement.
May 19 02:19:05 raspberrypi systemd[1]: Starting RPC Port Mapper.
May 19 02:19:05 raspberrypi systemd[1]: Reached target RPC Port Mapper.
May 19 02:19:08 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:13 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:18 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:23 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:28 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:33 raspberrypi upsmon[811]: UPS [ups@localhost]: connect failed: Connection failure: Connection refused
May 19 02:19:33 raspberrypi rsyslogd-2007: action 'action 17' suspended, next retry is Fri May 19 02:20:03 2017 [try http://www.rsyslog.com/e/2007 ]


Andere "Zeitjobs" kann ich nicht finden, keine Cron Jobs. Die Uhrzeitverstellung passiert auch nicht immer zum gleichen Zeitpunkt, wenn auch meistens nachts.

captain@raspberrypi:~ $ sudo ps -ef | grep -i time
UID        PID  PPID  C STIME TTY        &n

[kadettilac89]

da hilft dir nur langsames Herantasten. Deaktiviere mal den Zeitdienst NTP. Solange der Server läuft sollte die Zeit auch so weiterlaufen. Ein paar Sekunden Abweichung tun zum Test nicht weh. Schalte auch den Watchdog ab der auf die Files prüft um zu sehen ob sich die Zeit wieder "zurückstellt" oder dann falsch bleibt.

[amenomade]

Du kannst auch ntp Statistiken generieren lassen. Vielleicht findest Du so die Ursache.
http://doc.ntp.org/4.2.6p3/monopt.html

[frank]

Die Uhrzeitverstellung passiert auch nicht immer zum gleichen Zeitpunkt, wenn auch meistens nachts.

vielleicht gibt es einen zusammenhang mit der zwangstrennung der internetverbindung.

[nils_]

deine uhrzeit macht ja 2 sprünge....!?!

Code Auswählen Erweitern


2017-05-19_02:55:20 RaspberryStat uptime: 0 days, 19:49
2017-05-19_02:19:43 RaspberryStat 0.48 0.64 0.28
2017-05-19_02:19:43 RaspberryStat load: 0.48
2017-05-19_02:19:43 RaspberryStat temperature: 34.166
2017-05-19_02:19:43 RaspberryStat uptime: 0 days, 0:02
2017-05-19_03:06:53 RaspberryStat 0.32 0.57 0.28

[Wernieman]

vielleicht gibt es einen zusammenhang mit der zwangstrennung der internetverbindung.

Das würde dafür sprechen, das Du eine ntpdate laufen lässt und der zu einem falschen Zeitserver "springt"

Mach doch mal (als root) ein:

Code Auswählen Erweitern

grep -r ntpdate /etc/cron*
grep -r ntpdate grep /var/spool/cron

[Otto123]

Hi,

oder ist es andersherum? Es wird ein harter reboot ausgelöst (warum auch immer) und im filelog passiert der Sprung weil:
fake-hwclock schreibt
Zeit stimmt (ntp)
5 min später reboot, fake-hwclock hat keine Chance
Startzeit des Pi ist damit die alte fake-hwclock (vor 5 min)
ntp läuft noch nicht
filelog schreibt mit alter Zeit
ntp sync
filelog schreibt wieder mit richtiger Zeit

Nur mal schräg gedacht ...

Gruß Otto