DietPi v6.28.0 Wifi drop update: freezed networking

kriznik · 3 February 2020 19:07

Hi,
firstly thank you for great piece of software I’m using for year or so.

However I need bit of help with newest DietPi v6.28.0 . As after upgrade from 6.26 my wifi connection is not re-established when dropped, which is very annoying as this is garden-pi controlling my lights.

It was not happening at 6.26 with uptime over 150days without any issue. Now it drops connection like after day ish and is not able to reestablish it anyhow.
I need to do power on/off to reboot and reestablish it as it is headless indeed.

Is there any known ongoing issue with wifi manager ? can i debug it somehow, can i do something other than somehow downgrade to 6.26 (which I really considering as quick solution)

Thanks for reply!
K.

Joulinar · 3 February 2020 19:54

Hi,

there is no known issue using WiFi on v6.28. It might be needed to open a Report on GitHub. However it will be hard to debug without logs or error messages from the system.

https://github.com/MichaIng/DietPi

kriznik · 3 February 2020 20:36

yeah sadly there is nothing in logs as i can’t get to the device without wifi ;/
crap

Joulinar · 3 February 2020 21:06

Well you could increase DietPi log level and check if there will be something after you restarted the system.

dietpi-software → change “Log System” to FULL

This will install rsyslog and create log files on /var/log/*

kriznik · 5 February 2020 16:04

ok so logs … are kind of weird
It happened again and last logs (before hardreset which was around 16:52)

seems like it kind of restarted itself around 14:17 for some reason? but last received data via wifi were around 14:36 ish
and then there is a gape till the hard restart where is nothing in the logs, so basically looks like system completely frozen?
any idea?

auth.log

Feb  5 14:36:02 garden sudo:   dietpi : TTY=unknown ; PWD=/home/dietpi ; USER=root ; COMMAND=/usr/bin/python3 /home/dietpi/scripts/temperature.py
Feb  5 14:36:03 garden sudo: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb  5 14:36:03 garden sudo: pam_unix(sudo:session): session opened for user root by (uid=0)
Feb  5 14:36:04 garden sudo: pam_unix(sudo:session): session closed for user root
Feb  5 14:36:05 garden sudo: pam_unix(sudo:session): session closed for user root
Feb  5 16:52:53 garden dropbear[612]: Running in background
Feb  5 16:52:57 garden dropbear[627]: Child connection from 10.10.1.xxx:58997
Feb  5 16:52:57 garden dropbear[627]: Pubkey auth succeeded for 'dietpi'

daemon.log (seems like it restarted itself at 14:17?)

Feb  5 14:26:32 garden wpa_supplicant[9466]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb  5 14:29:08 garden wpa_supplicant[9466]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb  5 14:29:09 garden wpa_supplicant[9466]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb  5 14:34:20 garden wpa_supplicant[9466]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb  5 14:36:58 garden wpa_supplicant[9466]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb  5 14:17:18 garden systemd-modules-load[69]: Inserted module 'i2c_bcm2708'
Feb  5 14:17:18 garden systemd-modules-load[69]: Inserted module 'i2c_dev'
Feb  5 14:17:18 garden fake-hwclock[73]: Wed  5 Feb 13:17:01 UTC 2020
Feb  5 14:17:18 garden systemd-fsck[92]: e2fsck 1.44.5 (15-Dec-2018)
Feb  5 14:17:18 garden systemd-fsck[92]: /dev/sda1: clean, 58417/938400 files, 435280/3753472 blocks
Feb  5 14:17:18 garden systemd[1]: Started Set the console keyboard layout.
Feb  5 14:17:18 garden systemd[1]: Started udev Coldplug all Devices.
Feb  5 14:17:18 garden systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Feb  5 14:17:18 garden systemd[1]: Started Helper to synchronize boot up for ifupdown.
Feb  5 14:17:18 garden systemd[1]: Started Remount Root and Kernel File Systems.
Feb  5 14:17:18 garden systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Feb  5 14:17:18 garden systemd[1]: Starting Load/Save Random Seed...
Feb  5 14:17:18 garden systemd[1]: Starting Create System Users...
Feb  5 14:17:18 garden systemd[1]: Starting Flush Journal to Persistent Storage...
Feb  5 14:17:18 garden systemd[1]: Started Load/Save Random Seed.
Feb  5 14:17:18 garden systemd[1]: Started Create System Users.
Feb  5 14:17:18 garden systemd[1]: Started Flush Journal to Persistent Storage.
Feb  5 14:17:18 garden systemd[1]: Starting Create Static Device Nodes in /dev...
Feb  5 14:17:18 garden systemd[1]: Started Create Static Device Nodes in /dev.
Feb  5 14:17:18 garden systemd[1]: Reached target Local File Systems (Pre).
Feb  5 14:17:18 garden systemd[1]: DietPi.mount: Directory /DietPi to mount over is not empty, mounting anyway.
Feb  5 14:17:18 garden systemd[1]: Mounting /DietPi...
Feb  5 14:17:18 garden systemd[1]: Mounting /var/data...
...
...
Feb  5 14:17:30 garden systemd[1]: Reached target System Time Synchronized.
Feb  5 14:17:30 garden dhclient[414]: DHCPREQUEST for 10.10.1.55 on wlan0 to 255.255.255.255 port 67
Feb  5 14:17:30 garden sh[314]: DHCPREQUEST for 10.10.1.55 on wlan0 to 255.255.255.255 port 67
Feb  5 14:17:31 garden sh[314]: DHCPACK of 10.10.1.55 from 10.10.1.1
Feb  5 14:17:31 garden dhclient[414]: DHCPACK of 10.10.1.55 from 10.10.1.1
Feb  5 14:17:31 garden dhclient[414]: bound to 10.10.1.55 -- renewal in 40699 seconds.
Feb  5 14:17:31 garden sh[314]: bound to 10.10.1.55 -- renewal in 40699 seconds.
Feb  5 14:17:32 garden sh[314]: wlan0=wlan0
Feb  5 14:17:43 garden systemd[1]: systemd-fsckd.service: Succeeded.
Feb  5 16:52:52 garden systemd[1]: Stopping Network Time Synchronization...
Feb  5 16:52:52 garden systemd[1]: systemd-timesyncd.service: Succeeded.
Feb  5 16:52:52 garden systemd[1]: Stopped Network Time Synchronization.
Feb  5 16:52:52 garden systemd[1]: Started DietPi-Boot.

syslog

Feb  5 14:36:02 garden CRON[19210]: (dietpi) CMD (sudo python3 ~/scripts/temperature.py &)
Feb  5 14:36:58 garden wpa_supplicant[9466]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb  5 14:17:18 garden systemd-modules-load[69]: Inserted module 'i2c_bcm2708'
Feb  5 14:17:18 garden systemd-modules-load[69]: Inserted module 'i2c_dev'
Feb  5 14:17:18 garden fake-hwclock[73]: Wed  5 Feb 13:17:01 UTC 2020
Feb  5 14:17:18 garden systemd-fsck[92]: e2fsck 1.44.5 (15-Dec-2018)
Feb  5 14:17:18 garden systemd-fsck[92]: /dev/sda1: clean, 58417/938400 files, 435280/3753472 blocks
Feb  5 14:17:18 garden systemd[1]: Started Set the console keyboard layout.
Feb  5 14:17:18 garden systemd[1]: Started udev Coldplug all Devices.
Feb  5 14:17:18 garden systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Feb  5 14:17:18 garden systemd[1]: Started Helper to synchronize boot up for ifupdown.
Feb  5 14:17:18 garden systemd[1]: Started Remount Root and Kernel File Systems.
Feb  5 14:17:18 garden kernel: [    0.000000] Booting Linux on physical CPU 0x0
Feb  5 14:17:18 garden kernel: [    0.000000] Linux version 4.19.93+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1290 Fri Jan 10 16:34:37 GMT 2020
Feb  5 14:17:18 garden kernel: [    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Feb  5 14:17:18 garden kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Feb  5 14:17:18 garden kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi Model B Rev 2
Feb  5 14:17:18 garden kernel: [    0.000000] Memory policy: Data cache writeback
Feb  5 14:17:18 garden kernel: [    0.000000] cma: Reserved 8 MiB at 0x0e400000
Feb  5 14:17:18 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  5 14:17:18 garden kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x8c/0x494 with crng_init=0
Feb  5 14:17:18 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  5 14:17:18 garden kernel: [    0.000000] pcpu-alloc: [0] 0
...
...
..
Feb  5 14:17:18 garden kernel: [    0.822027] Multiprocessor Interrupt Enhancement - disabled
Feb  5 14:17:18 garden systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Feb  5 14:17:18 garden systemd[1]: Starting Load/Save Random Seed...
Feb  5 14:17:18 garden systemd[1]: Starting Create System Users...
Feb  5 14:17:18 garden systemd[1]: Starting Flush Journal to Persistent Storage...
Feb  5 14:17:18 garden systemd[1]: Started Load/Save Random Seed.
Feb  5 14:17:18 garden systemd[1]: Started Create System Users.
Feb  5 14:17:18 garden systemd[1]: Started Flush Journal to Persistent Storage.
Feb  5 14:17:18 garden systemd[1]: Starting Create Static Device Nodes in /dev...
Feb  5 14:17:18 garden systemd[1]: Started Create Static Device Nodes in /dev.
Feb  5 14:17:18 garden systemd[1]: Reached target Local File Systems (Pre).
Feb  5 14:17:18 garden systemd[1]: DietPi.mount: Directory /DietPi to mount over is not empty, mounting anyway.
Feb  5 14:17:18 garden systemd[1]: Mounting /DietPi...
Feb  5 14:17:18 garden systemd[1]: Mounting /var/data...
...
...
...
Feb  5 14:17:31 garden sh[314]: bound to 10.10.1.55 -- renewal in 40699 seconds.
Feb  5 14:17:32 garden sh[314]: wlan0=wlan0
Feb  5 14:17:43 garden systemd[1]: systemd-fsckd.service: Succeeded.
Feb  5 16:52:50 garden systemd-timesyncd[452]: Synchronized to time server for the first time 37.187.104.44:123 (2.debian.pool.ntp.org).
Feb  5 16:52:52 garden systemd[1]: Stopping Network Time Synchronization...
Feb  5 16:52:52 garden systemd[1]: systemd-timesyncd.service: Succeeded.
Feb  5 16:52:52 garden systemd[1]: Stopped Network Time Synchronization.
Feb  5 16:52:52 garden systemd[1]: Started DietPi-Boot.

btw this is in debug
but yesterday around 20:03 it was working if i can recon correctly. But today 14:17 kind of coresponds with where issues started maybe?

Feb  4 20:03:22 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  4 20:03:22 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  4 20:03:22 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  4 20:03:22 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  4 20:03:22 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  4 20:03:22 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  4 20:03:22 garden kernel: [    0.825768] dwc_otg: Microframe scheduler enabled
Feb  4 20:03:23 garden kernel: [    0.833938] dwc_otg: FIQ enabled
Feb  4 20:03:23 garden kernel: [    0.833949] dwc_otg: NAK holdoff enabled
Feb  4 20:03:23 garden kernel: [    0.833955] dwc_otg: FIQ split-transaction FSM enabled
Feb  4 20:03:23 garden kernel: [    0.833973] Module dwc_common_port init
Feb  4 20:03:23 garden kernel: [    3.291366] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  4 20:03:23 garden kernel: [   26.595840] ath: EEPROM regdomain: 0x809c
Feb  4 20:03:23 garden kernel: [   26.595847] ath: EEPROM indicates we should expect a country code
Feb  4 20:03:23 garden kernel: [   26.595856] ath: doing EEPROM country->regdmn map search
Feb  4 20:03:23 garden kernel: [   26.595864] ath: country maps to regdmn code: 0x52
Feb  4 20:03:23 garden kernel: [   26.595872] ath: Country alpha2 being used: CN
Feb  4 20:03:23 garden kernel: [   26.595878] ath: Regpair used: 0x52
Feb  5 14:17:18 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  5 14:17:18 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  5 14:17:18 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  5 14:17:18 garden kernel: [    0.825760] dwc_otg: Microframe scheduler enabled
Feb  5 14:17:18 garden kernel: [    0.833920] dwc_otg: FIQ enabled
Feb  5 14:17:18 garden kernel: [    0.833931] dwc_otg: NAK holdoff enabled
Feb  5 14:17:18 garden kernel: [    0.833937] dwc_otg: FIQ split-transaction FSM enabled
Feb  5 14:17:18 garden kernel: [    0.833955] Module dwc_common_port init
Feb  5 14:17:18 garden kernel: [    3.289836] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  5 14:17:18 garden kernel: [   27.846853] ath: EEPROM regdomain: 0x809c
Feb  5 14:17:18 garden kernel: [   27.846860] ath: EEPROM indicates we should expect a country code
Feb  5 14:17:18 garden kernel: [   27.846868] ath: doing EEPROM country->regdmn map search
Feb  5 14:17:18 garden kernel: [   27.846875] ath: country maps to regdmn code: 0x52
Feb  5 14:17:18 garden kernel: [   27.846884] ath: Country alpha2 being used: CN
Feb  5 14:17:18 garden kernel: [   27.846890] ath: Regpair used: 0x52

Joulinar · 6 February 2020 11:41

indeed it seems your system was restarted at 14:17. What I find strange, that boot process seems stopped between 14:17 and 16:52. at least there are no lines on your logfile

Feb  5 14:17:43 garden systemd[1]: systemd-fsckd.service: Succeeded.
Feb  5 16:52:50 garden systemd-timesyncd[452]: Synchronized to time server for the first time 37.187.104.44:123 (2.debian.pool.ntp.org).
Feb  5 16:52:52 garden systemd[1]: Stopping Network Time Synchronization...
Feb  5 16:52:52 garden systemd[1]: systemd-timesyncd.service: Succeeded.
Feb  5 16:52:52 garden systemd[1]: Stopped Network Time Synchronization.

can you check the time sync service? I’m missing the lines where the time sync was started. As time sync is part of the boot process, it could indicate when system was started.

journalctl -u systemd-timesyncd.service

Next to that, did you checked your power supply? It’s quite strange that your system reboots out of the blue.

kriznik · 6 February 2020 16:41

I think it’s dietpi itself freezing/restarting … (now again ded from 8:12am - need to poweroff/on again)

but physically power is there, everything is powered, pi is up, wifi icon is green, but system is not accessible.
It looks like it’s “rebooting” but more like internally which then somehow freezes it.

Will try to post complete logs from this morning, when it become available again

kriznik · 6 February 2020 16:58

debug
this is strange, 20pm and 23pm i restarted it manually, 14:17 and 7:17 is something causing “restart-freeze”
which is exactly at 17th minute

Feb  4 20:03:22 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  4 20:03:22 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  4 20:03:22 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  4 20:03:22 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  4 20:03:22 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  4 20:03:22 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  4 20:03:22 garden kernel: [    0.825768] dwc_otg: Microframe scheduler enabled
Feb  4 20:03:23 garden kernel: [    0.833938] dwc_otg: FIQ enabled
Feb  4 20:03:23 garden kernel: [    0.833949] dwc_otg: NAK holdoff enabled
Feb  4 20:03:23 garden kernel: [    0.833955] dwc_otg: FIQ split-transaction FSM enabled
Feb  4 20:03:23 garden kernel: [    0.833973] Module dwc_common_port init
Feb  4 20:03:23 garden kernel: [    3.291366] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  4 20:03:23 garden kernel: [   26.595840] ath: EEPROM regdomain: 0x809c
Feb  4 20:03:23 garden kernel: [   26.595847] ath: EEPROM indicates we should expect a country code
Feb  4 20:03:23 garden kernel: [   26.595856] ath: doing EEPROM country->regdmn map search
Feb  4 20:03:23 garden kernel: [   26.595864] ath: country maps to regdmn code: 0x52
Feb  4 20:03:23 garden kernel: [   26.595872] ath: Country alpha2 being used: CN
Feb  4 20:03:23 garden kernel: [   26.595878] ath: Regpair used: 0x52
Feb  5 14:17:18 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  5 14:17:18 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  5 14:17:18 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  5 14:17:18 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  5 14:17:18 garden kernel: [    0.825760] dwc_otg: Microframe scheduler enabled
Feb  5 14:17:18 garden kernel: [    0.833920] dwc_otg: FIQ enabled
Feb  5 14:17:18 garden kernel: [    0.833931] dwc_otg: NAK holdoff enabled
Feb  5 14:17:18 garden kernel: [    0.833937] dwc_otg: FIQ split-transaction FSM enabled
Feb  5 14:17:18 garden kernel: [    0.833955] Module dwc_common_port init
Feb  5 14:17:18 garden kernel: [    3.289836] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  5 14:17:18 garden kernel: [   27.846853] ath: EEPROM regdomain: 0x809c
Feb  5 14:17:18 garden kernel: [   27.846860] ath: EEPROM indicates we should expect a country code
Feb  5 14:17:18 garden kernel: [   27.846868] ath: doing EEPROM country->regdmn map search
Feb  5 14:17:18 garden kernel: [   27.846875] ath: country maps to regdmn code: 0x52
Feb  5 14:17:18 garden kernel: [   27.846884] ath: Country alpha2 being used: CN
Feb  5 14:17:18 garden kernel: [   27.846890] ath: Regpair used: 0x52
Feb  5 23:01:34 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  5 23:01:34 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  5 23:01:34 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  5 23:01:34 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  5 23:01:34 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  5 23:01:34 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  5 23:01:34 garden kernel: [    0.825818] dwc_otg: Microframe scheduler enabled
Feb  5 23:01:34 garden kernel: [    0.833992] dwc_otg: FIQ enabled
Feb  5 23:01:34 garden kernel: [    0.834003] dwc_otg: NAK holdoff enabled
Feb  5 23:01:34 garden kernel: [    0.834009] dwc_otg: FIQ split-transaction FSM enabled
Feb  5 23:01:34 garden kernel: [    0.834027] Module dwc_common_port init
Feb  5 23:01:34 garden kernel: [    3.291420] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  5 23:01:34 garden kernel: [   26.866783] ath: EEPROM regdomain: 0x809c
Feb  5 23:01:34 garden kernel: [   26.866826] ath: EEPROM indicates we should expect a country code
Feb  5 23:01:34 garden kernel: [   26.866839] ath: doing EEPROM country->regdmn map search
Feb  5 23:01:34 garden kernel: [   26.866847] ath: country maps to regdmn code: 0x52
Feb  5 23:01:34 garden kernel: [   26.866856] ath: Country alpha2 being used: CN
Feb  5 23:01:34 garden kernel: [   26.866862] ath: Regpair used: 0x52
Feb  6 07:17:19 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  6 07:17:19 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  6 07:17:19 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  6 07:17:19 garden kernel: [    0.825715] dwc_otg: Microframe scheduler enabled
Feb  6 07:17:19 garden kernel: [    0.833875] dwc_otg: FIQ enabled
Feb  6 07:17:19 garden kernel: [    0.833887] dwc_otg: NAK holdoff enabled
Feb  6 07:17:19 garden kernel: [    0.833893] dwc_otg: FIQ split-transaction FSM enabled
Feb  6 07:17:19 garden kernel: [    0.833911] Module dwc_common_port init
Feb  6 07:17:19 garden kernel: [    3.289831] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  6 07:17:19 garden kernel: [   27.646858] ath: EEPROM regdomain: 0x809c
Feb  6 07:17:19 garden kernel: [   27.646865] ath: EEPROM indicates we should expect a country code
Feb  6 07:17:19 garden kernel: [   27.646873] ath: doing EEPROM country->regdmn map search
Feb  6 07:17:19 garden kernel: [   27.646880] ath: country maps to regdmn code: 0x52
Feb  6 07:17:19 garden kernel: [   27.646889] ath: Country alpha2 being used: CN
Feb  6 07:17:19 garden kernel: [   27.646894] ath: Regpair used: 0x52

and it corresponds with: (syslog)

Feb  6 07:17:02 garden CRON[24050]: (root) CMD (cd / && run-parts --report /etc/cron.hourly)

journalctl -u systemd-timesyncd.service

looks like my dietpi does not have one → journalctl: command not found

kriznik · 6 February 2020 17:03

and strange syslog (17:43 power/on off)
it was running from 7:17 till 8:12 where its last info in log as well as in my mqtt as received status update
(and empty line between 8:12 and 7:17 it’s there full of nulls it’s not editor error)

Feb  6 08:00:08 garden kernel: [32323.451112] w1_master_driver w1_bus_master1: Attaching one wire slave 28.031661a80eff crc f5
Feb  6 08:01:56 garden rngd[207]: stats: bits received from HRNG source: 320064
Feb  6 08:01:56 garden rngd[207]: stats: bits sent to kernel pool: 273824
Feb  6 08:01:56 garden rngd[207]: stats: entropy added to kernel pool: 273824
Feb  6 08:01:56 garden rngd[207]: stats: FIPS 140-2 successes: 16
Feb  6 08:01:56 garden rngd[207]: stats: FIPS 140-2 failures: 0
Feb  6 08:01:56 garden rngd[207]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Feb  6 08:01:56 garden rngd[207]: stats: FIPS 140-2(2001-10-10) Poker: 0
Feb  6 08:01:56 garden rngd[207]: stats: FIPS 140-2(2001-10-10) Runs: 0
Feb  6 08:01:56 garden rngd[207]: stats: FIPS 140-2(2001-10-10) Long run: 0
Feb  6 08:01:56 garden rngd[207]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Feb  6 08:01:56 garden rngd[207]: stats: HRNG source speed: (min=227.719; avg=605.585; max=917.348)Kibits/s
Feb  6 08:01:56 garden rngd[207]: stats: FIPS tests speed: (min=637.797; avg=2956.369; max=6960.531)Kibits/s
Feb  6 08:01:56 garden rngd[207]: stats: Lowest ready-buffers level: 2
Feb  6 08:01:56 garden rngd[207]: stats: Entropy starvations: 0
Feb  6 08:01:56 garden rngd[207]: stats: Time spent starving for entropy: (min=0; avg=0.000; max=0)us
Feb  6 08:03:01 garden CRON[26134]: (dietpi) CMD (sudo python3 ~/scripts/lux.py &)
Feb  6 08:04:01 garden CRON[26181]: (dietpi) CMD (sudo python3 ~/scripts/temperature.py &)
Feb  6 08:05:01 garden CRON[26223]: (dietpi) CMD (sudo python3 ~/scripts/rain.py &)
Feb  6 08:06:01 garden CRON[26270]: (dietpi) CMD (sudo python3 ~/scripts/lux.py &)
Feb  6 08:08:01 garden CRON[26349]: (dietpi) CMD (sudo python3 ~/scripts/temperature.py &)
Feb  6 08:09:01 garden CRON[26396]: (dietpi) CMD (sudo python3 ~/scripts/lux.py &)
Feb  6 08:10:01 garden CRON[26443]: (dietpi) CMD (sudo python3 ~/scripts/rain.py &)
Feb  6 08:11:01 garden CRON[26490]: (dietpi) CMD (sudo python3 ~/scripts/water.py &)
Feb  6 08:12:01 garden CRON[26542]: (dietpi) CMD (sudo python3 ~/scripts/lux.py &)
Feb  6 08:12:01 garden CRON[26543]: (dietpi) CMD (sudo python3 ~/scripts/temperature.py &)

Feb  6 07:17:19 garden systemd-modules-load[66]: Inserted module 'i2c_bcm2708'
Feb  6 07:17:19 garden systemd-modules-load[66]: Inserted module 'i2c_dev'
Feb  6 07:17:19 garden fake-hwclock[69]: Thu  6 Feb 06:17:02 UTC 2020
Feb  6 07:17:19 garden systemd-fsck[92]: e2fsck 1.44.5 (15-Dec-2018)
Feb  6 07:17:19 garden systemd-fsck[92]: /dev/sda1: clean, 58419/938400 files, 435467/3753472 blocks
Feb  6 07:17:19 garden systemd[1]: Started File System Check on Root Device.
Feb  6 07:17:19 garden systemd[1]: Starting Remount Root and Kernel File Systems...
Feb  6 07:17:19 garden systemd[1]: Started Set the console keyboard layout.
Feb  6 07:17:19 garden systemd[1]: Started udev Coldplug all Devices.
Feb  6 07:17:19 garden systemd[1]: Started Remount Root and Kernel File Systems.
Feb  6 07:17:19 garden systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Feb  6 07:17:19 garden systemd[1]: Starting Flush Journal to Persistent Storage...
Feb  6 07:17:19 garden systemd[1]: Starting Create System Users...
Feb  6 07:17:19 garden systemd[1]: Starting Load/Save Random Seed...
Feb  6 07:17:19 garden systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Feb  6 07:17:19 garden systemd[1]: Started Helper to synchronize boot up for ifupdown.
Feb  6 07:17:19 garden systemd[1]: Started Flush Journal to Persistent Storage.
Feb  6 07:17:19 garden systemd[1]: Started Create System Users.
Feb  6 07:17:19 garden systemd[1]: Started Load/Save Random Seed.
Feb  6 07:17:19 garden systemd[1]: Starting Create Static Device Nodes in /dev...
Feb  6 07:17:19 garden systemd[1]: Started Create Static Device Nodes in /dev.
Feb  6 07:17:19 garden systemd[1]: Starting udev Kernel Device Manager...
Feb  6 07:17:19 garden systemd[1]: Reached target Local File Systems (Pre).
Feb  6 07:17:19 garden systemd[1]: Mounting /var/data...
Feb  6 07:17:19 garden systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Feb  6 07:17:19 garden systemd[1]: Mounting /tmp...
Feb  6 07:17:19 garden systemd[1]: DietPi.mount: Directory /DietPi to mount over is not empty, mounting anyway.
Feb  6 07:17:19 garden systemd[1]: Mounting /DietPi...
Feb  6 07:17:19 garden systemd[1]: Mounted /var/data.
Feb  6 07:17:19 garden kernel: [    0.000000] Booting Linux on physical CPU 0x0
Feb  6 07:17:19 garden kernel: [    0.000000] Linux version 4.19.93+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1290 Fri Jan 10 16:34:37 GMT 2020
Feb  6 07:17:19 garden kernel: [    0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
Feb  6 07:17:19 garden kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
Feb  6 07:17:19 garden kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi Model B Rev 2
Feb  6 07:17:19 garden kernel: [    0.000000] Memory policy: Data cache writeback
Feb  6 07:17:19 garden kernel: [    0.000000] cma: Reserved 8 MiB at 0x0e400000
Feb  6 07:17:19 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  6 07:17:19 garden kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x8c/0x494 with crng_init=0
Feb  6 07:17:19 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  6 07:17:19 garden kernel: [    0.000000] pcpu-alloc: [0] 0 
Feb  6 07:17:19 garden kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 60900
Feb  6 07:17:19 garden kernel: [    0.000000] Kernel command line: coherent_pool=1M bcm2708_fb.fbwidth=16 bcm2708_fb.fbheight=16 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:9C:FA:95 vc_mem.mem_base=0xfa00000 vc_mem.mem_size=0x10000000  dwc_otg.lpm_enable=0 console=ttyAMA0,115200 console=tty1 rootdelay=10 root=PARTUUID=07eac884-a11d-4168-b01c-4130209852f4 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
Feb  6 07:17:19 garden kernel: [    0.000000] Dentry cache hash table entries: 32768 (order: 5, 131072 bytes)
Feb  6 07:17:19 garden kernel: [    0.000000] Inode-cache hash table entries: 16384 (order: 4, 65536 bytes)
Feb  6 07:17:19 garden kernel: [    0.000000] Memory: 223232K/245760K available (7142K kernel code, 656K rwdata, 2128K rodata, 468K init, 797K bss, 14336K reserved, 8192K cma-reserved)
Feb  6 07:17:19 garden kernel: [    0.000000] Virtual kernel memory layout:
Feb  6 07:17:19 garden kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Feb  6 07:17:19 garden kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Feb  6 07:17:19 garden kernel: [    0.000000]     vmalloc : 0xcf800000 - 0xff800000   ( 768 MB)
Feb  6 07:17:19 garden kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xcf000000   ( 240 MB)
Feb  6 07:17:19 garden kernel: [    0.000000]     modules : 0xbf000000 - 0xc0000000   (  16 MB)
Feb  6 07:17:19 garden kernel: [    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (7144 kB)
Feb  6 07:17:19 garden kernel: [    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   ( 468 kB)
Feb  6 07:17:19 garden kernel: [    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 657 kB)
Feb  6 07:17:19 garden kernel: [    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 798 kB)
Feb  6 07:17:19 garden kernel: [    0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Feb  6 07:17:19 garden kernel: [    0.000000] ftrace: allocating 25651 entries in 76 pages
Feb  6 07:17:19 garden kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Feb  6 07:17:19 garden kernel: [    0.000027] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
Feb  6 07:17:19 garden kernel: [    0.000083] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
Feb  6 07:17:19 garden kernel: [    0.000202] bcm2835: system timer (irq = 27)
Feb  6 07:17:19 garden kernel: [    0.000580] Console: colour dummy device 80x30
Feb  6 07:17:19 garden kernel: [    0.001127] console [tty1] enabled
Feb  6 07:17:19 garden kernel: [    0.001202] Calibrating delay loop... 898.66 BogoMIPS (lpj=4493312)
Feb  6 07:17:19 garden kernel: [    0.090429] pid_max: default: 32768 minimum: 301
Feb  6 07:17:19 garden kernel: [    0.090917] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Feb  6 07:17:19 garden kernel: [    0.090970] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Feb  6 07:17:19 garden kernel: [    0.092271] CPU: Testing write buffer coherency: ok
Feb  6 07:17:19 garden kernel: [    0.093543] Setting up static identity map for 0x8200 - 0x8238
Feb  6 07:17:19 garden kernel: [    0.094833] devtmpfs: initialized
Feb  6 07:17:19 garden kernel: [    0.104043] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
Feb  6 07:17:19 garden kernel: [    0.104482] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Feb  6 07:17:19 garden kernel: [    0.104550] futex hash table entries: 256 (order: -1, 3072 bytes)
Feb  6 07:17:19 garden kernel: [    0.105784] pinctrl core: initialized pinctrl subsystem
Feb  6 07:17:19 garden kernel: [    0.107272] NET: Registered protocol family 16
Feb  6 07:17:19 garden kernel: [    0.110108] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Feb  6 07:17:19 garden kernel: [    0.116545] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
Feb  6 07:17:19 garden kernel: [    0.116617] hw-breakpoint: maximum watchpoint size is 4 bytes.
Feb  6 07:17:19 garden kernel: [    0.116768] Serial: AMBA PL011 UART driver
Feb  6 07:17:19 garden kernel: [    0.119848] bcm2835-mbox 2000b880.mailbox: mailbox enabled
Feb  6 07:17:19 garden kernel: [    0.157250] bcm2835-dma 20007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1
Feb  6 07:17:19 garden kernel: [    0.159561] SCSI subsystem initialized
Feb  6 07:17:19 garden kernel: [    0.159862] usbcore: registered new interface driver usbfs
Feb  6 07:17:19 garden kernel: [    0.160010] usbcore: registered new interface driver hub
Feb  6 07:17:19 garden kernel: [    0.160254] usbcore: registered new device driver usb
Feb  6 07:17:19 garden kernel: [    0.180864] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-01-10 17:01, variant start_cd
Feb  6 07:17:19 garden kernel: [    0.190888] raspberrypi-firmware soc:firmware: Firmware hash is f3e39fed4763ce4efbffabb2d4787aaacddeda0f
Feb  6 07:17:19 garden kernel: [    0.203139] clocksource: Switched to clocksource timer
Feb  6 07:17:19 garden kernel: [    0.292430] VFS: Disk quotas dquot_6.6.0
Feb  6 07:17:19 garden kernel: [    0.292603] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Feb  6 07:17:19 garden kernel: [    0.292927] FS-Cache: Loaded
Feb  6 07:17:19 garden kernel: [    0.293382] CacheFiles: Loaded
Feb  6 07:17:19 garden kernel: [    0.311031] NET: Registered protocol family 2
Feb  6 07:17:19 garden kernel: [    0.312272] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes)
Feb  6 07:17:19 garden kernel: [    0.312386] TCP established hash table entries: 2048 (order: 1, 8192 bytes)
Feb  6 07:17:19 garden kernel: [    0.312456] TCP bind hash table entries: 2048 (order: 1, 8192 bytes)
Feb  6 07:17:19 garden kernel: [    0.312522] TCP: Hash tables configured (established 2048 bind 2048)
Feb  6 07:17:19 garden kernel: [    0.312712] UDP hash table entries: 256 (order: 0, 4096 bytes)
Feb  6 07:17:19 garden kernel: [    0.312773] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Feb  6 07:17:19 garden kernel: [    0.313295] NET: Registered protocol family 1
Feb  6 07:17:19 garden kernel: [    0.314358] RPC: Registered named UNIX socket transport module.
Feb  6 07:17:19 garden kernel: [    0.314417] RPC: Registered udp transport module.
Feb  6 07:17:19 garden kernel: [    0.314443] RPC: Registered tcp transport module.
Feb  6 07:17:19 garden kernel: [    0.314468] RPC: Registered tcp NFSv4.1 backchannel transport module.
Feb  6 07:17:19 garden kernel: [    0.316428] hw perfevents: no irqs for PMU, sampling events not supported
Feb  6 07:17:19 garden kernel: [    0.316545] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
Feb  6 07:17:19 garden kernel: [    0.320486] Initialise system trusted keyrings
Feb  6 07:17:19 garden kernel: [    0.320914] workingset: timestamp_bits=14 max_order=16 bucket_order=2
Feb  6 07:17:19 garden kernel: [    0.334598] FS-Cache: Netfs 'nfs' registered for caching
Feb  6 07:17:19 garden kernel: [    0.335861] NFS: Registering the id_resolver key type
Feb  6 07:17:19 garden kernel: [    0.335956] Key type id_resolver registered
Feb  6 07:17:19 garden kernel: [    0.335987] Key type id_legacy registered
Feb  6 07:17:19 garden kernel: [    0.336033] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Feb  6 07:17:19 garden kernel: [    0.341147] Key type asymmetric registered
Feb  6 07:17:19 garden kernel: [    0.341212] Asymmetric key parser 'x509' registered
Feb  6 07:17:19 garden kernel: [    0.341339] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Feb  6 07:17:19 garden kernel: [    0.341809] io scheduler noop registered
Feb  6 07:17:19 garden kernel: [    0.341862] io scheduler deadline registered (default)
Feb  6 07:17:19 garden kernel: [    0.342423] io scheduler cfq registered
Feb  6 07:17:19 garden kernel: [    0.342471] io scheduler mq-deadline registered (default)
Feb  6 07:17:19 garden kernel: [    0.342500] io scheduler kyber registered
Feb  6 07:17:19 garden kernel: [    0.345138] bcm2708_fb soc:fb: FB found 1 display(s)
Feb  6 07:17:19 garden kernel: [    0.347939] Console: switching to colour frame buffer device 2x2
Feb  6 07:17:19 garden kernel: [    0.348914] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 16x16
Feb  6 07:17:19 garden kernel: [    0.352743] bcm2835-rng 20104000.rng: hwrng registered
Feb  6 07:17:19 garden kernel: [    0.353615] vc-mem: phys_addr:0x00000000 mem_base=0x0fa00000 mem_size:0x10000000(256 MiB)
Feb  6 07:17:19 garden kernel: [    0.355188] vc-sm: Videocore shared memory driver
Feb  6 07:17:19 garden kernel: [    0.356122] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
Feb  6 07:17:19 garden kernel: [    0.375145] brd: module loaded
Feb  6 07:17:19 garden kernel: [    0.387713] loop: module loaded
Feb  6 07:17:19 garden kernel: [    0.388762] Loading iSCSI transport class v2.0-870.
Feb  6 07:17:19 garden kernel: [    0.390023] usbcore: registered new interface driver smsc95xx
Feb  6 07:17:19 garden kernel: [    0.390442] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Feb  6 07:17:19 garden kernel: [    0.418829] dwc_otg 20980000.usb: base=(ptrval)
Feb  6 07:17:19 garden kernel: [    0.619807] Core Release: 2.80a
Feb  6 07:17:19 garden kernel: [    0.620058] Setting default values for core params
Feb  6 07:17:19 garden kernel: [    0.620396] Finished setting default values for core params
Feb  6 07:17:19 garden kernel: [    0.821353] Using Buffer DMA mode
Feb  6 07:17:19 garden kernel: [    0.821612] Periodic Transfer Interrupt Enhancement - disabled
Feb  6 07:17:19 garden kernel: [    0.821982] Multiprocessor Interrupt Enhancement - disabled
Feb  6 07:17:19 garden systemd[1]: Mounted /tmp.
Feb  6 07:17:19 garden systemd[1]: Started udev Kernel Device Manager.
Feb  6 07:17:19 garden systemd[1]: Mounted /DietPi.
Feb  6 07:17:19 garden systemd-udevd[130]: Using default interface naming scheme 'v240'.
Feb  6 07:17:19 garden systemd[1]: Found device /dev/disk/by-partuuid/cb7b86f7-01.
Feb  6 07:17:19 garden systemd[1]: Starting File System Check on /dev/disk/by-partuuid/cb7b86f7-01...
Feb  6 07:17:19 garden systemd-fsck[174]: fsck.fat 4.1 (2017-01-24)
Feb  6 07:17:19 garden systemd-fsck[174]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Feb  6 07:17:19 garden systemd-fsck[174]:  Automatically removing dirty bit.
Feb  6 07:17:19 garden systemd-fsck[174]: Performing changes.
Feb  6 07:17:19 garden systemd-fsck[174]: /dev/mmcblk0p1: 291 files, 109748/516191 clusters
Feb  6 07:17:19 garden systemd[1]: Started File System Check on /dev/disk/by-partuuid/cb7b86f7-01.
Feb  6 07:17:19 garden systemd-udevd[128]: Using default interface naming scheme 'v240'.
Feb  6 07:17:19 garden systemd-udevd[128]: Process '/sbin/iwconfig wlan0 power off' failed with exit code 250.
Feb  6 07:17:19 garden systemd[1]: Mounting /boot...
Feb  6 07:17:19 garden systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Feb  6 07:17:19 garden systemd[1]: Starting Load/Save RF Kill Switch Status...
Feb  6 07:17:19 garden systemd[1]: Mounted /boot.
Feb  6 07:17:19 garden systemd[1]: Reached target Local File Systems.
Feb  6 07:17:19 garden systemd[1]: Starting Create Volatile Files and Directories...
Feb  6 07:17:19 garden systemd[1]: Starting Set console font and keymap...
Feb  6 07:17:19 garden systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Feb  6 07:17:19 garden systemd[1]: Started Set console font and keymap.
Feb  6 07:17:19 garden systemd[1]: Started Load/Save RF Kill Switch Status.
Feb  6 07:17:19 garden systemd[1]: Started Create Volatile Files and Directories.
Feb  6 07:17:19 garden systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Feb  6 07:17:19 garden systemd[1]: Found device AR9271 802.11n.
Feb  6 07:17:19 garden systemd[1]: Started Update UTMP about System Boot/Shutdown.
Feb  6 07:17:19 garden systemd[1]: Reached target System Initialization.
Feb  6 07:17:19 garden systemd[1]: Started Daily Cleanup of Temporary Directories.
Feb  6 07:17:19 garden systemd[1]: Started Daily rotation of log files.
Feb  6 07:17:19 garden systemd[1]: Reached target Timers.
Feb  6 07:17:19 garden systemd[1]: Reached target Basic System.
Feb  6 07:17:19 garden systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
Feb  6 07:17:19 garden systemd[1]: Starting DietPi-RAMdisk...
Feb  6 07:17:19 garden systemd[1]: Starting rng-tools.service...
Feb  6 07:17:19 garden rng-tools[204]: Starting Hardware RNG entropy gatherer daemon: rngd.
Feb  6 07:17:19 garden rngd[208]: rngd 2-unofficial-mt.14 starting up...
Feb  6 07:17:19 garden systemd[1]: Started rng-tools.service.
Feb  6 07:17:19 garden kernel: [    0.822330] OTG VER PARAM: 0, OTG VER FLAG: 0
Feb  6 07:17:19 garden kernel: [    0.822684] Dedicated Tx FIFOs mode
Feb  6 07:17:19 garden kernel: [    0.823758] WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = ce514000 dma = 0x4e514000 len=9024
Feb  6 07:17:19 garden kernel: [    0.824408] FIQ FSM acceleration enabled for :
Feb  6 07:17:19 garden kernel: [    0.824408] Non-periodic Split Transactions
Feb  6 07:17:19 garden kernel: [    0.824408] Periodic Split Transactions
Feb  6 07:17:19 garden kernel: [    0.824408] High-Speed Isochronous Endpoints
Feb  6 07:17:19 garden kernel: [    0.824408] Interrupt/Control Split Transaction hack enabled
Feb  6 07:17:19 garden kernel: [    0.825715] dwc_otg: Microframe scheduler enabled
Feb  6 07:17:19 garden kernel: [    0.825915] WARN::hcd_init_fiq:457: FIQ on core 0
Feb  6 07:17:19 garden kernel: [    0.826238] WARN::hcd_init_fiq:458: FIQ ASM at c05268b8 length 36
Feb  6 07:17:19 garden kernel: [    0.826626] WARN::hcd_init_fiq:497: MPHI regs_base at cf810000
Feb  6 07:17:19 garden kernel: [    0.827024] dwc_otg 20980000.usb: DWC OTG Controller
Feb  6 07:17:19 garden kernel: [    0.827396] dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1
Feb  6 07:17:19 garden kernel: [    0.827915] dwc_otg 20980000.usb: irq 56, io mem 0x00000000
Feb  6 07:17:19 garden kernel: [    0.828304] Init: Port Power? op_state=1
Feb  6 07:17:19 garden kernel: [    0.828560] Init: Power Port (0)
Feb  6 07:17:19 garden kernel: [    0.829132] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
Feb  6 07:17:19 garden kernel: [    0.829662] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Feb  6 07:17:19 garden kernel: [    0.830111] usb usb1: Product: DWC OTG Controller
Feb  6 07:17:19 garden kernel: [    0.830410] usb usb1: Manufacturer: Linux 4.19.93+ dwc_otg_hcd
Feb  6 07:17:19 garden kernel: [    0.830779] usb usb1: SerialNumber: 20980000.usb
Feb  6 07:17:19 garden kernel: [    0.832094] hub 1-0:1.0: USB hub found
Feb  6 07:17:19 garden kernel: [    0.832475] hub 1-0:1.0: 1 port detected
Feb  6 07:17:19 garden kernel: [    0.833875] dwc_otg: FIQ enabled
Feb  6 07:17:19 garden kernel: [    0.833887] dwc_otg: NAK holdoff enabled
Feb  6 07:17:19 garden kernel: [    0.833893] dwc_otg: FIQ split-transaction FSM enabled
Feb  6 07:17:19 garden kernel: [    0.833911] Module dwc_common_port init
Feb  6 07:17:19 garden kernel: [    0.834411] usbcore: registered new interface driver usb-storage
Feb  6 07:17:19 garden kernel: [    0.835273] mousedev: PS/2 mouse device common for all mice
Feb  6 07:17:19 garden kernel: [    0.836940] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Feb  6 07:17:19 garden kernel: [    0.837784] bcm2835-cpufreq: min=700000 max=900000
Feb  6 07:17:19 garden kernel: [    0.839328] sdhci: Secure Digital Host Controller Interface driver
Feb  6 07:17:19 garden kernel: [    0.839753] sdhci: Copyright(c) Pierre Ossman
Feb  6 07:17:19 garden kernel: [    0.840653] sdhost-bcm2835 20202000.mmc: could not get clk, deferring probe
Feb  6 07:17:19 garden kernel: [    0.841350] sdhci-pltfm: SDHCI platform and OF driver helper
Feb  6 07:17:19 garden kernel: [    0.842324] ledtrig-cpu: registered to indicate activity on CPUs
Feb  6 07:17:19 garden kernel: [    0.842857] hidraw: raw HID events driver (C) Jiri Kosina
Feb  6 07:17:19 garden kernel: [    0.843552] usbcore: registered new interface driver usbhid
Feb  6 07:17:19 garden kernel: [    0.843929] usbhid: USB HID core driver
Feb  6 07:17:19 garden kernel: [    0.845181] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0
Feb  6 07:17:19 garden kernel: [    0.847586] [vc_sm_connected_init]: start
Feb  6 07:17:19 garden kernel: [    0.851277] vc_vchi_sm_init: failed to open VCHI service (-1)
Feb  6 07:17:19 garden kernel: [    0.851292] [vc_sm_connected_init]: failed to initialize shared memory service
Feb  6 07:17:19 garden kernel: [    0.852148] [vc_sm_connected_init]: end - returning -1
Feb  6 07:17:19 garden kernel: [    0.854296] Initializing XFRM netlink socket
Feb  6 07:17:19 garden kernel: [    0.854694] NET: Registered protocol family 17
Feb  6 07:17:19 garden kernel: [    0.855146] Key type dns_resolver registered
Feb  6 07:17:19 garden kernel: [    0.857141] registered taskstats version 1
Feb  6 07:17:19 garden kernel: [    0.857465] Loading compiled-in X.509 certificates
Feb  6 07:17:19 garden kernel: [    0.868966] sdhost: log_buf @ (ptrval) (4e513000)
Feb  6 07:17:19 garden kernel: [    0.912168] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Feb  6 07:17:19 garden kernel: [    0.929948] of_cfs_init
Feb  6 07:17:19 garden kernel: [    0.930302] of_cfs_init: OK
Feb  6 07:17:19 garden kernel: [    0.931313] Waiting 10 sec before mounting root device...
Feb  6 07:17:19 garden kernel: [    0.941790] random: fast init done
Feb  6 07:17:19 garden kernel: [    0.969154] mmc0: host does not support reading read-only switch, assuming write-enable
Feb  6 07:17:19 garden kernel: [    0.972284] mmc0: new high speed SDHC card at address 59b4
Feb  6 07:17:19 garden kernel: [    0.974296] mmcblk0: mmc0:59b4 00000 7.35 GiB
Feb  6 07:17:19 garden kernel: [    0.976996]  mmcblk0: p1 p2
Feb  6 07:17:19 garden kernel: [    1.043425] Indeed it is in host mode hprt0 = 00021501
Feb  6 07:17:19 garden kernel: [    1.233220] usb 1-1: new high-speed USB device number 2 using dwc_otg
Feb  6 07:17:19 garden kernel: [    1.233855] Indeed it is in host mode hprt0 = 00001101
Feb  6 07:17:19 garden kernel: [    1.453643] usb 1-1: New USB device found, idVendor=0424, idProduct=9512, bcdDevice= 2.00
Feb  6 07:17:19 garden kernel: [    1.454230] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Feb  6 07:17:19 garden kernel: [    1.455839] hub 1-1:1.0: USB hub found
Feb  6 07:17:19 garden kernel: [    1.456311] hub 1-1:1.0: 3 ports detected
Feb  6 07:17:19 garden kernel: [    1.773223] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Feb  6 07:17:19 garden kernel: [    1.903631] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
Feb  6 07:17:19 garden kernel: [    1.904195] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Feb  6 07:17:19 garden kernel: [    1.907746] smsc95xx v1.0.6
Feb  6 07:17:19 garden kernel: [    1.998486] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-20980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:9c:fa:95
Feb  6 07:17:19 garden kernel: [    2.093209] usb 1-1.2: new high-speed USB device number 4 using dwc_otg
Feb  6 07:17:19 garden kernel: [    2.224109] usb 1-1.2: New USB device found, idVendor=0781, idProduct=5571, bcdDevice= 1.00
Feb  6 07:17:19 garden kernel: [    2.224676] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Feb  6 07:17:19 garden kernel: [    2.225134] usb 1-1.2: Product: Cruzer Fit
Feb  6 07:17:19 garden kernel: [    2.225408] usb 1-1.2: Manufacturer: SanDisk'
Feb  6 07:17:19 garden kernel: [    2.225691] usb 1-1.2: SerialNumber: 4C530000050412116035
Feb  6 07:17:19 garden kernel: [    2.227133] usb-storage 1-1.2:1.0: USB Mass Storage device detected
Feb  6 07:17:19 garden kernel: [    2.228631] scsi host0: usb-storage 1-1.2:1.0
Feb  6 07:17:19 garden kernel: [    2.323204] usb 1-1.3: new high-speed USB device number 5 using dwc_otg
Feb  6 07:17:19 garden kernel: [    2.474388] usb 1-1.3: New USB device found, idVendor=0cf3, idProduct=9271, bcdDevice= 1.08
Feb  6 07:17:19 garden kernel: [    2.474952] usb 1-1.3: New USB device strings: Mfr=16, Product=32, SerialNumber=48
Feb  6 07:17:19 garden kernel: [    2.475433] usb 1-1.3: Product: USB2.0 WLAN
Feb  6 07:17:19 garden kernel: [    2.475705] usb 1-1.3: Manufacturer: ATHEROS
Feb  6 07:17:19 garden kernel: [    2.475988] usb 1-1.3: SerialNumber: 12345
Feb  6 07:17:19 garden kernel: [    3.284747] scsi 0:0:0:0: Direct-Access     SanDisk' Cruzer Fit       1.00 PQ: 0 ANSI: 6
Feb  6 07:17:19 garden kernel: [    3.287455] sd 0:0:0:0: [sda] 30031872 512-byte logical blocks: (15.4 GB/14.3 GiB)
Feb  6 07:17:19 garden kernel: [    3.289467] sd 0:0:0:0: [sda] Write Protect is off
Feb  6 07:17:19 garden kernel: [    3.289831] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  6 07:17:19 garden kernel: [    3.290566] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA
Feb  6 07:17:19 garden kernel: [    3.366702]  sda: sda1
Feb  6 07:17:19 garden kernel: [    3.372537] sd 0:0:0:0: [sda] Attached SCSI removable disk
Feb  6 07:17:19 garden kernel: [   11.369485] EXT4-fs (sda1): INFO: recovery required on readonly filesystem
Feb  6 07:17:19 garden kernel: [   11.369986] EXT4-fs (sda1): write access will be enabled during recovery
Feb  6 07:17:19 garden kernel: [   12.418439] EXT4-fs (sda1): recovery complete
Feb  6 07:17:19 garden kernel: [   12.426800] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
Feb  6 07:17:19 garden kernel: [   12.427464] VFS: Mounted root (ext4 filesystem) readonly on device 8:1.
Feb  6 07:17:19 garden kernel: [   12.455974] devtmpfs: mounted
Feb  6 07:17:19 garden kernel: [   12.460338] Freeing unused kernel memory: 468K
Feb  6 07:17:19 garden kernel: [   12.460667] This architecture does not have kernel memory protection.
Feb  6 07:17:19 garden kernel: [   12.461070] Run /sbin/init as init process
Feb  6 07:17:19 garden kernel: [   13.638421] NET: Registered protocol family 10
Feb  6 07:17:19 garden kernel: [   13.640892] Segment Routing with IPv6
Feb  6 07:17:19 garden kernel: [   15.546328] random: crng init done
Feb  6 07:17:19 garden kernel: [   16.584995] i2c /dev entries driver
Feb  6 07:17:19 garden kernel: [   20.161254] EXT4-fs (sda1): re-mounted. Opts: (null)
Feb  6 07:17:19 garden kernel: [   22.550070] Driver for 1-wire Dallas network protocol.
Feb  6 07:17:19 garden kernel: [   22.566892] gpio-4 (onewire@4): enforced open drain please flag it properly in DT/ACPI DSDT/board file
Feb  6 07:17:19 garden kernel: [   22.738315] w1_master_driver w1_bus_master1: Attaching one wire slave 28.031661a80eff crc f5
Feb  6 07:17:19 garden kernel: [   22.763506] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Feb  6 07:17:19 garden kernel: [   22.766498] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Feb  6 07:17:19 garden kernel: [   22.766524] [vc_sm_connected_init]: start
Feb  6 07:17:19 garden kernel: [   22.819848] vc_sm_cma_vchi_init: failed to open VCHI service (-1)
Feb  6 07:17:19 garden kernel: [   22.819864] [vc_sm_connected_init]: failed to initialize shared memory service
Feb  6 07:17:19 garden kernel: [   23.736688] usbcore: registered new interface driver uas
Feb  6 07:17:19 garden kernel: [   24.143732] sd 0:0:0:0: Attached scsi generic sg0 type 0
Feb  6 07:17:19 garden kernel: [   24.510724] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Feb  6 07:17:19 garden kernel: [   24.902496] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Feb  6 07:17:19 garden kernel: [   26.648701] usb 1-1.3: ath9k_htc: Firmware ath9k_htc/htc_9271-1.4.0.fw requested
Feb  6 07:17:19 garden kernel: [   26.721922] usbcore: registered new interface driver ath9k_htc
Feb  6 07:17:19 garden kernel: [   26.935851] usb 1-1.3: ath9k_htc: Transferred FW: ath9k_htc/htc_9271-1.4.0.fw, size: 51008
Feb  6 07:17:19 garden kernel: [   27.197655] ath9k_htc 1-1.3:1.0: ath9k_htc: HTC initialized with 33 credits
Feb  6 07:17:19 garden kernel: [   27.646781] ath9k_htc 1-1.3:1.0: ath9k_htc: FW Version: 1.4
Feb  6 07:17:19 garden kernel: [   27.646844] ath9k_htc 1-1.3:1.0: FW RMW support: On
Feb  6 07:17:19 garden kernel: [   27.646858] ath: EEPROM regdomain: 0x809c
Feb  6 07:17:19 garden kernel: [   27.646865] ath: EEPROM indicates we should expect a country code
Feb  6 07:17:19 garden kernel: [   27.646873] ath: doing EEPROM country->regdmn map search
Feb  6 07:17:19 garden kernel: [   27.646880] ath: country maps to regdmn code: 0x52
Feb  6 07:17:19 garden kernel: [   27.646889] ath: Country alpha2 being used: CN
Feb  6 07:17:19 garden kernel: [   27.646894] ath: Regpair used: 0x52
Feb  6 07:17:19 garden kernel: [   28.044774] ieee80211 phy0: Atheros AR9271 Rev:1
Feb  6 07:17:19 garden rngd[208]: entropy feed to the kernel ready
Feb  6 07:17:19 garden systemd[1]: Started DietPi-RAMdisk.
Feb  6 07:17:19 garden systemd[1]: Starting System Logging Service...
Feb  6 07:17:19 garden systemd[1]: Starting DietPi-PreBoot...
Feb  6 07:17:19 garden systemd[1]: Started System Logging Service.
Feb  6 07:17:19 garden rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.1901.0]
Feb  6 07:17:19 garden rsyslogd:  [origin software="rsyslogd" swVersion="8.1901.0" x-pid="221" x-info="https://www.rsyslog.com"] start
Feb  6 07:17:21 garden systemd[1]: systemd-rfkill.service: Succeeded.
Feb  6 07:17:23 garden systemd[1]: Started DietPi-PreBoot.
Feb  6 07:17:23 garden systemd[1]: Reached target Network (Pre).
Feb  6 07:17:23 garden systemd[1]: Starting Raise network interfaces...
Feb  6 07:17:23 garden systemd[1]: Started ifup for wlan0.
Feb  6 07:17:24 garden wpa_supplicant[336]: Successfully initialized wpa_supplicant
Feb  6 07:17:24 garden systemd[1]: Started Raise network interfaces.
Feb  6 07:17:24 garden systemd[1]: Reached target Network.
Feb  6 07:17:24 garden systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Feb  6 07:17:24 garden systemd[1]: Starting Permit User Sessions...
Feb  6 07:17:25 garden systemd[1]: Starting DietPi-Boot...
Feb  6 07:17:25 garden systemd[1]: Reached target Network is Online.
Feb  6 07:17:25 garden systemd[1]: Starting /etc/rc.local Compatibility...
Feb  6 07:17:25 garden systemd[1]: Started Permit User Sessions.
Feb  6 07:17:26 garden systemd[1]: Started /etc/rc.local Compatibility.
Feb  6 07:17:26 garden wpa_supplicant[401]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB
Feb  6 07:17:27 garden dhclient[424]: Internet Systems Consortium DHCP Client 4.4.1
Feb  6 07:17:27 garden sh[314]: Internet Systems Consortium DHCP Client 4.4.1
Feb  6 07:17:27 garden sh[314]: Copyright 2004-2018 Internet Systems Consortium.
Feb  6 07:17:27 garden sh[314]: All rights reserved.
Feb  6 07:17:27 garden sh[314]: For info, please visit https://www.isc.org/software/dhcp/
Feb  6 07:17:27 garden dhclient[424]: Copyright 2004-2018 Internet Systems Consortium.
Feb  6 07:17:27 garden dhclient[424]: All rights reserved.
Feb  6 07:17:27 garden dhclient[424]: For info, please visit https://www.isc.org/software/dhcp/
Feb  6 07:17:27 garden dhclient[424]: 
Feb  6 07:17:28 garden dhclient[424]: Listening on LPF/wlan0/18:d6:c7:10:f6:2d
Feb  6 07:17:28 garden sh[314]: Listening on LPF/wlan0/18:d6:c7:10:f6:2d
Feb  6 07:17:28 garden dhclient[424]: Sending on   LPF/wlan0/18:d6:c7:10:f6:2d
Feb  6 07:17:28 garden sh[314]: Sending on   LPF/wlan0/18:d6:c7:10:f6:2d
Feb  6 07:17:28 garden sh[314]: Sending on   Socket/fallback
Feb  6 07:17:28 garden sh[314]: DHCPREQUEST for 10.10.1.55 on wlan0 to 255.255.255.255 port 67
Feb  6 07:17:28 garden dhclient[424]: Sending on   Socket/fallback
Feb  6 07:17:28 garden dhclient[424]: DHCPREQUEST for 10.10.1.55 on wlan0 to 255.255.255.255 port 67
Feb  6 07:17:28 garden kernel: [   43.385916] wlan0: authenticate with 14:cc:20:97:da:b2
Feb  6 07:17:28 garden wpa_supplicant[401]: wlan0: SME: Trying to authenticate with 14:cc:20:97:da:b2 (SSID='makova.cz' freq=2462 MHz)
Feb  6 07:17:28 garden systemd[1]: Starting Network Time Synchronization...
Feb  6 07:17:29 garden wpa_supplicant[401]: wlan0: Trying to associate with 14:cc:20:97:da:b2 (SSID='makova.cz' freq=2462 MHz)
Feb  6 07:17:29 garden kernel: [   43.964206] wlan0: send auth to 14:cc:20:97:da:b2 (try 1/3)
Feb  6 07:17:29 garden kernel: [   43.967697] wlan0: authenticated
Feb  6 07:17:29 garden kernel: [   43.983510] wlan0: associate with 14:cc:20:97:da:b2 (try 1/3)
Feb  6 07:17:29 garden kernel: [   43.987839] wlan0: RX AssocResp from 14:cc:20:97:da:b2 (capab=0x31 status=0 aid=3)
Feb  6 07:17:29 garden kernel: [   44.000071] wlan0: associated
Feb  6 07:17:29 garden wpa_supplicant[401]: wlan0: Associated with 14:cc:20:97:da:b2
Feb  6 07:17:29 garden wpa_supplicant[401]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Feb  6 07:17:29 garden wpa_supplicant[401]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CZ
Feb  6 07:17:31 garden wpa_supplicant[401]: wlan0: WPA: Key negotiation completed with 14:cc:20:97:da:b2 [PTK=CCMP GTK=CCMP]
Feb  6 07:17:31 garden wpa_supplicant[401]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:cc:20:97:da:b2 completed [id=0 id_str=]
Feb  6 07:17:31 garden wpa_supplicant[401]: wlan0: CTRL-EVENT-BEACON-LOSS 
Feb  6 07:17:31 garden systemd[1]: Started Network Time Synchronization.
Feb  6 07:17:31 garden systemd[1]: Reached target System Time Synchronized.
Feb  6 07:17:33 garden dhclient[424]: DHCPREQUEST for 10.10.1.55 on wlan0 to 255.255.255.255 port 67
Feb  6 07:17:33 garden sh[314]: DHCPREQUEST for 10.10.1.55 on wlan0 to 255.255.255.255 port 67
Feb  6 07:17:33 garden sh[314]: DHCPACK of 10.10.1.55 from 10.10.1.1
Feb  6 07:17:33 garden dhclient[424]: DHCPACK of 10.10.1.55 from 10.10.1.1
Feb  6 07:17:34 garden dhclient[424]: bound to 10.10.1.55 -- renewal in 34867 seconds.
Feb  6 07:17:34 garden sh[314]: bound to 10.10.1.55 -- renewal in 34867 seconds.
Feb  6 07:17:34 garden sh[314]: wlan0=wlan0
Feb  6 07:17:44 garden systemd[1]: systemd-fsckd.service: Succeeded.
Feb  6 17:43:27 garden systemd-timesyncd[441]: Synchronized to time server for the first time 31.31.74.35:123 (2.debian.pool.ntp.org).
Feb  6 17:43:28 garden systemd[1]: Stopping Network Time Synchronization...
Feb  6 17:43:28 garden systemd[1]: systemd-timesyncd.service: Succeeded.
Feb  6 17:43:28 garden systemd[1]: Stopped Network Time Synchronization.
Feb  6 17:43:28 garden systemd[1]: Started DietPi-Boot.

kriznik · 6 February 2020 18:11

strange, another restart and journalctl appeared

dietpi@garden:~$ sudo journalctl -u systemd-timesyncd.service
-- Logs begin at Thu 2020-02-06 18:17:02 CET, end at Thu 2020-02-06 19:10:13 CET. --
Feb 06 18:17:28 garden systemd[1]: Starting Network Time Synchronization...
Feb 06 18:17:30 garden systemd[1]: Started Network Time Synchronization.
Feb 06 19:05:33 garden systemd-timesyncd[454]: Synchronized to time server for the first time 5.1.56.123:123 (2.debian.pool.ntp.org).
Feb 06 19:05:34 garden systemd[1]: Stopping Network Time Synchronization...
Feb 06 19:05:34 garden systemd[1]: systemd-timesyncd.service: Succeeded.
Feb 06 19:05:34 garden systemd[1]: Stopped Network Time Synchronization.

kriznik · 7 February 2020 08:13

small update
I’ve installed 6.28 on another rpi for testing, with different usb wifi and same software.
again 1:17am system is unreachable via wifi, but this time i do have monitor and shell as well directly.

interestingly, system is not freezed, even wifi has got IP, but networking is ded. no ping in no ping out at all.

systemctl restart networking solved it

so I’m wondering what can cause networking to stall thanks to hourly crons, which seems to be only doing some logg rotating if enabled?
as seen from previous debug, hourly cron somehow restarts networking for some uknown reason (maybe thanks to wifi manager?) but sometimes it got stuck.

How we can debug more around networking what is happening and why?

as quick fix, i probably just edit dietpi-wifi-monitor.sh and instead of ifdown/ifup I’ll restart whole networking service

Joulinar · 7 February 2020 11:37

hi,

your system seems to reboot completely time by time. Probably some issues with power plug or device (hardware). Maybe SD card or file system corruption. There is no reason on the logs for the reboot . Unfortunately we don’t know if there are any memory issues / overflow at the time your system get stuck.

As well it seems your system is not booting correctly once it crashed or it’s hanging during boot. or it has issues to connect to WiFi. Because 1 hour for time sync it a huuuuugggge amount of time.

pls can you check these 2 values

cat /DietPi/dietpi.txt |grep CONFIG_NTP_MODE
cat /DietPi/dietpi.txt |grep CONFIG_BOOT_WAIT_FOR_NETWORK

kriznik · 7 February 2020 12:05

dietpi@garden:~$ cat /DietPi/dietpi.txt |grep CONFIG_NTP_MODE
CONFIG_NTP_MODE=4
dietpi@garden:~$ cat /DietPi/dietpi.txt |grep CONFIG_BOOT_WAIT_FOR_NETWORK
# - It is HIGHLY recommended to also set CONFIG_BOOT_WAIT_FOR_NETWORK=2, to force infinite wait for network connection during boot and prevent connection timeout errors.
CONFIG_BOOT_WAIT_FOR_NETWORK=1
dietpi@garden:~$

and second device

root@DietPi:~# cat /DietPi/dietpi.txt |grep CONFIG_NTP_MODE
CONFIG_NTP_MODE=2
root@DietPi:~# cat /DietPi/dietpi.txt |grep CONFIG_BOOT_WAIT_FOR_NETWORK
# - It is HIGHLY recommended to also set CONFIG_BOOT_WAIT_FOR_NETWORK=2, to force infinite wait for network connection during boot and prevent connection timeout errors.
CONFIG_BOOT_WAIT_FOR_NETWORK=1
root@DietPi:~#

Hi, no … as I posted in previous post, it is happening on two completely different raspberries (both model 2b) with usb wifi dongles from different vendors. And uptime is not changed eventho it looks like it’s rebooted. Power is ok, sd/usb is indeed ok, memory is fine.

Logs on the second pi (which i have monitor attached to it and keyboard) seems exactly the same as on the first one. I was able to gain network access by simply restarting whole networking → systemctl restart networking

on first machine there is gap I assume because networking freezed as well, but as i can’t observe it as it is headless, hard to tell.

MichaIng · 7 February 2020 12:52

Hmm, strange issue. When you have some Pi up, could you paste output of:

systemctl status networking
systemctl status ifup@wlan0
cat /etc/network/interfaces

kriznik · 7 February 2020 12:57

sure i can
first one

dietpi@garden:~$ sudo systemctl status networking
● networking.service - Raise network interfaces
   Loaded: loaded (/lib/systemd/system/networking.service; enabled; vendor preset: enabled)
   Active: active (exited) since Thu 2020-02-06 20:17:27 CET; 17h ago
     Docs: man:interfaces(5)
  Process: 317 ExecStart=/sbin/ifup -a --read-environment (code=exited, status=0/SUCCESS)
 Main PID: 317 (code=exited, status=0/SUCCESS)

Warning: Journal has been rotated since unit was started. Log output is incomplete or unavailable.
dietpi@garden:~$ sudo systemctl status ifup@wlan0
● ifup@wlan0.service - ifup for wlan0
   Loaded: loaded (/lib/systemd/system/ifup@.service; static; vendor preset: enabled)
   Active: active (exited) since Thu 2020-02-06 20:17:25 CET; 17h ago
  Process: 318 ExecStart=/bin/sh -ec ifup --allow=hotplug wlan0; ifquery --state wlan0 (code=exited, status=0/SUCCESS)
 Main PID: 318 (code=exited, status=0/SUCCESS)
   Memory: 5.4M
   CGroup: /system.slice/ifup@wlan0.service
           ├─396 /sbin/wpa_supplicant -s -B -P /run/wpa_supplicant.wlan0.pid -i wlan0 -D nl80211,wext -c /etc/wpa_supplicant/wpa_suppl
icant.conf
           └─424 /sbin/dhclient -4 -v -i -pf /run/dhclient.wlan0.pid -lf /var/lib/dhcp/dhclient.wlan0.leases -I -df /var/lib/dhcp/dhcl
ient6.wlan0.leases wlan0

Feb 07 13:27:00 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:29:34 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:32:10 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:32:11 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:32:12 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:34:46 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:34:47 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:37:20 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:37:24 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
Feb 07 13:45:10 garden wpa_supplicant[396]: wlan0: CTRL-EVENT-BEACON-LOSS
dietpi@garden:~$ sudo cat /etc/network/interfaces
# Location: /etc/network/interfaces
# Please modify network settings via: dietpi-config
# Or create your own drop-ins in: /etc/network/interfaces.d/

# Drop-in configs
source interfaces.d/*

# Local
auto lo
iface lo inet loopback

# Ethernet
#allow-hotplug eth0
iface eth0 inet dhcp
address 0.0.0.0
netmask 0.0.0.0
gateway 0.0.0.0
#dns-nameservers 0.0.0.0

# WiFi
allow-hotplug wlan0
iface wlan0 inet dhcp
address 10.10.1.55
netmask 255.255.255.0
gateway 10.10.1.1
#dns-nameservers 10.10.1.5
wpa-conf /etc/wpa_supplicant/wpa_supplicant.conf
dietpi@garden:~$

second one

root@DietPi:~# systemctl status networking
● networking.service - Raise network interfaces
   Loaded: loaded (/lib/systemd/system/networking.service; enabled; vendor preset: enabled)
   Active: active (exited) since Fri 2020-02-07 12:15:21 CET; 1h 40min ago
     Docs: man:interfaces(5)
  Process: 6304 ExecStart=/sbin/ifup -a --read-environment (code=exited, status=0/SUCCESS)
 Main PID: 6304 (code=exited, status=0/SUCCESS)

Feb 07 12:15:21 DietPi systemd[1]: Starting Raise network interfaces...
Feb 07 12:15:21 DietPi systemd[1]: Started Raise network interfaces.
root@DietPi:~# systemctl status ifup@wlan0
● ifup@wlan0.service - ifup for wlan0
   Loaded: loaded (/lib/systemd/system/ifup@.service; static; vendor preset: enabled)
   Active: active (exited) since Fri 2020-02-07 12:15:41 CET; 1h 40min ago
  Process: 6376 ExecStart=/bin/sh -ec ifup --allow=hotplug wlan0; ifquery --state wlan0 (code=exited, status=0/SUCCESS)
 Main PID: 6376 (code=exited, status=0/SUCCESS)
   Memory: 1.7M
   CGroup: /system.slice/ifup@wlan0.service
           ├─6391 /sbin/wpa_supplicant -s -B -P /run/wpa_supplicant.wlan0.pid -i wlan0 -D nl80211,wext -c /etc/wpa_supplicant/wpa_supplicant.conf
           └─6397 /sbin/dhclient -4 -v -i -pf /run/dhclient.wlan0.pid -lf /var/lib/dhcp/dhclient.wlan0.leases -I -df /var/lib/dhcp/dhclient6.wlan0.leases wlan0

Feb 07 12:15:46 DietPi sh[6376]: DHCPDISCOVER on wlan0 to 255.255.255.255 port 67 interval 6
Feb 07 12:15:49 DietPi dhclient[6397]: DHCPOFFER of 10.10.1.182 from 10.10.1.1
Feb 07 12:15:49 DietPi sh[6376]: DHCPOFFER of 10.10.1.182 from 10.10.1.1
Feb 07 12:15:49 DietPi sh[6376]: DHCPREQUEST for 10.10.1.182 on wlan0 to 255.255.255.255 port 67
Feb 07 12:15:49 DietPi dhclient[6397]: DHCPREQUEST for 10.10.1.182 on wlan0 to 255.255.255.255 port 67
Feb 07 12:15:49 DietPi sh[6376]: DHCPACK of 10.10.1.182 from 10.10.1.1
Feb 07 12:15:49 DietPi dhclient[6397]: DHCPACK of 10.10.1.182 from 10.10.1.1
Feb 07 12:15:50 DietPi dhclient[6397]: bound to 10.10.1.182 -- renewal in 33495 seconds.
Feb 07 12:15:50 DietPi sh[6376]: bound to 10.10.1.182 -- renewal in 33495 seconds.
Feb 07 12:15:50 DietPi sh[6376]: wlan0=wlan0
root@DietPi:~# cat /etc/network/interfaces
# Location: /etc/network/interfaces
# Please modify network settings via: dietpi-config
# Or create your own drop-ins in: /etc/network/interfaces.d/

# Drop-in configs
source interfaces.d/*

# Local
auto lo
iface lo inet loopback

# Ethernet
allow-hotplug eth0
iface eth0 inet dhcp
address 10.10.1.170
netmask 255.255.255.0
gateway 10.10.1.1
#dns-nameservers 10.10.1.5

# WiFi
allow-hotplug wlan0
iface wlan0 inet dhcp
address 10.10.1.182
netmask 255.255.255.0
gateway 10.10.1.1
#dns-nameservers 10.10.1.5
wpa-conf /etc/wpa_supplicant/wpa_supplicant.conf
root@DietPi:~#

kriznik · 7 February 2020 13:30

btw MichaIng is there some reason that hourly cron restarts network (or usb dongle ath)? As far as I can see in /etc/cron.hourly/dietpi it does not look like anything should mess with networking at all.
And as it is the only cron ran at 17th minute I assume there have to be something in there.

on top of it, not every hour tho, but 17th minute is indicating me hourly cron

debug log from first machine

Feb  6 07:17:19 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  6 07:17:19 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  6 07:17:19 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  6 07:17:19 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  6 07:17:19 garden kernel: [    0.825715] dwc_otg: Microframe scheduler enabled
Feb  6 07:17:19 garden kernel: [    0.833875] dwc_otg: FIQ enabled
Feb  6 07:17:19 garden kernel: [    0.833887] dwc_otg: NAK holdoff enabled
Feb  6 07:17:19 garden kernel: [    0.833893] dwc_otg: FIQ split-transaction FSM enabled
Feb  6 07:17:19 garden kernel: [    0.833911] Module dwc_common_port init
Feb  6 07:17:19 garden kernel: [    3.289831] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  6 07:17:19 garden kernel: [   27.646858] ath: EEPROM regdomain: 0x809c
Feb  6 07:17:19 garden kernel: [   27.646865] ath: EEPROM indicates we should expect a country code
Feb  6 07:17:19 garden kernel: [   27.646873] ath: doing EEPROM country->regdmn map search
Feb  6 07:17:19 garden kernel: [   27.646880] ath: country maps to regdmn code: 0x52
Feb  6 07:17:19 garden kernel: [   27.646889] ath: Country alpha2 being used: CN
Feb  6 07:17:19 garden kernel: [   27.646894] ath: Regpair used: 0x52
Feb  6 18:17:18 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  6 18:17:18 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  6 18:17:18 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  6 18:17:18 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  6 18:17:18 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  6 18:17:18 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  6 18:17:18 garden kernel: [    0.825647] dwc_otg: Microframe scheduler enabled
Feb  6 18:17:18 garden kernel: [    0.833821] dwc_otg: FIQ enabled
Feb  6 18:17:18 garden kernel: [    0.833833] dwc_otg: NAK holdoff enabled
Feb  6 18:17:18 garden kernel: [    0.833839] dwc_otg: FIQ split-transaction FSM enabled
Feb  6 18:17:18 garden kernel: [    0.833856] Module dwc_common_port init
Feb  6 18:17:18 garden kernel: [    3.289928] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  6 18:17:18 garden kernel: [   27.006839] ath: EEPROM regdomain: 0x809c
Feb  6 18:17:18 garden kernel: [   27.006847] ath: EEPROM indicates we should expect a country code
Feb  6 18:17:18 garden kernel: [   27.006855] ath: doing EEPROM country->regdmn map search
Feb  6 18:17:18 garden kernel: [   27.006862] ath: country maps to regdmn code: 0x52
Feb  6 18:17:18 garden kernel: [   27.006871] ath: Country alpha2 being used: CN
Feb  6 18:17:18 garden kernel: [   27.006876] ath: Regpair used: 0x52
Feb  6 20:17:21 garden kernel: [    0.000000] On node 0 totalpages: 61440
Feb  6 20:17:21 garden kernel: [    0.000000]   Normal zone: 540 pages used for memmap
Feb  6 20:17:21 garden kernel: [    0.000000]   Normal zone: 0 pages reserved
Feb  6 20:17:21 garden kernel: [    0.000000]   Normal zone: 61440 pages, LIFO batch:15
Feb  6 20:17:21 garden kernel: [    0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Feb  6 20:17:21 garden kernel: [    0.000000] pcpu-alloc: [0] 0
Feb  6 20:17:21 garden kernel: [    0.825680] dwc_otg: Microframe scheduler enabled
Feb  6 20:17:21 garden kernel: [    0.833834] dwc_otg: FIQ enabled
Feb  6 20:17:21 garden kernel: [    0.833846] dwc_otg: NAK holdoff enabled
Feb  6 20:17:21 garden kernel: [    0.833852] dwc_otg: FIQ split-transaction FSM enabled
Feb  6 20:17:21 garden kernel: [    0.833870] Module dwc_common_port init
Feb  6 20:17:21 garden kernel: [    3.289808] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
Feb  6 20:17:21 garden kernel: [   27.232844] ath: EEPROM regdomain: 0x809c
Feb  6 20:17:21 garden kernel: [   27.232851] ath: EEPROM indicates we should expect a country code
Feb  6 20:17:21 garden kernel: [   27.232860] ath: doing EEPROM country->regdmn map search
Feb  6 20:17:21 garden kernel: [   27.232867] ath: country maps to regdmn code: 0x52
Feb  6 20:17:21 garden kernel: [   27.232875] ath: Country alpha2 being used: CN
Feb  6 20:17:21 garden kernel: [   27.232881] ath: Regpair used: 0x52

I as well suspecting node-red might be part of the issue. I’ve disabled it on first machine and left running on second one, for observation.
It seems to hang somehow when network being restarted and node-red wants to communicate with mqtt server and then network hangs as well.

Joulinar · 7 February 2020 13:36

hi,

one small question. on the second device, you have Ethernet (10.10.1.170) as well as WiFi (10.10.1.182) connect at the same time?

kriznik · 7 February 2020 13:47

it was yes (because i needed to compile driver for that edimax from other thread )
ethernet was disconnected before night yesterday, and stalled at 1:17am which had only wifi connection

Joulinar · 7 February 2020 14:13

yep /etc/cron.hourly/dietpi did not contain anything related to network. so would be good to find out what happen at the 17th minute of a hour on your system. Did you checked crontab? If not you could run the following. It will display active crontab only. If there are no crontab entries, it will report nothing.

for user in $(cut -f1 -d: /etc/passwd); do crontab -u $user -l 2>/dev/null | grep -v '^#'; done

if you like to see all crontab for all user, even if they are empty, go for this

for user in $(cut -f1 -d: /etc/passwd); do echo $user; crontab -u $user -l; done

kriznik · 7 February 2020 14:38

nothing is running at 17th minute via crons for any users