[durch dummheit erledigt] debian stürzt ab

[the ratman]

hallo,

vorgeschichte:
ich teste ja mit dem programmierer des hm-moduls die neue version der wetterstation. gestern wollten wir ein wenig sniffen, was da so gar nicht funzen will.
ich hab nach der anleitung unter https://wiki.fhem.de/wiki/Homematic_Nachrichten_sniffen

Code Auswählen Erweitern

attr global verbose 1
attr global mseclog 1
attr <hmio> logIDs all,syseingetragen. nach einem shutdown restart ist dann dabian in meiner virtuellen console (oracle vm) abgestürzt (letzte meldung: "reboot halt"). seitdem stürzt das ding immer ab.

reperaturversuche:
drum hab ich ein weiteres virtuelles debian aufgesetzt, fhem frisch installiert und ein backup einer alten fhem.cfg (in dem auch noch nichts von dem sniffer-zeugs drinnen steht) eingespielt. sofort stürzt das neue debian wieder ab.
auch, wenn ich mein hmLan vom netz nehme, stürzt es ab
das ganze rennt unter einem intel nuc mit win10pro (alles aktuell), einer aktuellen oracle vm und einem debian mit fhem - ebenfalls aktuell.

ich bin nun sehr stark am verzweifeln - was kann ich machen, wie kann ich z.b. mal debian ohne fhem anstarten? wo könnte das problem überhaupt liegen?
bitte dringendst um hilfe!

[Sebigamer4]

Hallo, kannst du eventuell noch weitere Infos geben?
Interessant währen u.a. die syslog Einträge (/var/Log/syslog)



Edit:

Ansonsten mit Oracle VMs hab ich noch Ned zu tun gehabt

Was du machen kannst, ist Debian im resc Modus Mal zu starten (kannst beim booten auswählen)
Dann hast du n 2. System, das in den Ram bootet und von dem aus du auf die Daten zugreifen kannst

Dann kannst du z.b. deine fhem conf rausnehmen, wodurch fhem Ned startet
(Oder du trägst fhem ausm Autostart aus, das ist aber n wenig komplexer, wenn du keinen direkten Zugriff aufs Debian hast, weil's crasht)

Edit edit:
Ich meld mich in der Mittagspause dann wieder
Gesendet von meinem ONEPLUS A5000 mit Tapatalk

[the ratman]

thx für die antwort

syslog wird schwer - debian fahrt an und sobald fhem hoch kommt gibts nen absturz
abgesichert das selbe - hab also keinens chimmer, wie ich an die daten auf der virtuellen platte kommen könnte. mittlerweile stürzt das ding einfach so ab, auch nachdem ichs geschafft habe, fhem aus dem autostart (wie heißt der da noch?) in debian zu nehmen.
ich vermute also böseste verrenkungen bei der vm. ich versteh halt nicht, warums mir ein neues virtuelles debian nach dem einspielen einer alten config zerreißt. das ist so 1:1 problemlos gelaufen. es gab auch in den tagen kein win-update (das herbst-update hab ich zum glück erfolgreich verweigert *g* und auch sonst hab ich nix geändert - weder an oracle, noch am server selber.

ich hab jetzt mal einen alten raspi rausgekramt und setz den grad auf - alle perl-zusatz-libs sollt ich schon mal installiert haben.jetzt gehts bei fhem device für device weiter. bis jetzt geht alles, wobei ich noch nicht mal 10% der devices hab.
zumindest rennt aber mal hmlan und ne vccu, dazu 1 sensor ... der fehler liegt also zumindest nicht an meinem hmlan.

hue-devices, netatmo rennen auch schon alle und auch fritzbox und der fb_callmonitor
an harmony arbeit ich grad - aber die hardware rennt auch schon mal.

sollte das alles rennen, mach ich das spielchen nochmal virtuell am richtigen server unter der vm

ich bin aber wirklich für jede idee dankbar

[Sebigamer4]

Du hast also keine möglichkeiten, Iwie auf die Files zu kommen der VM?
Hast du ansonsten die möglichkeit eine 2. VM aufzusetzen und da die Platte der 1. VM durchzureichen?

Alternativ könntest du auch ne Linux ISO in der VM starten, das läuft halt dann im Ram


Das mounten auf Linux-ebene währe dann relativ einfach

Das das Resc abschießt ist auch komisch, meines Wissens sollte das unabhängig von den Systemdaten sein


Als Problemquelle würd ich Mal iwo n Buggy Perl Modul, welches (nur) in den Debian distros vorkommt mutmaßen

Aber ohne Log ist das jetzt Mal blind Geraten

Es könnte auch eventuell an der Virtualisierung Ansich liegen, aber da hast du bei nem normalen Linux system eher seltener Probleme
BSD macht diesbezüglich häufiger Probleme, oder auch Windows (Server)

Falls du iwie n Linux system hast, das Zugriff auf die vhd der kaputten VM hat

Dann machst du folgendes

1. Mountpoint erstellen
Im Grunde unter /mnt n unterverzeichnis, in den du den root (also / ) deiner kaputten Platte einhängst

mkdir /mnt/vhd

2. Den Devicepfad deiner broken Platte suchen

lsblk

Dieser kommand spuckt dir alle verfügbaren storagedevices und Partitionen aus

Deine hinzugefügte Partition solltest du anhand der Größe relativ einfach finden

Sie sollte den Schema /dev/sdX# entsprechen (X durch Buchstabe # durch Zahl ersetzen)

3. Das ganze mountest du dann auf unsern vorher erstellten Punkt


mount /dev/sdX# /mnt/vhd

(Weis grad Ned auswendig wie herum er richtig ist, wenn's nicht geht vertausch die beiden Parameter)
4. Jetzt hast du vollständigen Zugriff auf die Platte
Syslog sind z.b. unter /mnt/vhd/var/Log/


5. Wenn du noch nen Schritt weiter gehen willst und jetzt versuchen willst fhem zu starten, dann arbeite Mal die wikipage auf ubuntuusers (das funktioniert 1 zu 1 auch bei Debian) zu chroot durch


Hoffe, das bringt dich weiter


Gesendet von meinem ONEPLUS A5000 mit Tapatalk

[the ratman]

wow - zumindest wird das mal vorgemerkt - vielen dank für die vielen infos, wenn ich jetzt auch recht erschlagen bin *g*.
ich werd aber mal (den für mich) leichteren weg gehen und mal alles ganz von vorne anfangen.

ich probier jetzt mal alles auf nem raspi aus - das rennt derweil schon recht gut - denke, ich hab 50% der devices mal durch.
wenn das rennt, probier ich mich wieder am win-server. da dort auch andere, wichtige sachen, rennen, will ich mich ned zu viel spielen. ich will vorher zumindest mal wissen, ob alles geht, oder ob irgendein device schuld ist, bevor ich der vm die schuld geb, dies ja bis jetzt problemlos getan hat.
wobei ich mir sogar überleg, ob ich nicht wieder am raspi gehen sollte. ist zwar bei den plots dann sau langsam, aber dafür hätt ich wieder zuverlässig funzendes bt/usb für fhem. das will auf der vm ja immer wieder mal nicht laufen. je nachdem, wie er grad beim hochfahren lustig ist.

derzeit frag ich mich halt, was diese 3 kleinen attribute fürs sniffen so derartig verdrehen könnten?
ich mein:
attr global verbose 1 <-- hab ich immer so am laufen
attr global mseclog 1 <-- da passiert doch auch nix "ausserhalb" fhem, denk ich mal
attr hmLan2 logIDs all,sys <-- sollte doch auch nix böses machen.
warum also stellts mir die orcale vm dann permanent auf? anderes hab ich zw. "ich hab ein funzendes system" und "debian verstirbt" nicht gemacht.

[the ratman]

jetzt verzweifel ich echt ...

ich hab auf dem nigelngelneuen rasbpian alle hm-devices (bis auf die wetterstation) erfolgreich angelegt gehabt. mit der station wollt ich warten, bis alles andere läuft.
auch alle andere hardware und schon div doifs übernommen - ALLES IST GELAUFEN, daten sind gekommen, daten wurden in mysql geschrieben, ich konnte alles schalten ...

dann hab ich mal restartet - der raspi fährt hoch und dann gleich wieder runter und macht keinen mucks mehr
was bitte kann das sein?
das ist nun ein anderer rechner, alles neu aufgesetzt, alles stück für stück händisch übertragen ...


zumindest bin ich nu schnell genug, ums log zu retten.
das sind die letzten einträge:

Code Auswählen Erweitern

2018.10.10 16:13:36 1: Timeout for LGTV_WebOS_PresenceRun reached, terminated process 926
2018.10.10 16:13:36 1: HMUARTLGW hmLan2 did not respond after all, reopening
2018.10.10 16:13:37 0: Server shutdown

ist an meiner global alles richtig?

Code Auswählen Erweitern

attr global userattr DbLogExclude DbLogInclude cmdIcon devStateIcon devStateStyle icon sortby webCmd webCmdLabel:textField-long widgetOverride
attr global altitude 163
attr global autoload_undefined_devices 1
attr global autosave 0
attr global dnsServer 192.168.178.1
attr global group sys,
attr global holiday2we bw.holiday
attr global icon audio_eq
attr global language DE
attr global latitude 48.47350
attr global logfile ./log/fhem-%Y-%m.log
attr global longitude 7.94980
attr global modpath .
attr global motd none
attr global nrarchive 1
attr global room fhem
attr global sendStatistics never
attr global stacktrace 1
attr global statefile ./log/fhem.save
attr global updateInBackground 1
attr global verbose 1so hat die zwar immer schon ausgesehen, aber langsam glaub ich ja an alles.

mal wieder die letzten log-einträge erwischt:

Code Auswählen Erweitern

2018.10.10 16:27:09 0: Server shutdown
2018.10.10 16:27:21 1: Including fhem.cfgalso liegts problem in der config?

[Sebigamer4]

Kurz, vom sniffen hab ich wiederum keine Ahnung und auch mein HM System ist relativ klein, aber dafür kenn ich mich in Linux eben gut aus


Raspian ist auch nur debian, unterscheidet sich von deiner VM also Garner soo viel
Und wieder währe ein syslog interessant

Edit:

Entweder du Debuggst jetzt weiter an deiner VM
Oder du bootet am Pi von nem USB-Stick
Oder du machst dir die Mühe und packst dir am Rechner nen EXT4 Treiber drauf um die SD Karte auslesen zu könmen
Gesendet von meinem ONEPLUS A5000 mit Tapatalk

[the ratman]

ich nähere mich dem problem

13 hm devices funzen (da hatte ich ein backup der cfg gemacht)
weitere 14 devices würden bei der version noch fehlen + alle doifs, die ich vorsichtshalber gelöscht hab - eines davon ist der übeltäter.

syslog gerne - hab jetzt mal teile rein (alles is n bissi viel)

Code Auswählen Erweitern

Jun 27 01:22:33 raspberrypi systemd-modules-load[89]: Inserted module 'i2c_dev'
Jun 27 01:22:33 raspberrypi fake-hwclock[84]: Wed 27 Jun 01:22:31 UTC 2018
Jun 27 01:22:33 raspberrypi systemd[1]: Started Apply Kernel Variables.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
Jun 27 01:22:33 raspberrypi systemd-fsck[92]: e2fsck 1.43.4 (31-Jan-2017)
Jun 27 01:22:33 raspberrypi systemd-fsck[92]: rootfs: clean, 127825/291456 files, 1069060/1179648 blocks
Jun 27 01:22:33 raspberrypi systemd[1]: Started File System Check on Root Device.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
Jun 27 01:22:33 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Jun 27 01:22:33 raspberrypi systemd[1]: Starting udev Coldplug all Devices...
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Linux version 4.14.50-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1122 SMP Tue Jun 19 12:26:26 BST 2018
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] CPU: div instructions available: patching division code
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] cma: Reserved 8 MiB at 0x3ac00000
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Load/Save Random Seed...
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] On node 0 totalpages: 242688
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] free_area_init_node: node 0, pgdat 80c85000, node_mem_map ba3a1000
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]   Normal zone: 2133 pages used for memmap
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]   Normal zone: 0 pages reserved
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]   Normal zone: 242688 pages, LIFO batch:31
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Flush Journal to Persistent Storage...
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] percpu: Embedded 17 pages/cpu @ba34a000 s38720 r8192 d22720 u69632
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Jun 27 01:22:33 raspberrypi systemd[1]: Started Load/Save Random Seed.
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=e4279544-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Jun 27 01:22:33 raspberrypi systemd[1]: Started udev Kernel Device Manager.
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Memory: 940236K/970752K available (7168K kernel code, 575K rwdata, 2072K rodata, 1024K init, 706K bss, 22324K reserved, 8192K cma-reserved)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
Jun 27 01:22:33 raspberrypi systemd[1]: Started Flush Journal to Persistent Storage.
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]     vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]     lowmem  : 0x80000000 - 0xbb400000   ( 948 MB)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
Jun 27 01:22:33 raspberrypi systemd[1]: Started Set the console keyboard layout.
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]       .text : 0x80008000 - 0x80800000   (8160 kB)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]       .init : 0x80b00000 - 0x80c00000   (1024 kB)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]       .data : 0x80c00000 - 0x80c8fef4   ( 576 kB)
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
Jun 27 01:22:33 raspberrypi kernel: [    0.000000]        .bss : 0x80c96f40 - 0x80d478b4   ( 707 kB)
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] ftrace: allocating 25253 entries in 75 pages
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] Hierarchical RCU implementation.
Jun 27 01:22:33 raspberrypi systemd[1]: Started udev Coldplug all Devices.
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Jun 27 01:22:33 raspberrypi kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Jun 27 01:22:33 raspberrypi kernel: [    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Jun 27 01:22:33 raspberrypi kernel: [    0.000018] Switching to timer-based delay loop, resolution 52ns
Jun 27 01:22:33 raspberrypi kernel: [    0.000261] Console: colour dummy device 80x30
Jun 27 01:22:33 raspberrypi kernel: [    0.000279] console [tty1] enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.000302] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Jun 27 01:22:33 raspberrypi kernel: [    0.000318] pid_max: default: 32768 minimum: 301
Jun 27 01:22:33 raspberrypi kernel: [    0.000635] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.000649] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.001583] Disabling memory control group subsystem
Jun 27 01:22:33 raspberrypi kernel: [    0.001664] CPU: Testing write buffer coherency: ok
Jun 27 01:22:33 raspberrypi kernel: [    0.002058] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Jun 27 01:22:33 raspberrypi kernel: [    0.002433] Setting up static identity map for 0x100000 - 0x10003c
Jun 27 01:22:33 raspberrypi kernel: [    0.002549] Hierarchical SRCU implementation.
Jun 27 01:22:33 raspberrypi kernel: [    0.003180] smp: Bringing up secondary CPUs ...
Jun 27 01:22:33 raspberrypi kernel: [    0.003867] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Jun 27 01:22:33 raspberrypi kernel: [    0.004613] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Jun 27 01:22:33 raspberrypi kernel: [    0.005343] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Jun 27 01:22:33 raspberrypi kernel: [    0.005447] smp: Brought up 1 node, 4 CPUs
Jun 27 01:22:33 raspberrypi kernel: [    0.005458] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Jun 27 01:22:33 raspberrypi kernel: [    0.005463] CPU: All CPU(s) started in HYP mode.
Jun 27 01:22:33 raspberrypi kernel: [    0.005467] CPU: Virtualization extensions available.
Jun 27 01:22:33 raspberrypi kernel: [    0.006320] devtmpfs: initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.016227] random: get_random_u32 called from bucket_table_alloc+0xfc/0x24c with crng_init=0
Jun 27 01:22:33 raspberrypi kernel: [    0.016983] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Jun 27 01:22:33 raspberrypi kernel: [    0.017201] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Jun 27 01:22:33 raspberrypi kernel: [    0.017219] futex hash table entries: 1024 (order: 4, 65536 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.017774] pinctrl core: initialized pinctrl subsystem
Jun 27 01:22:33 raspberrypi kernel: [    0.018517] NET: Registered protocol family 16
Jun 27 01:22:33 raspberrypi kernel: [    0.021240] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Jun 27 01:22:33 raspberrypi kernel: [    0.026112] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Jun 27 01:22:33 raspberrypi kernel: [    0.026120] hw-breakpoint: maximum watchpoint size is 8 bytes.
Jun 27 01:22:33 raspberrypi kernel: [    0.026335] Serial: AMBA PL011 UART driver
Jun 27 01:22:33 raspberrypi kernel: [    0.027948] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.028404] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
Jun 27 01:22:33 raspberrypi kernel: [    0.059775] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb813000, dmachans=0x1
Jun 27 01:22:33 raspberrypi kernel: [    0.061264] SCSI subsystem initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.061486] usbcore: registered new interface driver usbfs
Jun 27 01:22:33 raspberrypi kernel: [    0.061538] usbcore: registered new interface driver hub
Jun 27 01:22:33 raspberrypi kernel: [    0.061626] usbcore: registered new device driver usb
Jun 27 01:22:33 raspberrypi kernel: [    0.070091] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-06-07 15:31
Jun 27 01:22:33 raspberrypi kernel: [    0.071513] clocksource: Switched to clocksource arch_sys_counter
Jun 27 01:22:33 raspberrypi kernel: [    0.148523] VFS: Disk quotas dquot_6.6.0
Jun 27 01:22:33 raspberrypi kernel: [    0.148608] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.148792] FS-Cache: Loaded
Jun 27 01:22:33 raspberrypi kernel: [    0.148990] CacheFiles: Loaded
Jun 27 01:22:33 raspberrypi kernel: [    0.157768] NET: Registered protocol family 2
Jun 27 01:22:33 raspberrypi kernel: [    0.158489] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.158612] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.158797] TCP: Hash tables configured (established 8192 bind 8192)
Jun 27 01:22:33 raspberrypi kernel: [    0.158927] UDP hash table entries: 512 (order: 2, 16384 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.158971] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.159199] NET: Registered protocol family 1
Jun 27 01:22:33 raspberrypi kernel: [    0.159632] RPC: Registered named UNIX socket transport module.
Jun 27 01:22:33 raspberrypi kernel: [    0.159638] RPC: Registered udp transport module.
Jun 27 01:22:33 raspberrypi kernel: [    0.159643] RPC: Registered tcp transport module.
Jun 27 01:22:33 raspberrypi kernel: [    0.159648] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jun 27 01:22:33 raspberrypi kernel: [    0.161178] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Jun 27 01:22:33 raspberrypi kernel: [    0.164022] workingset: timestamp_bits=14 max_order=18 bucket_order=4
Jun 27 01:22:33 raspberrypi kernel: [    0.171964] FS-Cache: Netfs 'nfs' registered for caching
Jun 27 01:22:33 raspberrypi kernel: [    0.172556] NFS: Registering the id_resolver key type
Jun 27 01:22:33 raspberrypi kernel: [    0.172586] Key type id_resolver registered
Jun 27 01:22:33 raspberrypi kernel: [    0.172592] Key type id_legacy registered
Jun 27 01:22:33 raspberrypi kernel: [    0.172607] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Jun 27 01:22:33 raspberrypi kernel: [    0.174474] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jun 27 01:22:33 raspberrypi kernel: [    0.174594] io scheduler noop registered
Jun 27 01:22:33 raspberrypi kernel: [    0.174601] io scheduler deadline registered (default)
Jun 27 01:22:33 raspberrypi kernel: [    0.174874] io scheduler cfq registered
Jun 27 01:22:33 raspberrypi kernel: [    0.174881] io scheduler mq-deadline registered
Jun 27 01:22:33 raspberrypi kernel: [    0.174887] io scheduler kyber registered
Jun 27 01:22:33 raspberrypi kernel: [    0.177537] BCM2708FB: allocated DMA memory fad10000
Jun 27 01:22:33 raspberrypi kernel: [    0.177563] BCM2708FB: allocated DMA channel 0 @ bb813000
Jun 27 01:22:33 raspberrypi kernel: [    0.227731] Console: switching to colour frame buffer device 228x61
Jun 27 01:22:33 raspberrypi kernel: [    0.257951] bcm2835-rng 3f104000.rng: hwrng registered
Jun 27 01:22:33 raspberrypi kernel: [    0.258083] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Jun 27 01:22:33 raspberrypi kernel: [    0.258574] vc-sm: Videocore shared memory driver
Jun 27 01:22:33 raspberrypi kernel: [    0.258843] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Jun 27 01:22:33 raspberrypi kernel: [    0.268476] brd: module loaded
Jun 27 01:22:33 raspberrypi kernel: [    0.277469] loop: module loaded
Jun 27 01:22:33 raspberrypi kernel: [    0.277483] Loading iSCSI transport class v2.0-870.
Jun 27 01:22:33 raspberrypi kernel: [    0.278148] libphy: Fixed MDIO Bus: probed
Jun 27 01:22:33 raspberrypi kernel: [    0.278255] usbcore: registered new interface driver lan78xx
Jun 27 01:22:33 raspberrypi kernel: [    0.278307] usbcore: registered new interface driver smsc95xx
Jun 27 01:22:33 raspberrypi kernel: [    0.278324] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jun 27 01:22:33 raspberrypi kernel: [    0.506464] Core Release: 2.80a
Jun 27 01:22:33 raspberrypi kernel: [    0.506475] Setting default values for core params
Jun 27 01:22:33 raspberrypi kernel: [    0.506508] Finished setting default values for core params
Jun 27 01:22:33 raspberrypi kernel: [    0.706770] Using Buffer DMA mode
Jun 27 01:22:33 raspberrypi kernel: [    0.706776] Periodic Transfer Interrupt Enhancement - disabled
Jun 27 01:22:33 raspberrypi kernel: [    0.706782] Multiprocessor Interrupt Enhancement - disabled
Jun 27 01:22:33 raspberrypi kernel: [    0.706788] OTG VER PARAM: 0, OTG VER FLAG: 0
Jun 27 01:22:33 raspberrypi kernel: [    0.706800] Dedicated Tx FIFOs mode
Jun 27 01:22:33 raspberrypi kernel: [    0.707155] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbad04000 dma = 0xfad04000 len=9024
Jun 27 01:22:33 raspberrypi kernel: [    0.707182] FIQ FSM acceleration enabled for :
Jun 27 01:22:33 raspberrypi kernel: [    0.707182] Non-periodic Split Transactions
Jun 27 01:22:33 raspberrypi kernel: [    0.707182] Periodic Split Transactions
Jun 27 01:22:33 raspberrypi kernel: [    0.707182] High-Speed Isochronous Endpoints
Jun 27 01:22:33 raspberrypi kernel: [    0.707182] Interrupt/Control Split Transaction hack enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.707203] dwc_otg: Microframe scheduler enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.707291] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805e7d20
Jun 27 01:22:33 raspberrypi kernel: [    0.707301] WARN::hcd_init_fiq:460: FIQ ASM at 0x805e8088 length 36
Jun 27 01:22:33 raspberrypi kernel: [    0.707314] WARN::hcd_init_fiq:486: MPHI regs_base at 0xbb87e000
Jun 27 01:22:33 raspberrypi kernel: [    0.707371] dwc_otg 3f980000.usb: DWC OTG Controller
Jun 27 01:22:33 raspberrypi kernel: [    0.707404] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Jun 27 01:22:33 raspberrypi kernel: [    0.707436] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
Jun 27 01:22:33 raspberrypi kernel: [    0.707483] Init: Port Power? op_state=1
Jun 27 01:22:33 raspberrypi kernel: [    0.707488] Init: Power Port (0)
Jun 27 01:22:33 raspberrypi kernel: [    0.707692] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Jun 27 01:22:33 raspberrypi kernel: [    0.707702] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jun 27 01:22:33 raspberrypi kernel: [    0.707710] usb usb1: Product: DWC OTG Controller
Jun 27 01:22:33 raspberrypi kernel: [    0.707718] usb usb1: Manufacturer: Linux 4.14.50-v7+ dwc_otg_hcd
Jun 27 01:22:33 raspberrypi kernel: [    0.707726] usb usb1: SerialNumber: 3f980000.usb
Jun 27 01:22:33 raspberrypi kernel: [    0.708362] hub 1-0:1.0: USB hub found
Jun 27 01:22:33 raspberrypi kernel: [    0.708403] hub 1-0:1.0: 1 port detected
Jun 27 01:22:33 raspberrypi kernel: [    0.708944] dwc_otg: FIQ enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.708949] dwc_otg: NAK holdoff enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.708953] dwc_otg: FIQ split-transaction FSM enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.708963] Module dwc_common_port init
Jun 27 01:22:33 raspberrypi kernel: [    0.709203] usbcore: registered new interface driver usb-storage
Jun 27 01:22:33 raspberrypi kernel: [    0.709373] mousedev: PS/2 mouse device common for all mice
Jun 27 01:22:33 raspberrypi kernel: [    0.709447] IR NEC protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709453] IR RC5(x/sz) protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709458] IR RC6 protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709463] IR JVC protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709469] IR Sony protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709473] IR SANYO protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709479] IR Sharp protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709484] IR MCE Keyboard/mouse protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.709489] IR XMP protocol handler initialized
Jun 27 01:22:33 raspberrypi kernel: [    0.710161] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
Jun 27 01:22:33 raspberrypi kernel: [    0.710433] bcm2835-cpufreq: min=600000 max=1200000
Jun 27 01:22:33 raspberrypi kernel: [    0.710797] sdhci: Secure Digital Host Controller Interface driver
Jun 27 01:22:33 raspberrypi kernel: [    0.710802] sdhci: Copyright(c) Pierre Ossman
Jun 27 01:22:33 raspberrypi kernel: [    0.711151] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
Jun 27 01:22:33 raspberrypi kernel: [    0.711472] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Jun 27 01:22:33 raspberrypi kernel: [    0.711605] sdhci-pltfm: SDHCI platform and OF driver helper
Jun 27 01:22:33 raspberrypi kernel: [    0.713015] ledtrig-cpu: registered to indicate activity on CPUs
Jun 27 01:22:33 raspberrypi kernel: [    0.713199] hidraw: raw HID events driver (C) Jiri Kosina
Jun 27 01:22:33 raspberrypi kernel: [    0.713324] usbcore: registered new interface driver usbhid
Jun 27 01:22:33 raspberrypi kernel: [    0.713329] usbhid: USB HID core driver
Jun 27 01:22:33 raspberrypi kernel: [    0.714080] vchiq: vchiq_init_state: slot_zero = bad80000, is_master = 0
Jun 27 01:22:33 raspberrypi kernel: [    0.715581] [vc_sm_connected_init]: start
Jun 27 01:22:33 raspberrypi kernel: [    0.725018] [vc_sm_connected_init]: end - returning 0
Jun 27 01:22:33 raspberrypi kernel: [    0.725615] Initializing XFRM netlink socket
Jun 27 01:22:33 raspberrypi kernel: [    0.725637] NET: Registered protocol family 17
Jun 27 01:22:33 raspberrypi kernel: [    0.725734] Key type dns_resolver registered
Jun 27 01:22:33 raspberrypi kernel: [    0.726280] Registering SWP/SWPB emulation handler
Jun 27 01:22:33 raspberrypi kernel: [    0.726854] registered taskstats version 1
Jun 27 01:22:33 raspberrypi kernel: [    0.732829] uart-pl011 3f201000.serial: cts_event_workaround enabled
Jun 27 01:22:33 raspberrypi kernel: [    0.732909] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
Jun 27 01:22:33 raspberrypi kernel: [    0.734645] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
Jun 27 01:22:33 raspberrypi kernel: [    0.734653] mmc-bcm2835 3f300000.mmc: DMA channel allocated
Jun 27 01:22:33 raspberrypi kernel: [    0.792159] sdhost: log_buf @ bad07000 (fad07000)
Jun 27 01:22:33 raspberrypi kernel: [    0.829973] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.831551] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.833098] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.835873] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Jun 27 01:22:33 raspberrypi kernel: [    0.871572] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Jun 27 01:22:33 raspberrypi kernel: [    0.872632] of_cfs_init
Jun 27 01:22:33 raspberrypi kernel: [    0.872777] of_cfs_init: OK
Jun 27 01:22:33 raspberrypi kernel: [    0.873340] Waiting for root device PARTUUID=e4279544-02...
Jun 27 01:22:33 raspberrypi kernel: [    0.911908] random: fast init done
Jun 27 01:22:33 raspberrypi kernel: [    0.921616] Indeed it is in host mode hprt0 = 00021501
Jun 27 01:22:33 raspberrypi kernel: [    1.011166] mmc1: new high speed SDIO card at address 0001
Jun 27 01:22:33 raspberrypi kernel: [    1.051476] mmc0: host does not support reading read-only switch, assuming write-enable
Jun 27 01:22:33 raspberrypi kernel: [    1.055041] mmc0: new high speed SDHC card at address aaaa
Jun 27 01:22:33 raspberrypi kernel: [    1.055514] mmcblk0: mmc0:aaaa AFGCD 29.7 GiB
Jun 27 01:22:33 raspberrypi kernel: [    1.056974]  mmcblk0: p1 p2
Jun 27 01:22:33 raspberrypi kernel: [    1.084928] EXT4-fs (mmcblk0p2): INFO: recovery required on readonly filesystem
Jun 27 01:22:33 raspberrypi kernel: [    1.084937] EXT4-fs (mmcblk0p2): write access will be enabled during recovery
Jun 27 01:22:33 raspberrypi kernel: [    1.131584] usb 1-1: new high-speed USB device number 2 using dwc_otg
Jun 27 01:22:33 raspberrypi kernel: [    1.131729] Indeed it is in host mode hprt0 = 00001101
Jun 27 01:22:33 raspberrypi kernel: [    1.192804] EXT4-fs (mmcblk0p2): recovery complete
Jun 27 01:22:33 raspberrypi kernel: [    1.196470] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jun 27 01:22:33 raspberrypi kernel: [    1.196530] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Jun 27 01:22:33 raspberrypi kernel: [    1.196643] devtmpfs: mounted
Jun 27 01:22:33 raspberrypi kernel: [    1.199799] Freeing unused kernel memory: 1024K
Jun 27 01:22:33 raspberrypi kernel: [    1.371859] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Jun 27 01:22:33 raspberrypi kernel: [    1.371877] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jun 27 01:22:33 raspberrypi kernel: [    1.372568] hub 1-1:1.0: USB hub found
Jun 27 01:22:33 raspberrypi kernel: [    1.372656] hub 1-1:1.0: 5 ports detected
Jun 27 01:22:33 raspberrypi kernel: [    1.691612] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Jun 27 01:22:33 raspberrypi kernel: [    1.699172] NET: Registered protocol family 10
Jun 27 01:22:33 raspberrypi kernel: [    1.700423] Segment Routing with IPv6
Jun 27 01:22:33 raspberrypi kernel: [    1.711258] ip_tables: (C) 2000-2006 Netfilter Core Team
Jun 27 01:22:33 raspberrypi kernel: [    1.727583] random: systemd: uninitialized urandom read (16 bytes read)
Jun 27 01:22:33 raspberrypi kernel: [    1.773488] random: systemd: uninitialized urandom read (16 bytes read)
Jun 27 01:22:33 raspberrypi kernel: [    1.791955] random: systemd-cryptse: uninitialized urandom read (16 bytes read)
Jun 27 01:22:33 raspberrypi kernel: [    1.822060] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Jun 27 01:22:33 raspberrypi kernel: [    1.822090] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jun 27 01:22:33 raspberrypi kernel: [    1.825245] smsc95xx v1.0.6
Jun 27 01:22:33 raspberrypi kernel: [    1.915068] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:70:b9:be
Jun 27 01:22:33 raspberrypi kernel: [    2.011601] usb 1-1.4: new full-speed USB device number 4 using dwc_otg
Jun 27 01:22:33 raspberrypi kernel: [    2.148102] usb 1-1.4: New USB device found, idVendor=046d, idProduct=c52b
Jun 27 01:22:33 raspberrypi kernel: [    2.148117] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
Jun 27 01:22:33 raspberrypi kernel: [    2.148126] usb 1-1.4: Product: USB Receiver
Jun 27 01:22:33 raspberrypi kernel: [    2.148134] usb 1-1.4: Manufacturer: Logitech
Jun 27 01:22:33 raspberrypi kernel: [    2.285480] i2c /dev entries driver
Jun 27 01:22:33 raspberrypi kernel: [    2.616551] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Jun 27 01:22:33 raspberrypi kernel: [    3.213044] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Jun 27 01:22:33 raspberrypi kernel: [    3.217211] bcm2835_alsa bcm2835_alsa: card created with 8 channels
Jun 27 01:22:33 raspberrypi kernel: [    3.395398] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Jun 27 01:22:33 raspberrypi kernel: [    3.419379] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43430-sdio.bin for chip 0x00a9a6(43430) rev 0x000001
Jun 27 01:22:33 raspberrypi kernel: [    3.419673] usbcore: registered new interface driver brcmfmac
Jun 27 01:22:33 raspberrypi kernel: [    3.433055] logitech-djreceiver 0003:046D:C52B.0003: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-3f980000.usb-1.4/input2
Jun 27 01:22:33 raspberrypi kernel: [    3.647843] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f
Jun 27 01:22:33 raspberrypi kernel: [    3.648698] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.39 Inc Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-10-23 03:47:14
Jun 27 01:22:33 raspberrypi kernel: [    3.659487] input: Logitech K400 as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.2/0003:046D:C52B.0003/0003:046D:400E.0004/input/input0
Jun 27 01:22:33 raspberrypi kernel: [    3.662534] logitech-hidpp-device 0003:046D:400E.0004: input,hidraw1: USB HID v1.11 Keyboard [Logitech K400] on usb-3f980000.usb-1.4:1
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Show Plymouth Boot Screen...
Jun 27 01:22:33 raspberrypi systemd[1]: Started Show Plymouth Boot Screen.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Paths.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Encrypted Volumes.
Jun 27 01:22:33 raspberrypi systemd[1]: Found device /dev/serial1.
Jun 27 01:22:33 raspberrypi mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4"
Jun 27 01:22:33 raspberrypi mtp-probe: bus: 1, device: 4 was not an MTP device
Jun 27 01:22:33 raspberrypi mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1"
Jun 27 01:22:33 raspberrypi mtp-probe: bus: 1, device: 3 was not an MTP device
Jun 27 01:22:33 raspberrypi systemd[1]: Found device /dev/disk/by-partuuid/e4279544-01.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting File System Check on /dev/disk/by-partuuid/e4279544-01...
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Sound Card.
Jun 27 01:22:33 raspberrypi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Jun 27 01:22:33 raspberrypi systemd-fsck[209]: fsck.fat 4.1 (2017-01-24)
Jun 27 01:22:33 raspberrypi systemd-fsck[209]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Jun 27 01:22:33 raspberrypi systemd-fsck[209]:  Automatically removing dirty bit.
Jun 27 01:22:33 raspberrypi systemd-fsck[209]: Performing changes.
Jun 27 01:22:33 raspberrypi systemd-fsck[209]: /dev/mmcblk0p1: 166 files, 44385/87078 clusters
Jun 27 01:22:33 raspberrypi systemd[1]: Started File System Check on /dev/disk/by-partuuid/e4279544-01.
Jun 27 01:22:33 raspberrypi systemd[1]: Mounting /boot...
Jun 27 01:22:33 raspberrypi systemd-udevd[143]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 99.
Jun 27 01:22:33 raspberrypi systemd[1]: Mounted /boot.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Local File Systems.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Set console font and keymap...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Preprocess NFS configuration...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Raise network interfaces...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Jun 27 01:22:33 raspberrypi systemd[1]: Started Preprocess NFS configuration.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Set console font and keymap.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target NFS client services.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Remote File Systems (Pre).
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Remote File Systems.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
Jun 27 01:22:33 raspberrypi systemd-udevd[150]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Network Time Synchronization...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Jun 27 01:22:33 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Jun 27 01:22:33 raspberrypi systemd-udevd[141]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Network Time Synchronization.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target System Initialization.
Jun 27 01:22:33 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
Jun 27 01:22:33 raspberrypi systemd[1]: Listening on triggerhappy.socket.
Jun 27 01:22:33 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Sockets.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Basic System.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting LSB: Resize the root filesystem to fill partition...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting System Logging Service...
Jun 27 01:22:33 raspberrypi systemd[1]: Started Regular background program processing daemon.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting triggerhappy global hotkey daemon...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting LSB: Autogenerate and use a swap file...
Jun 27 01:22:33 raspberrypi cron[301]: (CRON) INFO (pidfile fd = 3)
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Save/Restore Sound Card State...
Jun 27 01:22:33 raspberrypi systemd[1]: Started D-Bus System Message Bus.
Jun 27 01:22:33 raspberrypi alsactl[307]: /usr/sbin/alsactl: load_state:1683: Cannot open /var/lib/alsa/asound.state for reading: No such file or directory
Jun 27 01:22:33 raspberrypi thd[302]: Found socket passed from systemd
Jun 27 01:22:33 raspberrypi alsactl[307]: Found hardware: "bcm2835_alsa" "Broadcom Mixer" "" "" ""
Jun 27 01:22:33 raspberrypi alsactl[307]: Hardware is initialized using a generic method
Jun 27 01:22:33 raspberrypi cron[301]: (CRON) INFO (Running @reboot jobs)
Jun 27 01:22:33 raspberrypi liblogging-stdlog:  [origin software="rsyslogd" swVersion="8.24.0" x-pid="299" x-info="http://www.rsyslog.com"] start
Jun 27 01:22:33 raspberrypi kernel: [    4.544725] uart-pl011 3f201000.serial: no DMA platform data
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Regenerate SSH host keys...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Check for v3d driver...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Disable WiFi if country not set...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Login Service...
Jun 27 01:22:33 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target System Time Synchronized.
Jun 27 01:22:33 raspberrypi systemd[1]: apt-daily.timer: Adding 10h 56min 42.117042s random time.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Daily apt download activities.
Jun 27 01:22:33 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 3min 10.248797s random time.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
Jun 27 01:22:33 raspberrypi systemd[1]: Reached target Timers.
Jun 27 01:22:33 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon.
Jun 27 01:22:33 raspberrypi dd[337]: 1+0 records in
Jun 27 01:22:33 raspberrypi dd[337]: 1+0 records out
Jun 27 01:22:33 raspberrypi dd[337]: 4096 bytes (4.1 kB, 4.0 KiB) copied, 0.0439835 s, 93.1 kB/s
Jun 27 01:22:33 raspberrypi systemd[1]: Started System Logging Service.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Save/Restore Sound Card State.
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: Found user 'avahi' (UID 108) and group 'avahi' (GID 112).
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: Successfully dropped root privileges.
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: avahi-daemon 0.6.32 starting up.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Disable WiFi if country not set.
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: Successfully called chroot().
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: Successfully dropped remaining capabilities.
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: No service file found in /etc/avahi/services.
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: Network interface enumeration completed.
Jun 27 01:22:33 raspberrypi avahi-daemon[338]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 174772690.
Jun 27 01:22:33 raspberrypi dhcpcd[342]: dev: loaded udev
Jun 27 01:22:33 raspberrypi dhcpcd[342]: forked to background, child pid 355
Jun 27 01:22:33 raspberrypi systemd[1]: Started Check for v3d driver.
Jun 27 01:22:33 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Jun 27 01:22:33 raspberrypi systemd[1]: Started Login Service.
Jun 27 01:22:33 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status...
Jun 27 01:22:33 raspberrypi systemd-rfkill[364]: Failed to parse state file /var/lib/systemd/rfkill/platform-3f300000.mmc:wlan: Invalid argument
Jun 27 01:22:33 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status.
Jun 27 01:22:33 raspberrypi dhcpcd-run-hooks[375]: wlan0: starting wpa_supplicant
Jun 27 01:22:33 raspberrypi kernel: [    5.036106] random: crng init done
Jun 27 01:22:33 raspberrypi kernel: [    5.036123] random: 7 urandom warning(s) missed due to ratelimiting
Jun 27 01:22:33 raspberrypi kernel: [    5.169843] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jun 27 01:22:33 raspberrypi kernel: [    5.169879] brcmfmac: power management disabled
Jun 27 01:22:34 raspberrypi systemd[1]: Received SIGRTMIN+20 from PID 166 (plymouthd).
Jun 27 01:22:34 raspberrypi systemd[1]: Started Raise network interfaces.
Jun 27 01:22:34 raspberrypi systemd[1]: Reached target Network.
Jun 27 01:22:34 raspberrypi systemd[1]: Starting Permit User Sessions...
Jun 27 01:22:34 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
Jun 27 01:22:34 raspberrypi kernel: [    5.524315] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Jun 27 01:22:34 raspberrypi kernel: [    5.524596] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Jun 27 01:22:34 raspberrypi dhcpcd[355]: eth0: waiting for carrier
Jun 27 01:22:34 raspberrypi dhcpcd[355]: wlan0: waiting for carrier
Jun 27 01:22:34 raspberrypi dhcpcd[355]: wlan0: carrier acquired
Jun 27 01:22:34 raspberrypi systemd[1]: Started Permit User Sessions.
Jun 27 01:22:34 raspberrypi dhcpcd[355]: error reading DUID: /etc/dhcpcd.duid: Success
Jun 27 01:22:34 raspberrypi systemd[1]: Starting Light Display Manager...
Jun 27 01:22:34 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
Jun 27 01:22:34 raspberrypi dhcpcd[355]: DUID 00:01:00:01:22:c5:a3:5a:b8:27:eb:25:ec:eb
Jun 27 01:22:34 raspberrypi dhcpcd[355]: wlan0: IAID eb:25:ec:eb
Jun 27 01:22:34 raspberrypi dhcpcd[355]: error reading secret: /etc/dhcpcd.secret: Success
Jun 27 01:22:34 raspberrypi systemd[1]: Starting Terminate Plymouth Boot Screen...
Jun 27 01:22:34 raspberrypi dhcpcd[355]: wlan0: adding address fe80::2f86:aae0:858d:3bb6
Jun 27 01:22:34 raspberrypi systemd[1]: Starting Hold until boot process finishes up...
Jun 27 01:22:34 raspberrypi dhcpcd[355]: wlan0: carrier lost
Jun 27 01:22:34 raspberrypi dhcpcd[355]: wlan0: deleting address fe80::2f86:aae0:858d:3bb6
Jun 27 01:22:34 raspberrypi lightdm[406]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jun 27 01:22:35 raspberrypi dphys-swapfile[306]: Starting dphys-swapfile swapfile setup ...
Jun 27 01:22:35 raspberrypi systemd[1]: Received SIGRTMIN+21 from PID 166 (plymouthd).
Jun 27 01:22:35 raspberrypi kernel: [    6.570775] EXT4-fs (mmcblk0p2): resizing filesystem from 1179648 to 7779456 blocks
Jun 27 01:22:35 raspberrypi resize2fs_once[298]: Starting resize2fs_once:resize2fs 1.43.4 (31-Jan-2017)
Jun 27 01:22:35 raspberrypi systemd[1]: Started Terminate Plymouth Boot Screen.
Jun 27 01:22:35 raspberrypi systemd[1]: Started Hold until boot process finishes up.
Jun 27 01:22:35 raspberrypi systemd[1]: Started Getty on tty1.
Jun 27 01:22:35 raspberrypi systemd[1]: Reached target Login Prompts.
Jun 27 01:22:35 raspberrypi lightdm[406]: error: unexpectedly disconnected from boot status daemon
Jun 27 01:22:35 raspberrypi lightdm[406]: Could not enumerate user data directory /var/lib/lightdm/data: Error opening directory '/var/lib/lightdm/data': No such file or directory
Jun 27 01:22:35 raspberrypi systemd[1]: Started Light Display Manager.
Jun 27 01:22:35 raspberrypi dphys-swapfile[306]: want /var/swap=100MByte, checking existing: keeping it
Jun 27 01:22:35 raspberrypi dphys-swapfile[306]: swapon: /var/swap: insecure permissions 0644, 0600 suggested.
Jun 27 01:22:35 raspberrypi dphys-swapfile[306]: swapon: /var/swap: read swap header failed
Jun 27 01:22:35 raspberrypi dphys-swapfile[306]: done.
Jun 27 01:22:35 raspberrypi systemd[1]: Started LSB: Autogenerate and use a swap file.
Jun 27 01:22:35 raspberrypi dhcpcd[355]: eth0: carrier acquired
Jun 27 01:22:35 raspberrypi kernel: [    7.143666] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
Jun 27 01:22:35 raspberrypi kernel: [    7.144373] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Jun 27 01:22:35 raspberrypi dhcpcd[355]: eth0: IAID eb:70:b9:be
Jun 27 01:22:35 raspberrypi dhcpcd[355]: eth0: adding address fe80::893e:cf2f:8caa:6bcd
Jun 27 01:22:35 raspberrypi dhcpcd[355]: eth0: soliciting an IPv6 router
Jun 27 01:22:36 raspberrypi raspi-config[305]: Checking if shift key is held down: No. Switching to ondemand scaling governor.
Jun 27 01:22:36 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: soliciting a DHCP lease
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: offered 192.168.178.7 from 192.168.178.1
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: probing address 192.168.178.7/24
Jun 27 01:22:36 raspberrypi avahi-daemon[338]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::893e:cf2f:8caa:6bcd.
Jun 27 01:22:36 raspberrypi avahi-daemon[338]: New relevant interface eth0.IPv6 for mDNS.
Jun 27 01:22:36 raspberrypi avahi-daemon[338]: Registering new address record for fe80::893e:cf2f:8caa:6bcd on eth0.*.
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: Router Advertisement from fe80::cece:1eff:feb1:dc06
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: adding address 2003:df:7f12:d00:1d24:21d8:9832:2f90/64
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: adding route to 2003:df:7f12:d00::/64
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: adding default route via fe80::cece:1eff:feb1:dc06
Jun 27 01:22:36 raspberrypi dhcpcd[355]: eth0: requesting DHCPv6 information
Jun 27 01:22:38 raspberrypi avahi-daemon[338]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::893e:cf2f:8caa:6bcd.
Jun 27 01:22:38 raspberrypi avahi-daemon[338]: Joining mDNS multicast group on interface eth0.IPv6 with address 2003:df:7f12:d00:1d24:21d8:9832:2f90.
Jun 27 01:22:38 raspberrypi avahi-daemon[338]: Registering new address record for 2003:df:7f12:d00:1d24:21d8:9832:2f90 on eth0.*.
Jun 27 01:22:38 raspberrypi avahi-daemon[338]: Withdrawing address record for fe80::893e:cf2f:8caa:6bcd on eth0.
Jun 27 01:22:39 raspberrypi kernel: [   10.332075] Bluetooth: Core ver 2.22
Jun 27 01:22:39 raspberrypi kernel: [   10.332174] NET: Registered protocol family 31
Jun 27 01:22:39 raspberrypi kernel: [   10.332203] Bluetooth: HCI device and connection manager initialized
Jun 27 01:22:39 raspberrypi kernel: [   10.332225] Bluetooth: HCI socket layer initialized
Jun 27 01:22:39 raspberrypi kernel: [   10.332241] Bluetooth: L2CAP socket layer initialized
Jun 27 01:22:39 raspberrypi kernel: [   10.332278] Bluetooth: SCO socket layer initialized
Jun 27 01:22:39 raspberrypi kernel: [   10.580515] Bluetooth: HCI UART driver ver 2.3
Jun 27 01:22:39 raspberrypi kernel: [   10.580531] Bluetooth: HCI UART protocol H4 registered
Jun 27 01:22:39 raspberrypi kernel: [   10.580539] Bluetooth: HCI UART protocol Three-wire (H5) registered
Jun 27 01:22:39 raspberrypi kernel: [   10.580805] Bluetooth: HCI UART protocol Broadcom registered
Jun 27 01:22:39 raspberrypi btuart[304]: bcm43xx_init
Jun 27 01:22:39 raspberrypi btuart[304]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd
Jun 27 01:22:39 raspberrypi btuart[304]: Set BDADDR UART: b8:27:eb:da:13:14
Jun 27 01:22:39 raspberrypi btuart[304]: Set Controller UART speed to 921600 bit/s
Jun 27 01:22:39 raspberrypi btuart[304]: Device setup complete
Jun 27 01:22:39 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status...
Jun 27 01:22:39 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART.
Jun 27 01:22:39 raspberrypi systemd[1]: Starting Bluetooth service...
Jun 27 01:22:39 raspberrypi bluetoothd[473]: Bluetooth daemon 5.43
Jun 27 01:22:39 raspberrypi systemd[1]: Started Bluetooth service.
Jun 27 01:22:39 raspberrypi systemd[1]: Started BluezALSA proxy.
Jun 27 01:22:39 raspberrypi bluetoothd[473]: Starting SDP server
Jun 27 01:22:39 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status.
Jun 27 01:22:39 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper.
Jun 27 01:22:39 raspberrypi systemd[1]: Reached target Bluetooth.
Jun 27 01:22:39 raspberrypi bluetoothd[473]: Bluetooth management interface 1.14 initialized
Jun 27 01:22:39 raspberrypi bluetoothd[473]: Failed to obtain handles for "Service Changed" characteristic
Jun 27 01:22:39 raspberrypi dbus[308]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
Jun 27 01:22:39 raspberrypi kernel: [   10.785939] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jun 27 01:22:39 raspberrypi kernel: [   10.785951] Bluetooth: BNEP filters: protocol multicast
Jun 27 01:22:39 raspberrypi kernel: [   10.785967] Bluetooth: BNEP socket layer initialized
Jun 27 01:22:39 raspberrypi bluetoothd[473]: Sap driver initialization failed.
Jun 27 01:22:39 raspberrypi bluetoothd[473]: sap-server: Operation not permitted (1)
Jun 27 01:22:39 raspberrypi bluetoothd[473]: Endpoint registered: sender=:1.7 path=/A2DP/SBC/Source/1
Jun 27 01:22:39 raspberrypi bluetoothd[473]: Endpoint registered: sender=:1.7 path=/A2DP/SBC/Sink/1
Jun 27 01:22:39 raspberrypi systemd[1]: Starting Hostname Service...
Jun 27 01:22:39 raspberrypi kernel: [   10.850994] Bluetooth: RFCOMM TTY layer initialized
Jun 27 01:22:39 raspberrypi kernel: [   10.851013] Bluetooth: RFCOMM socket layer initialized
Jun 27 01:22:39 raspberrypi kernel: [   10.851037] Bluetooth: RFCOMM ver 1.11
Jun 27 01:22:39 raspberrypi dbus[308]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jun 27 01:22:39 raspberrypi systemd[1]: Started Hostname Service.
Jun 27 01:22:43 raspberrypi dhcpcd[355]: eth0: leased 192.168.178.7 for 864000 seconds
Jun 27 01:22:43 raspberrypi dhcpcd[355]: eth0: adding route to 192.168.178.0/24
Jun 27 01:22:43 raspberrypi dhcpcd[355]: eth0: adding default route via 192.168.178.1
Jun 27 01:22:43 raspberrypi avahi-daemon[338]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.7.
Jun 27 01:22:43 raspberrypi avahi-daemon[338]: New relevant interface eth0.IPv4 for mDNS.
Jun 27 01:22:43 raspberrypi avahi-daemon[338]: Registering new address record for 192.168.178.7 on eth0.IPv4.
Jun 27 01:22:43 raspberrypi ssh-keygen[353]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519
Jun 27 01:22:43 raspberrypi systemctl[648]: Removed /etc/systemd/system/multi-user.target.wants/regenerate_ssh_host_keys.service.
Jun 27 01:22:43 raspberrypi systemd[1]: Reloading.
Jun 27 01:22:44 raspberrypi systemd[1]: Started Regenerate SSH host keys.
Jun 27 01:22:45 raspberrypi kernel: [   16.949911] EXT4-fs (mmcblk0p2): resized to 3670016 blocks
Jun 27 01:22:46 raspberrypi systemd[1]: Created slice User Slice of pi.
Jun 27 01:22:46 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Jun 27 01:22:46 raspberrypi systemd[1]: Started Session c1 of user pi.
Jun 27 01:22:50 raspberrypi systemd[667]: Listening on GnuPG cryptographic agent and passphrase cache.
Jun 27 01:22:50 raspberrypi systemd[667]: Listening on GnuPG cryptographic agent (access for web browsers).
Jun 27 01:22:50 raspberrypi systemd[667]: Starting D-Bus User Message Bus Socket.
Jun 27 01:22:50 raspberrypi systemd[667]: Reached target Paths.
Jun 27 01:22:50 raspberrypi systemd[667]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Jun 27 01:22:50 raspberrypi systemd[667]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Jun 27 01:22:50 raspberrypi systemd[667]: Reached target Timers.
Jun 27 01:22:50 raspberrypi systemd[667]: Listening on D-Bus User Message Bus Socket.
Jun 27 01:22:50 raspberrypi systemd[667]: Reached target Sockets.
Jun 27 01:22:50 raspberrypi systemd[667]: Reached target Basic System.
Jun 27 01:22:50 raspberrypi systemd[667]: Reached target Default.
Jun 27 01:22:50 raspberrypi systemd[667]: Startup finished in 2.534s.
Jun 27 01:22:50 raspberrypi systemd[1]: Started User Manager for UID 1000.
Jun 27 01:22:57 raspberrypi kernel: [   28.828818] EXT4-fs (mmcblk0p2): resized to 6291456 blocks
Jun 27 01:23:03 raspberrypi kernel: [   35.266742] EXT4-fs (mmcblk0p2): resized filesystem to 7779456
Jun 27 01:23:04 raspberrypi resize2fs_once[298]: Filesystem at /dev/mmcblk0p2 is mounted on /; on-line resizing required
Jun 27 01:23:04 raspberrypi resize2fs_once[298]: old_desc_blocks = 1, new_desc_blocks = 2
Jun 27 01:23:04 raspberrypi resize2fs_once[298]: The filesystem on /dev/mmcblk0p2 is now 7779456 (4k) blocks long.
Jun 27 01:23:04 raspberrypi systemd[1]: Reloading.
Jun 27 01:23:04 raspberrypi systemd[1]: Reached target Multi-User System.
Jun 27 01:23:04 raspberrypi systemd[1]: Reached target Graphical Interface.
Jun 27 01:23:04 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jun 27 01:23:04 raspberrypi resize2fs_once[298]: .
Jun 27 01:23:04 raspberrypi lightdm[719]: Error getting user list from org.freedesktop.Accounts: GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name org.freedesktop.Accounts was not provided by any .service files
Jun 27 01:23:04 raspberrypi systemd[1]: Started LSB: Resize the root filesystem to fill partition.
Jun 27 01:23:04 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
Jun 27 01:23:04 raspberrypi systemd[1]: Startup finished in 1.526s (kernel) + 34.420s (userspace) = 35.946s.
Jun 27 01:23:04 raspberrypi systemd[1]: Started Session c2 of user pi.
Jun 27 01:23:04 raspberrypi lightdm[406]: Error opening audit socket: Protocol not supported
Jun 27 01:23:04 raspberrypi systemd[667]: Started D-Bus User Message Bus.
Jun 27 01:23:06 raspberrypi dbus-daemon[742]: Activating via systemd: service name='org.gtk.vfs.Daemon' unit='gvfs-daemon.service'
Jun 27 01:23:06 raspberrypi systemd[667]: Starting Virtual filesystem service...
Jun 27 01:23:06 raspberrypi dbus-daemon[742]: Successfully activated service 'org.gtk.vfs.Daemon'
Jun 27 01:23:06 raspberrypi systemd[667]: Started Virtual filesystem service.
Jun 27 01:23:06 raspberrypi kernel: [   38.137104] fuse init (API version 7.26)
Jun 27 01:23:06 raspberrypi systemd[1]: Mounting FUSE Control File System...
Jun 27 01:23:06 raspberrypi systemd[1]: Mounted FUSE Control File System.
Jun 27 01:23:06 raspberrypi dbus[308]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
Jun 27 01:23:07 raspberrypi systemd[1]: Starting Authorization Manager...
Jun 27 01:23:07 raspberrypi polkitd[856]: started daemon version 0.105 using authority implementation `local' version `0.105'
Jun 27 01:23:07 raspberrypi dbus[308]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jun 27 01:23:07 raspberrypi systemd[1]: Started Authorization Manager.
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Activating via systemd: service name='org.gtk.vfs.UDisks2VolumeMonitor' unit='gvfs-udisks2-volume-monitor.service'
Jun 27 01:23:08 raspberrypi systemd[667]: Starting Virtual filesystem service - disk device monitor...
Jun 27 01:23:08 raspberrypi dbus[308]: [system] Activating via systemd: service name='org.freedesktop.UDisks2' unit='udisks2.service'
Jun 27 01:23:08 raspberrypi systemd[1]: Starting Disk Manager...
Jun 27 01:23:08 raspberrypi udisksd[912]: udisks daemon version 2.1.8 starting
Jun 27 01:23:08 raspberrypi dbus[308]: [system] Successfully activated service 'org.freedesktop.UDisks2'
Jun 27 01:23:08 raspberrypi systemd[1]: Started Disk Manager.
Jun 27 01:23:08 raspberrypi udisksd[912]: Acquired the name org.freedesktop.UDisks2 on the system message bus
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Successfully activated service 'org.gtk.vfs.UDisks2VolumeMonitor'
Jun 27 01:23:08 raspberrypi systemd[667]: Started Virtual filesystem service - disk device monitor.
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Activating via systemd: service name='org.gtk.vfs.AfcVolumeMonitor' unit='gvfs-afc-volume-monitor.service'
Jun 27 01:23:08 raspberrypi systemd[667]: Starting Virtual filesystem service - Apple File Conduit monitor...
Jun 27 01:23:08 raspberrypi gvfs-afc-volume-monitor[921]: Volume monitor alive
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Successfully activated service 'org.gtk.vfs.AfcVolumeMonitor'
Jun 27 01:23:08 raspberrypi systemd[667]: Started Virtual filesystem service - Apple File Conduit monitor.
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Activating via systemd: service name='org.gtk.vfs.GPhoto2VolumeMonitor' unit='gvfs-gphoto2-volume-monitor.service'
Jun 27 01:23:08 raspberrypi systemd[667]: Starting Virtual filesystem service - digital camera monitor...
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Successfully activated service 'org.gtk.vfs.GPhoto2VolumeMonitor'
Jun 27 01:23:08 raspberrypi systemd[667]: Started Virtual filesystem service - digital camera monitor.
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Activating via systemd: service name='org.gtk.vfs.GoaVolumeMonitor' unit='gvfs-goa-volume-monitor.service'
Jun 27 01:23:08 raspberrypi systemd[667]: Starting Virtual filesystem service - GNOME Online Accounts monitor...
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Successfully activated service 'org.gtk.vfs.GoaVolumeMonitor'
Jun 27 01:23:08 raspberrypi systemd[667]: Started Virtual filesystem service - GNOME Online Accounts monitor.
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Activating via systemd: service name='org.gtk.vfs.MTPVolumeMonitor' unit='gvfs-mtp-volume-monitor.service'
Jun 27 01:23:08 raspberrypi systemd[667]: Starting Virtual filesystem service - Media Transfer Protocol monitor...
Jun 27 01:23:08 raspberrypi dbus-daemon[742]: Successfully activated service 'org.gtk.vfs.MTPVolumeMonitor'
Jun 27 01:23:08 raspberrypi systemd[667]: Started Virtual filesystem service - Media Transfer Protocol monitor.
Jun 27 01:23:13 raspberrypi systemd-timesyncd[256]: Timed out waiting for reply from [2001:19f0:6c01:10e3:5400:1ff:fe87:1978]:123 (2.debian.pool.ntp.org).
Jun 27 01:23:15 raspberrypi kernel: [   46.980327] logitech-hidpp-device 0003:046D:400E.0004: HID++ 2.0 device connected.
Jun 27 01:23:23 raspberrypi systemd-timesyncd[256]: Timed out waiting for reply from [2a02:180:1:1::517:1034]:123 (2.debian.pool.ntp.org).
Oct 10 08:04:03 raspberrypi systemd[667]: Time has been changed
Oct 10 08:04:03 raspberrypi systemd[1]: Time has been changed
Oct 10 08:04:03 raspberrypi systemd-timesyncd[256]: Synchronized to time server [2a01:4f8:1c17:51f9:995:993:587:25]:123 (2.debian.pool.ntp.org).
Oct 10 08:04:03 raspberrypi systemd[1]: apt-daily.timer: Adding 1h 1min 6.224991s random time.
Oct 10 08:04:03 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 16min 31.702198s random time.
Oct 10 08:04:03 raspberrypi systemd[1]: Reached target Network is Online.
Oct 10 08:04:03 raspberrypi systemd[1]: Starting Daily apt download activities...
Oct 10 08:04:09 raspberrypi systemd[1]: Started Daily apt download activities.
Oct 10 08:04:09 raspberrypi systemd[1]: apt-daily.timer: Adding 9h 1min 33.556476s random time.
Oct 10 08:04:09 raspberrypi systemd[1]: apt-daily.timer: Adding 9h 40min 18.003827s random time.
Oct 10 08:04:09 raspberrypi systemd[1]: Starting Daily apt upgrade and clean activities...
Oct 10 08:04:11 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
Oct 10 08:04:11 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 17min 7.227630s random time.
Oct 10 08:04:11 raspberrypi systemd[1]: apt-daily-upgrade.timer: Adding 24min 45.064740s random time.
Oct 10 08:04:58 raspberrypi dbus[308]: [system] Activating via systemd: service name='org.freedesktop.PackageKit' unit='packagekit.service'
Oct 10 08:04:58 raspberrypi systemd[1]: Starting PackageKit Daemon...
Oct 10 08:04:58 raspberrypi PackageKit: daemon start
Oct 10 08:04:58 raspberrypi dbus[308]: [system] Successfully activated service 'org.freedesktop.PackageKit'
Oct 10 08:04:58 raspberrypi systemd[1]: Started PackageKit Daemon.
Oct 10 08:05:09 raspberrypi PackageKit: refresh-cache transaction /1_cadeebaa from uid 0 finished with success after 10493ms
Oct 10 08:05:15 raspberrypi PackageKit: get-updates transaction /2_cbdedaaa from uid 0 finished with success after 6379ms
Oct 10 08:05:18 raspberrypi PackageKit: new update-packages transaction /3_eebedccc scheduled from uid 0
Oct 10 08:07:33 raspberrypi packagekitd[1163]: PARENT process running...
Oct 10 08:08:45 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:45 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:46 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:46 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:46 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:46 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:46 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:47 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:47 raspberrypi dbus-daemon[742]: Reloaded configuration
Oct 10 08:08:47 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:47 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:47 raspberrypi dbus-daemon[742]: Reloaded configuration
Oct 10 08:08:47 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:47 raspberrypi dbus[308]: [system] Reloaded configuration
Oct 10 08:08:48 raspberrypi systemd[1]: Reloading.
Oct 10 08:09:27 raspberrypi systemd[1]: Reloading.
Oct 10 08:09:32 raspberrypi systemd[1]: Stopping BluezALSA proxy...
Oct 10 08:09:32 raspberrypi systemd[1]: Stopped BluezALSA proxy.
Oct 10 08:09:32 raspberrypi bluetoothd[473]: Endpoint unregistered: sender=:1.7 path=/A2DP/SBC/Source/1
Oct 10 08:09:32 raspberrypi bluetoothd[473]: Endpoint unregistered: sender=:1.7 path=/A2DP/SBC/Sink/1
Oct 10 08:09:32 raspberrypi systemd[1]: Reloading.
Oct 10 08:09:33 raspberrypi systemd[1]: Reloading.
Oct 10 08:09:34 raspberrypi systemd[1]: Reloading.
Oct 10 08:10:40 raspberrypi systemd[1]: Reloading.
Oct 10 08:10:41 raspberrypi systemd[1]: Reloading.
Oct 10 08:10:41 raspberrypi systemd[1]: Reloading.
Oct 10 08:10:41 raspberrypi systemd[1]: Reloading.
Oct 10 08:10:51 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:30 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:32 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:35 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:44 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:44 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:50 raspberrypi systemd[1]: Stopping udev Kernel Device Manager...
Oct 10 08:11:50 raspberrypi systemd[1]: Stopped udev Kernel Device Manager.
Oct 10 08:11:50 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:51 raspberrypi systemd[1]: Reloading.
Oct 10 08:11:51 raspberrypi systemd

[Sebigamer4]

Das Log bringt mir aktuell Ned viel, das ist nur vom Bootvorgang Weg bis ist Zustand


(Die Zeit ist anders, da der beim Bootvorgang noch keine Uhrzeit hat)

Gesendet von meinem ONEPLUS A5000 mit Tapatalk

[the ratman]

schad ... naja, derzeit schauts ja wieder gut aus - mittlerweile leben wieder 21 hm-devices von 30 und viele andere sachen.
ist halt n horror, weil ich nach jedem neu angelegten device restarte und die cfg wegspeichere, wenns funzt. so kann man auch nen tag rum bringen ...

den neuesten fehler kan ich auch schon suchen wieder - hmlan geht beim neustart in den disconnect, macht aber keine probs, es wieder aufzuwecken. is das irgendwie n raspberry-ding? hat ich auf meiner vm nie.

Code Auswählen Erweitern

2018.10.10 18:08:59 0: Server started with 126 defined entities (fhem.pl:17488/2018-10-08 perl:5.024001 os:linux user:fhem pid:5938)
2018.10.10 18:08:59 1: hmLan2: Can't connect to 192.168.178.10:2000: Operation now in progress
2018.10.10 18:08:59 1: hmLan2: Can't connect to 192.168.178.10:2000: 192.168.178.10: Die Verbindung wurde vom Kommunikationspartner zur�ckgesetzt
2018.10.10 18:09:19 1: 192.168.178.10:2000 reappeared (hmLan2)

[martinp876]

Um die linux maschine zum abschmieren zu bringen muss reicht hm in fhem nicht. Auch das sniffen kann kein Problem sein. Es schreibt nur ins log. Die ms funktion wird genutzt, ok. Das sollte das os abkönnen. Die ios nutzen treiber. Da könnte etwas passieren. Evtl noch die Kommunikation zu anderen Prozessen. Msql,....
Fhem hm rechnet nur intern umher. Das wird vom os sauber getrennt und generiert max einen fhem Absturz
Mein raspi ist nun schon 4 mal abgeschmiert. Fhem war nicht der grund. Ich vermute eine schlechts sd karte. Oder Probleme mit der spannungsversorgung. Beides habe ich getauscht. Aktuell läuft es.

[Sebigamer4]

Das erklärt aber dennoch Ned die Reproduzierbarkeit und die Crashs in der vm

Gesendet von meinem ONEPLUS A5000 mit Tapatalk

[the ratman]

@martin876
hat sich halt so dargestellt - als du mir gestern den auftrag zum sniffen gegeben hast. restart und nix ging mehr.

mittlerweile bin ich auch der meinung, dass es nicht an hm liegt - hab eben den letzten schalter eingebunden und die sache rennt auch nach nem restart. jetzt noch n reboot.
bleiben tut das komische verhalten beim neustart, dass der hmlan disconnected.

den "bösen" hab ich bis jetzt noch nicht gefunden. aber jetzt kanns nur mehr zusatzzeug sein wie z.b. regenmengen-modul, abs-temperatur oder auch anderes wetterzeug - was weiß ich?
oder schlicht und ergreifend ein schreibfehler in der cfg, denn ich dann brav beim "retten" immer mitgenommen hab - das wär wohl das lustigste ...

aber wie gsagt - ich weiß noch ned, worans wirklich liegt. noch hab ich genug devices, doifs, rg's, ... zum neu eintragen. irgendwas davon hat immerhin 3 vollständig getrennte systeme zum absturz gebracht.


@Sebigamer4
nicht nur in der vm - das waren ja 2 vm's (davon eine neu installiert) und am nachmittag der frisch installierte raspi als nr. 3
aber ich taste mich ran *g*

[the ratman]

o.k. wieder einmal erweist sich: die größten fehler befinden sich zwischen tastatur und sessel - asche auf mein haupt und sorry, dass ich euch alle irre gemacht hab.

und damits wenigstens was zum ausgiebigen lachen habts, hier der fehler:


ein doif für div. automatische sachen. hab ich mir zum testen gebaut, um zu sehen, was da so alles geht.

ein zweig z.b. mountet automatisch einen share - damit dies auch sicher passiert hab ich beim doif im attr startup diesen zweig nach 5 sek. ausführen lassen.
ein anderer zweig war zum automatischen shutdown gedacht - z.b.: wenn der prozzi zu heiß wird, usw.
das ging auch alles super
nun kam ein neuer zweig dazu (gleich als nr. 1 drüber geschrieben). der soll den fhem-server up2date halten. soweit so gut - könnts auch schon denken, was passiert is?
nö?
na dann - jetzt kommt die lachnummer - aufgepasst:
natürlich hab ich vergessen das startup-attr anzupassen. somit hat fhem genau gemacht, was ich angeschafft hab - es hat nach exakt 5 sek. den alten zweig aufgerufen, der vorher der automount war, neu nun aber der shutdown.

juhuu ... ich bin der größte depp, den es in diesem forum gibt. ihr dürfts mir gratulieren ...

[frank]

immerhin selbst gefunden. 

steht ja eigentlich auch schon im log. 
vielleicht spendierst du noch ne extra logzeile bei automatischem shutdown, sodass du es nächstes mal schneller erkennst.