Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Raspberry Pi 4: Network non-functional directly after (re)boot #3034

Closed
M-Reimer opened this issue Jun 30, 2019 · 100 comments
Closed

Raspberry Pi 4: Network non-functional directly after (re)boot #3034

M-Reimer opened this issue Jun 30, 2019 · 100 comments

Comments

@M-Reimer
Copy link

Describe the bug
If I boot directly to an up-to-date Raspbian, then the network starts up in some kind of "no-functional" state. Means that dhcpcd does not manage to get an IP and trying to manually run dhcpcd on the interface hangs forever.

The problem resets if I unplug and replug the network cable. This triggers fetching a valid IP and properly enables the network interface.

It is also possible to reset from the non-functional state by running
sudo mii-tool -r eth0
This also "unblocks" the network card and makes dhcpcd get a new IP.

To reproduce
Seems like not everyone is able to reproduce this bug. Maybe it's even some kind of "hardware problem".
But on affected Raspberry Pi 4 board, everything you have to do is to reboot. Result will be non-functional network.

Expected behaviour
Network should come up without problems every time.

Actual behaviour
Network hangs until mii-tool -r is called or the network cable is unplugged and replugged.

Logs
I already published some logs here:
https://www.raspberrypi.org/forums/viewtopic.php?f=66&t=244061#p1488426
I can provide more if needed.

@JamesH65
Copy link
Contributor

JamesH65 commented Jul 1, 2019

Weird, I reboot my Pi4 all the time with no network issues at all, and i have not heard of anyone else with anything similar. Can you try with a different router (or perhaps even reboot your router in case that behaving oddly).

@M-Reimer
Copy link
Author

M-Reimer commented Jul 1, 2019

I've tried all that already (check forum thread). All the previous raspberry models work without any issues at the same router.

I think it is interesting that the problem can be reset in software. The kernel module should be able to detect this case to "fix" it automatically.

@ummon29
Copy link

ummon29 commented Jul 1, 2019

I seem to have similar Problems with my new Pi4.
It's an System with a manualy configured IP. The System is the old system upgraded to buster. Had it running on an Pi3B with buster while I waited for the Pi4B without any Problems. Since I switched to the Pi4B I had Problems with the NIC.
Yeserday the network stopped to work and I rebooted (the System is Headless).
The reboot didn't help. The LEDs of the NIC flashed every few seconds but I couldn't get a Link.
Two further reboots didn't help only after restarting the switch the Link would come up again. (A managed HP-Switch. The Log of the Switch didn't show any problems).
Today I had the problem again. The NIC stoped working for 15 to 20 minutes. Afterwards it started to work again without any change from my side.
Looking in the dmesg output I can see that the problem is happening from tine to time.
I'll try if plugging the RPi4B in another Switch changes the behaviour.

dmesg from tonight:

[40987.338593] bcmgenet fd580000.genet eth0: Link is Down
[40987.338717] br0: port 1(eth0) entered disabled state
[40989.418628] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off
[40989.418675] br0: port 1(eth0) entered blocking state
[40989.418695] br0: port 1(eth0) entered forwarding state
[40994.619008] bcmgenet fd580000.genet eth0: Link is Down
[40994.619131] br0: port 1(eth0) entered disabled state
[40997.738788] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off
[40997.738843] br0: port 1(eth0) entered blocking state
[40997.738862] br0: port 1(eth0) entered forwarding state
[41005.018835] bcmgenet fd580000.genet eth0: Link is Down
[41005.018956] br0: port 1(eth0) entered disabled state
[41007.098925] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off
[41007.098971] br0: port 1(eth0) entered blocking state
[41007.098990] br0: port 1(eth0) entered forwarding state
[41012.298947] bcmgenet fd580000.genet eth0: Link is Down
[41007.098990] br0: port 1(eth0) entered forwarding state
[41012.298947] bcmgenet fd580000.genet eth0: Link is Down
[41012.299070] br0: port 1(eth0) entered disabled state
[41014.379015] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off
[41014.379060] br0: port 1(eth0) entered blocking state
[41014.379080] br0: port 1(eth0) entered forwarding state
[41024.779133] bcmgenet fd580000.genet eth0: Link is Down
[41024.779254] br0: port 1(eth0) entered disabled state
[41027.899209] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off
[41027.899256] br0: port 1(eth0) entered blocking state
[41027.899276] br0: port 1(eth0) entered forwarding state
[48321.531109] bcmgenet fd580000.genet eth0: Link is Down
[48321.531231] br0: port 1(eth0) entered disabled state
[48323.611093] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off
[48323.611139] br0: port 1(eth0) entered blocking state
[48323.611158] br0: port 1(eth0) entered forwarding state
[48321.531231] br0: port 1(eth0) entered disabled state
[48323.611093] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control off
[48323.611139] br0: port 1(eth0) entered blocking state
[48323.611158] br0: port 1(eth0) entered forwarding state

@M-Reimer
Copy link
Author

M-Reimer commented Jul 1, 2019

@ummon29 Can you try if "mii-tool -r eth0" fixes the issue for you, too?

As the problem only seems to happen for a few Raspberry Pi systems, I think it could be a hardware issue.
I'm planning to buy a second unit just to check if the LAN interface is stable with a second one.

I still don't think that this is an issue that can't be fixed on kernel side ("mii-tool -r eth0" actually fixes the problem for me) but I'm no kernel hacker. With this specific model it can be reproduced 100% of the time. I would just need more info on how to deliver the info a kernel developer needs. I could also ship the unit to someone with the needed knowledge. I would pay for shipping in one direction but would either want my unit back after some time or the money for the RPi board.

@JamesH65
Copy link
Contributor

JamesH65 commented Jul 1, 2019

@M-Reimer Not sure yet whether we would need this back - we might. We always replace like for like, plus goodies to pay for the postage.

@M-Reimer
Copy link
Author

M-Reimer commented Jul 1, 2019

So I'll wait. My plan was to try to get a refund from the seller in case noone here is interested in this particular unit.
Will buy a second one now to get sure that a new unit doesn't have the same problems (to rule out my network infrastructure). But I'm almost 100% sure it is not my network. I had several RPi 1, 2 and 3 boards running here without ever having problems with the network.
In fact the gigabit network is one of the reasons why I really like the new RPi 4. If we get this stable it can be used for several network services like small NAS systems, router, small in-house server, ...

@JamesH65
Copy link
Contributor

JamesH65 commented Jul 1, 2019

We have noted some strange DHCP issues that seem related to the network hardware, different switches show different results. We'll look in to it.

@M-Reimer
Copy link
Author

M-Reimer commented Jul 1, 2019

It doesn't seem to be DHCP issues only. I configured my RPi 4 for static IP and rebooted several times. The journal always says that IP, route and DNS are set properly but it is impossible to reach the RPi.

Then I tried the switch thing. I still have some old 100MBit switch and connected it in place of my 1GBit one. With this switch in place I was able to reboot 5 times and network was always available.

So yes, this changes with changing the switch. But of course I would prefer to run the 1GBit card on a 1GBit switch 😛

So I think if I buy a second one, then this will show exactly the same problem on this switch?

@6by9
Copy link
Contributor

6by9 commented Jul 1, 2019

What make and model is your gigabit switch? Is it managed, and if so what speed, duplex and flow control settings have you set?
We're generally running every day with a gigabit switch with no issues, so there's obviously something subtle in there somewhere.

@M-Reimer
Copy link
Author

M-Reimer commented Jul 1, 2019

D-Link DGS-108
https://www.amazon.de/dp/B000BCC0LO/
Simple switch without management.
I never had problems with this switch on any other device. Including Raspberry boards from version 1 to 3. It's the RPi 4 which causes problems for the first time...

I've ordered a second RPi 4 now. Just to see if a new board causes the same problems on this switch.

@ummon29
Copy link

ummon29 commented Jul 1, 2019

At the moment everything is running fine. When it's happening again and I have the opportunity I'll try "mii-tool -r eth0". My Switch is a managed HP 1810-8G The connection is running at 1000Mbps FullDuplex. The error counters are at 0 and the only log entry was that the port was down. Disconnecting an reconnecting didn't work even after a reboot of the RPi. Connecting the RPi with another cable to an AVM FritzBox (7490) worked instantly. After I restarted the Switch everything was fine again. I sill have to replace the cable to rule out that the problem is in the cable. My RPi has a manually configured IP.
In my case I'm not 100% sure if the problem is the Raspberry but it started the day after I switched from the RPi3B to the RPi4B without any other changes (I already updated to buster with the RPi3B as soon as it was possible).

@pelwell
Copy link
Contributor

pelwell commented Jul 1, 2019

It's likely that there is a bug in Ethernet/PHY driver. Even hardware and drivers that appear to work well suddenly develop issues when exposed to the vast number of devices and use cases that make up the world of Pi. We had a very busy few weeks after the launch of the 3B+ with network glitches, but they were sorted eventually.

@ummon29
Copy link

ummon29 commented Jul 2, 2019

After changing to another cable and switch still the same problem.
I'm now directly connected to the AVM FritzBox 7490.

dmesg:
[ 182.553342] bcmgenet fd580000.genet eth0: Link is Down
[ 182.553465] br0: port 1(eth0) entered disabled state
[ 185.673598] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 185.673644] br0: port 1(eth0) entered blocking state
[ 185.673664] br0: port 1(eth0) entered forwarding state

I had no chance to test "mii-tool -r eth0" yet.
The error isn't happening all the time. I have it once or twice a day at different times. Most of the time the link comes up again after a few seconds. Most of the time I only see the errors afterwards in the dmesg output.

@M-Reimer
Copy link
Author

M-Reimer commented Jul 9, 2019

I've received another RPi 4 board, now.

I just moved the same SD card to the new board and rebootet about 10 times. With static IP and with DHCP. Never had a problem. But I'll do some more tries just to be sure. I'll then also start to use this board for some 24/7 network stuff to see how stable the ethernet card will be.

After this test, I move the SD card back to the old board. First boot activated the network interface without issues but the first "sudo reboot" started in non-functional network, again. Unplugging and replugging the network cable fixed the problem just as before.

So this may have something to do with my hardware. Probably in combination with the specific switch that I use.

Tell me if it is possible to help with fixing this issue somehow.

@P33M
Copy link
Contributor

P33M commented Jul 9, 2019

Can you try forcing the mac address of the not-working Pi to that of the working Pi? Use the config.txt option force_mac_address=xx:xx:xx:xx:xx:xx

@M-Reimer
Copy link
Author

I've tried that (and verified that the mac address really changed).
It doesn't change anything.
Network failed right on the first boot with the config.txt change and worked after unplugging/replugging the cable...

@tbaeuerlepi
Copy link

I have exactly the same issue on my RPi 4 4GB and only unplug and replug the network cable brings the pi back to be able to SSH into it.
Running on a Netgear switch with Fritzbox 7490 as DHCP Server. With or without a static IP does not change anything

@api4user
Copy link

I have also experienced this issue and I raised the issue on the Raspberry Pi forum.

I now have two Pi 4's each with 4Gb of RAM. With everything the same except the Pi board one works and one does not.

As @M-Reimer discovered the only ways to get an IP address with the not working one seem to be to unplug/plug the network cable or to somehow run ethtool -r eth0 or mii-tool -r eth0.

Let me know if there are any tests or commands you would like me to run.

@api4user
Copy link

Any further news or should I just throw away this defective Pi 4? I do not feel it is up to the usual Pi standard in manufacture (I own/have owned more than 10 and this is only the second I have had problems with) and would not feel confident using it in a production environment due to the hack required to make it boot. I wish there was some other way of contacting Raspberry Pi rather than having to air my grievances in public.

@JamesH65
Copy link
Contributor

@api4user It does sound like a defective device given an alternative device does not show the same issue. Do not throw it away, it should be replaced under warranty. I'll ping some others to see if they have any comment. @pelwell @P33M @jimbojr Would it be worth getting defective device back to us?

@JamesH65
Copy link
Contributor

@api4user Yes, we would like that device back for internal testing please. We will replace it by return + goodies to cover postage costs. Please contact me via email info@raspberrypi.org, using FAO James Hughes at the top, to arrange how to get it to us. Thanks.

@M-Reimer
Copy link
Author

M-Reimer commented Jul 16, 2019

Same for my case or should I return to my seller for a refund?
@JamesH65

@JamesH65
Copy link
Contributor

@M-Reimer Yes please, can you follow instructions in my previous post and contact me via email please. We are trying to get together a database of unusual failures for quality control purposes, so everything is helpful!

@ummon29
Copy link

ummon29 commented Jul 20, 2019

I found a bit of time to debug the problem further.
I still had no chance to test mii-tool -r eth0 because for me eth0 doesn't hang at startup all the time. I had it a few times when I was connected to the HP-Switch (HP 1810-8G). I had it once that for several reboots the device didn't come up. Switching from HP-Switch to the FritzBox 7490 fixed it that time. Most of the time everything starts fine and after some time (seconds to hours) eth0 disconnects and reconnects for a few times. afterwards everything is fine again for some time.
When the problem occurs it happens for 1 to 3 times in a short period and afterwards everything if fine for a while. Most of the times the link is only down for 2-3 seconds.
Here is a typical dmesg output of such a case:

[ 159.832782] bcmgenet fd580000.genet eth0: Link is Down
[ 162.952881] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 165.032880] bcmgenet fd580000.genet eth0: Link is Down
[ 167.112949] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[ 172.312944] bcmgenet fd580000.genet eth0: Link is Down
[ 175.433042] bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx

I tested with a fresh raspbian buster install to ensure that it's no problem with my old updated system. The problem is the same.
Using dhcp or static IPs doesn't make a difference.
When switching back to my old Pi3B the same SD-Card with the system has no problem.
When switching from eth0 to eth1 (eth1 is an USB to Ethernet Adapter) on the Pi4B the problem is gone.
I connected the the Pi4B to an FortiGate firewall in transparent mode and monitored the traffic to see if I find some packets that trigger the problem. The problem was the same connected to the FortiGate but nothing special happened in the packet dump while the problem occurred.
All devices the PI4 was connected to (FritzBox, HP-Switch and FortiGate) show nothing special in their logs except that the link goes down and comes up again.
All devices use auto negotiation for the speed of the links. The Link comes always up with 1Gbps/Full. I haven't tried to set the NICs to a fixed speed yet.
It should be no problem with the power. I use the original USB-C adapter for the PI.
The temperature is most of the time around 60 degrees Celsius.
I'm just testing with an Cisco SG250-08 as a switch. No problems yet but I'm just a few minutes in the test.

@M-Reimer
Copy link
Author

M-Reimer commented Jul 21, 2019

@M-Reimer Yes please, can you follow instructions in my previous post and contact me via email please.

I guess this "info@" address gets too much mail and so it takes long for the queue to be processed?
Anyway. That's my mail address: manuel.reimer [at] gmx.de

@ummon29
Copy link

ummon29 commented Jul 23, 2019

A quick Update.
The three days since my Pi4B is connected to the Cisco SG250-08 i had no connection problems with the NIC. I'm cautiously hopeful to have a combination here that is working without the problem. Before trying the Cisco Switch the longest time between those disconnects were around one day.
I don't know exactly which Switch/Router/Firewall was connected when but if I remember correctly the AVM FritzBox 7490 had the most problems. I often had the first disconnect within minutes after the Pi started and when it started I then I got 2-3 disconnects in a row. But I never got any disconnects that didn't recover within 2-3 seconds.
With the HP 1810-8G I had problems between one and four times a day. This is the only device where I had disconnects that didn't recover within seconds.
The FortiGate (61E) was only tested for a short time by me. I had my first disconnects with this device within 2-4 hours.

@TheGreatestJannet
Copy link

TheGreatestJannet commented Jul 25, 2019

I am also experiencing the exact same issue on a Pi4B 4GB model. @JamesH65 do you still want it back or should I contact the seller?

Sorry for the ping I have resolved the issue. It seems that a program edited the dhcpcd.conf to add a static ip. The config was:
interface eth0
static ip_address=192.168.3.7
static routers=192.168.3.254
static domain_name_servers=
After commenting it out the pi connects every time after reboot.

@api4user
Copy link

I returned my Pi 4 to you a week ago. You emailed to say you could not replicate the problem and were sending my Pi back. I have still not received it back. I am not happy.

There definitely IS a problem as one of my Pi's works and the other did not.

@JamesH65
Copy link
Contributor

@api4user It's only been a week! Please take into account the time to pack stuff up and get it back to you. I wasn't dealing with this one, so will try and find out what is happening. Who emailed you the results? (Difficult to cross reference api4user against an email address/actual identity)

@osfrance
Copy link

Hi @pelwell ! this is the output :
root@RPI-11:~# ifconfig eth0
eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 192.168.200.244 netmask 255.255.255.0 broadcast 192.168.200.255
inet6 fe80::9a3f:xxxx:xxxx:xxxx prefixlen 64 scopeid 0x20
ether dc:a6:32:xx:xx:xx txqueuelen 1000 (Ethernet)
RX packets 47 bytes 3008 (2.9 KiB)
RX errors 0 dropped 47 overruns 0 frame 0
TX packets 69 bytes 8808 (8.6 KiB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0

PING 192.168.200.200 (192.168.200.200) from 192.168.200.244 eth0: 56(84) bytes of data.
........................................... (five lines like that)
--- 192.168.200.200 ping statistics ---
1000 packets transmitted, 0 received, 100% packet loss, time 1183ms
pipe 14

root@RPI-11:~# ifconfig eth0
eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500
inet 192.168.200.244 netmask 255.255.255.0 broadcast 192.168.200.255
inet6 fe80::9a3f:xxxx:xxxx:xxxx prefixlen 64 scopeid 0x20
ether dc:a6:32:xx:xx:xx txqueuelen 1000 (Ethernet)
RX packets 47 bytes 3008 (2.9 KiB)
RX errors 0 dropped 47 overruns 0 frame 0
TX packets 69 bytes 8808 (8.6 KiB)
TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0

dmesg is empty

@pelwell
Copy link
Contributor

pelwell commented May 19, 2020

Thanks, but those statistics don't make a lot of sense - it's like the ping packets just disappeared.

Have you tried adding genet.skip_umac_reset=n to cmdline.txt? (Remember to keep all settings in a single line).

@osfrance
Copy link

I add genet.skip_umac_reset=n in cmdline.txt, but I have the same issue :(
cmdline.txt content (in the same line):
console=serial0,115200 console=tty1 root=PARTUUID=738a4d67-02 rootfstype=ext4 elevator=deadline fsck.repair=yes genet.skip_umac_reset=n rootwait

@nurit-cyber
Copy link

So, I have run into the same issue. I was able to remedy the issue by typing in "ifconfig eth0 up//sudo dhclient" upon every reboot.

However, I was able to "solve" the issue by factory resetting my Raspberry Pi 4 and then installing NOOB and downloading Raspbian (Rasbperry Pi OS) from there. After the reinstall and updating and upgrading the OS, I was able to reboot it with no issue and could connect to the network just fine. I am not sure this will work for everyone though.

@itspa1
Copy link

itspa1 commented Jul 11, 2020

so, I have run into the same issue. I am able to get a DHCP address when I set the speed to 100mbps full-duplex or 10mbps (in case of several pi boards), but with the default gigabit ethernet (1gbps) there is no address and it is specific to a D-link switch, the Cisco switch actually allocates an IP to this pi on the gigabit ethernet. I'll share some logs also if it helps. Any help would be appreciated. at least an explanation of what might be happening would help a lot, or any workarounds for the same. Thank you.

Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Linux version 4.19.75-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1270 SMP Tue Sep 24 18:51:41 BST 2019
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] CPU: div instructions available: patching division code
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
Jul 10 08:48:04 raspberrypi systemd-fsck[127]: rootfs: clean, 71044/438144 files, 550392/1824768 blocks
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] cma: Reserved 256 MiB at 0x000000001ec00000
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] On node 0 totalpages: 504832
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]   DMA zone: 1728 pages used for memmap
Jul 10 08:48:04 raspberrypi systemd[1]: Started Set the console keyboard layout.
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]   DMA zone: 0 pages reserved
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]   DMA zone: 196608 pages, LIFO batch:63
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]   HighMem zone: 308224 pages, LIFO batch:63
Jul 10 08:48:04 raspberrypi systemd[1]: Started udev Coldplug all Devices.
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] random: get_random_bytes called from start_kernel+0xc0/0x4e8 with crng_init=0
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] percpu: Embedded 17 pages/cpu s39488 r8192 d21952 u69632
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] pcpu-alloc: s39488 r8192 d21952 u69632 alloc=17*4096
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 503104
Jul 10 08:48:04 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 cma=64M cma=256M  smsc95xx.macaddr=DC:A6:32:14:B7:D1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=e8429ad3-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Load/Save Random Seed...
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Memory: 1721828K/2019328K available (8192K kernel code, 661K rwdata, 2352K rodata, 2048K init, 850K bss, 35356K reserved, 262144K cma-reserved, 1232896K highmem)
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Flush Journal to Persistent Storage...
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Create System Users...
Jul 10 08:48:04 raspberrypi systemd[1]: Started Load/Save Random Seed.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Create System Users.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Create Static Device Nodes in /dev...
Jul 10 08:48:04 raspberrypi systemd[1]: Started Flush Journal to Persistent Storage.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
Jul 10 08:48:04 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] Virtual kernel memory layout:
Jul 10 08:48:04 raspberrypi systemd[1]: Started udev Kernel Device Manager.
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
Jul 10 08:48:04 raspberrypi systemd-udevd[178]: Using default interface naming scheme 'v240'.
Jul 10 08:48:04 raspberrypi systemd[1]: Found device /dev/serial1.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Uncomplicated firewall.
Jul 10 08:48:04 raspberrypi systemd[1]: Found device /dev/disk/by-partuuid/e8429ad3-01.
Jul 10 08:48:04 raspberrypi systemd-udevd[184]: Using default interface naming scheme 'v240'.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in FUSE Control File System being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Huge Pages File System being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting File System Check on /dev/disk/by-partuuid/e8429ad3-01...
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status...
Jul 10 08:48:04 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status.
Jul 10 08:48:04 raspberrypi systemd[1]: Found device /sys/subsystem/net/devices/wlan0.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Helper to synchronize boot up for ifupdown.
Jul 10 08:48:04 raspberrypi systemd-fsck[344]: fsck.fat 4.1 (2017-01-24)
Jul 10 08:48:04 raspberrypi systemd-fsck[344]: /dev/mmcblk0p1: 226 files, 106068/516191 clusters
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]     vmalloc : 0xf0800000 - 0xff800000   ( 240 MB)
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]     lowmem  : 0xc0000000 - 0xf0000000   ( 768 MB)
Jul 10 08:48:04 raspberrypi systemd[1]: Started File System Check on /dev/disk/by-partuuid/e8429ad3-01.
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]     pkmap   : 0xbfe00000 - 0xc0000000   (   2 MB)
Jul 10 08:48:04 raspberrypi systemd[1]: Mounting /boot...
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]     modules : 0xbf000000 - 0xbfe00000   (  14 MB)
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]       .text : 0x(ptrval) - 0x(ptrval)   (10208 kB)
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]       .init : 0x(ptrval) - 0x(ptrval)   (2048 kB)
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]       .data : 0x(ptrval) - 0x(ptrval)   ( 662 kB)
Jul 10 08:48:04 raspberrypi systemd[1]: Mounted /boot.
Jul 10 08:48:04 raspberrypi kernel: [    0.000000]        .bss : 0x(ptrval) - 0x(ptrval)   ( 851 kB)
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] ftrace: allocating 28124 entries in 83 pages
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Local File Systems.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Preprocess NFS configuration...
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Set console font and keymap...
Jul 10 08:48:04 raspberrypi systemd[1]: Started ifup for eth0.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Raise network interfaces...
Jul 10 08:48:04 raspberrypi systemd[1]: Started ifup for wlan0.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
Jul 10 08:48:04 raspberrypi systemd[1]: nfs-config.service: Succeeded.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Preprocess NFS configuration.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Set console font and keymap.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target NFS client services.
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Remote File Systems (Pre).
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Remote File Systems.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Network Time Synchronization being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Jul 10 08:48:04 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target System Initialization.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Clean PHP session files every 30 mins.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Daily rotation of log files.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
Jul 10 08:48:04 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Daily apt download activities.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
Jul 10 08:48:04 raspberrypi systemd[1]: Listening on triggerhappy.socket.
Jul 10 08:48:04 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Sockets.
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Basic System.
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Jul 10 08:48:04 raspberrypi systemd[1]: Starting triggerhappy global hotkey daemon...
Jul 10 08:48:04 raspberrypi dhclient[407]: Internet Systems Consortium DHCP Client 4.4.1
Jul 10 08:48:04 raspberrypi sh[352]: Internet Systems Consortium DHCP Client 4.4.1
Jul 10 08:48:04 raspberrypi sh[352]: Copyright 2004-2018 Internet Systems Consortium.
Jul 10 08:48:04 raspberrypi sh[352]: All rights reserved.
Jul 10 08:48:04 raspberrypi sh[352]: For info, please visit https://www.isc.org/software/dhcp/
Jul 10 08:48:04 raspberrypi dhclient[407]: Copyright 2004-2018 Internet Systems Consortium.
Jul 10 08:48:04 raspberrypi systemd[1]: Started D-Bus System Message Bus.
Jul 10 08:48:04 raspberrypi dhclient[407]: All rights reserved.
Jul 10 08:48:04 raspberrypi dhclient[407]: For info, please visit https://www.isc.org/software/dhcp/
Jul 10 08:48:04 raspberrypi dhclient[407]: 
Jul 10 08:48:04 raspberrypi systemd[1]: Starting dphys-swapfile - set up, mount/unmount, and delete a swap file...
Jul 10 08:48:04 raspberrypi wpa_supplicant[397]: Successfully initialized wpa_supplicant
Jul 10 08:48:04 raspberrypi thd[417]: Found socket passed from systemd
Jul 10 08:48:04 raspberrypi ifup[355]: ifup: waiting for lock on /run/network/ifstate.eth0
Jul 10 08:48:04 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Jul 10 08:48:04 raspberrypi systemd[1]: Started Regular background program processing daemon.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting System Logging Service...
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Jul 10 08:48:04 raspberrypi cron[429]: (CRON) INFO (pidfile fd = 3)
Jul 10 08:48:04 raspberrypi systemd[1]: Starting WPA supplicant...
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Login Service...
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] rcu: Hierarchical RCU implementation.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Manage Sound Card State (restore and store).
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Save/Restore Sound Card State...
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] GIC: Using split EOI/Deactivate mode
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
Jul 10 08:48:04 raspberrypi kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
Jul 10 08:48:04 raspberrypi systemd[1]: Starting rng-tools.service...
Jul 10 08:48:04 raspberrypi kernel: [    0.000005] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
Jul 10 08:48:04 raspberrypi kernel: [    0.000022] Switching to timer-based delay loop, resolution 18ns
Jul 10 08:48:04 raspberrypi kernel: [    0.000248] Console: colour dummy device 80x30
Jul 10 08:48:04 raspberrypi alsactl[440]: alsactl 1.1.8 daemon started
Jul 10 08:48:04 raspberrypi cron[429]: (CRON) INFO (Running @reboot jobs)
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Deferred execution scheduler...
Jul 10 08:48:04 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: Found user 'avahi' (UID 108) and group 'avahi' (GID 113).
Jul 10 08:48:04 raspberrypi systemd[1]: Started Daily man-db regeneration.
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: Successfully dropped root privileges.
Jul 10 08:48:04 raspberrypi kernel: [    0.000705] console [tty1] enabled
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Timers.
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: avahi-daemon 0.7 starting up.
Jul 10 08:48:04 raspberrypi kernel: [    0.000756] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
Jul 10 08:48:04 raspberrypi kernel: [    0.000798] pid_max: default: 32768 minimum: 301
Jul 10 08:48:04 raspberrypi kernel: [    0.001070] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.001103] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jul 10 08:48:04 raspberrypi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
Jul 10 08:48:04 raspberrypi kernel: [    0.001858] CPU: Testing write buffer coherency: ok
Jul 10 08:48:04 raspberrypi kernel: [    0.002280] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Jul 10 08:48:04 raspberrypi kernel: [    0.002926] Setting up static identity map for 0x200000 - 0x20003c
Jul 10 08:48:04 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
Jul 10 08:48:04 raspberrypi kernel: [    0.003096] rcu: Hierarchical SRCU implementation.
Jul 10 08:48:04 raspberrypi kernel: [    0.003967] smp: Bringing up secondary CPUs ...
Jul 10 08:48:04 raspberrypi kernel: [    0.004865] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Jul 10 08:48:04 raspberrypi kernel: [    0.005876] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Jul 10 08:48:04 raspberrypi kernel: [    0.006853] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Jul 10 08:48:04 raspberrypi kernel: [    0.006983] smp: Brought up 1 node, 4 CPUs
Jul 10 08:48:04 raspberrypi kernel: [    0.007051] SMP: Total of 4 processors activated (432.00 BogoMIPS).
Jul 10 08:48:04 raspberrypi kernel: [    0.007074] CPU: All CPU(s) started in HYP mode.
Jul 10 08:48:04 raspberrypi kernel: [    0.007094] CPU: Virtualization extensions available.
Jul 10 08:48:04 raspberrypi kernel: [    0.007880] devtmpfs: initialized
Jul 10 08:48:04 raspberrypi kernel: [    0.018216] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
Jul 10 08:48:04 raspberrypi kernel: [    0.018438] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Jul 10 08:48:04 raspberrypi kernel: [    0.018481] futex hash table entries: 1024 (order: 4, 65536 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.025980] pinctrl core: initialized pinctrl subsystem
Jul 10 08:48:04 raspberrypi kernel: [    0.026850] NET: Registered protocol family 16
Jul 10 08:48:04 raspberrypi kernel: [    0.029651] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Jul 10 08:48:04 raspberrypi kernel: [    0.031077] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Jul 10 08:48:04 raspberrypi kernel: [    0.031109] hw-breakpoint: maximum watchpoint size is 8 bytes.
Jul 10 08:48:04 raspberrypi kernel: [    0.031315] Serial: AMBA PL011 UART driver
Jul 10 08:48:04 raspberrypi kernel: [    0.034481] bcm2835-mbox fe00b880.mailbox: mailbox enabled
Jul 10 08:48:04 raspberrypi kernel: [    0.070133] bcm2835-dma fe007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1
Jul 10 08:48:04 raspberrypi kernel: [    0.072445] vgaarb: loaded
Jul 10 08:48:04 raspberrypi kernel: [    0.072826] SCSI subsystem initialized
Jul 10 08:48:04 raspberrypi kernel: [    0.073033] usbcore: registered new interface driver usbfs
Jul 10 08:48:04 raspberrypi kernel: [    0.073097] usbcore: registered new interface driver hub
Jul 10 08:48:04 raspberrypi kernel: [    0.073205] usbcore: registered new device driver usb
Jul 10 08:48:04 raspberrypi kernel: [    0.090394] raspberrypi-firmware soc:firmware: Attached to firmware from 2019-09-24 17:34, variant start
Jul 10 08:48:04 raspberrypi kernel: [    0.100100] raspberrypi-firmware soc:firmware: Firmware hash is cd3add54955f8fa065b414d8fc07c525e7ddffc8
Jul 10 08:48:04 raspberrypi kernel: [    0.111527] clocksource: Switched to clocksource arch_sys_counter
Jul 10 08:48:04 raspberrypi kernel: [    0.188671] VFS: Disk quotas dquot_6.6.0
Jul 10 08:48:04 raspberrypi kernel: [    0.188769] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.188964] FS-Cache: Loaded
Jul 10 08:48:04 raspberrypi kernel: [    0.189160] CacheFiles: Loaded
Jul 10 08:48:04 raspberrypi kernel: [    0.189676] simple-framebuffer: probe of 0.framebuffer failed with error -12
Jul 10 08:48:04 raspberrypi kernel: [    0.198814] NET: Registered protocol family 2
Jul 10 08:48:04 raspberrypi kernel: [    0.199479] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.199524] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.199609] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.199694] TCP: Hash tables configured (established 8192 bind 8192)
Jul 10 08:48:04 raspberrypi kernel: [    0.199822] UDP hash table entries: 512 (order: 2, 16384 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.199867] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.200172] NET: Registered protocol family 1
Jul 10 08:48:04 raspberrypi kernel: [    0.200732] RPC: Registered named UNIX socket transport module.
Jul 10 08:48:04 raspberrypi kernel: [    0.200759] RPC: Registered udp transport module.
Jul 10 08:48:04 raspberrypi kernel: [    0.200782] RPC: Registered tcp transport module.
Jul 10 08:48:04 raspberrypi kernel: [    0.200803] RPC: Registered tcp NFSv4.1 backchannel transport module.
Jul 10 08:48:04 raspberrypi kernel: [    0.200832] PCI: CLS 0 bytes, default 64
Jul 10 08:48:04 raspberrypi kernel: [    0.203760] Initialise system trusted keyrings
Jul 10 08:48:04 raspberrypi kernel: [    0.203941] workingset: timestamp_bits=14 max_order=19 bucket_order=5
Jul 10 08:48:04 raspberrypi kernel: [    0.212909] FS-Cache: Netfs 'nfs' registered for caching
Jul 10 08:48:04 raspberrypi kernel: [    0.213419] NFS: Registering the id_resolver key type
Jul 10 08:48:04 raspberrypi kernel: [    0.213462] Key type id_resolver registered
Jul 10 08:48:04 raspberrypi kernel: [    0.213484] Key type id_legacy registered
Jul 10 08:48:04 raspberrypi kernel: [    0.213515] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Jul 10 08:48:04 raspberrypi kernel: [    0.215757] Key type asymmetric registered
Jul 10 08:48:04 raspberrypi kernel: [    0.215784] Asymmetric key parser 'x509' registered
Jul 10 08:48:04 raspberrypi kernel: [    0.215905] bounce: pool size: 64 pages
Jul 10 08:48:04 raspberrypi kernel: [    0.215956] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Jul 10 08:48:04 raspberrypi kernel: [    0.216129] io scheduler noop registered
Jul 10 08:48:04 raspberrypi kernel: [    0.216152] io scheduler deadline registered (default)
Jul 10 08:48:04 raspberrypi kernel: [    0.216317] io scheduler cfq registered
Jul 10 08:48:04 raspberrypi kernel: [    0.216340] io scheduler mq-deadline registered (default)
Jul 10 08:48:04 raspberrypi kernel: [    0.216363] io scheduler kyber registered
Jul 10 08:48:04 raspberrypi kernel: [    0.219320] brcm-pcie fd500000.pcie: could not get clock
Jul 10 08:48:04 raspberrypi kernel: [    0.219394] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
Jul 10 08:48:04 raspberrypi kernel: [    0.219446] brcm-pcie fd500000.pcie:   MEM 0x600000000..0x603ffffff -> 0xf8000000
Jul 10 08:48:04 raspberrypi kernel: [    0.271560] brcm-pcie fd500000.pcie: link up, 5.0 Gbps x1 (!SSC)
Jul 10 08:48:04 raspberrypi kernel: [    0.271809] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
Jul 10 08:48:04 raspberrypi kernel: [    0.271840] pci_bus 0000:00: root bus resource [bus 00-01]
Jul 10 08:48:04 raspberrypi kernel: [    0.271871] pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff])
Jul 10 08:48:04 raspberrypi kernel: [    0.271940] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
Jul 10 08:48:04 raspberrypi kernel: [    0.272078] pci 0000:00:00.0: PME# supported from D0 D3hot
Jul 10 08:48:04 raspberrypi kernel: [    0.274872] PCI: bus0: Fast back to back transfers disabled
Jul 10 08:48:04 raspberrypi kernel: [    0.274903] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring
Jul 10 08:48:04 raspberrypi kernel: [    0.275079] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
Jul 10 08:48:04 raspberrypi kernel: [    0.275139] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
Jul 10 08:48:04 raspberrypi kernel: [    0.275305] pci 0000:01:00.0: PME# supported from D0 D3cold
Jul 10 08:48:04 raspberrypi kernel: [    0.278022] PCI: bus1: Fast back to back transfers disabled
Jul 10 08:48:04 raspberrypi kernel: [    0.278051] pci_bus 0000:01: busn_res: [bus 01] end is updated to 01
Jul 10 08:48:04 raspberrypi kernel: [    0.278096] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
Jul 10 08:48:04 raspberrypi kernel: [    0.278129] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
Jul 10 08:48:04 raspberrypi kernel: [    0.278177] pci 0000:00:00.0: PCI bridge to [bus 01]
Jul 10 08:48:04 raspberrypi kernel: [    0.278206] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
Jul 10 08:48:04 raspberrypi kernel: [    0.278422] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
Jul 10 08:48:04 raspberrypi kernel: [    0.278608] pcieport 0000:00:00.0: Signaling PME with IRQ 55
Jul 10 08:48:04 raspberrypi kernel: [    0.278779] pcieport 0000:00:00.0: AER enabled with IRQ 55
Jul 10 08:48:04 raspberrypi kernel: [    0.278929] pci 0000:01:00.0: enabling device (0140 -> 0142)
Jul 10 08:48:04 raspberrypi kernel: [    0.282147] iproc-rng200 fe104000.rng: hwrng registered
Jul 10 08:48:04 raspberrypi kernel: [    0.282378] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Jul 10 08:48:04 raspberrypi kernel: [    0.282895] vc-sm: Videocore shared memory driver
Jul 10 08:48:04 raspberrypi kernel: [    0.283301] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
Jul 10 08:48:04 raspberrypi kernel: [    0.293139] brd: module loaded
Jul 10 08:48:04 raspberrypi kernel: [    0.302638] loop: module loaded
Jul 10 08:48:04 raspberrypi kernel: [    0.303390] Loading iSCSI transport class v2.0-870.
Jul 10 08:48:04 raspberrypi kernel: [    0.305065] libphy: Fixed MDIO Bus: probed
Jul 10 08:48:04 raspberrypi kernel: [    0.305496] bcmgenet fd580000.genet: failed to get enet clock
Jul 10 08:48:04 raspberrypi kernel: [    0.305527] bcmgenet fd580000.genet: GENET 5.0 EPHY: 0x0000
Jul 10 08:48:04 raspberrypi kernel: [    0.305558] bcmgenet fd580000.genet: failed to get enet-wol clock
Jul 10 08:48:04 raspberrypi kernel: [    0.305587] bcmgenet fd580000.genet: failed to get enet-eee clock
Jul 10 08:48:04 raspberrypi kernel: [    0.305623] bcmgenet: Skipping UMAC reset
Jul 10 08:48:04 raspberrypi kernel: [    0.305862] unimac-mdio unimac-mdio.-19: DMA mask not set
Jul 10 08:48:04 raspberrypi kernel: [    0.321558] libphy: bcmgenet MII bus: probed
Jul 10 08:48:04 raspberrypi kernel: [    0.362030] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus at 0x(ptrval)
Jul 10 08:48:04 raspberrypi kernel: [    0.362915] usbcore: registered new interface driver r8152
Jul 10 08:48:04 raspberrypi kernel: [    0.362990] usbcore: registered new interface driver lan78xx
Jul 10 08:48:04 raspberrypi kernel: [    0.363058] usbcore: registered new interface driver smsc95xx
Jul 10 08:48:04 raspberrypi kernel: [    0.363271] xhci_hcd 0000:01:00.0: xHCI Host Controller
Jul 10 08:48:04 raspberrypi kernel: [    0.363315] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
Jul 10 08:48:04 raspberrypi kernel: [    0.364979] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890
Jul 10 08:48:04 raspberrypi kernel: [    0.365168] genirq: irq_chip Brcm_MSI did not update eff. affinity mask of irq 56
Jul 10 08:48:04 raspberrypi kernel: [    0.365798] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
Jul 10 08:48:04 raspberrypi kernel: [    0.365832] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jul 10 08:48:04 raspberrypi kernel: [    0.365861] usb usb1: Product: xHCI Host Controller
Jul 10 08:48:04 raspberrypi kernel: [    0.365884] usb usb1: Manufacturer: Linux 4.19.75-v7l+ xhci-hcd
Jul 10 08:48:04 raspberrypi kernel: [    0.365908] usb usb1: SerialNumber: 0000:01:00.0
Jul 10 08:48:04 raspberrypi kernel: [    0.366419] hub 1-0:1.0: USB hub found
Jul 10 08:48:04 raspberrypi kernel: [    0.366483] hub 1-0:1.0: 1 port detected
Jul 10 08:48:04 raspberrypi kernel: [    0.366896] xhci_hcd 0000:01:00.0: xHCI Host Controller
Jul 10 08:48:04 raspberrypi kernel: [    0.366932] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
Jul 10 08:48:04 raspberrypi kernel: [    0.366969] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
Jul 10 08:48:04 raspberrypi kernel: [    0.367318] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19
Jul 10 08:48:04 raspberrypi kernel: [    0.367351] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jul 10 08:48:04 raspberrypi kernel: [    0.367379] usb usb2: Product: xHCI Host Controller
Jul 10 08:48:04 raspberrypi kernel: [    0.367402] usb usb2: Manufacturer: Linux 4.19.75-v7l+ xhci-hcd
Jul 10 08:48:04 raspberrypi kernel: [    0.367426] usb usb2: SerialNumber: 0000:01:00.0
Jul 10 08:48:04 raspberrypi kernel: [    0.367916] hub 2-0:1.0: USB hub found
Jul 10 08:48:04 raspberrypi kernel: [    0.367981] hub 2-0:1.0: 4 ports detected
Jul 10 08:48:04 raspberrypi kernel: [    0.369093] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jul 10 08:48:04 raspberrypi kernel: [    0.394882] dwc_otg fe980000.usb: base=(ptrval)
Jul 10 08:48:04 raspberrypi kernel: [    0.595167] Core Release: 2.80a
Jul 10 08:48:04 raspberrypi kernel: [    0.595194] Setting default values for core params
Jul 10 08:48:04 raspberrypi kernel: [    0.595237] Finished setting default values for core params
Jul 10 08:48:04 raspberrypi kernel: [    0.795584] Using Buffer DMA mode
Jul 10 08:48:04 raspberrypi kernel: [    0.795606] Periodic Transfer Interrupt Enhancement - disabled
Jul 10 08:48:04 raspberrypi kernel: [    0.795629] Multiprocessor Interrupt Enhancement - disabled
Jul 10 08:48:04 raspberrypi kernel: [    0.795653] OTG VER PARAM: 0, OTG VER FLAG: 0
Jul 10 08:48:04 raspberrypi kernel: [    0.795723] Dedicated Tx FIFOs mode
Jul 10 08:48:04 raspberrypi kernel: [    0.796116] WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = ded34000 dma = 0x00000000ded34000 len=9024
Jul 10 08:48:04 raspberrypi kernel: [    0.796164] FIQ FSM acceleration enabled for :
Jul 10 08:48:04 raspberrypi kernel: [    0.796164] Non-periodic Split Transactions
Jul 10 08:48:04 raspberrypi kernel: [    0.796164] Periodic Split Transactions
Jul 10 08:48:04 raspberrypi kernel: [    0.796164] High-Speed Isochronous Endpoints
Jul 10 08:48:04 raspberrypi kernel: [    0.796164] Interrupt/Control Split Transaction hack enabled
Jul 10 08:48:04 raspberrypi kernel: [    0.796222] dwc_otg: Microframe scheduler enabled
Jul 10 08:48:04 raspberrypi kernel: [    0.796277] WARN::hcd_init_fiq:457: FIQ on core 1
Jul 10 08:48:04 raspberrypi kernel: [    0.796310] WARN::hcd_init_fiq:458: FIQ ASM at c07b6224 length 36
Jul 10 08:48:04 raspberrypi kernel: [    0.796342] WARN::hcd_init_fiq:497: MPHI regs_base at f0810200
Jul 10 08:48:04 raspberrypi kernel: [    0.796392] dwc_otg fe980000.usb: DWC OTG Controller
Jul 10 08:48:04 raspberrypi kernel: [    0.796436] dwc_otg fe980000.usb: new USB bus registered, assigned bus number 3
Jul 10 08:48:04 raspberrypi kernel: [    0.796487] dwc_otg fe980000.usb: irq 37, io mem 0x00000000
Jul 10 08:48:04 raspberrypi kernel: [    0.796552] Init: Port Power? op_state=1
Jul 10 08:48:04 raspberrypi kernel: [    0.796573] Init: Power Port (0)
Jul 10 08:48:04 raspberrypi kernel: [    0.796848] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
Jul 10 08:48:04 raspberrypi kernel: [    0.796881] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jul 10 08:48:04 raspberrypi kernel: [    0.796911] usb usb3: Product: DWC OTG Controller
Jul 10 08:48:04 raspberrypi kernel: [    0.796934] usb usb3: Manufacturer: Linux 4.19.75-v7l+ dwc_otg_hcd
Jul 10 08:48:04 raspberrypi kernel: [    0.796959] usb usb3: SerialNumber: fe980000.usb
Jul 10 08:48:04 raspberrypi kernel: [    0.797478] hub 3-0:1.0: USB hub found
Jul 10 08:48:04 raspberrypi kernel: [    0.797540] hub 3-0:1.0: 1 port detected
Jul 10 08:48:04 raspberrypi kernel: [    0.798138] dwc_otg: FIQ enabled
Jul 10 08:48:04 raspberrypi kernel: [    0.798148] dwc_otg: NAK holdoff enabled
Jul 10 08:48:04 raspberrypi kernel: [    0.798156] dwc_otg: FIQ split-transaction FSM enabled
Jul 10 08:48:04 raspberrypi kernel: [    0.798170] Module dwc_common_port init
Jul 10 08:48:04 raspberrypi kernel: [    0.798382] usbcore: registered new interface driver uas
Jul 10 08:48:04 raspberrypi kernel: [    0.798513] usbcore: registered new interface driver usb-storage
Jul 10 08:48:04 raspberrypi kernel: [    0.798691] mousedev: PS/2 mouse device common for all mice
Jul 10 08:48:04 raspberrypi kernel: [    0.799880] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Jul 10 08:48:04 raspberrypi kernel: [    0.800076] bcm2835-cpufreq: min=600000 max=1500000
Jul 10 08:48:04 raspberrypi kernel: [    0.800695] sdhci: Secure Digital Host Controller Interface driver
Jul 10 08:48:04 raspberrypi kernel: [    0.800718] sdhci: Copyright(c) Pierre Ossman
Jul 10 08:48:04 raspberrypi kernel: [    0.801111] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
Jul 10 08:48:04 raspberrypi kernel: [    0.801497] sdhci-pltfm: SDHCI platform and OF driver helper
Jul 10 08:48:04 raspberrypi kernel: [    0.804499] ledtrig-cpu: registered to indicate activity on CPUs
Jul 10 08:48:04 raspberrypi kernel: [    0.804661] hidraw: raw HID events driver (C) Jiri Kosina
Jul 10 08:48:04 raspberrypi kernel: [    0.804820] usbcore: registered new interface driver usbhid
Jul 10 08:48:04 raspberrypi kernel: [    0.804843] usbhid: USB HID core driver
Jul 10 08:48:04 raspberrypi kernel: [    0.805580] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0
Jul 10 08:48:04 raspberrypi kernel: [    0.807140] [vc_sm_connected_init]: start
Jul 10 08:48:04 raspberrypi kernel: [    0.814731] [vc_sm_connected_init]: end - returning 0
Jul 10 08:48:04 raspberrypi kernel: [    0.815827] Initializing XFRM netlink socket
Jul 10 08:48:04 raspberrypi kernel: [    0.815869] NET: Registered protocol family 17
Jul 10 08:48:04 raspberrypi kernel: [    0.815973] Key type dns_resolver registered
Jul 10 08:48:04 raspberrypi kernel: [    0.816292] Registering SWP/SWPB emulation handler
Jul 10 08:48:04 raspberrypi kernel: [    0.816907] registered taskstats version 1
Jul 10 08:48:04 raspberrypi kernel: [    0.816937] Loading compiled-in X.509 certificates
Jul 10 08:48:04 raspberrypi kernel: [    0.824621] uart-pl011 fe201000.serial: cts_event_workaround enabled
Jul 10 08:48:04 raspberrypi kernel: [    0.824702] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2
Jul 10 08:48:04 raspberrypi kernel: [    0.827653] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Jul 10 08:48:04 raspberrypi kernel: [    0.828215] brcmstb_thermal fd5d2200.thermal: registered AVS TMON of-sensor driver
Jul 10 08:48:04 raspberrypi kernel: [    0.828839] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
Jul 10 08:48:04 raspberrypi kernel: [    0.828866] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
Jul 10 08:48:04 raspberrypi kernel: [    0.854672] sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.1
Jul 10 08:48:04 raspberrypi kernel: [    0.872010] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.873645] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.875441] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.878357] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.879990] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jul 10 08:48:04 raspberrypi kernel: [    0.887635] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
Jul 10 08:48:04 raspberrypi kernel: [    0.890062] of_cfs_init
Jul 10 08:48:04 raspberrypi kernel: [    0.890167] of_cfs_init: OK
Jul 10 08:48:04 raspberrypi kernel: [    0.890786] Waiting for root device PARTUUID=e8429ad3-02...
Jul 10 08:48:04 raspberrypi kernel: [    0.936883] random: fast init done
Jul 10 08:48:04 raspberrypi kernel: [    0.951568] usb 1-1: new high-speed USB device number 2 using xhci_hcd
Jul 10 08:48:04 raspberrypi kernel: [    0.987956] mmc0: new ultra high speed DDR50 SDHC card at address 1234
Jul 10 08:48:04 raspberrypi kernel: [    0.989882] mmc1: new high speed SDIO card at address 0001
Jul 10 08:48:04 raspberrypi kernel: [    0.990692] mmcblk0: mmc0:1234 SA16G 14.5 GiB
Jul 10 08:48:04 raspberrypi kernel: [    0.991964]  mmcblk0: p1 p2
Jul 10 08:48:04 raspberrypi kernel: [    1.010848] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jul 10 08:48:04 raspberrypi kernel: [    1.010914] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Jul 10 08:48:04 raspberrypi kernel: [    1.016018] devtmpfs: mounted
Jul 10 08:48:04 raspberrypi kernel: [    1.022338] Freeing unused kernel memory: 2048K
Jul 10 08:48:04 raspberrypi kernel: [    1.051783] Run /sbin/init as init process
Jul 10 08:48:04 raspberrypi kernel: [    1.142985] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.20
Jul 10 08:48:04 raspberrypi kernel: [    1.143025] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Jul 10 08:48:04 raspberrypi kernel: [    1.143052] usb 1-1: Product: USB2.0 Hub
Jul 10 08:48:04 raspberrypi kernel: [    1.144677] hub 1-1:1.0: USB hub found
Jul 10 08:48:04 raspberrypi kernel: [    1.144963] hub 1-1:1.0: 4 ports detected
Jul 10 08:48:04 raspberrypi kernel: [    1.587566] NET: Registered protocol family 10
Jul 10 08:48:04 raspberrypi kernel: [    1.588789] Segment Routing with IPv6
Jul 10 08:48:04 raspberrypi kernel: [    2.300346] random: systemd: uninitialized urandom read (16 bytes read)
Jul 10 08:48:04 raspberrypi kernel: [    2.308873] random: systemd: uninitialized urandom read (16 bytes read)
Jul 10 08:48:04 raspberrypi kernel: [    2.309993] random: systemd: uninitialized urandom read (16 bytes read)
Jul 10 08:48:04 raspberrypi kernel: [    3.747557] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Jul 10 08:48:04 raspberrypi kernel: [    4.397679] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x0000ffff
Jul 10 08:48:04 raspberrypi kernel: [    4.398155] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00000fff
Jul 10 08:48:04 raspberrypi kernel: [    4.404273] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x0000ffff
Jul 10 08:48:04 raspberrypi kernel: [    4.406623] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x0000ffff
Jul 10 08:48:04 raspberrypi kernel: [    4.440991] media: Linux media interface: v0.10
Jul 10 08:48:04 raspberrypi kernel: [    4.465714] videodev: Linux video capture interface: v2.00
Jul 10 08:48:04 raspberrypi kernel: [    4.494013] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Jul 10 08:48:04 raspberrypi kernel: [    4.507698] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Jul 10 08:48:04 raspberrypi kernel: [    4.507715] [vc_sm_connected_init]: start
Jul 10 08:48:04 raspberrypi kernel: [    4.509065] [vc_sm_connected_init]: installed successfully
Jul 10 08:48:04 raspberrypi kernel: [    4.517230] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Jul 10 08:48:04 raspberrypi kernel: [    4.517990] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Jul 10 08:48:04 raspberrypi kernel: [    4.523247] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Jul 10 08:48:04 raspberrypi kernel: [    4.535623] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Jul 10 08:48:04 raspberrypi rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.1901.0]
Jul 10 08:48:04 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon.
Jul 10 08:48:04 raspberrypi rsyslogd:  [origin software="rsyslogd" swVersion="8.1901.0" x-pid="435" x-info="https://www.rsyslog.com"] start
Jul 10 08:48:04 raspberrypi kernel: [    4.544621] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Jul 10 08:48:04 raspberrypi kernel: [    4.544672] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Jul 10 08:48:04 raspberrypi kernel: [    4.564332] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Jul 10 08:48:04 raspberrypi kernel: [    4.564372] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Jul 10 08:48:04 raspberrypi kernel: [    4.579624] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Jul 10 08:48:04 raspberrypi kernel: [    4.579656] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Jul 10 08:48:04 raspberrypi kernel: [    4.740224] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Jul 10 08:48:04 raspberrypi kernel: [    4.749908] bcm2835_audio soc:audio: card created with 8 channels
Jul 10 08:48:04 raspberrypi kernel: [    4.836828] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Jul 10 08:48:04 raspberrypi kernel: [    4.899405] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 0
Jul 10 08:48:04 raspberrypi kernel: [    4.937128] [drm] No displays found. Consider forcing hotplug if HDMI is attached
Jul 10 08:48:04 raspberrypi kernel: [    4.937222] vc4-drm soc:gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
Jul 10 08:48:04 raspberrypi kernel: [    4.938308] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 1
Jul 10 08:48:04 raspberrypi kernel: [    4.938319] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
Jul 10 08:48:04 raspberrypi kernel: [    4.938329] [drm] No driver support for vblank timestamp query.
Jul 10 08:48:04 raspberrypi kernel: [    4.938338] [drm] Setting vblank_disable_immediate to false because get_vblank_timestamp == NULL
Jul 10 08:48:04 raspberrypi kernel: [    5.013405] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Jul 10 08:48:04 raspberrypi kernel: [    5.055139] brcmfmac: F1 signature read @0x18000000=0x15264345
Jul 10 08:48:04 raspberrypi kernel: [    5.071781] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Jul 10 08:48:04 raspberrypi kernel: [    5.072513] usbcore: registered new interface driver brcmfmac
Jul 10 08:48:04 raspberrypi kernel: [    5.308728] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Jul 10 08:48:04 raspberrypi kernel: [    5.322787] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
Jul 10 08:48:04 raspberrypi kernel: [    6.487483] bcmgenet: Skipping UMAC reset
Jul 10 08:48:04 raspberrypi kernel: [    6.582447] bcmgenet fd580000.genet: configuring instance for external RGMII (no delay)
Jul 10 08:48:04 raspberrypi kernel: [    6.582749] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Jul 10 08:48:04 raspberrypi kernel: [    6.655512] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 10 08:48:04 raspberrypi kernel: [    6.655530] brcmfmac: power management disabled
Jul 10 08:48:04 raspberrypi rng-tools[443]: Starting Hardware RNG entropy gatherer daemon: rngd.
Jul 10 08:48:04 raspberrypi sh[352]: Listening on LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:48:04 raspberrypi sh[352]: Sending on   LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:48:04 raspberrypi sh[352]: Sending on   Socket/fallback
Jul 10 08:48:04 raspberrypi sh[352]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 4
Jul 10 08:48:04 raspberrypi systemd[1]: Started Save/Restore Sound Card State.
Jul 10 08:48:04 raspberrypi dhclient[407]: Listening on LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:48:04 raspberrypi dhcpcd[458]: Not running dhcpcd because /etc/network/interfaces
Jul 10 08:48:04 raspberrypi dhcpcd[458]: defines some interfaces that will use a
Jul 10 08:48:04 raspberrypi dhcpcd[458]: DHCP client or static address
Jul 10 08:48:04 raspberrypi systemd[1]: Started System Logging Service.
Jul 10 08:48:04 raspberrypi dhclient[407]: Sending on   LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:48:04 raspberrypi systemd[1]: Started rng-tools.service.
Jul 10 08:48:04 raspberrypi dhclient[407]: Sending on   Socket/fallback
Jul 10 08:48:04 raspberrypi systemd[1]: Started Deferred execution scheduler.
Jul 10 08:48:04 raspberrypi rngd[466]: rngd 2-unofficial-mt.14 starting up...
Jul 10 08:48:04 raspberrypi dhclient[407]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 4
Jul 10 08:48:04 raspberrypi wpa_supplicant[437]: Successfully initialized wpa_supplicant
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: Successfully called chroot().
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: Successfully dropped remaining capabilities.
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: No service file found in /etc/avahi/services.
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: Network interface enumeration completed.
Jul 10 08:48:04 raspberrypi avahi-daemon[436]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 3819850766.
Jul 10 08:48:04 raspberrypi rngd[466]: entropy feed to the kernel ready
Jul 10 08:48:04 raspberrypi systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED
Jul 10 08:48:04 raspberrypi systemd[1]: dhcpcd.service: Failed with result 'exit-code'.
Jul 10 08:48:04 raspberrypi kernel: [    6.745187] random: crng init done
Jul 10 08:48:04 raspberrypi kernel: [    6.745202] random: 7 urandom warning(s) missed due to ratelimiting
Jul 10 08:48:04 raspberrypi systemd[1]: Failed to start dhcpcd on all interfaces.
Jul 10 08:48:04 raspberrypi kernel: [    6.756260] uart-pl011 fe201000.serial: no DMA platform data
Jul 10 08:48:04 raspberrypi dbus-daemon[419]: [system] Successfully activated service 'org.freedesktop.systemd1'
Jul 10 08:48:04 raspberrypi CRON[495]: (pi) CMD (sudo /usr/opt/ap)
Jul 10 08:48:04 raspberrypi dphys-swapfile[424]: want /var/swap=100MByte, checking existing: keeping it
Jul 10 08:48:04 raspberrypi CRON[500]: (pi) CMD (sudo /usr/opt/checkApps.sh )
Jul 10 08:48:04 raspberrypi raspi-config[427]: Checking if shift key is held down:Error opening '/dev/input/event*': No such file or directory
Jul 10 08:48:04 raspberrypi systemd[1]: Reached target Sound Card.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Jul 10 08:48:04 raspberrypi raspi-config[427]:  No. Switching to ondemand scaling governor.
Jul 10 08:48:04 raspberrypi systemd[1]: Started Login Service.
Jul 10 08:48:04 raspberrypi systemd[1]: Started WPA supplicant.
Jul 10 08:48:04 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Jul 10 08:48:04 raspberrypi kernel: [    6.920355] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
Jul 10 08:48:04 raspberrypi systemd[1]: Started dphys-swapfile - set up, mount/unmount, and delete a swap file.
Jul 10 08:48:04 raspberrypi loadcpufreq[415]: Loading cpufreq kernel modules...done (none).
Jul 10 08:48:04 raspberrypi wpa_supplicant[536]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=GB
Jul 10 08:48:04 raspberrypi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Jul 10 08:48:04 raspberrypi systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Jul 10 08:48:04 raspberrypi cpufrequtils[539]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
Jul 10 08:48:04 raspberrypi systemd[1]: Started LSB: set CPUFreq kernel parameters.
Jul 10 08:48:04 raspberrypi sh[359]: wlan0=wlan0
Jul 10 08:48:05 raspberrypi kernel: [    7.591627] bcmgenet fd580000.genet eth0: Link is Down
Jul 10 08:48:07 raspberrypi kernel: [   10.113327] Bluetooth: Core ver 2.22
Jul 10 08:48:07 raspberrypi kernel: [   10.113401] NET: Registered protocol family 31
Jul 10 08:48:07 raspberrypi kernel: [   10.113411] Bluetooth: HCI device and connection manager initialized
Jul 10 08:48:07 raspberrypi kernel: [   10.113437] Bluetooth: HCI socket layer initialized
Jul 10 08:48:07 raspberrypi kernel: [   10.113454] Bluetooth: L2CAP socket layer initialized
Jul 10 08:48:07 raspberrypi kernel: [   10.113497] Bluetooth: SCO socket layer initialized
Jul 10 08:48:08 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded.
Jul 10 08:48:08 raspberrypi dhclient[407]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8
Jul 10 08:48:08 raspberrypi sh[352]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8
Jul 10 08:48:10 raspberrypi kernel: [   13.348736] Bluetooth: HCI UART driver ver 2.3
Jul 10 08:48:10 raspberrypi kernel: [   13.348751] Bluetooth: HCI UART protocol H4 registered
Jul 10 08:48:10 raspberrypi kernel: [   13.348839] Bluetooth: HCI UART protocol Three-wire (H5) registered
Jul 10 08:48:10 raspberrypi kernel: [   13.349067] Bluetooth: HCI UART protocol Broadcom registered
Jul 10 08:48:10 raspberrypi btuart[451]: bcm43xx_init
Jul 10 08:48:10 raspberrypi btuart[451]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd
Jul 10 08:48:10 raspberrypi btuart[451]: Set Controller UART speed to 3000000 bit/s
Jul 10 08:48:10 raspberrypi btuart[451]: Device setup complete
Jul 10 08:48:10 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status...
Jul 10 08:48:10 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART.
Jul 10 08:48:10 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status.
Jul 10 08:48:10 raspberrypi systemd[1]: Starting Bluetooth service...
Jul 10 08:48:10 raspberrypi systemd[1]: Created slice system-bthelper.slice.
Jul 10 08:48:11 raspberrypi bluetoothd[661]: Bluetooth daemon 5.50
Jul 10 08:48:11 raspberrypi systemd[1]: Started Bluetooth service.
Jul 10 08:48:11 raspberrypi bluetoothd[661]: Starting SDP server
Jul 10 08:48:11 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper.
Jul 10 08:48:11 raspberrypi systemd[1]: Reached target Bluetooth.
Jul 10 08:48:11 raspberrypi kernel: [   13.620718] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Jul 10 08:48:11 raspberrypi kernel: [   13.620725] Bluetooth: BNEP filters: protocol multicast
Jul 10 08:48:11 raspberrypi kernel: [   13.620736] Bluetooth: BNEP socket layer initialized
Jul 10 08:48:11 raspberrypi bluetoothd[661]: Bluetooth management interface 1.14 initialized
Jul 10 08:48:11 raspberrypi dbus-daemon[419]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=661 comm="/usr/lib/bluetooth/bluetoothd ")
Jul 10 08:48:11 raspberrypi bluetoothd[661]: Sap driver initialization failed.
Jul 10 08:48:11 raspberrypi bluetoothd[661]: sap-server: Operation not permitted (1)
Jul 10 08:48:11 raspberrypi systemd[1]: bthelper@hci0.service: Succeeded.
Jul 10 08:48:11 raspberrypi systemd[1]: Starting Hostname Service...
Jul 10 08:48:11 raspberrypi bluetoothd[661]: Failed to set privacy: Rejected (0x0b)
Jul 10 08:48:11 raspberrypi dbus-daemon[419]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jul 10 08:48:11 raspberrypi systemd[1]: Started Hostname Service.
Jul 10 08:48:14 raspberrypi systemd-udevd[656]: Using default interface naming scheme 'v240'.
Jul 10 08:48:15 raspberrypi systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
Jul 10 08:48:15 raspberrypi dnsmasq[713]: dnsmasq: syntax check OK.
Jul 10 08:48:15 raspberrypi dnsmasq[714]: dnsmasq: unknown interface uap0
Jul 10 08:48:15 raspberrypi dnsmasq[714]: unknown interface uap0
Jul 10 08:48:15 raspberrypi dnsmasq[714]: FAILED to start up
Jul 10 08:48:15 raspberrypi systemd[1]: dnsmasq.service: Control process exited, code=exited, status=2/INVALIDARGUMENT
Jul 10 08:48:15 raspberrypi systemd[1]: dnsmasq.service: Failed with result 'exit-code'.
Jul 10 08:48:15 raspberrypi systemd[1]: Failed to start dnsmasq - A lightweight DHCP and caching DNS server.
Jul 10 08:48:15 raspberrypi avahi-daemon[436]: Joining mDNS multicast group on interface uap0.IPv4 with address 192.168.50.1.
Jul 10 08:48:15 raspberrypi avahi-daemon[436]: New relevant interface uap0.IPv4 for mDNS.
Jul 10 08:48:15 raspberrypi avahi-daemon[436]: Registering new address record for 192.168.50.1 on uap0.IPv4.
Jul 10 08:48:15 raspberrypi kernel: [   17.824722] IPv6: ADDRCONF(NETDEV_UP): uap0: link is not ready
Jul 10 08:48:15 raspberrypi kernel: [   18.018786] IPv6: ADDRCONF(NETDEV_CHANGE): uap0: link becomes ready
Jul 10 08:48:15 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded.
Jul 10 08:48:16 raspberrypi dhclient[407]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 19
Jul 10 08:48:16 raspberrypi sh[352]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 19
Jul 10 08:48:16 raspberrypi avahi-daemon[436]: Joining mDNS multicast group on interface uap0.IPv6 with address fe80::dea6:32ff:fe14:b7d2.
Jul 10 08:48:16 raspberrypi avahi-daemon[436]: New relevant interface uap0.IPv6 for mDNS.
Jul 10 08:48:16 raspberrypi avahi-daemon[436]: Registering new address record for fe80::dea6:32ff:fe14:b7d2 on uap0.*.
Jul 10 08:48:23 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:48:32 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:48:33 raspberrypi systemd[1]: systemd-fsckd.service: Succeeded.
Jul 10 08:48:35 raspberrypi dhclient[407]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 17
Jul 10 08:48:35 raspberrypi sh[352]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 17
Jul 10 08:48:40 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:48:41 raspberrypi systemd[1]: systemd-hostnamed.service: Succeeded.
Jul 10 08:48:49 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:48:52 raspberrypi dhclient[407]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8
Jul 10 08:48:52 raspberrypi sh[352]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8
Jul 10 08:48:58 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:49:00 raspberrypi dhclient[407]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
Jul 10 08:49:00 raspberrypi sh[352]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
Jul 10 08:49:05 raspberrypi dhclient[407]: No DHCPOFFERS received.
Jul 10 08:49:05 raspberrypi sh[352]: No DHCPOFFERS received.
Jul 10 08:49:05 raspberrypi sh[352]: No working leases in persistent database - sleeping.
Jul 10 08:49:05 raspberrypi dhclient[407]: No working leases in persistent database - sleeping.
Jul 10 08:49:06 raspberrypi sh[352]: eth0=eth0
Jul 10 08:49:06 raspberrypi ifup[355]: RTNETLINK answers: File exists
Jul 10 08:49:06 raspberrypi ifup[355]: ifup: failed to bring up uap0
Jul 10 08:49:06 raspberrypi systemd[1]: networking.service: Main process exited, code=exited, status=1/FAILURE
Jul 10 08:49:06 raspberrypi systemd[1]: networking.service: Failed with result 'exit-code'.
Jul 10 08:49:06 raspberrypi systemd[1]: Failed to start Raise network interfaces.
Jul 10 08:49:06 raspberrypi systemd[1]: Reached target Network.
Jul 10 08:49:06 raspberrypi systemd[1]: Starting Network Time Service...
Jul 10 08:49:06 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
Jul 10 08:49:06 raspberrypi systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server...
Jul 10 08:49:06 raspberrypi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Jul 10 08:49:06 raspberrypi systemd[1]: Reached target Network is Online.
Jul 10 08:49:06 raspberrypi systemd[1]: Started consul-template.
Jul 10 08:49:06 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
Jul 10 08:49:06 raspberrypi dnsmasq[994]: dnsmasq: syntax check OK.
Jul 10 08:49:06 raspberrypi systemd[1]: Starting Permit User Sessions...
Jul 10 08:49:06 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
Jul 10 08:49:06 raspberrypi systemd[1]: Started Permit User Sessions.
Jul 10 08:49:06 raspberrypi ntpd[989]: ntpd 4.2.8p12@1.3728-o (1): Starting
Jul 10 08:49:06 raspberrypi systemd[1]: Started Getty on tty1.
Jul 10 08:49:06 raspberrypi systemd[1]: Reached target Login Prompts.
Jul 10 08:49:06 raspberrypi ntpd[989]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 111:116
Jul 10 08:49:06 raspberrypi systemd[1]: Started Network Time Service.
Jul 10 08:49:06 raspberrypi ntpd[1015]: proto: precision = 0.759 usec (-20)
Jul 10 08:49:06 raspberrypi ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Jul 10 08:49:06 raspberrypi ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2020-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Jul 10 08:49:06 raspberrypi ntpd[1015]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 13 days ago
Jul 10 08:49:06 raspberrypi ntpd[1015]: Listen and drop on 0 v6wildcard [::]:123
Jul 10 08:49:06 raspberrypi ntpd[1015]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Jul 10 08:49:06 raspberrypi ntpd[1015]: Listen normally on 2 lo 127.0.0.1:123
Jul 10 08:49:06 raspberrypi ntpd[1015]: Listen normally on 3 uap0 192.168.50.1:123
Jul 10 08:49:06 raspberrypi ntpd[1015]: Listen normally on 4 lo [::1]:123
Jul 10 08:49:06 raspberrypi ntpd[1015]: Listen normally on 5 uap0 [fe80::dea6:32ff:fe14:b7d2%4]:123
Jul 10 08:49:06 raspberrypi ntpd[1015]: Listening on routing socket on fd #22 for interface updates
Jul 10 08:49:06 raspberrypi ntpd[1015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jul 10 08:49:06 raspberrypi ntpd[1015]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Jul 10 08:49:06 raspberrypi dnsmasq[1019]: started, version 2.80 cachesize 150
Jul 10 08:49:06 raspberrypi dnsmasq[1019]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile
Jul 10 08:49:06 raspberrypi dnsmasq-dhcp[1019]: DHCP, IP range 192.168.50.50 -- 192.168.50.150, lease time 12h
Jul 10 08:49:06 raspberrypi dnsmasq-dhcp[1019]: DHCP, sockets bound exclusively to interface uap0
Jul 10 08:49:06 raspberrypi dnsmasq[1019]: using nameserver 8.8.8.8#53
Jul 10 08:49:06 raspberrypi dnsmasq[1019]: read /etc/hosts - 5 addresses
Jul 10 08:49:06 raspberrypi consul-template.sh[995]: CLEARING ANY PENDING JOBS
Jul 10 08:49:06 raspberrypi consul-template.sh[995]: CLEARED OLD PENDING JOBS
Jul 10 08:49:06 raspberrypi consul-template.sh[995]: FILE DOES NOT EXIST
Jul 10 08:49:06 raspberrypi consul-template.sh[995]: GETTING NEW TOKEN
Jul 10 08:49:06 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
Jul 10 08:49:06 raspberrypi dnsmasq[1019]: no servers found in /run/dnsmasq/resolv.conf, will retry
Jul 10 08:49:06 raspberrypi dnsmasq[1021]: Too few arguments.
Jul 10 08:49:06 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:49:07 raspberrypi ntpd[1015]: error resolving pool time.google.com: Temporary failure in name resolution (-3)
Jul 10 08:49:07 raspberrypi systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server.
Jul 10 08:49:07 raspberrypi systemd[1]: Reached target Host and Network Name Lookups.
Jul 10 08:49:07 raspberrypi consul-template.sh[995]: GOT RESPONSE CODE OTHER THAN 200 SCHEDULING A JOB TO CHECK AFTER 5 MINUTES
Jul 10 08:49:07 raspberrypi systemd[1]: Starting LSB: exim Mail Transport Agent...
Jul 10 08:49:07 raspberrypi consul-template.sh[995]: warning: commands will be executed using /bin/sh
Jul 10 08:49:07 raspberrypi systemd[1]: Starting The Apache HTTP Server...
Jul 10 08:49:08 raspberrypi apachectl[1085]: AH00558: apache2: Could not reliably determine the server's fully qualified domain name, using 127.0.1.1. Set the 'ServerName' directive globally to suppress this message
Jul 10 08:49:08 raspberrypi exim4[1084]: Starting MTA: exim4.
Jul 10 08:49:08 raspberrypi systemd[1]: Started LSB: exim Mail Transport Agent.
Jul 10 08:49:08 raspberrypi systemd[1]: Started The Apache HTTP Server.
Jul 10 08:49:08 raspberrypi systemd[1]: Reached target Multi-User System.
Jul 10 08:49:08 raspberrypi systemd[1]: Reached target Graphical Interface.
Jul 10 08:49:08 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jul 10 08:49:08 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Jul 10 08:49:08 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
Jul 10 08:49:08 raspberrypi systemd[1]: Startup finished in 1.454s (kernel) + 1min 9.575s (userspace) = 1min 11.029s.
Jul 10 08:49:15 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:49:24 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:49:41 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:49:50 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:49:59 raspberrypi wpa_supplicant[536]: wlan0: Failed to initiate sched scan
Jul 10 08:50:01 raspberrypi CRON[1561]: (pi) CMD (sudo /usr/opt/crons/wifi_check.sh )
Jul 10 08:50:03 raspberrypi wpa_supplicant[536]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory
Jul 10 08:50:03 raspberrypi wpa_supplicant[536]: nl80211: Failed to set IPv4 unicast in multicast filter
Jul 10 08:50:03 raspberrypi wpa_supplicant[536]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory
Jul 10 08:50:03 raspberrypi wpa_supplicant[536]: nl80211: Failed to set IPv4 unicast in multicast filter
Jul 10 08:50:03 raspberrypi wpa_supplicant[536]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0
Jul 10 08:50:03 raspberrypi wpa_supplicant[1620]: Successfully initialized wpa_supplicant
Jul 10 08:50:03 raspberrypi wpa_supplicant[536]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING 
Jul 10 08:50:03 raspberrypi kernel: [  126.004942] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
Jul 10 08:50:03 raspberrypi kernel: [  126.004974] brcmfmac: power management disabled
Jul 10 08:50:03 raspberrypi wpa_supplicant[536]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Jul 10 08:50:03 raspberrypi wpa_supplicant[1620]: ctrl_iface exists and seems to be in use - cannot override it
Jul 10 08:50:03 raspberrypi wpa_supplicant[1620]: Delete '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore
Jul 10 08:50:03 raspberrypi wpa_supplicant[1620]: Failed to initialize control interface 'DIR=/var/run/wpa_supplicant GROUP=netdev'.#012You may have another wpa_supplicant process already running or the file was#012left by an unclean termination of wpa_supplicant in which case you will need#012to manually remove this file before starting wpa_supplicant again.
Jul 10 08:50:03 raspberrypi wpa_supplicant[1620]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0
Jul 10 08:50:04 raspberrypi wpa_supplicant[536]: wlan0: CTRL-EVENT-TERMINATING 
Jul 10 08:50:04 raspberrypi dhclient[1635]: Killed old client process
Jul 10 08:50:05 raspberrypi dhclient[1635]: Internet Systems Consortium DHCP Client 4.4.1
Jul 10 08:50:05 raspberrypi dhclient[1635]: Copyright 2004-2018 Internet Systems Consortium.
Jul 10 08:50:05 raspberrypi dhclient[1635]: All rights reserved.
Jul 10 08:50:05 raspberrypi dhclient[1635]: For info, please visit https://www.isc.org/software/dhcp/
Jul 10 08:50:05 raspberrypi dhclient[1635]: 
Jul 10 08:50:05 raspberrypi dhclient[1635]: Listening on LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:50:05 raspberrypi dhclient[1635]: Sending on   LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:50:05 raspberrypi dhclient[1635]: Sending on   Socket/fallback
Jul 10 08:50:05 raspberrypi dhclient[1677]: Internet Systems Consortium DHCP Client 4.4.1
Jul 10 08:50:05 raspberrypi dhclient[1677]: Copyright 2004-2018 Internet Systems Consortium.
Jul 10 08:50:05 raspberrypi dhclient[1677]: All rights reserved.
Jul 10 08:50:05 raspberrypi dhclient[1677]: For info, please visit https://www.isc.org/software/dhcp/
Jul 10 08:50:05 raspberrypi dhclient[1677]: 
Jul 10 08:50:05 raspberrypi kernel: [  128.224000] bcmgenet: Skipping UMAC reset
Jul 10 08:50:05 raspberrypi kernel: [  128.322894] bcmgenet fd580000.genet: configuring instance for external RGMII (no delay)
Jul 10 08:50:05 raspberrypi kernel: [  128.323107] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Jul 10 08:50:05 raspberrypi dhclient[1677]: Listening on LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:50:05 raspberrypi dhclient[1677]: Sending on   LPF/eth0/dc:a6:32:14:b7:d1
Jul 10 08:50:05 raspberrypi dhclient[1677]: Sending on   Socket/fallback
Jul 10 08:50:05 raspberrypi dhclient[1677]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 3
Jul 10 08:50:06 raspberrypi kernel: [  129.352858] bcmgenet fd580000.genet eth0: Link is Down
Jul 10 08:50:09 raspberrypi dhclient[1677]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
Jul 10 08:50:11 raspberrypi ntpd[1015]: error resolving pool time.google.com: Temporary failure in name resolution (-3)
Jul 10 08:50:14 raspberrypi dhclient[1677]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
Jul 10 08:50:19 raspberrypi dhclient[1677]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 11
Jul 10 08:50:25 raspberrypi kernel: [  148.285301] Bluetooth: hci0: advertising data len corrected
Jul 10 08:50:26 raspberrypi kernel: [  148.765304] Bluetooth: hci0: advertising data len corrected
Jul 10 08:50:26 raspberrypi kernel: [  148.809054] Bluetooth: hci0: advertising data len corrected
Jul 10 08:50:26 raspberrypi kernel: [  148.984060] Bluetooth: hci0: advertising data len corrected
Jul 10 08:50:27 raspberrypi kernel: [  149.809058] Bluetooth: hci0: advertising data len corrected
Jul 10 08:50:30 raspberrypi dhclient[1677]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 19
Jul 10 08:50:49 raspberrypi dhclient[1677]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 7
Jul 10 08:50:56 raspberrypi dhclient[1677]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 10
Jul 10 08:51:06 raspberrypi dhclient[1677]: No DHCPOFFERS received.
Jul 10 08:51:06 raspberrypi dhclient[1677]: No working leases in persistent database - sleeping.

@rlyons20
Copy link

I'm having this same problem on 2 RPi4 that I'm using as Pi-Holes.

I have tried new cables, new power supplies, new switch and even tried a USB NIC.

Nothing solved the problem permanently.

Please advise!

@ChuckNorrison
Copy link

same problem here... ip addr shows everything is fine. pings not possible, route -n is empty

@rlyons20
Copy link

So, I don't know if this is a cause or just a symptom, but I noticed in my syslog that other hosts on my network are trying to claim the RPi static addresses. These events seem to occur near the network drops, but the network does NOT drop every time they occur. I've also sniffed my network, and don't see any signs of a rogue DHCP server that would be trying to hand out these addresses. Their static addresses are NOT in my DHCP address range, so I have no idea how or why these hosts are trying to grab the address. Here is a log sample:

Sep 15 18:01:40 host-1 dhcpcd[1161]: eth0: hardware address xx:xx:xx:xx:xx:xx claims 192.168.xxx.xxx

@2play
Copy link

2play commented Nov 6, 2020

I noticed this weird activity started last week. I use pi4 2gb with latest updates, latest stable firmware and added today the latest eeprom.
I have in one place same eth cat5e cable connected to a small giga switch (all pcs get ip from dhcp server fine) and another place with wifi.
If pi4 is turned off it boots and gets IP from LAN port ok. if i reboot no ip from dhcp server and a private 169 ip shows up only

as most said above, since im next to Pi, if i unplug the cable and put back in, a new correct lan ip received.

It seems to me that the dhcp service is messed OR the LAN interface on Pi4 stays alive during reboot rather fully restart as if is when pi4 is powered off. (possible hw pan problem as stated above - even though all was working fine for 9+ months)
I ve noticed also some weird slowdown on internet downloads or network acting up. Maybe FW related also?
Will do some more testing my side for my own sake

@2play
Copy link

2play commented Nov 6, 2020

My culprit was the office lan cable.
I changed lan cable and tested with new lan switch. All ok! So my prob is that lan cable at the office ended up bad after couple years :-)
I got latest eerpom.
I also tested latest beta fw before i change cables to see if it was fw related but nope.
It was my cable my case. For others is hw problem as mentioned above.
Just wanted to share my case

@pelwell
Copy link
Contributor

pelwell commented Nov 6, 2020

Thanks for reporting back.

@rlyons20
Copy link

rlyons20 commented Nov 6, 2020

I changed cables and the switch the pis were connected to. I ended up replacing them with pi-zeros, and have had NO problems since the replacement.

@ChuckNorrison
Copy link

ChuckNorrison commented Nov 7, 2020

Bad LAN Cables?! Really? First time in my life heard of that... i dont believe in the LAN Cable theory.

By the way i am using CAT-8 and today my blitz refused with "Wait for LAN". Even after reconnect the cable it does not receive a new IP. it needs a sudo dhclient -r; sudo dhclient. I dont know yet why this happened today.

Using 1.6.1 here

I added a shell script to check network and will try to repair:

#!/bin/sh
logPath=/var/log
logFile=check_network.log
ping_target=8.8.8.8
find "$logPath/" -type f -size +512k -name "$logFile" -exec rm -rf {} \;
if [ ! -f "$logPath/$logFile" ]; then
  echo "`date`: Create new logfile: $logPath/$logFile" 
  touch "$logPath/$logFile"
fi

ping -q -c 4 $ping_target > /dev/null
if [ $? -eq 0 ]; then
  echo "`date`: Connectivity check successful"
else
  echo "`date`: Connectivity check failed"
  sudo dhclient -r; sudo dhclient > /dev/null
  sleep 15
  sudo systemctl restart nginx
  sudo systemctl restart sshd
  echo "`date`: Connectivity repaired... hopefully"
  exit
fi

make it executable with sudo chmod +x check_network.sh

this one could be placed in /root and set in roots crontab with sudo crontab -e

@reboot mkdir -p -m0755 /var/run/sshd && systemctl restart ssh.service
* * * * * /root/check_network.sh >> /var/log/zed_check_network.log 2>&1

Here i added an additional restart ssh service after each reboot. Tell me your thoughts on this.

@2play
Copy link

2play commented Nov 18, 2020

Thanks for reporting back.

ok it came back with the new cable too. first was random days. now its all day after reboot. its really weird

@ChuckNorrison havent tried above but id prefer an official fix rather a good workaround. OS should work as expected :-)
Btw
@ChuckNorrison the script works great!

@ChuckNorrison
Copy link

ChuckNorrison commented Nov 18, 2020

This seems related to disk corruption from power consumption issues on the pi 4.

Do not use external hdd on USB 3.0.
Set some fstab options for external hdd like _netdev, nofail
Disable WLAN/BT if not needed via /boot/config.txt

My script will make it possible to get around the missing ssh login and some failures related to a "bad boot"

@2play
Copy link

2play commented Nov 18, 2020

As long as i was getting an ip no prob with ssh or corruption but im happy for the script to force the dhcp client to do tis job :-)

@freddyrios
Copy link

I had something similar on a ring network of raspberry pis.

Not only when rebooting, but also when reconnecting one of the cables.

We tried with 3 usb to eth adapters. 2 of them failed. The one that worked came from the same brand to one that failed:

  • worked: deltaco, usb-a to network adapter with gibabit ethernet
  • fails on some connections & reboots: deltaco, usb-a hub and network adapter
  • fails on some connections & reboots: syncwire USB 3.0 to Ethernet Adapter

@lohikarhu
Copy link

I have just updated my Pi3B to the latest OS, and the eth0 will not work...it worked on the previous version of the OS, but seems to be misconfigured, as the port worked earlier.

I tried the mii ... command but it didn't reconnect

@rapierbit
Copy link

Well this has been an entertaining thread – how's the lawsuit going 😂

At any rate, here's my 2 cents. I have a (Canokit) 4 Model B Rev1.2 that I purchased Summer 2020, and a 3B Rev 1.2 (from maybe 2 years ago? no memory of where I got it). At the end of the summer I installed 08-20-buster in the "standard" way, and both RPis came up perfectly on both wireless and ethernet. I then let them languish for a while and noted that at some point the 3B dropped off the network. So I went back to it 2 days ago and first discovered that the 3B's microSD card had gone bad. So I redid 08-20-buster on a new flash card and the 3B came right up on wireless as before, but NOT on ethernet – no matter what I did (sorry not listing everything), it always self-assigned a non-routable 169 address. I did also try sudo mii-tool -r eth0 and that did NOT work.

However, what DID work was to create a static address in /etc/dhcpcd.conf. That surprised me a bit, but there you go. My observations:

  1. This 3B board ethernet used to work and then didn't, and it had nothing to do with the OS version.
  2. I sadly don't remember what router I used back in the summer, but for this go-round, I'm using an ancient WRT54G v5 (with the original Linksys firmware! not even DD_WRT yet).
  3. I tried playing with the ethernet cable and also power before I got it to work, to no avail.

Like voter fraud, I have no real evidence, but my experience suggests to me some subtle interaction with the particular router in question and perhaps the RPi hardware, but my 4 has been rock solid through all of this, and the 3B is pretty old (and presumably not flawed) at this point. Good luck finding this issue, if you are still looking for it, but I think next time, although the static address is simple enough, it obviously doesn't work for everyone and may be less than ideal. I would try a different router if I had to troubleshoot this again.

In a month I'm teaching a class in which 35 students are going to have to go through this, and I'm sure they will almost all have 4s because they believe the latest is always the greatest. I've bookmarked this thread and will report back what happens.

@lohikarhu
Copy link

lohikarhu commented Dec 25, 2020 via email

@chishop
Copy link

chishop commented Jan 1, 2021

I am not sure if my issue is exactly the same, but reading through the posts it seems to be, at the very least, quite similar.

I've recently moved my family into a new home. With this comes using a new isp with a new isp-provided router required to use their signal. The provider is Virgin using what they call their superhub 3.0 router.

My pi4 has never failed to connect to a wired connection before. I've used it on at least three different routers before I moved without issue. When I hook it up to this router it always reports "no carrier" and no lights come on the lan ports at the back of the router as they do when plugging in any other device.

I have tried plugging directly into the router rather than the ethernet ports I have installed in each of the rooms, but still get "no carrier." All other devices -- other Pis and Linux desktop machines, work flawlessly regardless of where they are plugged in, remotely or directly into the router.

I have tried using every different available Linux distribution for the pi4, none of which will give me a wired connection or report a carrier present when plugging in. I have then tried disabling default network managers and one by one trying every different piece of software I can find to manage the connection to see if one might know something the others don't and hopefully get it to work. At this point I felt that perhaps the controller had been physically damaged. Why else would every other device work but not this one, even when trying fresh installs of multiple different distributions? But then I tried something that destroyed that theory.

I plugged an old netgear router into the new router. I then plugged the pi4 into the netgear router. It Instantly connected with full lan and internet functionality over ethernet. This works if I plug the old router directly into the new router, or if I plug the old router into an ethernet port in another room and then plug the pi4 into it. I am completely bemused, have been trying to figure out what the heck is going on and why, and to make it work without an old router between them for days. I am obsessed and can't seem to let go of it. While it is an effecitve workaround, I don't want to have an extra router between my pi and the router everything else connects to without issue. I've browsed the internet to eyes-are-bloodshot stage and nothing, other than this topic, seems to sound anything like what I'm experiencing. Neither rebooting or replugging the cable nor using a mii-tool command has any effect. If someone were able to provide a solution, or, at the very least, explain why what is happening is happening, it would improve my sleep. I am scratching my head and at a complete loss.

@pelwell
Copy link
Contributor

pelwell commented Jan 1, 2021

Please create a new Issue for this.

@lohikarhu
Copy link

lohikarhu commented Jan 1, 2021 via email

@pelwell
Copy link
Contributor

pelwell commented Jan 1, 2021

Commenting on the end of a Closed (or just different) issue is a great way to be ignored or forgotten.

@M-Reimer
Copy link
Author

M-Reimer commented Jan 1, 2021

After the initial trouble which was the reason for getting this issue closed I was asked to create a new issue which is this (still open) one: #3108

But to this day I still get notifications about this closed issue. Could someone lock conversations here so all discussions happen on the new issue?

@raspberrypi raspberrypi locked as off-topic and limited conversation to collaborators Jan 1, 2021
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Labels
None yet
Projects
None yet
Development

No branches or pull requests