Pi defekt komme nicht weiter

Begonnen von h-man-kl, 14 April 2017, 19:22:16

Vorheriges Thema - Nächstes Thema

Thorsten Pferdekaemper

Zitat von: h-man-kl am 15 April 2017, 19:27:30Bin einen kleinen Schritt weiter: HM485 ist nachinstallieret, aber die Module wurden noch nicht erksannt.
Wie hast Du das "nachinstalliert" und was meinst Du mit "die Modue wurden nicht erkannt"?
Btw.: Wenn Du sowieso alles neu machen musst, dann könntest Du auch gleich HM485 v0800 nehmen. Details siehe hier:
https://forum.fhem.de/index.php/topic,70528.0.html
Bitte das ganze ausschließlich so installieren, wie dort bzw. im Wiki beschrieben. Wenn es damit Probleme gibt, dann bitte fragen und nicht in irgendwelchen anderen Blogs suchen. Die können nämlich v0800 noch gar nicht kennen.
...ansonsten wirst Du z.B. Probleme mit dem csrfToken haben.
Gruß,
   Thorsten
FUIP

h-man-kl

Schnelle Antwort bevor der Osterhase kommt:
Habe es wie im Wiki installiert, allerdings hatte ich glaube ich mache inne alten link.
Werde später mal genau checken.
Meine beiden IO Module sind inzwischen auch "aufgetaucht" und lassen sich schalten.
Hatte vorhin nur eine Meldung, dass sich der Eingang nicht von Switch auf pushbutton umställen lassen will, das muss ich noch prüfen.

Ansonsten geht es aufwärts

Schonmal vielen Dank an alle die mir geholfen haben. Die Tipps mit dem Backup einspielen werde ich mir auch noch in Ruhe durchlesen.
RasPi 3 mit MaxCube für FS20 , HM-Urart, HM-LAN, MiLight, HUE, Lightify, SONOS, Harmony, Unifi, FritzBox 7490... :-)
Ganz nach dem Motto: Normal? Normal is langweilig....

fiedel

Zitat von: Wernieman am 16 April 2017, 10:13:49
Aber dabei muß nicht immer die richtige Benutzereinstellung eingestellt werden ... es sei denn fhem, läuft bei Dir als root ...

Nein, läuft bei mir unter Benutzer "fhem". Werden denn nicht beim Entpacken und Kopieren so wie ich es mache die Rechte- und Besitzereinstellungen mitgegeben? Davon bin ich bisher ausgegangen.
FeatureLevel: 6.1 auf Wyse N03D ; Deb. 11 ; Perl: v5.14.2 ; IO: HM-MOD-RPI-PCB + VCCU|CUL 868 V 1.66|LinkUSBi |TEK603
HM: SEC-SCO|SCI-3-FM|LC-SW4-PCB|ES-PMSW1-PL|RC-4-2|SEN-MDIR-O|SEC-WDS-2
CUL: HMS100TF|FS20 S4A-2 ; OWDevice: DS18S20|DS2401|DS2406|DS2423

Thorsten Pferdekaemper

Zitat von: h-man-kl am 16 April 2017, 11:02:33Meine beiden IO Module sind inzwischen auch "aufgetaucht" und lassen sich schalten.
Hatte vorhin nur eine Meldung, dass sich der Eingang nicht von Switch auf pushbutton umställen lassen will, das muss ich noch prüfen.
Am besten tatsächlich v0800 installieren und dann immer schön warten, bis überall configStatus auf OK steht.
Gruß,
   Thorsten
FUIP

h-man-kl

Prima! Vielen Dank an alle es läuft nun wieder alles und ioch kann weiterbauen :-)

meine beiden     
HMW_IO_12_Sw7_DR zeigen brav OK, aber wo kann ich jetztz genau shen ob ich die V800 habe oder ob das nic ht geklappt hat?
RasPi 3 mit MaxCube für FS20 , HM-Urart, HM-LAN, MiLight, HUE, Lightify, SONOS, Harmony, Unifi, FritzBox 7490... :-)
Ganz nach dem Motto: Normal? Normal is langweilig....

Thorsten Pferdekaemper

Zitat von: h-man-kl am 17 April 2017, 11:33:49HMW_IO_12_Sw7_DR zeigen brav OK, aber wo kann ich jetztz genau shen ob ich die V800 habe oder ob das nic ht geklappt hat?
Wenn Du nicht weißt, welche Version Du hast, dann hast Du bestimmt nicht v0800. Wenn jetzt außerdem alles funktioniert, dann belasse es vielleicht auch erstmal dabei.
Gruß,
    Thorsten
FUIP

h-man-kl

Hallo zusammen, leider bin ich mit dem Thema nicht ganz durch :-(
die Sache läuft noch nicht rund.

Gestern Morgen stand das System. Anfangs kam ich per putty noch drauf, laut /etc/init.d/fhem status lief fehm auch, aber es war kein zugriff übers webif mehr möglich. Irgendwann ging auc kein putty mehr :-(
Heute Morgen, wieder kein webif nur putty.
Neu gestartet und es lief wieder. Mein Log endet um kurz nach 4 und der nächste Eintrag war dann um kurz nach 5 als ich das System gebootet habe. Wirklich was drinstehen tut nicht, da ich bei den meisten Einträgen Verbose auf 0 stehen habe.
Jetzt stelle ich mir die Frage wo ich das Logging "höher" stellen sollte.
Aufgefallen ist mir allerdings, dass mein HMUARTLGW (was ich erst am Sonntag dzugebaut habe) ständig disconnected:

HMUARTLGW HmUART_01 StartInit
2017.04.19 06:56:31 5: HMUARTLGW HmUART_01 send: 00 00
2017.04.19 06:56:31 5: HMUARTLGW HmUART_01 send: (8): fd00030001009e03
2017.04.19 06:56:31 5: SW: fd00030001009e03
2017.04.19 06:56:34 1: HMUARTLGW HmUART_01 did not respond for the 1. time, resending
2017.04.19 06:56:34 5: HMUARTLGW HmUART_01 send: (8): fd00030001009e03
2017.04.19 06:56:34 5: SW: fd00030001009e03
2017.04.19 06:56:37 1: HMUARTLGW HmUART_01 did not respond for the 2. time, resending
2017.04.19 06:56:37 5: HMUARTLGW HmUART_01 send: (8): fd00030001009e03
2017.04.19 06:56:37 5: SW: fd00030001009e03
2017.04.19 06:56:41 1: HMUARTLGW HmUART_01 did not respond for the 3. time, resending
2017.04.19 06:56:41 5: HMUARTLGW HmUART_01 send: (8): fd00030001009e03
2017.04.19 06:56:41 5: SW: fd00030001009e03


Jemand nen Tip....?

Vielen Dank!

RasPi 3 mit MaxCube für FS20 , HM-Urart, HM-LAN, MiLight, HUE, Lightify, SONOS, Harmony, Unifi, FritzBox 7490... :-)
Ganz nach dem Motto: Normal? Normal is langweilig....

CoolTux

Es wäre schlau wenn man Logauszüge hätte die zum Zeitpunkt und kurz vor dem Ausfall gemacht wurden.
Du musst nicht wissen wie es geht! Du musst nur wissen wo es steht, wie es geht.
Support me to buy new test hardware for development: https://www.paypal.com/paypalme/MOldenburg
My FHEM Git: https://git.cooltux.net/FHEM/
Das TuxNet Wiki:
https://www.cooltux.net

h-man-kl

nunja, da steht allerdings nichts bruchbares drin.
heute:

2017.04.19 04:11:48 3: HCS Waermebedarf.HCS Found 16 Device(s): 11 FHT, 5 HM-CC-TC, 0 MAX, demand: 2, idle: 7, ignored: 4, excluded: 0, unknown: 3, eco: no overdrive: no
2017.04.19 04:11:48 4: Harmony.SZ: send: <iq type='get' id='ping-3250'><ping xmlns='urn:xmpp:ping'/></iq>
2017.04.19 04:11:48 5: Harmony.SZ: tag: iq, attr:  id='ping-3250' type='result'
2017.04.19 04:11:48 5: Harmony.SZ: got ping response 3250
2017.04.19 04:11:51 2: ROOMMATE set rr_Viano home
2017.04.19 04:11:51 2: ROOMMATE set rr_Michael home
2017.04.19 04:11:51 5: ROOMMATE rr_Nadine: called function ROOMMATE_Set()
2017.04.19 04:11:51 2: ROOMMATE set rr_Nadine home
2017.04.19 04:12:38 4: Harmony.SZ: send: <iq type='get' id='ping-3254'><ping xmlns='urn:xmpp:ping'/></iq>
2017.04.19 04:12:38 5: Harmony.SZ: tag: iq, attr:  id='ping-3254' type='result'
2017.04.19 04:12:38 5: Harmony.SZ: got ping response 3254
2017.04.19 04:13:28 4: Harmony.SZ: send: <iq type='get' id='ping-3258'><ping xmlns='urn:xmpp:ping'/></iq>
2017.04.19 04:13:28 5: Harmony.SZ: tag: iq, attr:  id='ping-3258' type='result'
2017.04.19 04:13:28 5: Harmony.SZ: got ping response 3258
2017.04.19 04:14:18 4: Harmony.SZ: send: <iq type='get' id='ping-3262'><ping xmlns='urn:xmpp:ping'/></iq>
2017.04.19 04:14:18 5: Harmony.SZ: tag: iq, attr:  id='ping-3262' type='result'
2017.04.19 04:14:18 5: Harmony.SZ: got ping response 3262
2017.04.19 05:17:00 1: Including fhem.cfg


Gestern siehts nahezu genauso aus. Das ist ja der Punkt den ich meinte mit: Was könnte sinnvol sein zu loggen  ....
RasPi 3 mit MaxCube für FS20 , HM-Urart, HM-LAN, MiLight, HUE, Lightify, SONOS, Harmony, Unifi, FritzBox 7490... :-)
Ganz nach dem Motto: Normal? Normal is langweilig....

CoolTux

FHEM logt doch schon. Alles was FHEM Systeminterner ist wird gelogt. Zu mindest ein harter Ausfall sollte das. Wenn natürlich der FHEM Prozess hängen bleibt dann nicht. Du hättest also vor einem Neustart mal schauen müssen wie zum Beispiel die Auslastung des fhem prozesses ist oder ob dieser überhaupt noch aktiv ist.
Du musst nicht wissen wie es geht! Du musst nur wissen wo es steht, wie es geht.
Support me to buy new test hardware for development: https://www.paypal.com/paypalme/MOldenburg
My FHEM Git: https://git.cooltux.net/FHEM/
Das TuxNet Wiki:
https://www.cooltux.net

Wernieman

Wenn anschließend der PI weg ist, würde mich vor allem auch die Linux-Logfiles interessieren.

Mach doch mal ein:
grep -i i/o /var/log/kern.log /var/log/syslog

Bzw. wenn Du fhem (nur fhem) anschließend beendest, neu startest, bekommt der PI dann auch Probleme, das er nicht erreichbar ist?
- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html

h-man-kl

Guten Morgen zusammen (oder in meinem Fall auch nicht)

Hier mal die gewünschten Angaben. Der Pi ist von gestern morgen an gelaufen. Gestern hatte ich noch das Hommatic Modul für den pi im Verdacht also habe ich das runtergenommen, gegen 16:30 und zuletzt habe ich heute Nacht gegen 2 mitbekommen dass er was noch läuft.  und jetzt wieder das Gleiche. Putty ging noch also habe ich mal nur fhem gestoppt und wieder gestartet, aber das hat nichts gebracht. nach dem Neustart ging auch fhem wieder.

die Prozessorauslastung VOR dem Neustart:

%CPU   PID USER     COMMAND
2.2  9186 root     sshd: pi [priv]
19.9   598 fhem     perl fhem.pl fhem.cfg
12.5  9194 pi       -bash
0.9   809 fhem     /usr/bin/perl ./FHEM/00_SONOS.pm 4711 0 0
0.0     9 root     [migration/0]
0.0  9206 pi       less
0.0  9205 pi       sort -r -k1
0.0  9204 pi       ps -eo pcpu,pid,user,args
0.0  9192 pi       sshd: pi@pts/0
0.0  9067 root     [kworker/u8:1]
0.0  9039 root     [kworker/u8:2]
0.0     8 root     [rcu_bh]
0.0    89 root     [ipv6_addrconf]
0.0  8957 root     [kworker/1:2]
0.0  8952 root     [kworker/3:2]
0.0  8947 root     [kworker/0:2]
0.0  8924 root     [kworker/2:0]
0.0  8896 root     [kworker/1:1]
0.0  8884 root     [kworker/0:1]
0.0  8792 root     [kworker/2:1]
0.0  8673 root     [kworker/3:1]
0.0    85 root     [ext4-rsv-conver]
0.0    84 root     [jbd2/mmcblk0p2-]
0.0    83 root     [mmcqd/0]
0.0    82 root     [bioset]
0.0    80 root     [deferwq]
0.0   801 pi       /usr/lib/openssh/sftp-server
0.0     7 root     [rcu_sched]
0.0    79 root     [SMIO]
0.0   799 pi       sshd: pi@notty
0.0   792 fhem     adb -P 5037 fork-server server


Hätte jetzt gesagt, dass 20% für fhem ok sind, oder?

und hier das LOG von gestern früh bis jetzt (kann es sein, dass der Pi zwischendrion selbst neu startet?)

[code]
Apr 19 07:17:01 raspberrypi CRON[2386]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Apr 19 07:20:31 raspberrypi systemd[1]: Started Synchronise Hardware Clock to System Clock.
Apr 19 07:20:31 raspberrypi systemd[1]: Started Turns off Raspberry Pi display backlight on shutdown/reboot.
Apr 19 07:20:31 raspberrypi systemd[1]: Stopping Bluetooth.
Apr 19 07:20:31 raspberrypi systemd[1]: Stopped target Bluetooth.
Apr 19 07:20:31 raspberrypi systemd[1]: Stopping system-ifup.slice.
Apr 19 07:20:31 raspberrypi systemd[1]: Removed slice system-ifup.slice.
Apr 19 07:20:31 raspberrypi systemd[1]: Stopping Sound Card.
Apr 19 07:20:31 raspberrypi systemd[1]: Stopped target Sound Card.
Apr 19 07:20:31 raspberrypi systemd[1]: Stopping system-systemd\x2dfsck.slice.
Apr 19 07:20:31 raspberrypi systemd[1]: Removed slice system-systemd\x2dfsck.slice.
Apr 19 07:20:31 raspberrypi bluetoothd[625]: Terminating
Apr 19 07:20:31 raspberrypi systemd[1]: Stopping Bluetooth service...
Apr 19 07:20:31 raspberrypi systemd[1]: Stopping Graphical Interface.
Apr 19 07:20:31 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="398" x-info="http://www.rsyslog.com"] exiting on signal 15.
Apr 19 07:20:35 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="401" x-info="http://www.rsyslog.com"] start
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpuset
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpu
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpuacct
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Linux version 4.4.50-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #970 SMP Mon Feb 20 19:18:29 GMT 2017
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x3a800000
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] On node 0 totalpages: 241664
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 808c5040, node_mem_map b9fa6000
Apr 19 07:20:35 raspberrypi kernel: [    0.000000]   Normal zone: 2124 pages used for memmap
Apr 19 07:20:35 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
Apr 19 07:20:35 raspberrypi kernel: [    0.000000]   Normal zone: 241664 pages, LIFO batch:31
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] enter (9520->f3003010)
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] ncores=4
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] PERCPU: Embedded 13 pages/cpu @b9f62000 s22592 r8192 d22464 u53248
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] pcpu-alloc: s22592 r8192 d22464 u53248 alloc=13*4096
Apr 19 07:20:35 raspberrypi kernel: [    0.000000] pcpu-alloc:
  • 0
  • 1
  • 2
  • 3
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 239540
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 dma.dmachans=0x7f35 bcm2709.boardrev=0xa02082 bcm2709.serial=0x19ff3d77 bcm2709.uart_clock=48000000 smsc95xx.macaddr=B8:27:EB:FF:3D:77 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Memory: 939064K/966656K available (6357K kernel code, 432K rwdata, 1716K rodata, 476K init, 764K bss, 19400K reserved, 8192K cma-reserved)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]     vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]     lowmem  : 0x80000000 - 0xbb000000   ( 944 MB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]       .text : 0x80008000 - 0x807ea73c   (8074 kB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]       .init : 0x807eb000 - 0x80862000   ( 476 kB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]       .data : 0x80862000 - 0x808ce350   ( 433 kB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]        .bss : 0x808d1000 - 0x809901ec   ( 765 kB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Hierarchical RCU implementation.
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000]    Build-time adjustment of leaf fanout to 32.
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] NR_IRQS:16 nr_irqs:16 16
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] Architected cp15 timer(s) running at 19.20MHz (phys).
    Apr 19 07:20:35 raspberrypi kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
    Apr 19 07:20:35 raspberrypi kernel: [    0.000008] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
    Apr 19 07:20:35 raspberrypi kernel: [    0.000026] Switching to timer-based delay loop, resolution 52ns
    Apr 19 07:20:35 raspberrypi kernel: [    0.000289] Console: colour dummy device 80x30
    Apr 19 07:20:35 raspberrypi kernel: [    0.001342] console [tty1] enabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.001394] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
    Apr 19 07:20:35 raspberrypi kernel: [    0.001462] pid_max: default: 32768 minimum: 301
    Apr 19 07:20:35 raspberrypi kernel: [    0.001800] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.001843] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.002801] Disabling cpuset control group subsystem
    Apr 19 07:20:35 raspberrypi kernel: [    0.002858] Initializing cgroup subsys io
    Apr 19 07:20:35 raspberrypi kernel: [    0.002908] Initializing cgroup subsys memory
    Apr 19 07:20:35 raspberrypi kernel: [    0.002975] Initializing cgroup subsys devices
    Apr 19 07:20:35 raspberrypi kernel: [    0.003019] Initializing cgroup subsys freezer
    Apr 19 07:20:35 raspberrypi kernel: [    0.003062] Initializing cgroup subsys net_cls
    Apr 19 07:20:35 raspberrypi kernel: [    0.003132] CPU: Testing write buffer coherency: ok
    Apr 19 07:20:35 raspberrypi kernel: [    0.003216] ftrace: allocating 21246 entries in 63 pages
    Apr 19 07:20:35 raspberrypi kernel: [    0.052496] CPU0: update cpu_capacity 1024
    Apr 19 07:20:35 raspberrypi kernel: [    0.052566] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    Apr 19 07:20:35 raspberrypi kernel: [    0.052597] [bcm2709_smp_prepare_cpus] enter
    Apr 19 07:20:35 raspberrypi kernel: [    0.052755] Setting up static identity map for 0x8240 - 0x8274
    Apr 19 07:20:35 raspberrypi kernel: [    0.054409] [bcm2709_boot_secondary] cpu:1 started (0) 18
    Apr 19 07:20:35 raspberrypi kernel: [    0.054626] [bcm2709_secondary_init] enter cpu:1
    Apr 19 07:20:35 raspberrypi kernel: [    0.054670] CPU1: update cpu_capacity 1024
    Apr 19 07:20:35 raspberrypi kernel: [    0.054676] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
    Apr 19 07:20:35 raspberrypi kernel: [    0.055055] [bcm2709_boot_secondary] cpu:2 started (0) 18
    Apr 19 07:20:35 raspberrypi kernel: [    0.055219] [bcm2709_secondary_init] enter cpu:2
    Apr 19 07:20:35 raspberrypi kernel: [    0.055241] CPU2: update cpu_capacity 1024
    Apr 19 07:20:35 raspberrypi kernel: [    0.055247] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
    Apr 19 07:20:35 raspberrypi kernel: [    0.055607] [bcm2709_boot_secondary] cpu:3 started (0) 17
    Apr 19 07:20:35 raspberrypi kernel: [    0.055737] [bcm2709_secondary_init] enter cpu:3
    Apr 19 07:20:35 raspberrypi kernel: [    0.055757] CPU3: update cpu_capacity 1024
    Apr 19 07:20:35 raspberrypi kernel: [    0.055763] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
    Apr 19 07:20:35 raspberrypi kernel: [    0.055823] Brought up 4 CPUs
    Apr 19 07:20:35 raspberrypi kernel: [    0.055922] SMP: Total of 4 processors activated (153.60 BogoMIPS).
    Apr 19 07:20:35 raspberrypi kernel: [    0.055951] CPU: All CPU(s) started in HYP mode.
    Apr 19 07:20:35 raspberrypi kernel: [    0.055978] CPU: Virtualization extensions available.
    Apr 19 07:20:35 raspberrypi kernel: [    0.056609] devtmpfs: initialized
    Apr 19 07:20:35 raspberrypi kernel: [    0.067424] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
    Apr 19 07:20:35 raspberrypi kernel: [    0.067798] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    Apr 19 07:20:35 raspberrypi kernel: [    0.068537] pinctrl core: initialized pinctrl subsystem
    Apr 19 07:20:35 raspberrypi kernel: [    0.069088] NET: Registered protocol family 16
    Apr 19 07:20:35 raspberrypi kernel: [    0.074221] DMA: preallocated 4096 KiB pool for atomic coherent allocations
    Apr 19 07:20:35 raspberrypi kernel: [    0.081207] bcm2709: Mini UART enabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.081267] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    Apr 19 07:20:35 raspberrypi kernel: [    0.081314] hw-breakpoint: maximum watchpoint size is 8 bytes.
    Apr 19 07:20:35 raspberrypi kernel: [    0.081486] Serial: AMBA PL011 UART driver
    Apr 19 07:20:35 raspberrypi kernel: [    0.081646] uart-pl011 3f201000.uart: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
    Apr 19 07:20:35 raspberrypi kernel: [    0.081851] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.144628] bcm2835-dma 3f007000.dma: DMA legacy API manager at f3007000, dmachans=0x1
    Apr 19 07:20:35 raspberrypi kernel: [    0.145240] SCSI subsystem initialized
    Apr 19 07:20:35 raspberrypi kernel: [    0.145449] usbcore: registered new interface driver usbfs
    Apr 19 07:20:35 raspberrypi kernel: [    0.145562] usbcore: registered new interface driver hub
    Apr 19 07:20:35 raspberrypi kernel: [    0.145680] usbcore: registered new device driver usb
    Apr 19 07:20:35 raspberrypi kernel: [    0.152052] raspberrypi-firmware soc:firmware: Attached to firmware from 2017-04-05 11:49
    Apr 19 07:20:35 raspberrypi kernel: [    0.179184] clocksource: Switched to clocksource arch_sys_counter
    Apr 19 07:20:35 raspberrypi kernel: [    0.224018] FS-Cache: Loaded
    Apr 19 07:20:35 raspberrypi kernel: [    0.224312] CacheFiles: Loaded
    Apr 19 07:20:35 raspberrypi kernel: [    0.236667] NET: Registered protocol family 2
    Apr 19 07:20:35 raspberrypi kernel: [    0.237537] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.237676] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.237887] TCP: Hash tables configured (established 8192 bind 8192)
    Apr 19 07:20:35 raspberrypi kernel: [    0.238001] UDP hash table entries: 512 (order: 2, 16384 bytes)
    Apr 19 07:20:35 raspberrypi systemd[1]: Mounted Configuration File System.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Apply Kernel Variables.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
    Apr 19 07:20:35 raspberrypi fake-hwclock[102]: Wed 19 Apr 05:20:33 UTC 2017
    Apr 19 07:20:35 raspberrypi systemd-fsck[116]: e2fsck 1.42.12 (29-Aug-2014)
    Apr 19 07:20:35 raspberrypi systemd-fsck[116]: /dev/mmcblk0p2: clean, 43068/467712 files, 407565/1896704 blocks
    Apr 19 07:20:35 raspberrypi systemd[1]: Started udev Kernel Device Manager.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Copy rules generated while the root was ro...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: Set preliminary keymap...
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Copy rules generated while the root was ro.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Sound Card.
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Sound Card.
    Apr 19 07:20:35 raspberrypi systemd[1]: Found device /dev/mmcblk0p1.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting File System Check on /dev/mmcblk0p1...
    Apr 19 07:20:35 raspberrypi keyboard-setup[138]: Setting preliminary keymap...done.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started LSB: Set preliminary keymap.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Various fixups to make systemd work better on Debian.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Local File Systems (Pre).
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Load/Save Random Seed...
    Apr 19 07:20:35 raspberrypi systemd-fsck[213]: fsck.fat 3.0.27 (2014-11-12)
    Apr 19 07:20:35 raspberrypi systemd-fsck[213]: /dev/mmcblk0p1: 128 files, 2688/8057 clusters
    Apr 19 07:20:35 raspberrypi systemd[1]: Started File System Check on /dev/mmcblk0p1.
    Apr 19 07:20:35 raspberrypi systemd[1]: Mounting /boot...
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Load/Save Random Seed.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting system-systemd\x2drfkill.slice.
    Apr 19 07:20:35 raspberrypi kernel: [    0.238069] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    0.238322] NET: Registered protocol family 1
    Apr 19 07:20:35 raspberrypi systemd[1]: Created slice system-systemd\x2drfkill.slice.
    Apr 19 07:20:35 raspberrypi kernel: [    0.238678] RPC: Registered named UNIX socket transport module.
    Apr 19 07:20:35 raspberrypi kernel: [    0.238710] RPC: Registered udp transport module.
    Apr 19 07:20:35 raspberrypi kernel: [    0.238738] RPC: Registered tcp transport module.
    Apr 19 07:20:35 raspberrypi kernel: [    0.238766] RPC: Registered tcp NFSv4.1 backchannel transport module.
    Apr 19 07:20:35 raspberrypi kernel: [    0.239830] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
    Apr 19 07:20:35 raspberrypi kernel: [    0.241191] futex hash table entries: 1024 (order: 4, 65536 bytes)
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0...
    Apr 19 07:20:35 raspberrypi kernel: [    0.254443] VFS: Disk quotas dquot_6.6.0
    Apr 19 07:20:35 raspberrypi kernel: [    0.254773] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    Apr 19 07:20:35 raspberrypi systemd[1]: Mounted /boot.
    Apr 19 07:20:35 raspberrypi systemd[1]: Received SIGRTMIN+20 from PID 226 (plymouthd).
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Show Plymouth Boot Screen.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting system-ifup.slice.
    Apr 19 07:20:35 raspberrypi systemd[1]: Created slice system-ifup.slice.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Paths.
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Paths.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Local File Systems.
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Local File Systems.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Remote File Systems.
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Remote File Systems.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: Prepare console...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: Raise network interfaces....
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
    Apr 19 07:20:35 raspberrypi raspi-config[250]: Checking if shift key is held down:Error opening '/dev/input/event*': No such file or directory
    Apr 19 07:20:35 raspberrypi kernel: [    0.257022] FS-Cache: Netfs 'nfs' registered for caching
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
    Apr 19 07:20:35 raspberrypi kernel: [    0.257941] NFS: Registering the id_resolver key type
    Apr 19 07:20:35 raspberrypi kernel: [    0.258004] Key type id_resolver registered
    Apr 19 07:20:35 raspberrypi kernel: [    0.258033] Key type id_legacy registered
    Apr 19 07:20:35 raspberrypi kernel: [    0.260375] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
    Apr 19 07:20:35 raspberrypi kernel: [    0.260539] io scheduler noop registered
    Apr 19 07:20:35 raspberrypi kernel: [    0.260576] io scheduler deadline registered (default)
    Apr 19 07:20:35 raspberrypi kernel: [    0.260649] io scheduler cfq registered
    Apr 19 07:20:35 raspberrypi kernel: [    0.263213] BCM2708FB: allocated DMA memory fac10000
    Apr 19 07:20:35 raspberrypi kernel: [    0.263259] BCM2708FB: allocated DMA channel 0 @ f3007000
    Apr 19 07:20:35 raspberrypi kernel: [    0.272006] Console: switching to colour frame buffer device 82x26
    Apr 19 07:20:35 raspberrypi kernel: [    0.279134] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.283470] 3f215040.uart: ttyS0 at MMIO 0x3f215040 (irq = 59, base_baud = 31250000) is a 16550
    Apr 19 07:20:35 raspberrypi kernel: [    0.289630] bcm2835-rng 3f104000.rng: hwrng registered
    Apr 19 07:20:35 raspberrypi kernel: [    0.292226] vc-cma: Videocore CMA driver
    Apr 19 07:20:35 raspberrypi kernel: [    0.294639] vc-cma: vc_cma_base      = 0x00000000
    Apr 19 07:20:35 raspberrypi kernel: [    0.297001] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.299312] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.301723] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)
    Apr 19 07:20:35 raspberrypi kernel: [    0.320943] brd: module loaded
    Apr 19 07:20:35 raspberrypi kernel: [    0.331769] loop: module loaded
    Apr 19 07:20:35 raspberrypi kernel: [    0.334864] vchiq: vchiq_init_state: slot_zero = 0xbac80000, is_master = 0
    Apr 19 07:20:35 raspberrypi kernel: [    0.338588] Loading iSCSI transport class v2.0-870.
    Apr 19 07:20:35 raspberrypi kernel: [    0.341534] usbcore: registered new interface driver smsc95xx
    Apr 19 07:20:35 raspberrypi kernel: [    0.343850] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
    Apr 19 07:20:35 raspberrypi kbd[249]: Setting console screen modes.
    Apr 19 07:20:35 raspberrypi kernel: [    0.546471] Core Release: 2.80a
    Apr 19 07:20:35 raspberrypi kernel: [    0.548773] Setting default values for core params
    Apr 19 07:20:35 raspberrypi kernel: [    0.551138] Finished setting default values for core params
    Apr 19 07:20:35 raspberrypi kernel: [    0.753905] Using Buffer DMA mode
    Apr 19 07:20:35 raspberrypi raspi-config[250]: No. Switching to ondemand scaling governor.
    Apr 19 07:20:35 raspberrypi kernel: [    0.756241] Periodic Transfer Interrupt Enhancement - disabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.758621] Multiprocessor Interrupt Enhancement - disabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.760976] OTG VER PARAM: 0, OTG VER FLAG: 0
    Apr 19 07:20:35 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
    Apr 19 07:20:35 raspberrypi kernel: [    0.763240] Dedicated Tx FIFOs mode
    Apr 19 07:20:35 raspberrypi kernel: [    0.765735] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xbac04000 dma = 0xfac04000 len=9024
    Apr 19 07:20:35 raspberrypi kernel: [    0.770404] FIQ FSM acceleration enabled for :
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
    Apr 19 07:20:35 raspberrypi kernel: [    0.770404] Non-periodic Split Transactions
    Apr 19 07:20:35 raspberrypi kernel: [    0.770404] Periodic Split Transactions
    Apr 19 07:20:35 raspberrypi kernel: [    0.770404] High-Speed Isochronous Endpoints
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
    Apr 19 07:20:35 raspberrypi kernel: [    0.770404] Interrupt/Control Split Transaction hack enabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.782249] dwc_otg: Microframe scheduler enabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.782300] WARN::hcd_init_fiq:415: FIQ on core 1 at 0x80448d28
    Apr 19 07:20:35 raspberrypi kernel: [    0.784735] WARN::hcd_init_fiq:416: FIQ ASM at 0x80449098 length 36
    Apr 19 07:20:35 raspberrypi kernel: [    0.787104] WARN::hcd_init_fiq:441: MPHI regs_base at 0xbb87e000
    Apr 19 07:20:35 raspberrypi kbd[249]: setterm: $TERM is not defined.
    Apr 19 07:20:35 raspberrypi kernel: [    0.789458] dwc_otg 3f980000.usb: DWC OTG Controller
    Apr 19 07:20:35 raspberrypi kernel: [    0.791772] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
    Apr 19 07:20:35 raspberrypi kernel: [    0.794134] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
    Apr 19 07:20:35 raspberrypi kernel: [    0.796453] Init: Port Power? op_state=1
    Apr 19 07:20:35 raspberrypi systemd[1]: Started LSB: Prepare console.
    Apr 19 07:20:35 raspberrypi kernel: [    0.798707] Init: Power Port (0)
    Apr 19 07:20:35 raspberrypi kernel: [    0.801097] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
    Apr 19 07:20:35 raspberrypi kernel: [    0.803406] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: Set console font and keymap...
    Apr 19 07:20:35 raspberrypi kernel: [    0.805704] usb usb1: Product: DWC OTG Controller
    Apr 19 07:20:35 raspberrypi kernel: [    0.807953] usb usb1: Manufacturer: Linux 4.4.50-v7+ dwc_otg_hcd
    Apr 19 07:20:35 raspberrypi kernel: [    0.810241] usb usb1: SerialNumber: 3f980000.usb
    Apr 19 07:20:35 raspberrypi kernel: [    0.813308] hub 1-0:1.0: USB hub found
    Apr 19 07:20:35 raspberrypi systemd[1]: Started LSB: Set console font and keymap.
    Apr 19 07:20:35 raspberrypi kernel: [    0.815511] hub 1-0:1.0: 1 port detected
    Apr 19 07:20:35 raspberrypi kernel: [    0.818206] dwc_otg: FIQ enabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.818215] dwc_otg: NAK holdoff enabled
    Apr 19 07:20:35 raspberrypi console-setup[302]: Setting up console font and keymap...done.
    Apr 19 07:20:35 raspberrypi kernel: [    0.818224] dwc_otg: FIQ split-transaction FSM enabled
    Apr 19 07:20:35 raspberrypi kernel: [    0.818258] Module dwc_common_port init
    Apr 19 07:20:35 raspberrypi kernel: [    0.818511] usbcore: registered new interface driver usb-storage
    Apr 19 07:20:35 raspberrypi networking[252]: Configuring network interfaces...done.
    Apr 19 07:20:35 raspberrypi kernel: [    0.820907] mousedev: PS/2 mouse device common for all mice
    Apr 19 07:20:35 raspberrypi kernel: [    0.823799] bcm2835-cpufreq: min=600000 max=1200000
    Apr 19 07:20:35 raspberrypi kernel: [    0.826235] sdhci: Secure Digital Host Controller Interface driver
    Apr 19 07:20:35 raspberrypi systemd[1]: Started LSB: Raise network interfaces..
    Apr 19 07:20:35 raspberrypi kernel: [    0.828495] sdhci: Copyright(c) Pierre Ossman
    Apr 19 07:20:35 raspberrypi kernel: [    0.831040] sdhost: log_buf @ bac07000 (fac07000)
    Apr 19 07:20:35 raspberrypi kernel: [    0.889215] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting ifup for wlan0...
    Apr 19 07:20:35 raspberrypi kernel: [    0.893727] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
    Apr 19 07:20:35 raspberrypi kernel: [    0.896020] mmc-bcm2835 3f300000.mmc: DMA channel allocated
    Apr 19 07:20:35 raspberrypi kernel: [    0.946489] mmc0: host does not support reading read-only switch, assuming write-enable
    Apr 19 07:20:35 raspberrypi systemd[1]: Started ifup for wlan0.
    Apr 19 07:20:35 raspberrypi kernel: [    0.949298] sdhci-pltfm: SDHCI platform and OF driver helper
    Apr 19 07:20:35 raspberrypi kernel: [    0.949666] ledtrig-cpu: registered to indicate activity on CPUs
    Apr 19 07:20:35 raspberrypi kernel: [    0.949767] hidraw: raw HID events driver (C) Jiri Kosina
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting System Initialization.
    Apr 19 07:20:35 raspberrypi kernel: [    0.949940] usbcore: registered new interface driver usbhid
    Apr 19 07:20:35 raspberrypi kernel: [    0.949942] usbhid: USB HID core driver
    Apr 19 07:20:35 raspberrypi kernel: [    0.950505] Initializing XFRM netlink socket
    Apr 19 07:20:35 raspberrypi kernel: [    0.950532] NET: Registered protocol family 17
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target System Initialization.
    Apr 19 07:20:35 raspberrypi kernel: [    0.950663] Key type dns_resolver registered
    Apr 19 07:20:35 raspberrypi kernel: [    0.951117] Registering SWP/SWPB emulation handler
    Apr 19 07:20:35 raspberrypi kernel: [    0.951913] registered taskstats version 1
    Apr 19 07:20:35 raspberrypi kernel: [    0.952112] vc-sm: Videocore shared memory driver
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket.
    Apr 19 07:20:35 raspberrypi kernel: [    0.952121] [vc_sm_connected_init]: start
    Apr 19 07:20:35 raspberrypi kernel: [    0.960900] [vc_sm_connected_init]: end - returning 0
    Apr 19 07:20:35 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
    Apr 19 07:20:35 raspberrypi kernel: [    0.961275] 3f201000.uart: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
    Apr 19 07:20:35 raspberrypi kernel: [    0.961646] of_cfs_init
    Apr 19 07:20:35 raspberrypi kernel: [    0.961720] of_cfs_init: OK
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting D-Bus System Message Bus Socket.
    Apr 19 07:20:35 raspberrypi kernel: [    0.990172] Waiting for root device /dev/mmcblk0p2...
    Apr 19 07:20:35 raspberrypi kernel: [    0.991963] mmc0: new high speed SDHC card at address 0001
    Apr 19 07:20:35 raspberrypi kernel: [    0.992555] mmcblk0: mmc0:0001 00000 7.30 GiB
    Apr 19 07:20:35 raspberrypi kernel: [    0.995917]  mmcblk0: p1 p2
    Apr 19 07:20:35 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
    Apr 19 07:20:35 raspberrypi kernel: [    1.009297] Indeed it is in host mode hprt0 = 00021501
    Apr 19 07:20:35 raspberrypi kernel: [    1.013911] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Sockets.
    Apr 19 07:20:35 raspberrypi kernel: [    1.017498] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    1.021015] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    1.021692] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Sockets.
    Apr 19 07:20:35 raspberrypi kernel: [    1.021739] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
    Apr 19 07:20:35 raspberrypi kernel: [    1.031685] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
    Apr 19 07:20:35 raspberrypi kernel: [    1.036167] devtmpfs: mounted
    Apr 19 07:20:35 raspberrypi kernel: [    1.039031] Freeing unused kernel memory: 476K (807eb000 - 80862000)
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Daily Cleanup of Temporary Directories.
    Apr 19 07:20:35 raspberrypi kernel: [    1.126246] mmc1: new high speed SDIO card at address 0001
    Apr 19 07:20:35 raspberrypi kernel: [    1.189229] usb 1-1: new high-speed USB device number 2 using dwc_otg
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
    Apr 19 07:20:35 raspberrypi kernel: [    1.191738] Indeed it is in host mode hprt0 = 00001101
    Apr 19 07:20:35 raspberrypi kernel: [    1.377996] random: systemd: uninitialized urandom read (16 bytes read, 40 bits of entropy available)
    Apr 19 07:20:35 raspberrypi kernel: [    1.392566] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Timers.
    Apr 19 07:20:35 raspberrypi kernel: [    1.397976] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    Apr 19 07:20:35 raspberrypi kernel: [    1.401055] hub 1-1:1.0: USB hub found
    Apr 19 07:20:35 raspberrypi kernel: [    1.401145] hub 1-1:1.0: 5 ports detected
    Apr 19 07:20:35 raspberrypi kernel: [    1.515773] NET: Registered protocol family 10
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Timers.
    Apr 19 07:20:35 raspberrypi kernel: [    1.638236] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 66 bits of entropy available)
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Manage Sound Card State (restore and store).
    Apr 19 07:20:35 raspberrypi kernel: [    1.679254] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
    Apr 19 07:20:35 raspberrypi kernel: [    1.779550] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Restore Sound Card State...
    Apr 19 07:20:35 raspberrypi kernel: [    1.782363] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    Apr 19 07:20:35 raspberrypi kernel: [    1.787915] random: systemd: uninitialized urandom read (16 bytes read, 68 bits of entropy available)
    Apr 19 07:20:35 raspberrypi kernel: [    1.788012] smsc95xx v1.0.4
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Basic System.
    Apr 19 07:20:35 raspberrypi kernel: [    1.798157] random: systemd: uninitialized urandom read (16 bytes read, 69 bits of entropy available)
    Apr 19 07:20:35 raspberrypi kernel: [    1.804718] random: systemd: uninitialized urandom read (16 bytes read, 69 bits of entropy available)
    Apr 19 07:20:35 raspberrypi systemd[1]: Reached target Basic System.
    Apr 19 07:20:35 raspberrypi kernel: [    1.847811] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
    Apr 19 07:20:35 raspberrypi kernel: [    1.854912] random: systemd: uninitialized urandom read (16 bytes read, 72 bits of entropy available)
    Apr 19 07:20:35 raspberrypi kernel: [    1.861239] random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available)
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting System Logging Service...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Regular background program processing daemon...
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Regular background program processing daemon.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting Login Service...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting D-Bus System Message Bus...
    Apr 19 07:20:35 raspberrypi systemd[1]: Started D-Bus System Message Bus.
    Apr 19 07:20:35 raspberrypi cron[410]: (CRON) INFO (pidfile fd = 3)
    Apr 19 07:20:35 raspberrypi dhcpcd[403]: version 6.7.1 starting
    Apr 19 07:20:35 raspberrypi dhcpcd[403]: dev: loaded udev
    Apr 19 07:20:35 raspberrypi dhcpcd[403]: eth0: adding address fe80::edf5:cde2:68fa:947d
    Apr 19 07:20:35 raspberrypi dhcpcd[403]: wlan0: adding address fe80::21ad:8bcd:ca74:f616
    Apr 19 07:20:35 raspberrypi cron[410]: (CRON) INFO (Running @reboot jobs)
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Found user 'avahi' (UID 105) and group 'avahi' (GID 110).
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Successfully dropped root privileges.
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: avahi-daemon 0.6.31 starting up.
    Apr 19 07:20:35 raspberrypi wpa_supplicant[414]: Successfully initialized wpa_supplicant
    Apr 19 07:20:35 raspberrypi kernel: [    1.868493] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:ff:3d:77
    Apr 19 07:20:35 raspberrypi kernel: [    1.904291] random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available)
    Apr 19 07:20:35 raspberrypi kernel: [    1.927307] random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available)
    Apr 19 07:20:35 raspberrypi kernel: [    1.969239] usb 1-1.3: new full-speed USB device number 4 using dwc_otg
    Apr 19 07:20:35 raspberrypi kernel: [    2.108482] usb 1-1.3: New USB device found, idVendor=0403, idProduct=e0e8
    Apr 19 07:20:35 raspberrypi kernel: [    2.139204] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
    Apr 19 07:20:35 raspberrypi kernel: [    2.139215] usb 1-1.3: Product: ELV FHZ 1300 PC
    Apr 19 07:20:35 raspberrypi kernel: [    2.139221] usb 1-1.3: Manufacturer: ELV AG
    Apr 19 07:20:35 raspberrypi kernel: [    2.139228] usb 1-1.3: SerialNumber: EL3YMNIF
    Apr 19 07:20:35 raspberrypi kernel: [    3.288314] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
    Apr 19 07:20:35 raspberrypi kernel: [    3.292422] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
    Apr 19 07:20:35 raspberrypi kernel: [    3.666659] usbcore: registered new interface driver brcmfmac
    Apr 19 07:20:35 raspberrypi kernel: [    3.774169] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
    Apr 19 07:20:35 raspberrypi kernel: [    3.837681] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: May 27 2016 00:13:38 version 7.45.41.26 (r640327) FWID 01-df77e4a7
    Apr 19 07:20:35 raspberrypi kernel: [    3.870679] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code
    Apr 19 07:20:35 raspberrypi kernel: [    3.945017] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code
    Apr 19 07:20:35 raspberrypi kernel: [    3.945046] cfg80211: World regulatory domain updated:
    Apr 19 07:20:35 raspberrypi kernel: [    3.945055] cfg80211:  DFS Master region: unset
    Apr 19 07:20:35 raspberrypi kernel: [    3.945064] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945080] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945095] cfg80211:   (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945107] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945121] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945135] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945148] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945160] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    3.945172] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    4.604941] usbcore: registered new interface driver usbserial
    Apr 19 07:20:35 raspberrypi kernel: [    4.605002] usbcore: registered new interface driver usbserial_generic
    Apr 19 07:20:35 raspberrypi kernel: [    4.605045] usbserial: USB Serial support registered for generic
    Apr 19 07:20:35 raspberrypi kernel: [    4.614145] usbcore: registered new interface driver ftdi_sio
    Apr 19 07:20:35 raspberrypi kernel: [    4.614203] usbserial: USB Serial support registered for FTDI USB Serial Device
    Apr 19 07:20:35 raspberrypi kernel: [    4.614448] ftdi_sio 1-1.3:1.0: FTDI USB Serial Device converter detected
    Apr 19 07:20:35 raspberrypi kernel: [    4.614574] usb 1-1.3: Detected FT8U232AM
    Apr 19 07:20:35 raspberrypi kernel: [    4.614905] ftdi_sio ttyUSB0: Unable to read latency timer: -32
    Apr 19 07:20:35 raspberrypi kernel: [    4.615134] ftdi_sio ttyUSB0: Unable to write latency timer: -32
    Apr 19 07:20:35 raspberrypi kernel: [    4.615276] usb 1-1.3: FTDI USB Serial Device converter now attached to ttyUSB0
    Apr 19 07:20:35 raspberrypi kernel: [    4.828900] brcmfmac: brcmf_add_if: ERROR: netdev:wlan0 already exists
    Apr 19 07:20:35 raspberrypi kernel: [    4.828917] brcmfmac: brcmf_add_if: ignore IF event
    Apr 19 07:20:35 raspberrypi kernel: [    4.829933] random: nonblocking pool is initialized
    Apr 19 07:20:35 raspberrypi kernel: [    4.833211] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    Apr 19 07:20:35 raspberrypi kernel: [    4.833240] brcmfmac: power management disabled
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Successfully called chroot().
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Successfully dropped remaining capabilities.
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: No service file found in /etc/avahi/services.
    Apr 19 07:20:35 raspberrypi dbus[413]: [system] Successfully activated service 'org.freedesktop.systemd1'
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::21ad:8bcd:ca74:f616.
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: New relevant interface wlan0.IPv6 for mDNS.
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Network interface enumeration completed.
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Registering new address record for fe80::21ad:8bcd:ca74:f616 on wlan0.*.
    Apr 19 07:20:35 raspberrypi avahi-daemon[402]: Registering HINFO record with values 'ARMV7L'/'LINUX'.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: Autogenerate and use a swap file...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: FHEM server...
    Apr 19 07:20:35 raspberrypi systemd[1]: Starting LSB: triggerhappy hotkey daemon...
    Apr 19 07:20:35 raspberrypi systemd[1]: Started System Logging Service.
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Restore Sound Card State.
    Apr 19 07:20:35 raspberrypi dphys-swapfile[463]: Starting dphys-swapfile swapfile setup ...
    Apr 19 07:20:35 raspberrypi wpa_supplicant[482]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB
    Apr 19 07:20:35 raspberrypi kernel: [    5.485172] cfg80211: Regulatory domain changed to country: GB
    Apr 19 07:20:35 raspberrypi kernel: [    5.485189] cfg80211:  DFS Master region: ETSI
    Apr 19 07:20:35 raspberrypi kernel: [    5.485194] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
    Apr 19 07:20:35 raspberrypi kernel: [    5.485202] cfg80211:   (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    5.485210] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi kernel: [    5.485217] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
    Apr 19 07:20:35 raspberrypi kernel: [    5.485224] cfg80211:   (5490000 KHz - 5710000 KHz @ 160000 KHz), (N/A, 2700 mBm), (0 s)
    Apr 19 07:20:35 raspberrypi kernel: [    5.485230] cfg80211:   (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
    Apr 19 07:20:35 raspberrypi systemd[1]: Started Login Service.
    Apr 19 07:20:35 raspberrypi triggerhappy[476]: Error opening '/dev/input/event*': No such file or directory
    Apr 19 07:20:35 raspberrypi systemd[1]: Started LSB: triggerhappy hotkey daemon.
    Apr 19 07:20:36 raspberrypi dphys-swapfile[463]: want /var/swap=100MByte, checking existing: keeping it
    Apr 19 07:20:36 raspberrypi dphys-swapfile[463]: done.
    Apr 19 07:20:36 raspberrypi systemd[1]: Started LSB: Autogenerate and use a swap file.
    Apr 19 07:20:36 raspberrypi kernel: [    5.650334] Adding 102396k swap on /var/swap.  Priority:-1 extents:4 across:184316k SSFS
    Apr 19 07:20:36 raspberrypi avahi-daemon[402]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::edf5:cde2:68fa:947d.
    Apr 19 07:20:36 raspberrypi avahi-daemon[402]: New relevant interface eth0.IPv6 for mDNS.
    Apr 19 07:20:36 raspberrypi avahi-daemon[402]: Registering new address record for fe80::edf5:cde2:68fa:947d on eth0.*.
    Apr 19 07:20:36 raspberrypi dhcpcd[403]: eth0: waiting for carrier
    Apr 19 07:20:36 raspberrypi dhcpcd[403]: wlan0: waiting for carrier
    Apr 19 07:20:36 raspberrypi kernel: [    5.800824] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
    Apr 19 07:20:36 raspberrypi kernel: [    5.801258] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    Apr 19 07:20:36 raspberrypi fhem[473]: Starting fhem...
    Apr 19 07:20:37 raspberrypi avahi-daemon[402]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 3790712462.
    Apr 19 07:20:37 raspberrypi dhcpcd[403]: eth0: carrier acquired
    Apr 19 07:20:37 raspberrypi kernel: [    7.412048] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
    Apr 19 07:20:37 raspberrypi kernel: [    7.412895] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
    Apr 19 07:20:37 raspberrypi dhcpcd[403]: DUID 00:01:00:01:1f:77:63:d7:b8:27:eb:ff:3d:77
    Apr 19 07:20:37 raspberrypi dhcpcd[403]: eth0: IAID eb:ff:3d:77
    Apr 19 07:20:38 raspberrypi dhcpcd[403]: eth0: soliciting an IPv6 router
    Apr 19 07:20:38 raspberrypi dhcpcd[403]: eth0: rebinding lease of 192.168.10.8
    Apr 19 07:20:46 raspberrypi dhcpcd[403]: eth0: leased 192.168.10.8 for 86400 seconds
    Apr 19 07:20:46 raspberrypi avahi-daemon[402]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.10.8.
    Apr 19 07:20:46 raspberrypi avahi-daemon[402]: New relevant interface eth0.IPv4 for mDNS.
    Apr 19 07:20:46 raspberrypi avahi-daemon[402]: Registering new address record for 192.168.10.8 on eth0.IPv4.
    Apr 19 07:20:46 raspberrypi dhcpcd[403]: eth0: adding route to 192.168.10.0/24
    Apr 19 07:20:46 raspberrypi dhcpcd[403]: eth0: adding default route via 192.168.10.254
    Apr 19 07:20:46 raspberrypi dhcpcd[403]: forked to background, child pid 692
    Apr 19 07:20:46 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting Network.
    Apr 19 07:20:46 raspberrypi systemd[1]: Reached target Network.
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
    Apr 19 07:20:46 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting Permit User Sessions...
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting Network is Online.
    Apr 19 07:20:46 raspberrypi systemd[1]: Reached target Network is Online.
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting LSB: Start NTP daemon...
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting LSB: start Samba daemons for the AD DC...
    Apr 19 07:20:46 raspberrypi systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)...
    Apr 19 07:20:46 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
    Apr 19 07:20:46 raspberrypi systemd[1]: Started Permit User Sessions.
    Apr 19 07:20:47 raspberrypi systemd[1]: Starting Hold until boot process finishes up...
    Apr 19 07:20:47 raspberrypi systemd[1]: Starting Terminate Plymouth Boot Screen...
    Apr 19 07:20:47 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 226 (plymouthd).
    Apr 19 07:20:47 raspberrypi systemd[1]: Started Hold until boot process finishes up.
    Apr 19 07:20:47 raspberrypi systemd[1]: Started Terminate Plymouth Boot Screen.
    Apr 19 07:20:47 raspberrypi systemd[1]: Starting Getty on tty1...
    Apr 19 07:20:47 raspberrypi systemd[1]: Started Getty on tty1.
    Apr 19 07:20:47 raspberrypi systemd[1]: Starting Login Prompts.
    Apr 19 07:20:47 raspberrypi systemd[1]: Reached target Login Prompts.
    Apr 19 07:20:47 raspberrypi ntpd[721]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1)
    Apr 19 07:20:47 raspberrypi ntpd[726]: proto: precision = 0.520 usec
    Apr 19 07:20:47 raspberrypi ntpd[726]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
    Apr 19 07:20:47 raspberrypi ntp[697]: Starting NTP server: ntpd.
    Apr 19 07:20:47 raspberrypi systemd[1]: Started LSB: Start NTP daemon.
    Apr 19 07:20:47 raspberrypi ntpd[726]: Listen and drop on 1 v6wildcard :: UDP 123
    Apr 19 07:20:47 raspberrypi ntpd[726]: Listen normally on 2 lo 127.0.0.1 UDP 123
    Apr 19 07:20:47 raspberrypi ntpd[726]: Listen normally on 3 eth0 192.168.10.8 UDP 123
    Apr 19 07:20:47 raspberrypi ntpd[726]: Listen normally on 4 lo ::1 UDP 123
    Apr 19 07:20:47 raspberrypi ntpd[726]: Listen normally on 5 eth0 fe80::edf5:cde2:68fa:947d UDP 123
    Apr 19 07:20:47 raspberrypi ntpd[726]: peers refreshed
    Apr 19 07:20:47 raspberrypi ntpd[726]: Listening on routing socket on fd #22 for interface updates
    Apr 19 07:20:49 raspberrypi systemd[1]: Started LSB: start Samba daemons for the AD DC.
    Apr 19 07:20:49 raspberrypi nmbd[702]: Starting NetBIOS name server: nmbd.
    Apr 19 07:20:49 raspberrypi systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd).
    Apr 19 07:20:49 raspberrypi systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)...
    Apr 19 07:20:50 raspberrypi smbd[738]: Starting SMB/CIFS daemon: smbd.
    Apr 19 07:20:50 raspberrypi systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd).
    Apr 19 07:20:51 raspberrypi dhcpcd[692]: eth0: no IPv6 Routers available
    Apr 19 07:21:04 raspberrypi systemd[1]: Time has been changed
    Apr 19 07:21:15 raspberrypi btuart[411]: Initialization timed out.
    Apr 19 07:21:15 raspberrypi btuart[411]: bcm43xx_init
    Apr 19 07:21:15 raspberrypi systemd[1]: hciuart.service: control process exited, code=exited status=1
    Apr 19 07:21:15 raspberrypi systemd[1]: Failed to start Configure Bluetooth Modems connected by UART.
    Apr 19 07:21:15 raspberrypi systemd[1]: Unit hciuart.service entered failed state.
    Apr 19 07:21:16 raspberrypi kernel: [   35.685290] uart-pl011 3f201000.uart: no DMA platform data
    Apr 19 07:25:46 raspberrypi systemd[1]: fhem.service start operation timed out. Terminating.
    Apr 19 07:25:46 raspberrypi systemd[1]: Failed to start LSB: FHEM server.
    Apr 19 07:25:46 raspberrypi systemd[1]: Unit fhem.service entered failed state.
    Apr 19 07:25:46 raspberrypi systemd[1]: Starting Multi-User System.
    Apr 19 07:25:46 raspberrypi systemd[1]: Reached target Multi-User System.
    Apr 19 07:25:46 raspberrypi systemd[1]: Starting Graphical Interface.
    Apr 19 07:25:46 raspberrypi systemd[1]: Reached target Graphical Interface.
    Apr 19 07:25:46 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
    Apr 19 07:25:46 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
    Apr 19 07:25:46 raspberrypi systemd[1]: Startup finished in 1.306s (kernel) + 5min 4.403s (userspace) = 5min 5.710s.
    Apr 19 07:35:45 raspberrypi systemd[1]: Starting Cleanup of Temporary Directories...
    Apr 19 07:35:45 raspberrypi systemd[1]: Started Cleanup of Temporary Directories.
    Apr 19 07:58:45 raspberrypi systemd[1]: Reloading.
    Apr 19 07:58:47 raspberrypi systemd[1]: Reloading.
    Apr 19 07:58:48 raspberrypi systemd[1]: Reloading.
    Apr 19 07:58:48 raspberrypi systemd[1]: Reloading.
    Apr 19 07:58:48 raspberrypi systemd[1]: Started fast remote file copy program daemon.
    Apr 19 07:58:48 raspberrypi systemd[1]: Reloading.
    Apr 19 08:12:34 raspberrypi systemd[1]: Started Synchronise Hardware Clock to System Clock.
    Apr 19 08:12:34 raspberrypi systemd[1]: Started Turns off Raspberry Pi display backlight on shutdown/reboot.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping system-ifup.slice.
    Apr 19 08:12:34 raspberrypi systemd[1]: Removed slice system-ifup.slice.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Sound Card.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopped target Sound Card.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping system-systemd\x2dfsck.slice.
    Apr 19 08:12:34 raspberrypi systemd[1]: Removed slice system-systemd\x2dfsck.slice.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Graphical Interface.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopped target Graphical Interface.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Multi-User System.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopped target Multi-User System.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Regular background program processing daemon...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping OpenBSD Secure Shell server...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Login Service...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Login Prompts.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopped target Login Prompts.
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Getty on tty1...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping LSB: Autogenerate and use a swap file...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping LSB: Start NTP daemon...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping LSB: start Samba daemons for the AD DC...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping LSB: start Samba SMB/CIFS daemon (smbd)...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping LSB: triggerhappy hotkey daemon...
    Apr 19 08:12:34 raspberrypi systemd[1]: Stopping Avahi mDNS/DNS-SD Stack...
    Apr 19 08:12:34 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="401" x-info="http://www.rsyslog.com"] exiting on signal 15.
    Apr 19 08:12:38 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="401" x-info="http://www.rsyslog.com"] start
    Apr 19 08:12:38 raspberrypi systemd[1]: Mounted Configuration File System.
    Apr 19 08:12:38 raspberrypi systemd[1]: Started Apply Kernel Variables.
    Apr 19 08:12:38 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
    Apr 19 08:12:38 raspberrypi fake-hwclock[102]: Wed 19 Apr 06:12:36 UTC 2017
    Apr 19 08:12:38 raspberrypi systemd-fsck[119]: e2fsck 1.42.12 (29-Aug-2014)
    Apr 19 08:12:38 raspberrypi systemd-fsck[119]: /dev/mmcblk0p2: clean, 44128/467712 files, 416118/1896704 blocks
    Apr 19 08:12:38 raspberrypi systemd[1]: Started udev Kernel Device Manager.
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting Copy rules generated while the root was ro...
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting LSB: Set preliminary keymap...
    Apr 19 08:12:38 raspberrypi systemd[1]: Started Copy rules generated while the root was ro.
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting Sound Card.
    Apr 19 08:12:38 raspberrypi systemd[1]: Reached target Sound Card.
    Apr 19 08:12:38 raspberrypi systemd[1]: Found device /dev/mmcblk0p1.
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting File System Check on /dev/mmcblk0p1...
    Apr 19 08:12:38 raspberrypi keyboard-setup[141]: Setting preliminary keymap...done.
    Apr 19 08:12:38 raspberrypi systemd[1]: Started LSB: Set preliminary keymap.
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen...
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpuset
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpu
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpuacct
    Apr 19 08:12:38 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Linux version 4.4.50-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #970 SMP Mon Feb 20 19:18:29 GMT 2017
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
    Apr 19 08:12:38 raspberrypi systemd[1]: Started Various fixups to make systemd work better on Debian.
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x3a800000
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting Local File Systems (Pre).
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] On node 0 totalpages: 241664
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 808c5040, node_mem_map b9fa6000
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000]   Normal zone: 2124 pages used for memmap
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
    Apr 19 08:12:38 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000]   Normal zone: 241664 pages, LIFO batch:31
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] enter (9520->f3003010)
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting Load/Save Random Seed...
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] ncores=4
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] PERCPU: Embedded 13 pages/cpu @b9f62000 s22592 r8192 d22464 u53248
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] pcpu-alloc: s22592 r8192 d22464 u53248 alloc=13*4096
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] pcpu-alloc:
  • 0
  • 1
  • 2
  • 3
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 239540
    Apr 19 08:12:38 raspberrypi systemd[1]: Starting system-systemd\x2drfkill.slice.
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 dma.dmachans=0x7f35 bcm2709.boardrev=0xa02082 bcm2709.serial=0x19ff3d77 bcm2709.uart_clock=48000000 smsc95xx.macaddr=B8:27:EB:FF:3D:77 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
    Apr 19 08:12:38 raspberrypi systemd-fsck[216]: fsck.fat 3.0.27 (2014-11-12)
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
    Apr 19 08:12:38 raspberrypi kernel: [    0.000000] Memory: 939064K/966656K available (6357K kernel code, 432K rwdata, 1716K rodata, 476K init
RasPi 3 mit MaxCube für FS20 , HM-Urart, HM-LAN, MiLight, HUE, Lightify, SONOS, Harmony, Unifi, FritzBox 7490... :-)
Ganz nach dem Motto: Normal? Normal is langweilig....

CoolTux

Guten Morgen,

Eine Antwort auf Dein Problem habe ich leider nicht. Aber eine Bitte das die Helfenden etwas entlastet.
Du solltest versuchen Dich etwas mehr mit Linux und Proßessen auseinander zu setzen. Lass mich kurz erklären wieso.

Du hast geschrieben das Du FHEM beendet hast. In Deiner Prozesstabelle oben sehe ich aber das der Prozess noch läuft.
Es gibt Fälle wo das Startscript zum starten und beenden von Prozessen/Programmen nicht mehr funktionieren kann. Da es immer auf eine saubere Beendigung wartet.
Wenn das nicht klappt läuft der Prozess dennoch.

ps ax | grep fhem

kann Dir zeigen ob FHEM noch läuft.
Ist das der Fall merkst Du Dir die pid und beendest FHEM hart

kill -9 PID

Erst dann wird der Prozess ordentlich beendet. Im schlimmsten Fall muß man dann dem init Script noch mal ein stop verpassen.


Grüße
Du musst nicht wissen wie es geht! Du musst nur wissen wo es steht, wie es geht.
Support me to buy new test hardware for development: https://www.paypal.com/paypalme/MOldenburg
My FHEM Git: https://git.cooltux.net/FHEM/
Das TuxNet Wiki:
https://www.cooltux.net

h-man-kl

Hi,
schade dass du (CoolTux) keineAntwort hast :-( dann muss ich hoffen dass jemand anderes mir helfen kann.
Deinen Ratschlag nehme ich gerne an. Allerdings ist es hier korrekt, dass fhem in der Prozessliste als laufend drin steht.
Du attest ja den Vorschlag gemacht vor dem Neustart nachzusehen mit welche Last fhem verursacht und dazu muss es logischerweise laufen.

fhem habe ich nach diesem Auszug neu gestartet.

Gruß
H-Man
RasPi 3 mit MaxCube für FS20 , HM-Urart, HM-LAN, MiLight, HUE, Lightify, SONOS, Harmony, Unifi, FritzBox 7490... :-)
Ganz nach dem Motto: Normal? Normal is langweilig....

Wernieman

Beim nächsten Ausfall:
1. https://forum.fhem.de/index.php/topic,54271.msg467373.html#msg467373

Wenn Du fhem wiederbeleben willst:
1. entweder a) oder b)
a) /etc/init.d/fhem stop
b) service fhem stop
2. Gucken ob fhem noch läuft
ps aux | grep [f]hem
3. Wenn noch läuft, dann abschießen
kill -9 PID (die PID ist in jeder Zeile die 2. Zahl)
4. Nachkontrolle (siehe 2.) und fhem wieder starten
5. Prüfen ob fhem läuft (siehe 2.)
6. Wenn FHEM jetzt nicht erreichbar, dann siehe gans oben "Beim nächsten Ausfall:"

Zusätzlich:
Bitte das nächste Mal Logfiles per "code" Tags eintragen. So ist es unübersichtlich und sehr schwer zu lesen. Finde auf die Schnelle keine Auffälligkeiten, habe es aber auch nur "überflogen".

Warum hast Du meinen Tip bezüglich grep nicht befolgt?
Siehe meinen Beitrag vom: « Gestern um 13:27:11 »

- Bitte um Input für Output
- When there is a Shell, there is a Way
- Wann war Dein letztes Backup?

Wie man Fragen stellt: https://tty1.net/smart-questions_de.html