Probleme beim Booten des Raspi

Florian.Stern · 26 April 2017, 13:19:43

Hallo,
ich bin total verzweifelt...Mein Problem:

Meine FHEM Server läuft nun schon seit einigen Jahren sehr stabil.
Letzes Jahr ist mein Komplettes System auf einen neuen Raspberry Pi 3 umgezogen und auch das hat keinerlei Probleme gemacht.
Nun stehe ich heute morgen auf und muss leider festestellen das meine TabletUI die Verbindung zu FHEM verloren hat.
Also als ersten mal hin zum Raspi und schauen ob dieser läuft. Alles erstmal ganz normal. Einen Stromausfall kann ich ausschließen da der Server via USV versorgt wird. Ich habe mich dann via SSH auf den Server verbunden und auch dies klappte. Der FHEM Service lieft auch ohne probleme. Also erstmal Lösungsansatz No.1 "sudo reboot" denn oftmals hilft ein Neustart ja.

Doch jetzt gehen die Probleme erst richtig los.
Der Raspberry booten nicht mehr.

Der Startablauf läuft erstmal ohne Auffälligkeiten durch meldet dann aber:

(FAILED) Failed to start login service.

Der Startvorgang läuft aber mit weiteren (OK) meldung weiter. Bis zum Punkt:

A start job is running for avahi mDNS/DNS-SD Stack

Und danach ist leider schluss. Das System startet nicht weiter.
Die SD karte wurde im Ubuntu system auf Fehler überprüft > ohne Auffälligkeiten!

Und nein ich Idiot habe Kein Backup der SD Karte (Steht aber seit der umstellen auf den neuen Raspi auf meiner TO-DO Liste $:-\$ )

Hat jemand eine Idee wie ich aus diesem Schlamassel wieder rauskomme ohne das ganze System neu aufzusetzen?
Das wäre zwar im Prinzip möglich da ich wenigstens Backups mein FHEM Installation habe. Wäre jetzt aber nich unbedingt meine erste Wahl.

Liebe Grüsse

Wuppi68 · 26 April 2017, 16:27:21

klingt nach SD Karte und/oder Stromversorgung

ich würde als erstes ein anderes Netzteil versuchen und wenn auch das nicht hilft, alles was nicht zum Testbetrieb notwendig vom USB entfernen

Florian.Stern · 26 April 2017, 18:52:44

Es hängt nichts am USB... ein neues Netzteil habe ich auch getestet und ich habe eine Kopie der SD Karte auf eine Neue geschrieben.

Alles führt zu gleichen Ergebnis.

Gesendet von iPhone mit Tapatalk

Benni · 26 April 2017, 20:01:56

Startet der RasPi denn vielleicht irgendwann fertig?
Eventuell dauert es einfach nur sehr lange. Warum wäre dann noch zu erörtern.

Florian.Stern · 26 April 2017, 20:35:46

Das wäre noch einen Versuch wert bist jetzt ist das längste was ich gewartet habe ungefähr eine Stunde.

Bei der Avahi Meldung zählt er einen Countdown... zuerst ca.1 min und nach Ablauf nochmal 3 min... danach springt er eine Zeile tiefer und bleibt dort mit blinkendem Strich stehen

Gesendet von iPhone mit Tapatalk

Wuppi68 · 26 April 2017, 22:03:43

hier http://www.mapledyne.com/ideas/2015/8/4/reset-lost-admin-password-for-raspberry-pi steht wie der PI in den Single User mode startet ...

anstatt das root Passwort weg zu nehmen solltest Du ja den avahi daemon deaktivieren

Florian.Stern · 27 April 2017, 08:04:09

Guten Morgen...das System hängt jetzt seit 11 Std. Am Strom und tadaaa...FHEM ist erreichbar!

Aber leider kann ich nichts in der Konsole machen(direkt am Raspberry) und auch SSH Verbindungen werden abgelehnt (Target is not listening for SSH connections or explicitly rejected)

Gesendet von iPhone mit Tapatalk

Wuppi68 · 27 April 2017, 08:47:15

schön, dass es wieder "läuft" ...

nun haben wir zwar keine Konsole, aber dank FHEM kommen wir ja wenigstens an ein "paar" Dinge dran

tippe mal folgende Befehle nacheinander in die Kommandozeile von FHEM und sicher das Ergebnis in einem Textfile oder so ...

Code Auswählen

"ps aux" sehen was überhaupt im System abgeht

Code Auswählen

"ifconfig" sehen wie das Netz konfiguriert ist

Code Auswählen

"netstat" sehen was für Netzverbindungen existieren

Code Auswählen

"cat /var/log/syslog" sehen was im System so alles Protokolliert wurde (hoffentlich hat fhem die Rechte dazu (könnte ein langer Text werden"

die Gänsefüßchen bitte nicht vergessen und ggfls die Relevanten Teile der Ausgaben hier posten

Florian.Stern · 27 April 2017, 08:54:51

Ich hatte schon neue Hoffnung geschöpft...
Aber leider liefert keiner der Befehle in FHEM ein Ausgabe

Wuppi68 · 27 April 2017, 09:00:39

Zitat von: Florian.Stern am 27 April 2017, 08:54:51
Ich hatte schon neue Hoffnung geschöpft...
Aber leider liefert keiner der Befehle in FHEM ein Ausgabe

ich habe gerade keinen Zugriff auf ein Live System und habe einfach in der Commandref geschaut wie Shell Befehle abzusetzen sind ....

kam eine Fehlermeldung?
oder steht die Ausgabe vielleicht im fhem Logfile?

Florian.Stern · 27 April 2017, 09:10:56

Du hast Recht die ausgaben sind im Log...

Code Auswählen

"ps aux"

Signal 11 (SEGV) caught by ps (procps-ng version 3.3.9).
ps:display.c:66: please report this bug

Code Auswählen

"ifconfig"

eth0 Link encap:Ethernet HWaddr b8:27:eb:7f:7c:eb
inet addr:192.168.178.38 Bcast:192.168.178.255 Mask:255.255.255.0
inet6 addr: fe80::12c5:9668:63d0:2a52/64 Scope:Link
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:97846 errors:0 dropped:4 overruns:0 frame:0
TX packets:84845 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:14409141 (13.7 MiB) TX bytes:12241423 (11.6 MiB)

lo Link encap:Local Loopback
inet addr:127.0.0.1 Mask:255.0.0.0
inet6 addr: ::1/128 Scope:Host
UP LOOPBACK RUNNING MTU:65536 Metric:1
RX packets:271501 errors:0 dropped:0 overruns:0 frame:0
TX packets:271501 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1
RX bytes:17902872 (17.0 MiB) TX bytes:17902872 (17.0 MiB)

wlan0 Link encap:Ethernet HWaddr b8:27:eb:2a:29:be
inet6 addr: fe80::c3fd:cc8c:24ab:239e/64 Scope:Link
UP BROADCAST MULTICAST MTU:1500 Metric:1
RX packets:2 errors:0 dropped:2 overruns:0 frame:0
TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:123 (123.0 B) TX bytes:0 (0.0 B)

Code Auswählen

"netstat"

Active Internet connections (w/o servers)
Proto Recv-Q Send-Q Local Address Foreign Address State
tcp 0 0 raspberrypi.fritz.:8083 FlorianStern-PC.f:50635 ESTABLISHED
tcp 0 0 raspberrypi.fritz.:8083 FlorianStern-PC.f:50636 ESTABLISHED
tcp 0 69 raspberrypi.fritz.:8083 FlorianStern-PC.f:50637 ESTABLISHED
tcp 0 0 raspberrypi.fritz.:8083 Florians-iPad.fri:64977 ESTABLISHED
tcp 0 0 raspberrypi.fritz:46696 fritz.box:1012 ESTABLISHED
Active UNIX domain sockets (w/o servers)
Proto RefCnt Flags Type State I-Node Path
unix 2 [ ] DGRAM 1361 /run/systemd/notify
unix 2 [ ] DGRAM 1379 /run/systemd/shutdownd
unix 5 [ ] DGRAM 1381 /run/systemd/journal/dev-log
unix 4 [ ] DGRAM 1391 /run/systemd/journal/socket
unix 2 [ ] DGRAM 7030 /var/run/thd.socket
unix 2 [ ] DGRAM 4847 /run/systemd/journal/syslog
unix 2 [ ] DGRAM 8187 /var/run/wpa_supplicant/wlan0
unix 2 [ ] DGRAM 4851
unix 2 [ ] STREAM CONNECTING 0 /var/run/dbus/system_bus_socket
unix 3 [ ] STREAM CONNECTED 8032
unix 3 [ ] STREAM CONNECTED 9227 /run/systemd/journal/stdout
unix 3 [ ] STREAM CONNECTED 8073
unix 3 [ ] STREAM CONNECTED 9219 /run/systemd/journal/stdout
unix 2 [ ] DGRAM 7040
unix 2 [ ] DGRAM 9355
unix 3 [ ] STREAM CONNECTING 0 /var/run/dbus/system_bus_socket
unix 2 [ ] STREAM CONNECTING 0 /var/run/dbus/system_bus_socket
unix 3 [ ] STREAM CONNECTED 8786
unix 2 [ ] DGRAM 6362
unix 3 [ ] DGRAM 6366
unix 3 [ ] STREAM CONNECTING 0 /var/run/dbus/system_bus_socket
unix 3 [ ] DGRAM 6365
unix 3 [ ] STREAM CONNECTED 8652
unix 2 [ ] DGRAM 8659

Code Auswählen

"cat /var/log/syslog"

cat: /var/log/syslog: Permission denied

Was mir noch auffällt ist das das Datum und die Uhrzeit im Log falsch sind?!?!

2017.04.26 15:35:19

Warum auch immer?

Wuppi68 · 27 April 2017, 09:27:03

klingt spannend - mein Bauch sagt Memory Fehler

Starte das System mal im Single User Modus (siehe link oben)

dann kannst Du an der Konsole Dir das mir more /var/log/syslog anschauen ... vielleicht sieht man etwas mehr

vielleicht dabei noch direkt mit visudo fhem ein paar Rechte mehr geben

Code Auswählen

fhem ALL=(ALL) NOPASSWD: ALL Achtung - mögliches Sicherheits Problem

und dann wieder den Zusatz aus der Config.txt rausnehmen und "normal" starten

Florian.Stern · 27 April 2017, 10:04:39

Hier ist die Syslog:

Code Auswählen

Apr 26 15:17:54 raspberrypi rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="564" x-info="http://www.rsyslog.com"] start
Apr 26 15:17:54 raspberrypi systemd[1]: Mounted Configuration File System.
Apr 26 15:17:54 raspberrypi systemd[1]: Started Apply Kernel Variables.
Apr 26 15:17:54 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
Apr 26 15:17:54 raspberrypi systemd[1]: Started udev Kernel Device Manager.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Copy rules generated while the root was ro...
Apr 26 15:17:54 raspberrypi systemd-fsck[101]: e2fsck 1.42.12 (29-Aug-2014)
Apr 26 15:17:54 raspberrypi systemd-fsck[101]: /dev/mmcblk0p2: clean, 56676/1903104 files, 573997/7727872 blocks
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Set preliminary keymap...
Apr 26 15:17:54 raspberrypi fake-hwclock[104]: Wed 26 Apr 13:17:01 UTC 2017
Apr 26 15:17:54 raspberrypi systemd[1]: Started Copy rules generated while the root was ro.
Apr 26 15:17:54 raspberrypi keyboard-setup[137]: Setting preliminary keymap...done.
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Set preliminary keymap.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen...
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Sound Card.
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Sound Card.
Apr 26 15:17:54 raspberrypi systemd[1]: Found device /dev/mmcblk0p1.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting File System Check on /dev/mmcblk0p1...
Apr 26 15:17:54 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Apr 26 15:17:54 raspberrypi systemd[1]: Started Various fixups to make systemd work better on Debian.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpuset
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpu
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Initializing cgroup subsys cpuacct
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Load/Save Random Seed...
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Linux version 4.4.32-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611) ) #924 SMP Tue Nov 15 18:11:28 GMT 2016
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Local File Systems (Pre).
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x3a800000
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] On node 0 totalpages: 241664
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 808c4f40, node_mem_map b9fa6000
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]   Normal zone: 2124 pages used for memmap
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
Apr 26 15:17:54 raspberrypi systemd[1]: Started Load/Save Random Seed.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]   Normal zone: 241664 pages, LIFO batch:31
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] enter (9520->f3003010)
Apr 26 15:17:54 raspberrypi systemd-fsck[227]: fsck.fat 3.0.27 (2014-11-12)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] [bcm2709_smp_init_cpus] ncores=4
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] PERCPU: Embedded 13 pages/cpu @b9f62000 s22592 r8192 d22464 u53248
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] pcpu-alloc: s22592 r8192 d22464 u53248 alloc=13*4096
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Apr 26 15:17:54 raspberrypi systemd-fsck[227]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 239540
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Kernel command line: 8250.nr_uarts=0 dma.dmachans=0x7f35 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=768 bcm2709.boardrev=0xa02082 bcm2709.serial=0x8f7f7ceb smsc95xx.macaddr=B8:27:EB:7F:7C:EB bcm2708_fb.fbswap=1 bcm2709.uart_clock=48000000 vc_mem.mem_base=0x3dc00000 vc_mem.mem_size=0x3f000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
Apr 26 15:17:54 raspberrypi systemd-fsck[227]: Automatically removing dirty bit.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Apr 26 15:17:54 raspberrypi systemd-fsck[227]: Performing changes.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Memory: 939064K/966656K available (6353K kernel code, 432K rwdata, 1716K rodata, 480K init, 764K bss, 19400K reserved, 8192K cma-reserved)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Apr 26 15:17:54 raspberrypi systemd-fsck[227]: /dev/mmcblk0p1: 134 files, 2675/8057 clusters
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]     vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]     lowmem  : 0x80000000 - 0xbb000000   ( 944 MB)
Apr 26 15:17:54 raspberrypi systemd[1]: Started File System Check on /dev/mmcblk0p1.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]       .text : 0x80008000 - 0x807e9660   (8070 kB)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]       .init : 0x807ea000 - 0x80862000   ( 480 kB)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]       .data : 0x80862000 - 0x808ce250   ( 433 kB)
Apr 26 15:17:54 raspberrypi kernel: [    0.000000]        .bss : 0x808d1000 - 0x809901ec   ( 765 kB)
Apr 26 15:17:54 raspberrypi systemd[1]: Mounting /boot...
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Hierarchical RCU implementation.
Apr 26 15:17:54 raspberrypi systemd[1]: Received SIGRTMIN+20 from PID 232 (plymouthd).
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] 	Build-time adjustment of leaf fanout to 32.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] NR_IRQS:16 nr_irqs:16 16
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] Architected cp15 timer(s) running at 19.20MHz (phys).
Apr 26 15:17:54 raspberrypi systemd[1]: Started Show Plymouth Boot Screen.
Apr 26 15:17:54 raspberrypi kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Apr 26 15:17:54 raspberrypi kernel: [    0.000009] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Apr 26 15:17:54 raspberrypi kernel: [    0.000026] Switching to timer-based delay loop, resolution 52ns
Apr 26 15:17:54 raspberrypi kernel: [    0.000285] Console: colour dummy device 80x30
Apr 26 15:17:54 raspberrypi systemd[1]: Starting system-systemd\x2drfkill.slice.
Apr 26 15:17:54 raspberrypi kernel: [    0.001335] console [tty1] enabled
Apr 26 15:17:54 raspberrypi kernel: [    0.001380] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Apr 26 15:17:54 raspberrypi systemd[1]: Created slice system-systemd\x2drfkill.slice.
Apr 26 15:17:54 raspberrypi kernel: [    0.001449] pid_max: default: 32768 minimum: 301
Apr 26 15:17:54 raspberrypi kernel: [    0.001781] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.001825] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0...
Apr 26 15:17:54 raspberrypi kernel: [    0.002772] Disabling cpuset control group subsystem
Apr 26 15:17:54 raspberrypi kernel: [    0.002829] Initializing cgroup subsys io
Apr 26 15:17:54 raspberrypi kernel: [    0.002880] Initializing cgroup subsys memory
Apr 26 15:17:54 raspberrypi kernel: [    0.002946] Initializing cgroup subsys devices
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch.
Apr 26 15:17:54 raspberrypi kernel: [    0.002989] Initializing cgroup subsys freezer
Apr 26 15:17:54 raspberrypi kernel: [    0.003031] Initializing cgroup subsys net_cls
Apr 26 15:17:54 raspberrypi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Apr 26 15:17:54 raspberrypi kernel: [    0.003103] CPU: Testing write buffer coherency: ok
Apr 26 15:17:54 raspberrypi kernel: [    0.003190] ftrace: allocating 21235 entries in 63 pages
Apr 26 15:17:54 raspberrypi kernel: [    0.051535] CPU0: update cpu_capacity 1024
Apr 26 15:17:54 raspberrypi kernel: [    0.051602] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Apr 26 15:17:54 raspberrypi kernel: [    0.051636] [bcm2709_smp_prepare_cpus] enter
Apr 26 15:17:54 raspberrypi systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Apr 26 15:17:54 raspberrypi kernel: [    0.051792] Setting up static identity map for 0x8240 - 0x8274
Apr 26 15:17:54 raspberrypi kernel: [    0.053462] [bcm2709_boot_secondary] cpu:1 started (0) 18
Apr 26 15:17:54 raspberrypi kernel: [    0.053678] [bcm2709_secondary_init] enter cpu:1
Apr 26 15:17:54 raspberrypi kernel: [    0.053721] CPU1: update cpu_capacity 1024
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Paths.
Apr 26 15:17:54 raspberrypi kernel: [    0.053727] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Apr 26 15:17:54 raspberrypi kernel: [    0.054109] [bcm2709_boot_secondary] cpu:2 started (0) 18
Apr 26 15:17:54 raspberrypi kernel: [    0.054274] [bcm2709_secondary_init] enter cpu:2
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Paths.
Apr 26 15:17:54 raspberrypi kernel: [    0.054294] CPU2: update cpu_capacity 1024
Apr 26 15:17:54 raspberrypi kernel: [    0.054301] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Apr 26 15:17:54 raspberrypi kernel: [    0.054668] [bcm2709_boot_secondary] cpu:3 started (0) 17
Apr 26 15:17:54 raspberrypi systemd[1]: Mounted /boot.
Apr 26 15:17:54 raspberrypi kernel: [    0.054798] [bcm2709_secondary_init] enter cpu:3
Apr 26 15:17:54 raspberrypi kernel: [    0.054818] CPU3: update cpu_capacity 1024
Apr 26 15:17:54 raspberrypi kernel: [    0.054824] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Apr 26 15:17:54 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0.
Apr 26 15:17:54 raspberrypi kernel: [    0.054884] Brought up 4 CPUs
Apr 26 15:17:54 raspberrypi kernel: [    0.054983] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Apr 26 15:17:54 raspberrypi kernel: [    0.055013] CPU: All CPU(s) started in HYP mode.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting system-ifup.slice.
Apr 26 15:17:54 raspberrypi kernel: [    0.055039] CPU: Virtualization extensions available.
Apr 26 15:17:54 raspberrypi kernel: [    0.055671] devtmpfs: initialized
Apr 26 15:17:54 raspberrypi systemd[1]: Created slice system-ifup.slice.
Apr 26 15:17:54 raspberrypi kernel: [    0.066602] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Apr 26 15:17:54 raspberrypi kernel: [    0.066974] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Apr 26 15:17:54 raspberrypi kernel: [    0.067725] pinctrl core: initialized pinctrl subsystem
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Local File Systems.
Apr 26 15:17:54 raspberrypi kernel: [    0.068257] NET: Registered protocol family 16
Apr 26 15:17:54 raspberrypi kernel: [    0.073438] DMA: preallocated 4096 KiB pool for atomic coherent allocations
Apr 26 15:17:54 raspberrypi kernel: [    0.080341] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Apr 26 15:17:54 raspberrypi kernel: [    0.080390] hw-breakpoint: maximum watchpoint size is 8 bytes.
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Local File Systems.
Apr 26 15:17:54 raspberrypi kernel: [    0.080562] Serial: AMBA PL011 UART driver
Apr 26 15:17:54 raspberrypi kernel: [    0.080712] uart-pl011 3f201000.uart: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
Apr 26 15:17:54 raspberrypi kernel: [    0.080914] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Apr 26 15:17:54 raspberrypi kernel: [    0.143689] bcm2835-dma 3f007000.dma: DMA legacy API manager at f3007000, dmachans=0x1
Apr 26 15:17:54 raspberrypi kernel: [    0.144294] SCSI subsystem initialized
Apr 26 15:17:54 raspberrypi kernel: [    0.144504] usbcore: registered new interface driver usbfs
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
Apr 26 15:17:54 raspberrypi kernel: [    0.144615] usbcore: registered new interface driver hub
Apr 26 15:17:54 raspberrypi kernel: [    0.144734] usbcore: registered new device driver usb
Apr 26 15:17:54 raspberrypi kernel: [    0.151105] raspberrypi-firmware soc:firmware: Attached to firmware from 2016-11-16 12:24
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Remote File Systems.
Apr 26 15:17:54 raspberrypi kernel: [    0.178262] clocksource: Switched to clocksource arch_sys_counter
Apr 26 15:17:54 raspberrypi kernel: [    0.223337] FS-Cache: Loaded
Apr 26 15:17:54 raspberrypi kernel: [    0.223631] CacheFiles: Loaded
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Remote File Systems.
Apr 26 15:17:54 raspberrypi kernel: [    0.236005] NET: Registered protocol family 2
Apr 26 15:17:54 raspberrypi kernel: [    0.236883] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.237021] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.237232] TCP: Hash tables configured (established 8192 bind 8192)
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
Apr 26 15:17:54 raspberrypi kernel: [    0.237346] UDP hash table entries: 512 (order: 2, 16384 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.237415] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Prepare console...
Apr 26 15:17:54 raspberrypi kernel: [    0.237666] NET: Registered protocol family 1
Apr 26 15:17:54 raspberrypi kernel: [    0.238006] RPC: Registered named UNIX socket transport module.
Apr 26 15:17:54 raspberrypi kernel: [    0.238039] RPC: Registered udp transport module.
Apr 26 15:17:54 raspberrypi kernel: [    0.238066] RPC: Registered tcp transport module.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Apr 26 15:17:54 raspberrypi kernel: [    0.238094] RPC: Registered tcp NFSv4.1 backchannel transport module.
Apr 26 15:17:54 raspberrypi kernel: [    0.239192] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Apr 26 15:17:54 raspberrypi kernel: [    0.240539] futex hash table entries: 1024 (order: 4, 65536 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.253756] VFS: Disk quotas dquot_6.6.0
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Raise network interfaces....
Apr 26 15:17:54 raspberrypi kernel: [    0.254085] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    0.256323] FS-Cache: Netfs 'nfs' registered for caching
Apr 26 15:17:54 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
Apr 26 15:17:54 raspberrypi systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Apr 26 15:17:54 raspberrypi systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
Apr 26 15:17:54 raspberrypi kbd[257]: Setting console screen modes.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Apr 26 15:17:54 raspberrypi kbd[257]: setterm: $TERM is not defined.
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Prepare console.
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Set console font and keymap...
Apr 26 15:17:54 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Apr 26 15:17:54 raspberrypi networking[264]: Configuring network interfaces...Segmentation fault
Apr 26 15:17:54 raspberrypi console-setup[307]: Setting up console font and keymap...done.
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Set console font and keymap.
Apr 26 15:17:54 raspberrypi networking[264]: Segmentation fault
Apr 26 15:17:54 raspberrypi networking[264]: done.
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Raise network interfaces..
Apr 26 15:17:54 raspberrypi systemd[1]: Starting ifup for wlan0...
Apr 26 15:17:54 raspberrypi systemd[1]: Started ifup for wlan0.
Apr 26 15:17:54 raspberrypi wpa_supplicant[409]: Successfully initialized wpa_supplicant
Apr 26 15:17:54 raspberrypi wpa_supplicant[411]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB
Apr 26 15:17:54 raspberrypi ifup[400]: Segmentation fault
Apr 26 15:17:54 raspberrypi raspi-config[258]: Checking if shift key is held down: No. Switching to ondemand scaling governor.
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Apr 26 15:17:54 raspberrypi kernel: [    0.257249] NFS: Registering the id_resolver key type
Apr 26 15:17:54 raspberrypi kernel: [    0.257311] Key type id_resolver registered
Apr 26 15:17:54 raspberrypi systemd[1]: Starting System Initialization.
Apr 26 15:17:54 raspberrypi kernel: [    0.257340] Key type id_legacy registered
Apr 26 15:17:54 raspberrypi kernel: [    0.259683] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
Apr 26 15:17:54 raspberrypi kernel: [    0.259849] io scheduler noop registered
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target System Initialization.
Apr 26 15:17:54 raspberrypi kernel: [    0.259891] io scheduler deadline registered (default)
Apr 26 15:17:54 raspberrypi kernel: [    0.259963] io scheduler cfq registered
Apr 26 15:17:54 raspberrypi kernel: [    0.262506] BCM2708FB: allocated DMA memory fac10000
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket.
Apr 26 15:17:54 raspberrypi kernel: [    0.262553] BCM2708FB: allocated DMA channel 0 @ f3007000
Apr 26 15:17:54 raspberrypi kernel: [    0.285059] Console: switching to colour frame buffer device 128x48
Apr 26 15:17:54 raspberrypi kernel: [    1.215157] bcm2835-rng 3f104000.rng: hwrng registered
Apr 26 15:17:54 raspberrypi kernel: [    1.215458] vc-cma: Videocore CMA driver
Apr 26 15:17:54 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Apr 26 15:17:54 raspberrypi kernel: [    1.215578] vc-cma: vc_cma_base      = 0x00000000
Apr 26 15:17:54 raspberrypi kernel: [    1.215712] vc-cma: vc_cma_size      = 0x00000000 (0 MiB)
Apr 26 15:17:54 raspberrypi kernel: [    1.215862] vc-cma: vc_cma_initial   = 0x00000000 (0 MiB)
Apr 26 15:17:54 raspberrypi systemd[1]: Starting D-Bus System Message Bus Socket.
Apr 26 15:17:54 raspberrypi kernel: [    1.216231] vc-mem: phys_addr:0x00000000 mem_base=0x3dc00000 mem_size:0x3f000000(1008 MiB)
Apr 26 15:17:54 raspberrypi kernel: [    1.231234] brd: module loaded
Apr 26 15:17:54 raspberrypi kernel: [    1.239859] loop: module loaded
Apr 26 15:17:54 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
Apr 26 15:17:54 raspberrypi kernel: [    1.240890] vchiq: vchiq_init_state: slot_zero = 0xbac80000, is_master = 0
Apr 26 15:17:54 raspberrypi kernel: [    1.242539] Loading iSCSI transport class v2.0-870.
Apr 26 15:17:54 raspberrypi kernel: [    1.243329] usbcore: registered new interface driver smsc95xx
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Sockets.
Apr 26 15:17:54 raspberrypi kernel: [    1.243534] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Apr 26 15:17:54 raspberrypi kernel: [    1.443939] Core Release: 2.80a
Apr 26 15:17:54 raspberrypi kernel: [    1.448713] Setting default values for core params
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Sockets.
Apr 26 15:17:54 raspberrypi kernel: [    1.453535] Finished setting default values for core params
Apr 26 15:17:54 raspberrypi kernel: [    1.658764] Using Buffer DMA mode
Apr 26 15:17:54 raspberrypi kernel: [    1.663530] Periodic Transfer Interrupt Enhancement - disabled
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Daily Cleanup of Temporary Directories.
Apr 26 15:17:54 raspberrypi kernel: [    1.668349] Multiprocessor Interrupt Enhancement - disabled
Apr 26 15:17:54 raspberrypi kernel: [    1.673146] OTG VER PARAM: 0, OTG VER FLAG: 0
Apr 26 15:17:54 raspberrypi kernel: [    1.677899] Dedicated Tx FIFOs mode
Apr 26 15:17:54 raspberrypi kernel: [    1.682886] WARN::dwc_otg_hcd_init:1047: FIQ DMA bounce buffers: virt = 0xbac04000 dma = 0xfac04000 len=9024
Apr 26 15:17:54 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
Apr 26 15:17:54 raspberrypi kernel: [    1.687841] FIQ FSM acceleration enabled for :
Apr 26 15:17:54 raspberrypi kernel: [    1.687841] Non-periodic Split Transactions
Apr 26 15:17:54 raspberrypi kernel: [    1.687841] Periodic Split Transactions
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Timers.
Apr 26 15:17:54 raspberrypi kernel: [    1.687841] High-Speed Isochronous Endpoints
Apr 26 15:17:54 raspberrypi kernel: [    1.687841] Interrupt/Control Split Transaction hack enabled
Apr 26 15:17:54 raspberrypi kernel: [    1.712388] dwc_otg: Microframe scheduler enabled
Apr 26 15:17:54 raspberrypi kernel: [    1.712442] WARN::hcd_init_fiq:413: FIQ on core 1 at 0x80448370
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Timers.
Apr 26 15:17:54 raspberrypi kernel: [    1.717351] WARN::hcd_init_fiq:414: FIQ ASM at 0x804486e0 length 36
Apr 26 15:17:54 raspberrypi kernel: [    1.722187] WARN::hcd_init_fiq:439: MPHI regs_base at 0xbb87e000
Apr 26 15:17:54 raspberrypi systemd[1]: Started Manage Sound Card State (restore and store).
Apr 26 15:17:54 raspberrypi kernel: [    1.727028] dwc_otg 3f980000.usb: DWC OTG Controller
Apr 26 15:17:54 raspberrypi kernel: [    1.731839] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Apr 26 15:17:54 raspberrypi kernel: [    1.736645] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
Apr 26 15:17:54 raspberrypi kernel: [    1.741441] Init: Port Power? op_state=1
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Restore Sound Card State...
Apr 26 15:17:54 raspberrypi kernel: [    1.746150] Init: Power Port (0)
Apr 26 15:17:54 raspberrypi kernel: [    1.751053] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Apr 26 15:17:54 raspberrypi kernel: [    1.755845] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Basic System.
Apr 26 15:17:54 raspberrypi kernel: [    1.760604] usb usb1: Product: DWC OTG Controller
Apr 26 15:17:54 raspberrypi kernel: [    1.765323] usb usb1: Manufacturer: Linux 4.4.32-v7+ dwc_otg_hcd
Apr 26 15:17:54 raspberrypi systemd[1]: Reached target Basic System.
Apr 26 15:17:54 raspberrypi kernel: [    1.770122] usb usb1: SerialNumber: 3f980000.usb
Apr 26 15:17:54 raspberrypi kernel: [    1.775694] hub 1-0:1.0: USB hub found
Apr 26 15:17:54 raspberrypi kernel: [    1.780485] hub 1-0:1.0: 1 port detected
Apr 26 15:17:54 raspberrypi kernel: [    1.785816] dwc_otg: FIQ enabled
Apr 26 15:17:54 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
Apr 26 15:17:54 raspberrypi kernel: [    1.785826] dwc_otg: NAK holdoff enabled
Apr 26 15:17:54 raspberrypi kernel: [    1.785834] dwc_otg: FIQ split-transaction FSM enabled
Apr 26 15:17:54 raspberrypi kernel: [    1.785873] Module dwc_common_port init
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Regular background program processing daemon...
Apr 26 15:17:54 raspberrypi kernel: [    1.786144] usbcore: registered new interface driver usb-storage
Apr 26 15:17:54 raspberrypi kernel: [    1.791226] mousedev: PS/2 mouse device common for all mice
Apr 26 15:17:54 raspberrypi kernel: [    1.796723] bcm2835-cpufreq: min=600000 max=1200000
Apr 26 15:17:54 raspberrypi systemd[1]: Started Regular background program processing daemon.
Apr 26 15:17:54 raspberrypi kernel: [    1.801792] sdhci: Secure Digital Host Controller Interface driver
Apr 26 15:17:54 raspberrypi kernel: [    1.806640] sdhci: Copyright(c) Pierre Ossman
Apr 26 15:17:54 raspberrypi kernel: [    1.811844] sdhost: log_buf @ bac07000 (fac07000)
Apr 26 15:17:54 raspberrypi kernel: [    1.868291] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Apr 26 15:17:54 raspberrypi kernel: [    1.875470] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
Apr 26 15:17:54 raspberrypi kernel: [    1.880373] mmc-bcm2835 3f300000.mmc: DMA channel allocated
Apr 26 15:17:54 raspberrypi kernel: [    1.925369] mmc0: host does not support reading read-only switch, assuming write-enable
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Login Service...
Apr 26 15:17:54 raspberrypi kernel: [    1.928379] sdhci-pltfm: SDHCI platform and OF driver helper
Apr 26 15:17:54 raspberrypi kernel: [    1.928767] ledtrig-cpu: registered to indicate activity on CPUs
Apr 26 15:17:54 raspberrypi kernel: [    1.928881] hidraw: raw HID events driver (C) Jiri Kosina
Apr 26 15:17:54 raspberrypi systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
Apr 26 15:17:54 raspberrypi kernel: [    1.929065] usbcore: registered new interface driver usbhid
Apr 26 15:17:54 raspberrypi kernel: [    1.929068] usbhid: USB HID core driver
Apr 26 15:17:54 raspberrypi kernel: [    1.929594] Initializing XFRM netlink socket
Apr 26 15:17:54 raspberrypi kernel: [    1.929625] NET: Registered protocol family 17
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Autogenerate and use a swap file...
Apr 26 15:17:54 raspberrypi kernel: [    1.929758] Key type dns_resolver registered
Apr 26 15:17:54 raspberrypi kernel: [    1.930259] Registering SWP/SWPB emulation handler
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: FHEM server...
Apr 26 15:17:54 raspberrypi kernel: [    1.931091] registered taskstats version 1
Apr 26 15:17:54 raspberrypi kernel: [    1.931274] vc-sm: Videocore shared memory driver
Apr 26 15:17:54 raspberrypi kernel: [    1.931282] [vc_sm_connected_init]: start
Apr 26 15:17:54 raspberrypi kernel: [    1.940541] [vc_sm_connected_init]: end - returning 0
Apr 26 15:17:54 raspberrypi cron[435]: (CRON) INFO (pidfile fd = 3)
Apr 26 15:17:54 raspberrypi kernel: [    1.941919] 3f201000.uart: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
Apr 26 15:17:54 raspberrypi kernel: [    1.942293] of_cfs_init
Apr 26 15:17:54 raspberrypi kernel: [    1.942372] of_cfs_init: OK
Apr 26 15:17:54 raspberrypi dhcpcd[434]: version 6.7.1 starting
Apr 26 15:17:54 raspberrypi kernel: [    1.978371] Indeed it is in host mode hprt0 = 00021501
Apr 26 15:17:54 raspberrypi kernel: [    2.013610] mmc0: new high speed SDHC card at address 59b4
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Starts and stops the upsd daemon....
Apr 26 15:17:54 raspberrypi kernel: [    2.014185] mmcblk0: mmc0:59b4 SD    29.5 GiB
Apr 26 15:17:54 raspberrypi kernel: [    2.019336]  mmcblk0: p1 p2
Apr 26 15:17:54 raspberrypi kernel: [    2.037329] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    2.043652] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: triggerhappy hotkey daemon...
Apr 26 15:17:54 raspberrypi kernel: [    2.049830] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    2.052351] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Apr 26 15:17:54 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Apr 26 15:17:54 raspberrypi kernel: [    2.052398] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Apr 26 15:17:54 raspberrypi kernel: [    2.066497] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Apr 26 15:17:54 raspberrypi kernel: [    2.071893] devtmpfs: mounted
Apr 26 15:17:54 raspberrypi systemd[1]: Starting D-Bus System Message Bus...
Apr 26 15:17:54 raspberrypi kernel: [    2.077233] Freeing unused kernel memory: 480K (807ea000 - 80862000)
Apr 26 15:17:54 raspberrypi kernel: [    2.158310] usb 1-1: new high-speed USB device number 2 using dwc_otg
Apr 26 15:17:54 raspberrypi kernel: [    2.163235] Indeed it is in host mode hprt0 = 00001101
Apr 26 15:17:54 raspberrypi kernel: [    2.164475] mmc1: new high speed SDIO card at address 0001
Apr 26 15:17:54 raspberrypi systemd[1]: Started D-Bus System Message Bus.
Apr 26 15:17:54 raspberrypi kernel: [    2.347112] random: systemd: uninitialized urandom read (16 bytes read, 37 bits of entropy available)
Apr 26 15:17:54 raspberrypi kernel: [    2.368627] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Apr 26 15:17:54 raspberrypi dhcpcd[434]: dev: loaded udev
Apr 26 15:17:54 raspberrypi kernel: [    2.368637] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 26 15:17:54 raspberrypi kernel: [    2.372740] hub 1-1:1.0: USB hub found
Apr 26 15:17:54 raspberrypi kernel: [    2.372835] hub 1-1:1.0: 5 ports detected
Apr 26 15:17:54 raspberrypi kernel: [    2.504970] NET: Registered protocol family 10
Apr 26 15:17:54 raspberrypi dhcpcd[434]: eth0: adding address fe80::12c5:9668:63d0:2a52
Apr 26 15:17:54 raspberrypi kernel: [    2.648321] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Apr 26 15:17:54 raspberrypi kernel: [    2.651008] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read, 67 bits of entropy available)
Apr 26 15:17:54 raspberrypi kernel: [    2.758602] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Apr 26 15:17:54 raspberrypi dhcpcd[434]: wlan0: adding address fe80::c3fd:cc8c:24ab:239e
Apr 26 15:17:54 raspberrypi kernel: [    2.763904] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Apr 26 15:17:54 raspberrypi kernel: [    2.769965] random: systemd: uninitialized urandom read (16 bytes read, 69 bits of entropy available)
Apr 26 15:17:54 raspberrypi dphys-swapfile[439]: Starting dphys-swapfile swapfile setup ...
Apr 26 15:17:54 raspberrypi kernel: [    2.772086] smsc95xx v1.0.4
Apr 26 15:17:54 raspberrypi kernel: [    2.782253] random: systemd: uninitialized urandom read (16 bytes read, 70 bits of entropy available)
Apr 26 15:17:54 raspberrypi kernel: [    2.789423] random: systemd: uninitialized urandom read (16 bytes read, 70 bits of entropy available)
Apr 26 15:17:54 raspberrypi cron[435]: (CRON) INFO (Running @reboot jobs)
Apr 26 15:17:54 raspberrypi kernel: [    2.810496] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
Apr 26 15:17:54 raspberrypi kernel: [    2.816839] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
Apr 26 15:17:54 raspberrypi kernel: [    2.822560] random: systemd: uninitialized urandom read (16 bytes read, 71 bits of entropy available)
Apr 26 15:17:54 raspberrypi kernel: [    2.831977] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:7f:7c:eb
Apr 26 15:17:54 raspberrypi upsd[442]: upsd: Info: The current battery charge level is assumed to be 100 %.
Apr 26 15:17:54 raspberrypi kernel: [    2.863835] random: systemd: uninitialized urandom read (16 bytes read, 73 bits of entropy available)
Apr 26 15:17:54 raspberrypi kernel: [    2.876442] random: systemd: uninitialized urandom read (16 bytes read, 74 bits of entropy available)
Apr 26 15:17:54 raspberrypi upsd[442]: upsd: Info: A default configuration is used for now. Please check your configuration file.
Apr 26 15:17:54 raspberrypi upsd[469]: upsd daemon started.
Apr 26 15:17:54 raspberrypi upsd[469]: upsd is now ready and running. The interface eth0 is used to determine power outages.
Apr 26 15:17:54 raspberrypi upsd[469]: The interface eth0 is down right now. This program will not work until the interface is brought up.
Apr 26 15:17:54 raspberrypi upsd[442]: upsd: Info: The current battery charge level is assumed to be 100 %.
Apr 26 15:17:54 raspberrypi upsd[442]: upsd: Info: A default configuration is used for now. Please check your configuration file.
Apr 26 15:17:54 raspberrypi avahi-daemon[446]: Found user 'avahi' (UID 105) and group 'avahi' (GID 110).
Apr 26 15:17:54 raspberrypi avahi-daemon[446]: Successfully dropped root privileges.
Apr 26 15:17:54 raspberrypi avahi-daemon[446]: avahi-daemon 0.6.31 starting up.
Apr 26 15:17:54 raspberrypi dphys-swapfile[439]: want /var/swap=100MByte, checking existing: keeping it
Apr 26 15:17:54 raspberrypi dphys-swapfile[439]: done.
Apr 26 15:17:54 raspberrypi fhem[440]: Starting fhem...
Apr 26 15:17:54 raspberrypi fhem[440]: Daemon with PID 543 started!
Apr 26 15:17:54 raspberrypi hciattach[437]: bcm43xx_init
Apr 26 15:17:54 raspberrypi hciattach[437]: Flash firmware /lib/firmware/BCM43430A1.hcd
Apr 26 15:17:54 raspberrypi hciattach[437]: Set Controller UART speed to 921600 bit/s
Apr 26 15:17:54 raspberrypi hciattach[437]: Device setup complete
Apr 26 15:17:54 raspberrypi systemd[1]: Failed to register match for Disconnected message: Connection timed out
Apr 26 15:17:54 raspberrypi kernel: [    2.918315] usb 1-1.3: new full-speed USB device number 4 using dwc_otg
Apr 26 15:17:54 raspberrypi kernel: [    3.060835] usb 1-1.3: New USB device found, idVendor=05ac, idProduct=1003
Apr 26 15:17:54 raspberrypi kernel: [    3.060844] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Apr 26 15:17:54 raspberrypi kernel: [    3.060851] usb 1-1.3: Product: Hub in Apple Extended USB Keyboard
Apr 26 15:17:54 raspberrypi systemd[1]: Failed to register match for Disconnected message: Connection timed out
Apr 26 15:17:54 raspberrypi kernel: [    3.060858] usb 1-1.3: Manufacturer: Mitsumi Electric
Apr 26 15:17:54 raspberrypi kernel: [    3.061858] hub 1-1.3:1.0: USB hub found
Apr 26 15:17:54 raspberrypi systemd[1]: Starting System Logging Service...
Apr 26 15:17:54 raspberrypi kernel: [    3.063577] hub 1-1.3:1.0: 3 ports detected
Apr 26 15:17:54 raspberrypi kernel: [    3.398357] usb 1-1.3.1: new full-speed USB device number 5 using dwc_otg
Apr 26 15:17:54 raspberrypi systemd[1]: Started Restore Sound Card State.
Apr 26 15:17:54 raspberrypi kernel: [    3.513159] usb 1-1.3.1: New USB device found, idVendor=045e, idProduct=0745
Apr 26 15:17:54 raspberrypi kernel: [    3.513170] usb 1-1.3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Apr 26 15:17:54 raspberrypi kernel: [    3.513177] usb 1-1.3.1: Product: Microsoft® Nano Transceiver v2.0
Apr 26 15:17:54 raspberrypi kernel: [    3.513184] usb 1-1.3.1: Manufacturer: Microsoft
Apr 26 15:17:54 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART.
Apr 26 15:17:54 raspberrypi kernel: [    3.532861] input: Microsoft Microsoft® Nano Transceiver v2.0 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.1/1-1.3.1:1.0/0003:045E:0745.0001/input/input0
Apr 26 15:17:54 raspberrypi systemd[1]: systemd-logind.service: main process exited, code=exited, status=1/FAILURE
Apr 26 15:17:54 raspberrypi systemd[1]: Failed to start Login Service.
Apr 26 15:17:54 raspberrypi kernel: [    3.589170] hid-generic 0003:045E:0745.0001: input,hidraw0: USB HID v1.11 Keyboard [Microsoft Microsoft® Nano Transceiver v2.0] on usb-3f980000.usb-1.3.1/input0
Apr 26 15:17:54 raspberrypi kernel: [    3.632197] input: Microsoft Microsoft® Nano Transceiver v2.0 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.1/1-1.3.1:1.1/0003:045E:0745.0002/input/input1
Apr 26 15:17:54 raspberrypi systemd[1]: Unit systemd-logind.service entered failed state.
Apr 26 15:17:54 raspberrypi kernel: [    3.688867] hid-generic 0003:045E:0745.0002: input,hidraw1: USB HID v1.11 Mouse [Microsoft Microsoft® Nano Transceiver v2.0] on usb-3f980000.usb-1.3.1/input1
Apr 26 15:17:54 raspberrypi kernel: [    3.726071] input: Microsoft Microsoft® Nano Transceiver v2.0 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.1/1-1.3.1:1.2/0003:045E:0745.0003/input/input2
Apr 26 15:17:54 raspberrypi kernel: [    3.778868] hid-generic 0003:045E:0745.0003: input,hiddev0,hidraw2: USB HID v1.11 Device [Microsoft Microsoft® Nano Transceiver v2.0] on usb-3f980000.usb-1.3.1/input2
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Autogenerate and use a swap file.
Apr 26 15:17:54 raspberrypi kernel: [    3.888330] usb 1-1.3.3: new full-speed USB device number 6 using dwc_otg
Apr 26 15:17:54 raspberrypi kernel: [    4.031987] usb 1-1.3.3: New USB device found, idVendor=05ac, idProduct=020c
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: FHEM server.
Apr 26 15:17:54 raspberrypi kernel: [    4.031998] usb 1-1.3.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Apr 26 15:17:54 raspberrypi kernel: [    4.032006] usb 1-1.3.3: Product: Apple Extended USB Keyboard
Apr 26 15:17:54 raspberrypi kernel: [    4.032013] usb 1-1.3.3: Manufacturer: Mitsumi Electric
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Starts and stops the upsd daemon..
Apr 26 15:17:54 raspberrypi kernel: [    4.053181] input: Mitsumi Electric Apple Extended USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.0/0003:05AC:020C.0004/input/input3
Apr 26 15:17:54 raspberrypi kernel: [    4.109493] hid-generic 0003:05AC:020C.0004: input,hidraw3: USB HID v1.10 Keyboard [Mitsumi Electric Apple Extended USB Keyboard] on usb-3f980000.usb-1.3.3/input0
Apr 26 15:17:54 raspberrypi kernel: [    4.131252] input: Mitsumi Electric Apple Extended USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3.3/1-1.3.3:1.1/0003:05AC:020C.0005/input/input4
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: triggerhappy hotkey daemon.
Apr 26 15:17:54 raspberrypi kernel: [    4.188662] hid-generic 0003:05AC:020C.0005: input,hidraw4: USB HID v1.10 Device [Mitsumi Electric Apple Extended USB Keyboard] on usb-3f980000.usb-1.3.3/input1
Apr 26 15:17:54 raspberrypi kernel: [    4.656872] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
Apr 26 15:17:54 raspberrypi kernel: [    4.668861] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Apr 26 15:17:54 raspberrypi systemd[1]: dbus.service: main process exited, code=killed, status=11/SEGV
Apr 26 15:17:54 raspberrypi kernel: [    4.876285] random: nonblocking pool is initialized
Apr 26 15:17:54 raspberrypi systemd[1]: Unit dbus.service entered failed state.
Apr 26 15:17:54 raspberrypi kernel: [    4.969517] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Apr 26 15:17:54 raspberrypi kernel: [    4.979730] usbcore: registered new interface driver brcmfmac
Apr 26 15:17:54 raspberrypi systemd[1]: systemd-logind.service has no holdoff time, scheduling restart.
Apr 26 15:17:54 raspberrypi kernel: [    5.129296] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: May 27 2016 00:13:38 version 7.45.41.26 (r640327) FWID 01-df77e4a7
Apr 26 15:17:54 raspberrypi kernel: [    5.152314] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code
Apr 26 15:17:54 raspberrypi kernel: [    5.230373] brcmfmac: brcmf_cfg80211_reg_notifier: not a ISO3166 code
Apr 26 15:17:54 raspberrypi kernel: [    5.230385] cfg80211: World regulatory domain updated:
Apr 26 15:17:54 raspberrypi kernel: [    5.230395] cfg80211:  DFS Master region: unset
Apr 26 15:17:54 raspberrypi kernel: [    5.230395] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
Apr 26 15:17:54 raspberrypi kernel: [    5.230408] cfg80211:   (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    5.230416] cfg80211:   (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    5.230423] cfg80211:   (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    5.230432] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    5.230441] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
Apr 26 15:17:54 raspberrypi kernel: [    5.230448] cfg80211:   (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s)
Apr 26 15:17:54 raspberrypi kernel: [    5.230455] cfg80211:   (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    5.230462] cfg80211:   (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    6.214229] brcmfmac: brcmf_add_if: ERROR: netdev:wlan0 already exists
Apr 26 15:17:54 raspberrypi kernel: [    6.214250] brcmfmac: brcmf_add_if: ignore IF event
Apr 26 15:17:54 raspberrypi kernel: [    6.219784] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Apr 26 15:17:54 raspberrypi kernel: [    6.219933] brcmfmac: power management disabled
Apr 26 15:17:54 raspberrypi kernel: [    6.551651] cfg80211: Regulatory domain changed to country: GB
Apr 26 15:17:54 raspberrypi kernel: [    6.551676] cfg80211:  DFS Master region: ETSI
Apr 26 15:17:54 raspberrypi kernel: [    6.551685] cfg80211:   (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time)
Apr 26 15:17:54 raspberrypi kernel: [    6.551700] cfg80211:   (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    6.551717] cfg80211:   (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    6.551732] cfg80211:   (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s)
Apr 26 15:17:54 raspberrypi kernel: [    6.551745] cfg80211:   (5490000 KHz - 5710000 KHz @ 160000 KHz), (N/A, 2700 mBm), (0 s)
Apr 26 15:17:54 raspberrypi kernel: [    6.551761] cfg80211:   (57000000 KHz - 66000000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A)
Apr 26 15:17:54 raspberrypi kernel: [    6.960741] uart-pl011 3f201000.uart: no DMA platform data
Apr 26 15:17:54 raspberrypi kernel: [    7.407822] Adding 102396k swap on /var/swap.  Priority:-1 extents:4 across:184316k SSFS
Apr 26 15:17:54 raspberrypi kernel: [   11.384282] Bluetooth: Core ver 2.21
Apr 26 15:17:54 raspberrypi kernel: [   11.384349] NET: Registered protocol family 31
Apr 26 15:17:54 raspberrypi kernel: [   11.384355] Bluetooth: HCI device and connection manager initialized
Apr 26 15:17:54 raspberrypi kernel: [   11.384370] Bluetooth: HCI socket layer initialized
Apr 26 15:17:54 raspberrypi kernel: [   11.384379] Bluetooth: L2CAP socket layer initialized
Apr 26 15:17:54 raspberrypi kernel: [   11.384400] Bluetooth: SCO socket layer initialized
Apr 26 15:17:54 raspberrypi kernel: [   11.389859] Bluetooth: HCI UART driver ver 2.3
Apr 26 15:17:54 raspberrypi kernel: [   11.389873] Bluetooth: HCI UART protocol H4 registered
Apr 26 15:17:54 raspberrypi kernel: [   11.389878] Bluetooth: HCI UART protocol Three-wire (H5) registered
Apr 26 15:17:54 raspberrypi kernel: [   11.389974] Bluetooth: HCI UART protocol BCM registered
Apr 26 15:17:54 raspberrypi systemd[1]: Started System Logging Service.
Apr 26 15:17:54 raspberrypi dhcpcd[434]: eth0: waiting for carrier
Apr 26 15:17:54 raspberrypi dhcpcd[434]: wlan0: waiting for carrier
Apr 26 15:17:54 raspberrypi kernel: [   57.309478] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Apr 26 15:17:54 raspberrypi kernel: [   57.309664] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Apr 26 15:17:55 raspberrypi upsd[469]: There is a power outage right now.
Apr 26 15:17:55 raspberrypi upsd[469]: The estimated current battery charge level is 100 %.
Apr 26 15:17:55 raspberrypi upsd[469]: There are 02:48:00 hours remaining before the battery will be empty.
Apr 26 15:17:55 raspberrypi upsd[469]: If the battery should actually run empty, appropiate action will be taken.
Apr 26 15:17:56 raspberrypi dhcpcd[434]: eth0: carrier acquired
Apr 26 15:17:56 raspberrypi dhcpcd[434]: DUID 00:01:00:01:1f:77:63:de:b8:27:eb:7f:7c:eb
Apr 26 15:17:56 raspberrypi kernel: [   58.848378] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Apr 26 15:17:56 raspberrypi kernel: [   58.848914] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
Apr 26 15:17:56 raspberrypi dhcpcd[434]: eth0: IAID eb:7f:7c:eb
Apr 26 15:17:56 raspberrypi upsd[469]: The power outage is over now. It could be successfully bridged over by using the battery.
Apr 26 15:17:56 raspberrypi upsd[469]: The outage lasted for 00:00:01 hours.
Apr 26 15:17:56 raspberrypi upsd[469]: The estimated battery charge level decreased from 100 to 99 % during the outage.
Apr 26 15:17:56 raspberrypi upsd[469]: The system could have run for 02:47:59 more hours before the battery would have been empty.
Apr 26 15:17:56 raspberrypi dhcpcd[434]: eth0: soliciting an IPv6 router
Apr 26 15:17:57 raspberrypi dhcpcd[434]: eth0: rebinding lease of 192.168.178.38
Apr 26 15:18:02 raspberrypi dhcpcd[434]: eth0: leased 192.168.178.38 for 864000 seconds
Apr 26 15:18:02 raspberrypi dhcpcd[434]: eth0: adding route to 192.168.178.0/24
Apr 26 15:18:02 raspberrypi dhcpcd[434]: eth0: adding default route via 192.168.178.1
Apr 26 15:18:02 raspberrypi dhcpcd[434]: forked to background, child pid 701
Apr 26 15:18:02 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
Apr 26 15:18:09 raspberrypi dhcpcd[701]: eth0: no IPv6 Routers available
Apr 26 15:18:22 raspberrypi kernel: [   84.847393] Unhandled prefetch abort: unknown 1 (0x001) at 0xb0767316
Apr 26 15:18:34 raspberrypi systemd[1]: avahi-daemon.service start operation timed out. Terminating.

Wie bereits gesagt ist auch da warum auch immer das Datum und die Uhrzeit falsch!

edit: "upsd" ist meine USV... "upsd" überwacht die eth0 Schnitstelle und geht bei wegfallen der Verbindung in den USV betriebt, Berechtnet die Batteriekapazität und führt am ende der Kapazität einen Shutdown durch. Also nicht über die Meldungen "There is a power outage right now." wundern diese werden jedesmal ausgegeben wenn eth0 nicht verfügbar ist. Aber ist es Normal das während des Startens die eth0 so oft wegfällt?

Wuppi68 · 27 April 2017, 10:16:55

hier geht es los ....

Code Auswählen

[/Apr 26 15:17:54 raspberrypi systemd[1]: Starting LSB: Set console font and keymap...
Apr 26 15:17:54 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Apr 26 15:17:54 raspberrypi networking[264]: Configuring network interfaces...Segmentation fault
Apr 26 15:17:54 raspberrypi console-setup[307]: Setting up console font and keymap...done.
Apr 26 15:17:54 raspberrypi systemd[1]: Started LSB: Set console font and keymap.
Apr 26 15:17:54 raspberrypi networking[264]: Segmentation fault

Hast Du den Pi übertaktet?

Hast Du in der Apple Tastatur noch irgendwas mit USB eingestöpselt?

Nehme mal eine frische SD Karte und installiere da ein ganz frisches nacktes Image zum booten - wenn da ähnliche Fehler auftreten ist die Himbeere defekt

Florian.Stern · 27 April 2017, 10:18:57

In der Apple Tastertur steckt noch ein Receiver für eine Maus sonst ist nichts angeschlossen.
Spielt es eine Rolle?