Error resolving pool 0 ubuntu pool ntp org name or service not known 2

Ара Магеддон писал(а): ↑

28 июн 2019, 06:09

вывод команд inxi -Fxz

Ара Магеддон писал(а): ↑

28 июн 2019, 06:09

journalctl —boot -1 | tail -500

Linux Mint Forums

Welcome to the Linux Mint forums!

[SOLVED] NTP Errors

[SOLVED] NTP Errors

Post by Wirezfree » Fri Jan 31, 2020 1:07 pm

I’m trying to investigate a series of Mint Logs entries
The first group are all ntp related.

Some comments/threads seem imply ntp maybe running multiple times.
Where to check. I do not see any ntp items in system monitor.?

Many Thanks

Re: NTP Errors

Post by dave8671 » Fri Jan 31, 2020 2:36 pm

Re: NTP Errors

Post by Wirezfree » Fri Jan 31, 2020 2:49 pm

Yes, my time is correct.

I think maybe/possibly I have some how got ntp & ntpdate and systemd-timesyncd.service «all» doing time.

Can I just uninstall ntp & ntpdate and just let systemd-timesyncd.service do the time.

Re: NTP Errors

Post by karlchen » Fri Jan 31, 2020 5:19 pm

Yes, by all means. systemd-timesyncd should be used. Stop the service ntp. Disable the service ntp.
Makes sure the service systemd-timesyncd is running properly.
Feel free to uninstall ntp.

Best regards,
Karl

Re: NTP Errors

Post by Wirezfree » Fri Jan 31, 2020 8:44 pm

Yes, by all means. systemd-timesyncd should be used. Stop the service ntp. Disable the service ntp.
Makes sure the service systemd-timesyncd is running properly.
Feel free to uninstall ntp.

Best regards,
Karl

Stop, Disabled and uninstalled ntp & ntpd. all ntp/ntpd errors have now gone.
systemd-timesyncd appears to function normally

Where is the configuation file for the time » ntp source server» it seems to be » ntp.ubuntu.com«

Best Regards
mfg

Re: NTP Errors

Post by Wirezfree » Fri Jan 31, 2020 10:31 pm

I’m still trying to understand «systemd-timesyncd»

The following shows time is sync’d to «ntp.ubuntu.com»

[SOLVED]: NTP Errors

Post by Wirezfree » Sat Feb 01, 2020 8:10 am

Figured out and Configured

> Stopped and uninstalled ntp & ntpd

Re: [SOLVED] NTP Errors

Post by blecktar » Sun Jul 12, 2020 4:37 pm

Folks, this is still happening with Linux Mint 20.

I’m brand new to Linux Mint and not a Linux expert. Can somebody add this to the list of things that need to be corrected before the next release or teach me how to do that or submit some kind of report, please?

Here’s what version of Linux Mint I’m running:

System: Kernel: 5.4.0-40-generic x86_64 bits: 64 compiler: gcc v: 9.3.0 Desktop: Xfce 4.14.2
tk: Gtk 3.24.13 wm: xfwm4 dm: LightDM Distro: Linux Mint 20 Ulyana
base: Ubuntu 20.04 focal

Источник

Solved ntpd error

vince66

after booted my PC, I received the message:

Maybe is it means that the internet connection doesn’t work ?

Please, how can I check if my internet connection is up ?
I’ve tried with: ping google.com , receiving the message:cannot resolve google.com: Hostname lookup failure.

Is there a more performant method to check if the internet connection is on/off ?

Thanks in advance.

SirDice

Administrator

There are two problems here, the first is a failure to resolve DNS names, as indicated by the errors. The other may be the connection itself, if there’s no network connection DNS resolving obviously won’t work either. Look at the output of ifconfig check if your interfaces have proper IP addresses. Then look at the routing table; netstat -rn and try to ping the default gateway by IP address. Then ping 8.8.8.8 (Google DNS servers) for example. Only if all this works check name resolving settings in /etc/resolv.conf.

Diagnose things step by step. Don’t try and cut corners, check each step methodically and you’ll quickly find where the actual error is.

Reactions: Sensucht94 and vince66

vince66

as you surely know, typing 192.168.1.1 in the address bar of a web browser, you can connect with your router, then giving «admin» «admin», you can open its interface to configure network parameters.

Please, can you tell me in which a way I can do the same using FreeBSD ? Which command I need to use to connect with it and launchin the «reset» command inside it ? I use a TP-Link router.

Thanks very much in advance !

k.jacker

vince66

Thanks Jacker . really both login and password on my router are «admin»; however:

1 — if I want connect with it remotely how can I do in FreeBSD ?

2 — and if I want launch by remote the reboot command, which command I need to use in FreeBSD ?

Thanks in advance.

k.jacker

Hei Vince66,
you really shouldn’t use/keep the default settings of your router as they are braindead insecure.
While the webinterface on many routers isn’t available from the external interface (internet) by default, the admin/admin login is still unacceptable.

Since you want to access your router’s webinterface from the internet it’s important that it supports https.
So you have to check if you can access it from your local network via https://192.168.1.1 . If yes, you’re fine.

To make clear how important it is to have strong login credentials, I refuse to help you any further until you change your routers password,
and I’m pretty sure everyone else will do so, too.

Источник

Updating Time : ntpdate[3108]: the NTP socket is in use, exiting

The time on my system is incorrect for some reason.
i need to run an update but i can’t seem to be able to
this is the errror.

i just rebooted, i do not know how anything can be in use .

4 Answers 4

It’s in use because the ntp service is probably running. You did not mention which Linux you are using so this assumes you have service installed (that you are running a System V system):

If you want to execute ntpdate with the ntp daemon already up and running, use the following command:

and it will use a different port.

Simple one-liner to sync your clock:

You could have a firewall blocking port 123 which can cause the ntp server to never see time servers.

In my case my ISP (Internet Service Provider) was blocking ports.

A port scan of 123 didn’t detect the blockage either.

The smoking gun was that I temporarily have two independent, internet feeds. In linux if I run ntpq -p (repeatedly) with the first internet feed hooked up nothing gets updated, but with the other feed I get updates!

As @Jens A. Koch says, ntpdate -u does go around this, well, temporarily (no pun intended), it does not fix the time issue long term.

Over time drift will occur in both your system and hardware clocks. Also in my case dual booting linux/windows causes the hardware clock to further get messed up (by hours).

So you must have port 123 open for UDP for to maintain your system clock.

Rather than using -u , you can also do this which I think uses port 123 (rather than a non ‘privileged’ port):

service ntp stop , then
ntpdate (without the -u) and finally
service ntp start .

This seems to bypass the port blockage issue, at least to set the clock one time. If -u works, but this does not, then it’s a pretty sure sign that the port is blocked, or the server is unresponsive, .

Источник

Error resolving pool 0 debian pool ntp org

I have a Raspberry Pi4 (8GB) and i used it mostly for ADS-B ( running Piaware , RB Feeder and ADS-B exchange and Flight radar) and I noticed lately it does take time to boot when i do a reboot after an update or such
i copied some logs ( i’m new to PI and did some google search for some commands) if anyone can look at the logs and tell me that i’m missing or provide any info I would really appreciate

$ dmesg —level=err,warn
[ 0.000000] Kernel parameter elevator= does not have any effect anymore.
Please use sysfs to set IO scheduler for individual devices.
[ 0.151461] usb_phy_generic phy: supply vcc not found, using dummy regulator
[ 1.595568] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 1.597256] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.598940] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.601968] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 1.603823] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 5.164506] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.212321] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.234876] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.241029] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.276373] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.359410] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.392447] vc4-drm gpu: [drm] HDMI-A-1: EDID is invalid:
[ 5.392474] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.392489] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.392505] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.392519] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.392534] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.392549] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.392564] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.392579] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.954074] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 5.981497] brcmfmac mmc11: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[ 6.233781] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 6.248733] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[ 255.149200] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[ 269.951735] v3d fec00000.v3d: MMU error from client L2T (0) at 0x1541000, pt

systemd-analyze blame
7.502s hciuart.service
3.519s generate-pirehose-cert.service
2.626s udisks2.service
2.528s rpi-eeprom-update.service
1.854s dev-mmcblk0p2.device
1.625s lighttpd.service
1.593s snapd.service
1.075s raspi-config.service
1.044s mlat-client.service
1.009s avahi-daemon.service
934ms lightdm.service
913ms networking.service
895ms plymouth-quit-wait.service
857ms snapd.seeded.service
841ms systemd-logind.service
813ms dphys-swapfile.service
747ms polkit.service
597ms rng-tools.service
576ms systemd-udev-trigger.service
561ms wpa_supplicant.service
526ms rsyslog.service
522ms systemd-fsck@dev-disk-byx2dpartuuid-46bf572ex2d01.service
508ms gldriver-test.service
490ms ssh.service
477ms keyboard-setup.service
470ms alsa-restore.service
466ms adsbexchange-stats.service
344ms dhcpcd.service
332ms systemd-journald.service
297ms systemd-fsck-root.service
257ms fr24feed.service
256ms plymouth-read-write.service
242ms ntp.service
236ms user@1000.service
210ms triggerhappy.service
196ms systemd-remount-fs.service
181ms systemd-udevd.service
176ms systemd-tmpfiles-setup.service
143ms fake-hwclock.service
129ms systemd-user-sessions.service
pi@raspberrypi:

$ systemd-analyze critical-chain
The time after the unit is active or started is printed after the «@» character.
The time the unit takes to start is printed after the «+» character.

graphical.target @12.493s
└─multi-user.target @12.485s
└─hciuart.service @4.978s +7.502s
└─basic.target @4.770s
└─sockets.target @4.769s
└─snapd.socket @4.760s +8ms
└─sysinit.target @4.727s
└─systemd-update-utmp.service @4.627s +97ms
└─systemd-tmpfiles-setup.service @4.437s +176ms
└─local-fs.target @4.431s
└─run-user-1000.mount @10.511s
└─local-fs-pre.target @2.098s
└─systemd-tmpfiles-setup-dev.service @2.005s +83ms
└─systemd-sysusers.service @1.904s +93ms
└─systemd-remount-fs.service @1.694s +19

$ journalctl —boot=0 —priority=0..3
— Logs begin at Sun 2021-05-23 23:39:39 EDT, end at Sun 2021-05-23 23:59:07 EDT. —
May 23 23:39:41 raspberrypi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
May 23 23:39:41 raspberrypi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
May 23 23:39:41 raspberrypi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
May 23 23:39:43 raspberrypi adsbexchange-mlat[535]: Could not connect to 127.0.0.1:30005, retry in 30 seconds.
May 23 23:39:44 raspberrypi dhcpcd[473]: dhcpcd_prestartinterface: wlan0: Operation not possible due to RF-kill
May 23 23:39:44 raspberrypi ntpd[572]: error resolving pool 0.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 23 23:39:45 raspberrypi vncserver-x11[565]: HostedRendezvous: Rendezvous lookup failed: Hosted Bootstrap error: Network failure: Error connecting: getaddrinfo
May 23 23:39:45 raspberrypi ntpd[572]: error resolving pool 1.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 23 23:39:46 raspberrypi ntpd[572]: error resolving pool 2.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 23 23:39:47 raspberrypi ntpd[572]: error resolving pool 3.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 23 23:39:50 raspberrypi bluetoothd[800]: Sap driver initialization failed.
May 23 23:39:50 raspberrypi bluetoothd[800]: sap-server: Operation not permitted (1)
May 23 23:39:50 raspberrypi bluetoothd[800]: Failed to set privacy: Rejected (0x0b)
May 23 23:39:50 raspberrypi vncserver-x11[565]: HostedRendezvous: Rendezvous lookup failed: Hosted Bootstrap error: Network failure: Error connecting: getaddrinfo
May 23 23:44:07 raspberrypi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
May 23 23:44:14 raspberrypi ntpd[572]: bind(25) AF_INET6 fe80::67fa:c09f:4e43:edae%3#123 flags 0x11 failed: Cannot assign requested address
May 23 23:44:14 raspberrypi ntpd[572]: unable to create socket on wlan0 (6) for fe80::67fa:c09f:4e43:edae%3#123
May 23 23:44:22 raspberrypi kernel: v3d fec00000.v3d: MMU error from client L2T (0) at 0x1541000, pte invalid
lines 1-19/19 (END)

Re: Raspberry Pi 4 Boot time issue

If the network-related errors happen, say, more than 30 secs into boot, something is wrong with the network hardware or configuration. Issues setting up networking usually delay boot by a minute or two (and then the machine more or less works)
Most likely related to some configuration change you did recently.

These lines look dubious to me, but again without notion of time the error may get corrected later on (with no trace)

Re: Raspberry Pi 4 Boot time issue

Still takes around 2 minutes to booth i did disable the bluetooth and Wifi

$ journalctl —boot=0 —priority=0..3
— Logs begin at Tue 2021-05-25 12:49:44 EDT, end at Tue 2021-05-25 12:55:40 EDT. —
May 25 12:49:46 raspberrypi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
May 25 12:49:46 raspberrypi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
May 25 12:49:46 raspberrypi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
May 25 12:49:49 raspberrypi adsbexchange-mlat[515]: Could not connect to 127.0.0.1:30005, retry in 30 seconds.
May 25 12:49:50 raspberrypi dhcpcd[466]: dhcpcd_prestartinterface: wlan0: Operation not possible due to RF-kill
May 25 12:49:50 raspberrypi ntpd[574]: error resolving pool 0.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 25 12:49:51 raspberrypi vncserver-x11[569]: HostedRendezvous: Rendezvous lookup failed: Hosted Bootstrap error: Network failure: Error connecting: getaddrinfo: ‘Temporary failure in name resolu
May 25 12:49:51 raspberrypi ntpd[574]: error resolving pool 1.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 25 12:49:52 raspberrypi ntpd[574]: error resolving pool 2.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 25 12:49:53 raspberrypi ntpd[574]: error resolving pool 3.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 25 12:49:55 raspberrypi systemd[1]: Failed to start Raspberry Pi bluetooth helper.
May 25 12:49:55 raspberrypi bluetoothd[876]: Sap driver initialization failed.
May 25 12:49:55 raspberrypi bluetoothd[876]: sap-server: Operation not permitted (1)
May 25 12:49:56 raspberrypi bluetoothd[876]: Failed to set mode: Blocked through rfkill (0x12)
May 25 12:49:56 raspberrypi vncserver-x11[569]: HostedRendezvous: Rendezvous lookup failed: Hosted Bootstrap error: Network failure: Error connecting: getaddrinfo: ‘Temporary failure in name resolu
May 25 12:51:01 raspberrypi kernel: v3d fec00000.v3d: MMU error from client L2T (0) at 0x3901000, pte invalid

$ dmesg —level=err,warn
[ 0.000000] Kernel parameter elevator= does not have any effect anymore.
Please use sysfs to set IO scheduler for individual devices.
[ 0.151454] usb_phy_generic phy: supply vcc not found, using dummy regulator
[ 1.593333] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 1.595040] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.596741] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.599786] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 1.601488] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 4.899404] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.982267] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.032128] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.036062] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.054672] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.057884] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.113238] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.632791] vc4-drm gpu: [drm] HDMI-A-1: EDID is invalid:
[ 5.632814] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.632830] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.632845] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.632860] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.632875] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.632890] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.632905] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.632920] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.763798] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 5.794655] brcmfmac mmc11: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[ 6.037394] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 6.047268] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[ 81.483859] v3d fec00000.v3d: MMU error from client L2T (0) at 0x3901000, pte invalid
pi@raspberrypi:

$ dmesg | grep -i eth0
[ 9.524053] bcmgenet fd580000.ethernet eth0: Link is Down
[ 13.673730] bcmgenet fd580000.ethernet eth0: Link is Up — 1Gbps/Full — flow control rx/tx
[ 13.673757] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
pi@raspberrypi:

Re: Raspberry Pi 4 Boot time issue

Re: Raspberry Pi 4 Boot time issue

$ /opt/vc/bin/vcgencmd version
May 27 2021 14:02:15
Copyright (c) 2012 Broadcom
version 7d9a298cda813f747b51fe17e1e417e7bf5ca94d (clean) (release) (start)

$ cat /etc/debian_version
10.9

$ uname -a
Linux raspberrypi 5.10.17-v7l+ #1421 SMP Thu May 27 14:00:13 BST 2021 armv7l GNU/Linux

processor : 1
model name : ARMv7 Processor rev 3 (v7l)
BogoMIPS : 162.00
Features : half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part : 0xd08
CPU revision : 3

processor : 2
model name : ARMv7 Processor rev 3 (v7l)
BogoMIPS : 162.00
Features : half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part : 0xd08
CPU revision : 3

processor : 3
model name : ARMv7 Processor rev 3 (v7l)
BogoMIPS : 162.00
Features : half thumb fastmult vfp edsp neon vfpv3 tls vfpv4 idiva idivt vfpd32 lpae evtstrm crc32
CPU implementer : 0x41
CPU architecture: 7
CPU variant : 0x0
CPU part : 0xd08
CPU revision : 3

Hardware : BCM2711
Revision : d03114
Serial : 100000009700c74c
Model : Raspberry Pi 4 Model B Rev 1.4

Re: Raspberry Pi 4 Boot time issue

$ dmesg
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 5.10.17-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1421 SMP Thu May 27 14:00:13 BST 2021
[ 0.000000] CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
[ 0.000000] CPU: div instructions available: patching division code
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
[ 0.000000] OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.4
[ 0.000000] Memory policy: Data cache writealloc
[ 0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
[ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000000000-0x000000002fffffff]
[ 0.000000] Normal empty
[ 0.000000] HighMem [mem 0x0000000030000000-0x00000001ffffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x000000003b3fffff]
[ 0.000000] node 0: [mem 0x0000000040000000-0x00000000fbffffff]
[ 0.000000] node 0: [mem 0x0000000100000000-0x00000001ffffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
[ 0.000000] On node 0 totalpages: 2061312
[ 0.000000] DMA zone: 2304 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 196608 pages, LIFO batch:63
[ 0.000000] HighMem zone: 1864704 pages, LIFO batch:63
[ 0.000000] percpu: Embedded 20 pages/cpu s50700 r8192 d23028 u81920
[ 0.000000] pcpu-alloc: s50700 r8192 d23028 u81920 alloc=20*4096
[ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 2059008
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1920x1080M@60 smsc95xx.macaddr=DC:A6:32:D5:D5:DF vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 console=ttyS0,115200 console=tty1 root=PARTUUID=46bf572e-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait quiet splash plymouth.ignore-serial-consoles
[ 0.000000] Kernel parameter elevator= does not have any effect anymore.
Please use sysfs to set IO scheduler for individual devices.
[ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
[ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
[ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[ 0.000000] software IO TLB: mapped [mem 0x0000000014c00000-0x0000000018c00000] (64MB)
[ 0.000000] Memory: 7800088K/8245248K available (10240K kernel code, 1358K rwdata, 3160K rodata, 2048K init, 890K bss, 183016K reserved, 262144K cma-reserved, 7458816K highmem)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] ftrace: allocating 33942 entries in 67 pages
[ 0.000000] ftrace: allocated 67 pages with 3 groups
[ 0.000000] rcu: Hierarchical RCU implementation.
[ 0.000000] Rude variant of Tasks RCU enabled.
[ 0.000000] Tracing variant of Tasks RCU enabled.
[ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000000] GIC: Using split EOI/Deactivate mode
[ 0.000000] random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=0
[ 0.000008] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[ 0.000027] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[ 0.000091] bcm2835: system timer (irq = 25)
[ 0.000744] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[ 0.000762] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[ 0.000780] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[ 0.000797] Switching to timer-based delay loop, resolution 18ns
[ 0.001064] Console: colour dummy device 80×30
[ 0.001132] printk: console [tty1] enabled
[ 0.001187] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000)
[ 0.001220] pid_max: default: 32768 minimum: 301
[ 0.001392] LSM: Security Framework initializing
[ 0.001584] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.001610] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[ 0.003247] Disabling memory control group subsystem
[ 0.003369] CPU: Testing write buffer coherency: ok
[ 0.003860] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[ 0.005086] Setting up static identity map for 0x200000 — 0x20003c
[ 0.005289] rcu: Hierarchical SRCU implementation.
[ 0.006237] smp: Bringing up secondary CPUs .
[ 0.007508] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[ 0.008923] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[ 0.010260] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[ 0.010415] smp: Brought up 1 node, 4 CPUs
[ 0.010437] SMP: Total of 4 processors activated (432.00 BogoMIPS).
[ 0.010452] CPU: All CPU(s) started in HYP mode.
[ 0.010465] CPU: Virtualization extensions available.
[ 0.011434] devtmpfs: initialized
[ 0.025780] VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0
[ 0.026026] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.026059] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[ 0.033399] pinctrl core: initialized pinctrl subsystem
[ 0.034525] NET: Registered protocol family 16
[ 0.038753] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.039545] audit: initializing netlink subsys (disabled)
[ 0.039809] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[ 0.040407] thermal_sys: Registered thermal governor ‘step_wise’
[ 0.041255] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[ 0.041273] hw-breakpoint: maximum watchpoint size is 8 bytes.
[ 0.041689] Serial: AMBA PL011 UART driver
[ 0.073951] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[ 0.090879] raspberrypi-firmware soc:firmware: Attached to firmware from 2021-05-27T14:02:15, variant start
[ 0.100890] raspberrypi-firmware soc:firmware: Firmware hash is 7d9a298cda813f747b51fe17e1e417e7bf5ca94d
[ 0.145716] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[ 0.149917] vgaarb: loaded
[ 0.150381] SCSI subsystem initialized
[ 0.150614] usbcore: registered new interface driver usbfs
[ 0.150672] usbcore: registered new interface driver hub
[ 0.150750] usbcore: registered new device driver usb
[ 0.151455] usb_phy_generic phy: supply vcc not found, using dummy regulator
[ 0.153515] clocksource: Switched to clocksource arch_sys_counter
[ 1.154318] VFS: Disk quotas dquot_6.6.0
[ 1.154436] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 1.154623] FS-Cache: Loaded
[ 1.154814] CacheFiles: Loaded
[ 1.155908] simple-framebuffer 3e402000.framebuffer: framebuffer at 0x3e402000, 0x7f8000 bytes, mapped to 0x(ptrval)
[ 1.155929] simple-framebuffer 3e402000.framebuffer: format=a8r8g8b8, mode=1920x1080x32, linelength=7680
[ 1.156437] Console: switching to colour frame buffer device 240×67
[ 1.167039] simple-framebuffer 3e402000.framebuffer: fb0: simplefb registered!
[ 1.175796] NET: Registered protocol family 2
[ 1.176724] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
[ 1.176766] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[ 1.176939] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
[ 1.177013] TCP: Hash tables configured (established 8192 bind 8192)
[ 1.177182] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 1.177235] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[ 1.177494] NET: Registered protocol family 1
[ 1.178242] RPC: Registered named UNIX socket transport module.
[ 1.178259] RPC: Registered udp transport module.
[ 1.178274] RPC: Registered tcp transport module.
[ 1.178288] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 1.178312] PCI: CLS 0 bytes, default 64
[ 1.181375] Initialise system trusted keyrings
[ 1.181616] workingset: timestamp_bits=14 max_order=21 bucket_order=7
[ 1.190359] zbud: loaded
[ 1.192342] FS-Cache: Netfs ‘nfs’ registered for caching
[ 1.193144] NFS: Registering the id_resolver key type
[ 1.193195] Key type id_resolver registered
[ 1.193210] Key type id_legacy registered
[ 1.193361] nfs4filelayout_init: NFSv4 File Layout Driver Registering.
[ 1.194490] Key type asymmetric registered
[ 1.194507] Asymmetric key parser ‘x509’ registered
[ 1.194704] bounce: pool size: 64 pages
[ 1.194760] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[ 1.194991] io scheduler mq-deadline registered
[ 1.195008] io scheduler kyber registered
[ 1.199581] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[ 1.199614] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[ 1.199694] brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[ 1.199794] brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[ 1.255619] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[ 1.256000] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[ 1.256021] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 1.256042] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[ 1.256137] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[ 1.256397] pci 0000:00:00.0: PME# supported from D0 D3hot
[ 1.259924] PCI: bus0: Fast back to back transfers disabled
[ 1.260266] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[ 1.260394] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[ 1.260864] pci 0000:01:00.0: PME# supported from D0 D3hot
[ 1.264381] PCI: bus1: Fast back to back transfers disabled
[ 1.264446] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[ 1.264470] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[ 1.264553] pci 0000:00:00.0: PCI bridge to [bus 01]
[ 1.264579] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff]
[ 1.264988] pcieport 0000:00:00.0: enabling device (0140 -> 0142)
[ 1.265217] pcieport 0000:00:00.0: PME: Signaling with IRQ 62
[ 1.273880] iproc-rng200 fe104000.rng: hwrng registered
[ 1.274196] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[ 1.275051] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[ 1.287367] brd: module loaded
[ 1.299925] loop: module loaded
[ 1.301630] Loading iSCSI transport class v2.0-870.
[ 1.304208] libphy: Fixed MDIO Bus: probed
[ 1.305957] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[ 1.323562] libphy: bcmgenet MII bus: probed
[ 1.413663] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[ 1.414851] usbcore: registered new interface driver r8152
[ 1.414930] usbcore: registered new interface driver lan78xx
[ 1.414991] usbcore: registered new interface driver smsc95xx
[ 1.467150] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.467190] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[ 1.470419] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000030000000890
[ 1.471781] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
[ 1.471800] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.471818] usb usb1: Product: xHCI Host Controller
[ 1.471835] usb usb1: Manufacturer: Linux 5.10.17-v7l+ xhci-hcd
[ 1.471851] usb usb1: SerialNumber: 0000:01:00.0
[ 1.472584] hub 1-0:1.0: USB hub found
[ 1.472683] hub 1-0:1.0: 1 port detected
[ 1.473375] xhci_hcd 0000:01:00.0: xHCI Host Controller
[ 1.473404] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[ 1.473432] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[ 1.473952] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10
[ 1.473972] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.473989] usb usb2: Product: xHCI Host Controller
[ 1.474005] usb usb2: Manufacturer: Linux 5.10.17-v7l+ xhci-hcd
[ 1.474021] usb usb2: SerialNumber: 0000:01:00.0
[ 1.474747] hub 2-0:1.0: USB hub found
[ 1.474819] hub 2-0:1.0: 4 ports detected
[ 1.476532] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 1.476847] dwc_otg: FIQ enabled
[ 1.476861] dwc_otg: NAK holdoff enabled
[ 1.476875] dwc_otg: FIQ split-transaction FSM enabled
[ 1.476893] Module dwc_common_port init
[ 1.477391] usbcore: registered new interface driver uas
[ 1.477501] usbcore: registered new interface driver usb-storage
[ 1.477708] mousedev: PS/2 mouse device common for all mice
[ 1.479382] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[ 1.482943] sdhci: Secure Digital Host Controller Interface driver
[ 1.482959] sdhci: Copyright(c) Pierre Ossman
[ 1.483739] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe
[ 1.484361] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.488585] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.488935] hid: raw HID events driver (C) Jiri Kosina
[ 1.489132] usbcore: registered new interface driver usbhid
[ 1.489147] usbhid: USB HID core driver
[ 1.495231] Initializing XFRM netlink socket
[ 1.495276] NET: Registered protocol family 17
[ 1.495417] Key type dns_resolver registered
[ 1.495879] Registering SWP/SWPB emulation handler
[ 1.496052] registered taskstats version 1
[ 1.496082] Loading compiled-in X.509 certificates
[ 1.496947] Key type ._fscrypt registered
[ 1.496964] Key type .fscrypt registered
[ 1.496980] Key type fscrypt-provisioning registered
[ 1.508756] uart-pl011 fe201000.serial: there is not valid maps for state default
[ 1.509089] uart-pl011 fe201000.serial: cts_event_workaround enabled
[ 1.509172] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2
[ 1.515977] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[ 1.517042] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[ 1.517059] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[ 1.547524] of_cfs_init
[ 1.547800] of_cfs_init: OK
[ 1.562372] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 1.564074] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.565776] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.568815] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 1.570513] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.584029] mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA
[ 1.584707] Waiting for root device PARTUUID=46bf572e-02.
[ 1.614075] random: fast init done
[ 1.640744] mmc1: new high speed SDIO card at address 0001
[ 1.684652] mmc0: new ultra high speed DDR50 SDXC card at address 0001
[ 1.685618] mmcblk0: mmc0:0001 00000 59.6 GiB
[ 1.687680] mmcblk0: p1 p2
[ 1.720183] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 1.720266] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 1.722263] devtmpfs: mounted
[ 1.731408] Freeing unused kernel memory: 2048K
[ 1.763874] Run /sbin/init as init process
[ 1.763889] with arguments:
[ 1.763905] /sbin/init
[ 1.763921] splash
[ 1.763935] with environment:
[ 1.763951] HOME=/
[ 1.763966] TERM=linux
[ 1.833582] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[ 2.016196] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[ 2.016219] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 2.016237] usb 1-1: Product: USB2.0 Hub
[ 2.018284] hub 1-1:1.0: USB hub found
[ 2.018579] hub 1-1:1.0: 4 ports detected
[ 2.191794] systemd[1]: System time before build time, advancing clock.
[ 2.305909] NET: Registered protocol family 10
[ 2.307389] Segment Routing with IPv6
[ 2.343598] usb 1-1.1: new high-speed USB device number 3 using xhci_hcd
[ 2.366518] systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
[ 2.368221] systemd[1]: Detected architecture arm.
[ 2.456753] systemd[1]: Set hostname to .
[ 2.495899] usb 1-1.1: New USB device found, idVendor=0bda, idProduct=2838, bcdDevice= 1.00
[ 2.495928] usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 2.495946] usb 1-1.1: Product: RTL2838UHIDIR
[ 2.495963] usb 1-1.1: Manufacturer: Realtek
[ 2.495988] usb 1-1.1: SerialNumber: 00000001
[ 3.290389] random: systemd: uninitialized urandom read (16 bytes read)
[ 3.334807] random: systemd: uninitialized urandom read (16 bytes read)
[ 3.337300] systemd[1]: Created slice User and Session Slice.
[ 3.337603] random: systemd: uninitialized urandom read (16 bytes read)
[ 3.342621] systemd[1]: Listening on Journal Socket (/dev/log).
[ 3.342801] systemd[1]: Reached target Slices.
[ 3.343841] systemd[1]: Listening on udev Control Socket.
[ 3.346177] systemd[1]: Created slice system-getty.slice.
[ 3.347277] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[ 3.347992] systemd[1]: Listening on Syslog Socket.
[ 3.481359] i2c /dev entries driver
[ 4.018525] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 4.110139] systemd-journald[111]: Received request to flush runtime journal from PID 1
[ 4.847572] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.852629] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[ 4.876457] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 4.876921] mc: Linux media interface: v0.10
[ 4.879205] bcm2835_audio bcm2835_audio: card created with 4 channels
[ 4.890386] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[ 4.894207] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[ 4.896947] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[ 4.911991] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 4.915335] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 4.915363] [vc_sm_connected_init]: start
[ 4.916957] [vc_sm_connected_init]: installed successfully
[ 4.929278] videodev: Linux video capture interface: v2.00
[ 5.053943] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.054305] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.060519] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.084387] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.107591] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 5.107645] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 5.120390] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 5.120453] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 5.120487] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[ 5.132052] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[ 5.132123] checking generic (3e402000 7f8000) vs hw (0 ffffffffffffffff)
[ 5.132166] fb0: switching to vc4drmfb from simple
[ 5.137577] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[ 5.137581] Console: switching to colour dummy device 80×30
[ 5.138081] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[ 5.138525] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[ 5.138881] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[ 5.138911] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[ 5.138936] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[ 5.138959] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[ 5.138985] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[ 5.139182] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[ 5.150658] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 5.150704] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 5.174090] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 0
[ 5.181740] vc4-drm gpu: [drm] HDMI-A-1: EDID is invalid:
[ 5.181764] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.181780] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.181796] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.181811] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.181826] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.181841] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.181856] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.181871] [00] ZERO 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 5.339455] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 1
[ 5.373780] Console: switching to colour frame buffer device 240×67
[ 5.400327] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[ 5.495744] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 5.606253] cfg80211: Loaded X.509 cert ‘sforshee: 00b28ddf47aef9cea7’
[ 5.661939] brcmfmac: F1 signature read @0x18000000=0x15264345
[ 5.684347] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 5.686071] usbcore: registered new interface driver brcmfmac
[ 5.711051] brcmfmac mmc11: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2
[ 5.953318] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[ 5.964023] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e
[ 8.097760] 8021q: 802.1Q VLAN Support v1.8
[ 8.279605] random: crng init done
[ 8.279631] random: 7 urandom warning(s) missed due to ratelimiting
[ 8.461258] uart-pl011 fe201000.serial: no DMA platform data
[ 8.914507] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
[ 9.838432] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[ 9.838753] bcmgenet fd580000.ethernet eth0: Link is Down
[ 10.064023] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 12.384861] broken atomic modeset userspace detected, disabling atomic
[ 14.003736] bcmgenet fd580000.ethernet eth0: Link is Up — 1Gbps/Full — flow control rx/tx
[ 14.003764] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[ 15.674319] Bluetooth: Core ver 2.22
[ 15.674392] NET: Registered protocol family 31
[ 15.674402] Bluetooth: HCI device and connection manager initialized
[ 15.674422] Bluetooth: HCI socket layer initialized
[ 15.674438] Bluetooth: L2CAP socket layer initialized
[ 15.674460] Bluetooth: SCO socket layer initialized
[ 15.697575] Bluetooth: HCI UART driver ver 2.3
[ 15.697603] Bluetooth: HCI UART protocol H4 registered
[ 15.697871] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 15.698228] Bluetooth: HCI UART protocol Broadcom registered
[ 15.961771] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[ 15.961781] Bluetooth: BNEP filters: protocol multicast
[ 15.961795] Bluetooth: BNEP socket layer initialized
[ 16.663582] fuse: init (API version 7.32)
[ 17.780244] Bluetooth: RFCOMM TTY layer initialized
[ 17.780260] Bluetooth: RFCOMM socket layer initialized
[ 17.780283] Bluetooth: RFCOMM ver 1.11
[ 102.540990] v3d fec00000.v3d: MMU error from client L2T (0) at 0x1181000, pte invalid

Re: Raspberry Pi 4 Boot time issue

i provide buch of logs and if you could give me some feedback I would really appreciate
so basically when i’m connect via VNC and do a sudo reboot or click on the logout and reboot icon i have a ping running and it takes 2 minute for the PI to reboot from the moment i lost the VNC Connection and until i can re connect via VNC
i would do a reboot and would loose VNC connection but i still can ping it but i wont be able to reconnect after 2 minute ( reboot used to take 30 seconds or less and now over 2 minute

$ journalctl —boot=0 —priority=0..3
— Logs begin at Sat 2021-05-29 00:42:10 EDT, end at Sat 2021-05-29 00:55:25 EDT. —
May 29 00:42:12 raspberrypi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
May 29 00:42:12 raspberrypi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
May 29 00:42:12 raspberrypi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY)
May 29 00:42:16 raspberrypi adsbexchange-mlat[528]: Could not connect to 127.0.0.1:30005, retry in 30 seconds.
May 29 00:42:16 raspberrypi dhcpcd[409]: dhcpcd_prestartinterface: wlan0: Operation not possible due to RF-kill
May 29 00:42:17 raspberrypi ntpd[582]: error resolving pool 0.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 29 00:42:17 raspberrypi vncserver-x11[574]: HostedRendezvous: Rendezvous lookup failed: Hosted Bootstrap error: Network failure: Error connec
May 29 00:42:18 raspberrypi ntpd[582]: error resolving pool 1.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 29 00:42:19 raspberrypi ntpd[582]: error resolving pool 2.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 29 00:42:20 raspberrypi ntpd[582]: error resolving pool 3.debian.pool.ntp.org: Temporary failure in name resolution (-3)
May 29 00:42:22 raspberrypi ntpd[582]: bind(23) AF_INET6 fe80::60f1:e632:5631:2f5b%2#123 flags 0x11 failed: Cannot assign requested address
May 29 00:42:22 raspberrypi ntpd[582]: unable to create socket on eth0 (4) for fe80::60f1:e632:5631:2f5b%2#123
May 29 00:42:22 raspberrypi bluetoothd[772]: Failed to set privacy: Rejected (0x0b)
May 29 00:42:22 raspberrypi vncserver-x11[574]: HostedRendezvous: Rendezvous lookup failed: Hosted Bootstrap error: Network failure: Error connec
May 29 00:44:04 raspberrypi kernel: v3d fec00000.v3d: MMU error from client L2T (0) at 0x1181000, pte invalid
pi@raspberrypi:

$ cat /sys/devices/system/cpu/online
0-3
pi@raspberrypi:

$ sudo journalctl
— Logs begin at Sat 2021-05-29 00:42:10 EDT, end at Sat 2021-05-29 00:55:50 EDT. —
May 29 00:42:10 raspberrypi kernel: Booting Linux on physical CPU 0x0
May 29 00:42:10 raspberrypi kernel: Linux version 5.10.17-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GN
May 29 00:42:10 raspberrypi kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d
May 29 00:42:10 raspberrypi kernel: CPU: div instructions available: patching division code
May 29 00:42:10 raspberrypi kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache
May 29 00:42:10 raspberrypi kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.4
May 29 00:42:10 raspberrypi kernel: Memory policy: Data cache writealloc
May 29 00:42:10 raspberrypi kernel: Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
May 29 00:42:10 raspberrypi kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
May 29 00:42:10 raspberrypi kernel: Zone ranges:
May 29 00:42:10 raspberrypi kernel: DMA [mem 0x0000000000000000-0x000000002fffffff]
May 29 00:42:10 raspberrypi kernel: Normal empty
May 29 00:42:10 raspberrypi kernel: HighMem [mem 0x0000000030000000-0x00000001ffffffff]
May 29 00:42:10 raspberrypi kernel: Movable zone start for each node
May 29 00:42:10 raspberrypi kernel: Early memory node ranges
May 29 00:42:10 raspberrypi kernel: node 0: [mem 0x0000000000000000-0x000000003b3fffff]
May 29 00:42:10 raspberrypi kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff]
May 29 00:42:10 raspberrypi kernel: node 0: [mem 0x0000000100000000-0x00000001ffffffff]
May 29 00:42:10 raspberrypi kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff]
May 29 00:42:10 raspberrypi kernel: On node 0 totalpages: 2061312
May 29 00:42:10 raspberrypi kernel: DMA zone: 2304 pages used for memmap
May 29 00:42:10 raspberrypi kernel: DMA zone: 0 pages reserved
May 29 00:42:10 raspberrypi kernel: DMA zone: 196608 pages, LIFO batch:63
May 29 00:42:10 raspberrypi kernel: HighMem zone: 1864704 pages, LIFO batch:63
May 29 00:42:10 raspberrypi kernel: percpu: Embedded 20 pages/cpu s50700 r8192 d23028 u81920
May 29 00:42:10 raspberrypi kernel: pcpu-alloc: s50700 r8192 d23028 u81920 alloc=20*4096
May 29 00:42:10 raspberrypi kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
May 29 00:42:10 raspberrypi kernel: Built 1 zonelists, mobility grouping on. Total pages: 2059008
May 29 00:42:10 raspberrypi kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdm
May 29 00:42:10 raspberrypi kernel: Kernel parameter elevator= does not have any effect anymore.
Please use sysfs to set IO scheduler for individual devices.
May 29 00:42:10 raspberrypi kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
May 29 00:42:10 raspberrypi kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
May 29 00:42:10 raspberrypi kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
May 29 00:42:10 raspberrypi kernel: software IO TLB: mapped [mem 0x0000000014c00000-0x0000000018c00000] (64MB)
May 29 00:42:10 raspberrypi kernel: Memory: 7800088K/8245248K available (10240K kernel code, 1358K rwdata, 3160K rodata, 2048K init, 890K bss, 18
May 29 00:42:10 raspberrypi kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
May 29 00:42:10 raspberrypi kernel: ftrace: allocating 33942 entries in 67 pages
May 29 00:42:10 raspberrypi kernel: ftrace: allocated 67 pages with 3 groups
May 29 00:42:10 raspberrypi kernel: rcu: Hierarchical RCU implementation.
May 29 00:42:10 raspberrypi kernel: Rude variant of Tasks RCU enabled.
May 29 00:42:10 raspberrypi kernel: Tracing variant of Tasks RCU enabled.
pi@raspberrypi:

Источник

Страницы: [1] 2  Все   Вниз

0 Пользователей и 1 Гость просматривают эту тему.

---

---

---

---

---

---

---

---

---

---

---

---

---

---

---

Страницы: [1] 2  Все   Вверх

I am a enthusiastic linux user and I offered my experience to perform system upgrade on 40 old Classmate PCs (SEE system information on image bellow ) which were runing a deprecated ubuntu version. So, with the proper authorizations, I performed the upgrade and in near future I hope that students can use them for their porpouses (like navigation, researchs, to access Google Classroom functionalities and to make projects and communication with Arduinos. Those last two were not possible before).

When I started I knew I would have some challenges regarding permissions because, for the students, I would create a account with some restrictions (security porpouses). But I did not expect difficulties with simple tasks, like to connect wifi netwoks at the school or nearby, for the students.

During instalation of the new Linux Mint XFCE 19.3 32bits (image loaded via USB) and even after restart (when I log in the standard non privileged user created — sudoer) everething seems to works fine and I am able to connect the networks I desire (and I seem to have good and stable conections).
The problem arises when I login the new account created. This accont is not able to connect any network listed (the password is not even asked, the system only shows «you are disconected» and you can’t do much). Let me be more clear: When I try to connect any network with this new user (aluno/student — not admin) it simply does not ask the wifi pass key, and show that you are disconected. Not even networks already set for the admin acc (escola) work here. I only get internet for this specific aluno user if I use cable (ethernet) or if I make the trick of setting network via hidden option (that is say I enter the option for setting hidden network, I type the SSID [which is actually not hidden, SIC], choose the WPA2 security and Type the Key)
This is the most interesting aspect: why is it when I take one of those networks and pretend it is hidden and enter its exact name, choose the security and add manualy the password, the system connects. I know this is a stupid workaround that allow to connect without need further permissions, but other users won’t know about it by default.

On attempts to solve, I already gave all privileges that I know of on user configuration, and I even added the user to the netdev group (via $ sudo usermod -aG netdev alunocj33). Nothing solves. I would apreciate some help with this issue.

What is happening and how to solve this problem??

Addendum: I did many search for solutions for this problem in the internet and found only few similar. First I thought the problem was on the card or on the drive installed since I found some other users reporting such unstabilities with RTL8188EE (but the alternative drives suggested to solve the issues wasn’t availabre anymore — e.g. lwfinger/rtlwifi_new.git on GitHub).
I am trying to solve this since 2019 when I format the first units to use during my robotic classes. Then I relized the problem and should have ask for help. I am doing it now because that many machines with such strange behavior is a mistake. Please, if you have a clue on the solution, let me know.
Sorry for my explanation, I have some difficulties since I am not native in english.

_______________ Further tests _______________

Unsecured wifi Network > the same bahavior of secured ones: It does not connect!!

Tethering USB > Connection is easily stablished (on the same way of ethernet cable).

journalctl run (below):

1st > attempt to connect normaly > FAIL

2nd > connecting same network via hidden option > SUCCESS

OBS: Could not use sudo because user aluno**** has no such permission. Even with sudo permission, the retun journalctl are the same for this user ( device (wlp2s0): state change: need-auth -> failed (reason ‘no-secrets’, sys-iface-state: ‘managed’ — tested on several machines).

alunocj25@escolacj25-EC10IS2:~$ journalctl -f
-- Logs begin at Fri 2021-01-29 13:11:30 -03. --
mar 15 16:40:04 escolacj25-EC10IS2 NetworkManager[721]: <warn>  [1615837204.0992] device (wlp2s0): No agents were available for this request.
mar 15 16:40:04 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837204.0994] device (wlp2s0): state change: need-auth -> failed (reason 'no-secrets', sys-iface-state: 'managed')
mar 15 16:40:04 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837204.1037] manager: NetworkManager state is now DISCONNECTED
mar 15 16:40:04 escolacj25-EC10IS2 NetworkManager[721]: <warn>  [1615837204.1059] device (wlp2s0): Activation: failed for connection 'PROFESSORES_CaldasJunior 1'
mar 15 16:40:04 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837204.1100] device (wlp2s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
mar 15 16:40:24 escolacj25-EC10IS2 ntpd[803]: error resolving pool 0.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:40:27 escolacj25-EC10IS2 ntpd[803]: error resolving pool 1.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:40:30 escolacj25-EC10IS2 ntpd[803]: error resolving pool 3.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:40:32 escolacj25-EC10IS2 ntpd[803]: error resolving pool ntp.ubuntu.com: Name or service not known (-2)
mar 15 16:40:32 escolacj25-EC10IS2 ntpd[803]: error resolving pool 2.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:41:09 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837269.7398] audit: op="connection-delete" uuid="be4449bb-19c4-437e-91fb-5e54f30fe063" name="PROFESSORES_CaldasJunior 1" pid=1676 uid=1001 result="success"
mar 15 16:41:29 escolacj25-EC10IS2 ntpd[803]: error resolving pool 0.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:41:33 escolacj25-EC10IS2 ntpd[803]: error resolving pool 1.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8535] device (wlp2s0): Activation: starting connection 'PROFESSORES_CaldasJunior' (ca5f8693-7437-433b-b647-912b8f854359)
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8554] audit: op="connection-activate" uuid="ca5f8693-7437-433b-b647-912b8f854359" name="PROFESSORES_CaldasJunior" pid=1160 uid=1001 result="success"
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8572] device (wlp2s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8590] manager: NetworkManager state is now CONNECTING
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8637] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8653] device (wlp2s0): Activation: (wifi) access point 'PROFESSORES_CaldasJunior' has security, but secrets are required.
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8655] device (wlp2s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.8664] sup-iface[0x27c9170,wlp2s0]: wps: type pbc start...
mar 15 16:41:33 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837293.9149] device (wlp2s0): supplicant interface state: inactive -> scanning
mar 15 16:41:34 escolacj25-EC10IS2 NetworkManager[721]: <warn>  [1615837294.3059] device (wlp2s0): No agents were available for this request.
mar 15 16:41:36 escolacj25-EC10IS2 ntpd[803]: error resolving pool ntp.ubuntu.com: Name or service not known (-2)
mar 15 16:41:36 escolacj25-EC10IS2 ntpd[803]: error resolving pool 3.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:41:37 escolacj25-EC10IS2 ntpd[803]: error resolving pool 2.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:41:40 escolacj25-EC10IS2 kernel: perf: interrupt took too long (3977 > 3972), lowering kernel.perf_event_max_sample_rate to 50250
mar 15 16:42:04 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837324.2260] device (wlp2s0): state change: need-auth -> failed (reason 'no-secrets', sys-iface-state: 'managed')
mar 15 16:42:04 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837324.2282] manager: NetworkManager state is now DISCONNECTED
mar 15 16:42:04 escolacj25-EC10IS2 NetworkManager[721]: <warn>  [1615837324.2313] device (wlp2s0): Activation: failed for connection 'PROFESSORES_CaldasJunior'
mar 15 16:42:04 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837324.2369] device (wlp2s0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
mar 15 16:42:04 escolacj25-EC10IS2 wpa_supplicant[722]: wlp2s0: Reject scan trigger since one is already pending
mar 15 16:42:23 escolacj25-EC10IS2 dbus-daemon[574]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.40' (uid=1001 pid=1160 comm="nm-applet " label="unconfined")
mar 15 16:42:23 escolacj25-EC10IS2 systemd[1]: Starting Hostname Service...
mar 15 16:42:23 escolacj25-EC10IS2 dbus-daemon[574]: [system] Successfully activated service 'org.freedesktop.hostname1'
mar 15 16:42:23 escolacj25-EC10IS2 systemd[1]: Started Hostname Service.
mar 15 16:42:35 escolacj25-EC10IS2 ntpd[803]: error resolving pool 0.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:42:40 escolacj25-EC10IS2 ntpd[803]: error resolving pool 3.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:42:40 escolacj25-EC10IS2 ntpd[803]: error resolving pool 1.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:42:41 escolacj25-EC10IS2 ntpd[803]: error resolving pool ntp.ubuntu.com: Name or service not known (-2)
mar 15 16:42:44 escolacj25-EC10IS2 ntpd[803]: error resolving pool 2.ubuntu.pool.ntp.org: Name or service not known (-2)
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.5620] keyfile: add connection in-memory (50722fef-1ca0-4b5f-ba24-8b31f5466692,"PROFESSORES_CaldasJunior 1")
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.5715] device (wlp2s0): Activation: starting connection 'PROFESSORES_CaldasJunior 1' (50722fef-1ca0-4b5f-ba24-8b31f5466692)
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6419] settings-connection[0x2791cf8,50722fef-1ca0-4b5f-ba24-8b31f5466692]: write: successfully commited (keyfile: update /etc/NetworkManager/system-connections/PROFESSORES_CaldasJunior 1 (50722fef-1ca0-4b5f-ba24-8b31f5466692,"PROFESSORES_CaldasJunior 1") and persist connection)
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6425] audit: op="connection-add-activate" uuid="50722fef-1ca0-4b5f-ba24-8b31f5466692" name="PROFESSORES_CaldasJunior 1" pid=1160 uid=1001 result="success"
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6494] device (wlp2s0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6512] manager: NetworkManager state is now CONNECTING
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6582] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6607] device (wlp2s0): Activation: (wifi) access point 'PROFESSORES_CaldasJunior 1' has security, but secrets are required.
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6609] device (wlp2s0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6620] sup-iface[0x27c9170,wlp2s0]: wps: type pbc start...
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6859] device (wlp2s0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6891] device (wlp2s0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6904] device (wlp2s0): Activation: (wifi) connection 'PROFESSORES_CaldasJunior 1' has security, and secrets exist.  No new secrets needed.
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6907] Config: added 'ssid' value 'PROFESSORES_CaldasJunior'
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6908] Config: added 'scan_ssid' value '1'
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6909] Config: added 'bgscan' value 'simple:30:-80:86400'
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6911] Config: added 'key_mgmt' value 'WPA-PSK'
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6912] Config: added 'auth_alg' value 'OPEN'
mar 15 16:43:10 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837390.6913] Config: added 'psk' value '<hidden>'
mar 15 16:43:12 escolacj25-EC10IS2 wpa_supplicant[722]: wlp2s0: SME: Trying to authenticate with cc:32:e5:97:cb:c4 (SSID='PROFESSORES_CaldasJunior' freq=2462 MHz)
mar 15 16:43:12 escolacj25-EC10IS2 kernel: wlp2s0: authenticate with cc:32:e5:97:cb:c4
mar 15 16:43:12 escolacj25-EC10IS2 kernel: wlp2s0: send auth to cc:32:e5:97:cb:c4 (try 1/3)
mar 15 16:43:12 escolacj25-EC10IS2 kernel: wlp2s0: authenticated
mar 15 16:43:12 escolacj25-EC10IS2 wpa_supplicant[722]: wlp2s0: Trying to associate with cc:32:e5:97:cb:c4 (SSID='PROFESSORES_CaldasJunior' freq=2462 MHz)
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.2522] device (wlp2s0): supplicant interface state: scanning -> authenticating
mar 15 16:43:12 escolacj25-EC10IS2 kernel: wlp2s0: associate with cc:32:e5:97:cb:c4 (try 1/3)
mar 15 16:43:12 escolacj25-EC10IS2 kernel: wlp2s0: RX AssocResp from cc:32:e5:97:cb:c4 (capab=0x31 status=0 aid=1)
mar 15 16:43:12 escolacj25-EC10IS2 kernel: wlp2s0: associated
mar 15 16:43:12 escolacj25-EC10IS2 wpa_supplicant[722]: wlp2s0: Associated with cc:32:e5:97:cb:c4
mar 15 16:43:12 escolacj25-EC10IS2 wpa_supplicant[722]: wlp2s0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.2773] device (wlp2s0): supplicant interface state: authenticating -> associating
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.2788] device (wlp2s0): supplicant interface state: associating -> 4-way handshake
mar 15 16:43:12 escolacj25-EC10IS2 wpa_supplicant[722]: wlp2s0: WPA: Key negotiation completed with cc:32:e5:97:cb:c4 [PTK=CCMP GTK=CCMP]
mar 15 16:43:12 escolacj25-EC10IS2 wpa_supplicant[722]: wlp2s0: CTRL-EVENT-CONNECTED - Connection to cc:32:e5:97:cb:c4 completed [id=0 id_str=]
mar 15 16:43:12 escolacj25-EC10IS2 wpa_supplicant[722]: bgscan simple: Failed to enable signal strength monitoring
mar 15 16:43:12 escolacj25-EC10IS2 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlp2s0: link becomes ready
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.3191] device (wlp2s0): supplicant interface state: 4-way handshake -> completed
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.3193] device (wlp2s0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful.  Connected to wireless network 'PROFESSORES_CaldasJunior'.
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.3198] device (wlp2s0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.3492] dhcp4 (wlp2s0): activation: beginning transaction (timeout in 45 seconds)
mar 15 16:43:12 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837392.3570] dhcp4 (wlp2s0): dhclient started with pid 1810
mar 15 16:43:12 escolacj25-EC10IS2 avahi-daemon[564]: Joining mDNS multicast group on interface wlp2s0.IPv6 with address fe80::fb3d:a98a:602b:40ca.
mar 15 16:43:12 escolacj25-EC10IS2 avahi-daemon[564]: New relevant interface wlp2s0.IPv6 for mDNS.
mar 15 16:43:12 escolacj25-EC10IS2 avahi-daemon[564]: Registering new address record for fe80::fb3d:a98a:602b:40ca on wlp2s0.*.
mar 15 16:43:12 escolacj25-EC10IS2 dhclient[1810]: DHCPDISCOVER on wlp2s0 to 255.255.255.255 port 67 interval 3 (xid=0x71e96137)
mar 15 16:43:13 escolacj25-EC10IS2 dhclient[1810]: DHCPREQUEST of 192.168.0.107 on wlp2s0 to 255.255.255.255 port 67 (xid=0x3761e971)
mar 15 16:43:13 escolacj25-EC10IS2 dhclient[1810]: DHCPOFFER of 192.168.0.107 from 192.168.0.1
mar 15 16:43:13 escolacj25-EC10IS2 dhclient[1810]: DHCPACK of 192.168.0.107 from 192.168.0.1
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5224] dhcp4 (wlp2s0):   address 192.168.0.107
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5226] dhcp4 (wlp2s0):   plen 24 (255.255.255.0)
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5227] dhcp4 (wlp2s0):   gateway 192.168.0.1
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5229] dhcp4 (wlp2s0):   lease time 7200
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5230] dhcp4 (wlp2s0):   nameserver '192.168.0.1'
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5231] dhcp4 (wlp2s0): state changed unknown -> bound
mar 15 16:43:13 escolacj25-EC10IS2 avahi-daemon[564]: Joining mDNS multicast group on interface wlp2s0.IPv4 with address 192.168.0.107.
mar 15 16:43:13 escolacj25-EC10IS2 avahi-daemon[564]: New relevant interface wlp2s0.IPv4 for mDNS.
mar 15 16:43:13 escolacj25-EC10IS2 avahi-daemon[564]: Registering new address record for 192.168.0.107 on wlp2s0.IPv4.
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5370] device (wlp2s0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5415] device (wlp2s0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5436] device (wlp2s0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.5449] manager: NetworkManager state is now CONNECTED_LOCAL
mar 15 16:43:13 escolacj25-EC10IS2 dhclient[1810]: bound to 192.168.0.107 -- renewal in 2768 seconds.
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.7813] manager: NetworkManager state is now CONNECTED_SITE
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.7820] policy: set 'PROFESSORES_CaldasJunior 1' (wlp2s0) as default for IPv4 routing and DNS
mar 15 16:43:13 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837393.7845] device (wlp2s0): Activation: successful, device activated.
mar 15 16:43:13 escolacj25-EC10IS2 dbus-daemon[574]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.17' (uid=0 pid=721 comm="/usr/sbin/NetworkManager --no-daemon " label="unconfined")
mar 15 16:43:13 escolacj25-EC10IS2 systemd[1]: Starting Network Manager Script Dispatcher Service...
mar 15 16:43:13 escolacj25-EC10IS2 dbus-daemon[574]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
mar 15 16:43:13 escolacj25-EC10IS2 systemd[1]: Started Network Manager Script Dispatcher Service.
mar 15 16:43:13 escolacj25-EC10IS2 nm-dispatcher[1822]: req:1 'up' [wlp2s0]: new request (2 scripts)
mar 15 16:43:13 escolacj25-EC10IS2 nm-dispatcher[1822]: req:1 'up' [wlp2s0]: start running ordered scripts...
mar 15 16:43:14 escolacj25-EC10IS2 systemd-resolved[458]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
mar 15 16:43:14 escolacj25-EC10IS2 systemd-resolved[458]: Server returned error NXDOMAIN, mitigating potential DNS violation DVE-2018-0001, retrying transaction with reduced feature level UDP.
mar 15 16:43:15 escolacj25-EC10IS2 NetworkManager[721]: <info>  [1615837395.1577] manager: NetworkManager state is now CONNECTED_GLOBAL
mar 15 16:43:15 escolacj25-EC10IS2 nm-dispatcher[1822]: req:2 'connectivity-change': new request (2 scripts)
mar 15 16:43:15 escolacj25-EC10IS2 nm-dispatcher[1822]: req:2 'connectivity-change': start running ordered scripts...
mar 15 16:43:16 escolacj25-EC10IS2 ntpd[803]: Listen normally on 4 wlp2s0 192.168.0.107:123
mar 15 16:43:16 escolacj25-EC10IS2 ntpd[803]: Listen normally on 5 wlp2s0 [fe80::fb3d:a98a:602b:40ca%3]:123
mar 15 16:43:16 escolacj25-EC10IS2 ntpd[803]: new interface(s) found: waking up resolver
mar 15 16:43:16 escolacj25-EC10IS2 ntpdate[1867]: the NTP socket is in use, exiting

IMPORTANT: I found out on 17/03/21 something that must be related. When I reinstall gnome-keyring (which I had removed from all because it pop up window and I don’t wan’t anything storing/managing passwords or showing up/bothering during the use of the computer) the problem goes away for the user aluno/student.

---

Yesterday I hit a very strange error, when I attempted to run ntpdate on a Ubuntu 14.04 Desktop:

root@ubuntudesktop:~# ntpdate ntp.example.local
Error resolving ntp.example.local: Name or service not known (-2)

24 Nov 09:17:16 ntpdate[21746]: Can’t find host ntp.example.local: Name or service not known (-2)

24 Nov 09:17:16 ntpdate[21746]: no servers can be used, exiting

The domain (ntp.example.local) could not be resolved.

Looks like a typical DNS resolving issue — at first sight. However DNS is working and resolving correctly:

The same command was tested on a Ubuntu 14.04 Server in the same subnet and there it worked without problems. What’s the difference between the Desktop and Server version?

Strace gives more details. I will spare you the full output and show only the relevant output:

root@ubuntudesktop:~# strace ntpdate ntp.example.local
[…]

open(«/etc/hosts», O_RDONLY|O_CLOEXEC)  = 3

fstat(3, {st_mode=S_IFREG|0644, st_size=298, …}) = 0

mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f01da876000

read(3, «127.0.0.1tlocalhostn194.40.217.2″…, 4096) = 298

read(3, «», 4096)                       = 0

close(3)                                = 0

munmap(0x7f01da876000, 4096)            = 0

open(«/etc/ld.so.cache», O_RDONLY|O_CLOEXEC) = 3

fstat(3, {st_mode=S_IFREG|0644, st_size=87882, …}) = 0

mmap(NULL, 87882, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7f01da861000

close(3)                                = 0

access(«/etc/ld.so.nohwcap», F_OK)      = -1 ENOENT (No such file or directory)

open(«/lib/x86_64-linux-gnu/libnss_mdns4_minimal.so.2», O_RDONLY|O_CLOEXEC) = 3

read(3, «177ELF2113>120f»…, 832) = 832

fstat(3, {st_mode=S_IFREG|0644, st_size=10432, …}) = 0

mmap(NULL, 2105560, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7f01d968a000

mprotect(0x7f01d968c000, 2093056, PROT_NONE) = 0

mmap(0x7f01d988b000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x1000) = 0x7f01d988b000

close(3)                                = 0

mprotect(0x7f01d988b000, 4096, PROT_READ) = 0

munmap(0x7f01da861000, 87882)           = 0

socket(PF_LOCAL, SOCK_STREAM, 0)        = 3

fcntl(3, F_GETFD)                       = 0

fcntl(3, F_SETFD, FD_CLOEXEC)           = 0

connect(3, {sa_family=AF_LOCAL, sun_path=»/var/run/avahi-daemon/socket»}, 110) = 0

fcntl(3, F_GETFL)                       = 0x2 (flags O_RDWR)

fstat(3, {st_mode=S_IFSOCK|0777, st_size=0, …}) = 0

mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7f01da876000

lseek(3, 0, SEEK_CUR)                   = -1 ESPIPE (Illegal seek)

write(3, «RESOLVE-HOSTNAME-IPV4 ntp.example.»…, 38) = 38

read(3, «-15 Timeout reachedn», 4096)  = 20

close(3)                                = 0

munmap(0x7f01da876000, 4096)            = 0

write(2, «Error resolving ntp.example.local:»…, 64Error resolving ntp.example.local: Name or service not known (-2)

) = 64

[…]

On the Ubuntu Server, the strace output starts the same way, however there is a small difference:

root@ubuntuserver:~# strace ntpdate ntp.example.local
[…]

open(«/etc/resolv.conf», O_RDONLY|O_CLOEXEC) = 3

fstat(3, {st_mode=S_IFREG|0644, st_size=242, …}) = 0

mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc831bc4000

read(3, «# Dynamic resolv.conf(5) file fo»…, 4096) = 242

read(3, «», 4096)                       = 0

close(3)                                = 0

munmap(0x7fc831bc4000, 4096)            = 0

open(«/etc/hosts», O_RDONLY|O_CLOEXEC)  = 3

fstat(3, {st_mode=S_IFREG|0644, st_size=225, …}) = 0

mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc831bc4000

read(3, «127.0.0.1tlocalhostn194.40.217.1″…, 4096) = 225

read(3, «», 4096)                       = 0

close(3)                                = 0

munmap(0x7fc831bc4000, 4096)            = 0

open(«/etc/ld.so.cache», O_RDONLY|O_CLOEXEC) = 3

fstat(3, {st_mode=S_IFREG|0644, st_size=43388, …}) = 0

mmap(NULL, 43388, PROT_READ, MAP_PRIVATE, 3, 0) = 0x7fc831bba000

close(3)                                = 0

access(«/etc/ld.so.nohwcap», F_OK)      = -1 ENOENT (No such file or directory)

open(«/lib/x86_64-linux-gnu/libnss_dns.so.2», O_RDONLY|O_CLOEXEC) = 3

read(3, «177ELF2113>121″…, 832) = 832

fstat(3, {st_mode=S_IFREG|0644, st_size=22952, …}) = 0

mmap(NULL, 2117896, PROT_READ|PROT_EXEC, MAP_PRIVATE|MAP_DENYWRITE, 3, 0) = 0x7fc8309d5000

mprotect(0x7fc8309da000, 2093056, PROT_NONE) = 0

mmap(0x7fc830bd9000, 8192, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_FIXED|MAP_DENYWRITE, 3, 0x4000) = 0x7fc830bd9000

close(3)                                = 0

mprotect(0x7fc8309d1000, 4096, PROT_READ) = 0

mprotect(0x7fc830bd9000, 4096, PROT_READ) = 0

munmap(0x7fc831bba000, 43388)           = 0

stat(«/etc/resolv.conf», {st_mode=S_IFREG|0644, st_size=242, …}) = 0

open(«/etc/resolv.conf», O_RDONLY|O_CLOEXEC) = 3

fstat(3, {st_mode=S_IFREG|0644, st_size=242, …}) = 0

mmap(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0x7fc831bc4000

read(3, «# Dynamic resolv.conf(5) file fo»…, 4096) = 242

read(3, «», 4096)                       = 0

close(3)                                = 0

munmap(0x7fc831bc4000, 4096)            = 0

socket(PF_INET, SOCK_DGRAM|SOCK_NONBLOCK, IPPROTO_IP) = 3

connect(3, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr(«10.10.110.20»)}, 16) = 0

[…]

As you can see in the strace output of the Server, the DNS name could be resolved to 10.10.110.20.

But where does the difference start? It’s this line:

Desktop: open(«/lib/x86_64-linux-gnu/libnss_mdns4_minimal.so.2», O_RDONLY|O_CLOEXEC) = 3

Server: open(«/lib/x86_64-linux-gnu/libnss_dns.so.2», O_RDONLY|O_CLOEXEC) = 3

Ubuntu Desktop uses the DNS library libnss_mdns4_minimal.so, but Server uses the «normal» DNS library of libnss.
This behaviour is configured in /etc/nsswitch.conf which tells the Desktop version to use mdns4:

root@ubuntudesktop:~# cat /etc/nsswitch.conf | grep hosts
hosts:          files mdns4_minimal [NOTFOUND=return] dns

Comparison with the Server:

root@ubuntuserver:~# cat /etc/nsswitch.conf | grep hosts
hosts:          files dns

After removing the «mdns4_minimal [NOTFOUND=return]» entry from the hosts line in nsswitch.conf, ntpdate finally worked:

root@ubuntudesktop:~# ntpdate ntp.example.local
24 Nov 09:28:12 ntpdate[22745]: adjust time server 10.162.214.20 offset -0.008526 sec

Add a comment

Show form to leave a comment

Comments (newest first)

No comments yet.