Thank you for your donation!


Cloudsmith graciously provides open-source package management and distribution for our project.


Freezes after about 1 week and then fails to boot any longer until reimaged
#1
Hi, I'm running the latest moode on rpi3b+.  I'm using the built in pi audio.  It always works fine after freshly installed.

I've now reinstalled it 4 times.  Each time, after around a week, the whatever grinds to a halt and doesn't respond and it becomes impossible to ssh into.  Once this happens, a forced reboot doesn't restore it to a working state.

The latest time this occurred, I was still logged in via ssh when it ground to a halt.  It didn't immediately respond, but after a few minutes it did.  I could see that it isn't running out of disk space.  Top showed that it was having unusual cpu peaks of up to 12 times what it should.  But the web interface no longer responded, so I rebooted using the reboot command for a clean reboot.  Now it won't start, just like when I've previously had to do a forced reboot.

The one difference this time compared to previous times is that before, I was using the sd card to boot, but this time I had been running off usb instead as I wanted to rule out sd card issues.  But previously I had examined the un-bootable sd card from another Linux machine and it also did not appear to be running out of space.

Since it has no boot information as the image doesn't have hdmi enabled I cannot tell how far the boot sequence is getting.

Is there any diagnostics I can do to understand what is happening?  I tried the generic diagnostics linked to the forum sticky post but they weren't helpful for this problem. I also couldn't find anything related by searching the forum, which puzzles me since I've repeatedly had the same fault, surely someone else has experienced it too?

Thanks
Steve
Reply
#2
This is indeed an odd one. I thunk it would be useful to post the startup log here which will tell us a lot about your configuration and setup even though it won't capture the fault.
----------------
Robert
Reply
#3
(05-31-2023, 07:10 AM)the_bertrum Wrote: This is indeed an odd one.  I thunk it would be useful to post the startup log here which will tell us a lot about your configuration and setup even though it won't capture the fault.

Thanks Robert, the only way I can think of getting you a startup log would be to create a new image and boot that, since you don't provide a console on the HDMI interface and therefore if I have no SSH or web access I can't access the device. Can you suggest another way? eg. is there a way to change the default settings and enable an HDMI console access via a local keyboard?
Reply
#4
Very odd. I have one system on a 3B+ thats been up continuously for over a month.

If your boot media is corrupted and can't be read then the issue is prolly external to moOde software for example a hardware or power supply issue.

If the boot media can be read than try plugging it into a USB port on another moOde system (or Linux box that auto-mounts all the partitions) and then examine various logs and files via the path below.

# Logs
/media/rootfs/var/log/moode.log
/media/rootfs/var/log/syslog
.
.

# Linux startup files
/media/boot/config.txt
/media/boot/cmdline.txt
/media/rootfs/etc/rc.local
Enjoy the Music!
moodeaudio.org | Mastodon Feed | GitHub
Reply
#5
(05-31-2023, 07:40 AM)swilkey Wrote:
(05-31-2023, 07:10 AM)the_bertrum Wrote: This is indeed an odd one.  I thunk it would be useful to post the startup log here which will tell us a lot about your configuration and setup even though it won't capture the fault.

Thanks Robert, the only way I can think of getting you a startup log would be to create a new image and boot that, since you don't provide a console on the HDMI interface and therefore if I have no SSH or web access I can't access the device.  Can you suggest another way?  eg. is there a way to change the default settings and enable an HDMI console access via a local keyboard?

I was suggesting a startup log from a pre-crash image because that will tell us about your setup, but I see Tim has a better suggestion...
----------------
Robert
Reply
#6
@swilkey 

This is just a footnote to Tim's post.

Depending on your Linux system, you may find the sd card's partitions auto-mounted one level lower, below your <username>, e.g., /media/<username>/boot and /media/<username/rootfs.

Possibly you'll find the log files of interest have been zeroed out since a moOde maintenance task periodically clears the logs, but it's worth looking.

Like Tim, I'd suspect a power or other external cause here. While the symptom "freezes after a week" is suggestive of the O/S exhausting working (e.g., RAM) memory, something we've seen in the past with local display enabled, the failure to (re)boot suggests corruption of system files and that's definitely odd.

Regards,
Kent
Reply
#7
When I had this sort of problem with Moode a long time ago I fixed it by replacing the SD card with a quality SD card, and the problem never recurred.

Cheers,
Phil

Reply
#8
Thanks for your advice.  The moode.log doesn't tell me much so I've included it here for you.
The syslog was a little more helpful.  It informed me that despite being non-responsive, the moodeaudio image was booted and running up until the time I removed the drive from it and plugged it into my other linux machine to look at the logs.  It may tell more information to someone who understands what they are looking for.

I hope this gives you some clues.  The one thing it got me wondering about is whether the moode image copes with two IP addresses?  Although I had connected it to the wireless network, I'd never noticed what IP address it was given on the wifi until now so I'd never tested it.

The other thing that is really weird is that this time, after I re-inserted the boot drive and rebooted, I can access the moodeaudio server on both the wifi address and the cabled ethernet address.  I'll see if it is still working in a week and a half and let you know, but if there is anything you would like to suggest that I do before then to see what could be causing the problem please let me know.

Since it has now booted, I've captured the start up log as well so in case this happens again we have a record of it. (assuming that when you say startup log you mean the output of dmesg) - i'll send that as a reply to the message where it was requested.

Thanks,
Stephen

# Logs
/media/rootfs/var/log/moode.log

Code:
20230530 143011 worker: --

20230530 143011 worker: -- Start moOde 8 series
20230530 143011 worker: --
20230530 143011 worker: Successfully daemonized
20230530 143011 worker: Waiting for Linux startup...
20230530 143017 worker: Linux startup complete
20230530 143017 worker: Boot config backed up
20230530 143021 worker: File check (OK)
20230530 143024 worker: Session vacuumed
20230530 143025 worker: Session loaded
20230530 143027 worker: Debug logging (OFF)
20230530 143027 worker: --
20230530 143027 worker: -- Audio debug
20230530 143027 worker: --
20230530 143028 worker: ALSA cards: (0:b1 | 1:Headphones | 2:empty | 3:empty
20230530 143028 worker: MPD config: (1:Pi Headphone jack | mixer:(PCM) | card:1)
20230530 143029 worker: ALSA mixer actual (PCM)
20230530 143030 worker: ALSA PCM volume set to (0%)
20230530 143030 worker: --
20230530 143030 worker: -- System
20230530 143030 worker: --
20230530 143133 worker: Host      (moode)
20230530 143133 worker: Hardware  (Pi-3B 1.2 1GB)
20230530 143133 worker: moOde     (8.3.2 2023-04-21)
20230530 143133 worker: RaspiOS   (11.6)
20230530 143133 worker: Kernel    (6.1.21-v7+ #1642)
20230530 143133 worker: Procarch  (armv7l, 32-bit)
20230530 143133 worker: MPD ver   (0.23.12)
20230530 143133 worker: CPU gov   (ondemand)
20230530 143133 worker: Userid    (smw)
20230530 143133 worker: Homedir   (/home/smw)
20230530 143134 worker: USB boot  (enabled)
20230530 143134 worker: File sys  (expanded)
20230530 143134 worker: HDMI port (On)
20230530 143134 worker: Sys LED0  (On)
20230530 143134 worker: Sys LED1  (On)
20230530 143134 worker: --
20230530 143134 worker: -- Network
20230530 143134 worker: --
20230530 143134 worker: eth0 adapter exists
20230530 143134 worker: eth0 address check (Off)
20230530 143134 worker: IP addr (192.168.0.234)
20230530 143134 worker: Netmask (255.255.255.0)
20230530 143134 worker: Gateway (192.168.0.1)
20230530 143134 worker: Pri DNS (1.1.1.3)
20230530 143134 worker: Domain  ()
20230530 143135 worker: wlan0 adapter exists
20230530 143135 worker: wlan0 country (AU)
20230530 143135 worker: wlan0 configured SSID (WLAN)
20230530 143135 worker: wlan0 SSID alternates (None)
20230530 143135 worker: wlan0 router mode (Off)
20230530 143135 worker: wlan0 address check (up to 90 secs)
20230530 143135 worker: wlan0 connected to SSID (WLAN)
20230530 143136 worker: wlan0 power save (Disabled)
20230530 143136 worker: IP addr (192.168.0.199)
20230530 143136 worker: Netmask (255.255.255.0)
20230530 143136 worker: Gateway (192.168.0.1)
20230530 143136 worker: Pri DNS (1.1.1.3)
20230530 143136 worker: Domain  ()
20230530 143136 worker: --
20230530 143136 worker: -- Software update
20230530 143136 worker: --
20230530 143136 worker: Automatic check (Off)
20230530 143136 worker: --
20230530 143136 worker: -- Audio config
20230530 143136 worker: --
20230530 143138 worker: MPD conf updated
20230530 143138 worker: ALSA card number (1)
20230530 143138 worker: MPD audio output (Pi Headphone jack)
20230530 143138 worker: Audio formats (U8, S16_LE)
20230530 143138 worker: ALSA mixer name (PCM)
20230530 143138 worker: MPD mixer type (software)
20230530 143139 worker: Hdwr volume controller exists
20230530 143139 worker: Max ALSA volume (100%)
20230530 143139 worker: ALSA output mode (Default: plughw)
20230530 143140 worker: ALSA loopback (Off)
20230530 143140 worker: Reset renderer active flags
20230530 143140 worker: CamillaDSP configuration (off)
20230530 143140 worker: CamillaDSP volume sync   (off)
20230530 143140 worker: CamillaDSP volume range  (60)
20230530 143140 worker: --
20230530 143140 worker: -- File sharing
20230530 143140 worker: --
20230530 143140 worker: SMB file sharing (Off)
20230530 143140 worker: NFS file sharing (Off)
20230530 143140 worker: --
20230530 143140 worker: -- MPD startup
20230530 143140 worker: --
20230530 143141 worker: MPD started
20230530 143141 worker: MPD accepting connections
20230530 143141 worker: MPD output 1 ALSA Default (On)
20230530 143141 worker: MPD output 2 ALSA Bluetooth (Off)
20230530 143141 worker: MPD output 3 HTTP Server (On)
20230530 143141 worker: MPD crossfade (off)
20230530 143141 worker: MPD ignore CUE files (yes)
20230530 143141 worker: --
20230530 143141 worker: -- Music sources
20230530 143141 worker: --
20230530 143141 worker: USB sources
20230530 143141 worker: No drives found
20230530 143141 worker: NAS sources
20230530 143141 worker: Homeserver
20230530 143146 worker: Mount all submitted
20230530 143146 worker: --
20230530 143146 worker: -- Feature availability
20230530 143146 worker: --
20230530 143146 worker: Input select (available)
20230530 143146 worker: Input (MPD)
20230530 143146 worker: Output (Pi Headphone jack)
20230530 143146 worker: Bluetooth (available)
20230530 143147 worker: AirPlay renderer (available)
20230530 143147 worker: Spotify renderer (available)
20230530 143147 worker: Squeezelite (available)
20230530 143147 worker: RoonBridge renderer (not installed)
20230530 143147 worker: Multiroom sender (available)
20230530 143147 worker: Multiroom receiver (available)
20230530 143147 worker: UPnP renderer (available)
20230530 143147 worker: DLNA server (available)
20230530 143147 worker: GPIO button handler (available)
20230530 143147 worker: Stream recorder (available: not installed)
20230530 143147 worker: HTTPS-Only mode (n/a)
20230530 143147 worker: --
20230530 143147 worker: -- Other
20230530 143147 worker: --
20230530 143147 worker: USB volume knob (Off)
20230530 143147 worker: USB auto-mounter (udisks-glue)
20230530 143147 worker: Saved MPD vol level (0)
20230530 143147 worker: Preamp volume level (0)
20230530 143147 worker: MPD volume level (79) restored
20230530 143147 worker: ALSA PCM volume (100%)
20230530 143147 worker: Auto-play (On)
20230530 143147 worker: Auto-playing id (186)
20230530 143149 worker: LocalUI (On)
20230530 143149 worker: Automatic CoverView (Off)
20230530 143149 worker: On-screen keyboard (Off)
20230530 143149 worker: Library scope (all)
20230530 143149 worker: View reset to playback
20230530 143149 worker: Maintenance interval (360 minutes)
20230530 143149 worker: Screen saver activation (Never)
20230530 143150 worker: Session permissions (OK)
20230530 143150 worker: --
20230530 143150 worker: -- Startup complete
20230530 143150 worker: --
20230530 143150 worker: Mount monitor (off)
20230530 143152 worker: Watchdog monitor (started)
20230530 143152 worker: Ready
/media/rootfs/var/log/syslog
Code:
Jun  1 12:41:52 moode rngd[445]: stats: bits received from HRNG source: 1080064
Jun  1 12:41:53 moode rngd[445]: stats: bits sent to kernel pool: 1040000
Jun  1 12:41:53 moode rngd[445]: stats: entropy added to kernel pool: 1040000
Jun  1 12:41:53 moode rngd[445]: stats: FIPS 140-2 successes: 54
Jun  1 12:41:53 moode rngd[445]: stats: FIPS 140-2 failures: 0
Jun  1 12:41:53 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Jun  1 12:41:53 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Poker: 0
Jun  1 12:41:53 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Runs: 0
Jun  1 12:41:53 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Long run: 0
Jun  1 12:41:53 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Jun  1 12:41:53 moode rngd[445]: stats: HRNG source speed: (min=7.174; avg=73.112; max=854.610)Kibits/s
Jun  1 12:41:53 moode rngd[445]: stats: FIPS tests speed: (min=20.524; avg=856.776; max=26718.536)Kibits/s
Jun  1 12:41:53 moode rngd[445]: stats: Lowest ready-buffers level: 0
Jun  1 12:41:53 moode rngd[445]: stats: Entropy starvations: 2
Jun  1 12:41:53 moode rngd[445]: stats: Time spent starving for entropy: (min=1017; avg=1017.000; max=1017)us
Jun  1 12:41:53 moode winbindd[13604]: [2023/06/01 12:41:53.444788,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:41:58 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 12:41:58 moode winbindd[13604]: [2023/06/01 12:41:53.446605,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:41:58 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 12:46:53 moode winbindd[13604]: [2023/06/01 12:46:53.446306,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 12:46:54 moode winbindd[13604]: [2023/06/01 12:46:53.448292,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 12:50:01 moode systemd[1]: Starting system activity accounting tool...
Jun  1 12:50:01 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 12:50:01 moode systemd[1]: Finished system activity accounting tool.
Jun  1 12:51:53 moode winbindd[13604]: [2023/06/01 12:51:53.447420,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:51:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 12:51:53 moode winbindd[13604]: [2023/06/01 12:51:53.449331,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:51:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 12:56:53 moode winbindd[13604]: [2023/06/01 12:56:53.452559,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 12:56:54 moode winbindd[13604]: [2023/06/01 12:56:53.454513,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 12:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:00:01 moode systemd[1]: Starting system activity accounting tool...
Jun  1 13:00:01 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 13:00:01 moode systemd[1]: Finished system activity accounting tool.
Jun  1 13:01:53 moode winbindd[13604]: [2023/06/01 13:01:53.458257,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:01:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:01:53 moode winbindd[13604]: [2023/06/01 13:01:53.460173,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:01:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:06:53 moode winbindd[13604]: [2023/06/01 13:06:53.463331,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:06:54 moode winbindd[13604]: [2023/06/01 13:06:53.465358,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:10:01 moode systemd[1]: Starting system activity accounting tool...
Jun  1 13:10:01 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 13:10:01 moode systemd[1]: Finished system activity accounting tool.
Jun  1 13:11:53 moode winbindd[13604]: [2023/06/01 13:11:53.464792,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:11:53 moode winbindd[13604]: [2023/06/01 13:11:53.466672,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:16:53 moode winbindd[13604]: [2023/06/01 13:16:53.469090,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:16:54 moode winbindd[13604]: [2023/06/01 13:16:53.471076,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:20:01 moode systemd[1]: Starting system activity accounting tool...
Jun  1 13:20:01 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 13:20:01 moode systemd[1]: Finished system activity accounting tool.
Jun  1 13:21:53 moode winbindd[13604]: [2023/06/01 13:21:53.473484,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:21:54 moode winbindd[13604]: [2023/06/01 13:21:53.475454,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:26:53 moode winbindd[13604]: [2023/06/01 13:26:53.477714,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:26:54 moode winbindd[13604]: [2023/06/01 13:26:53.479617,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:30:01 moode systemd[1]: Starting system activity accounting tool...
Jun  1 13:30:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 13:30:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 13:31:53 moode winbindd[13604]: [2023/06/01 13:31:53.481433,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:31:54 moode winbindd[13604]: [2023/06/01 13:31:53.483268,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:36:53 moode winbindd[13604]: [2023/06/01 13:36:53.484807,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:36:54 moode winbindd[13604]: [2023/06/01 13:36:53.486702,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:40:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 13:40:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 13:40:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 13:41:52 moode rngd[445]: stats: bits received from HRNG source: 1120064
Jun  1 13:41:52 moode rngd[445]: stats: bits sent to kernel pool: 1070240
Jun  1 13:41:52 moode rngd[445]: stats: entropy added to kernel pool: 1070240
Jun  1 13:41:52 moode rngd[445]: stats: FIPS 140-2 successes: 56
Jun  1 13:41:52 moode rngd[445]: stats: FIPS 140-2 failures: 0
Jun  1 13:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Jun  1 13:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Poker: 0
Jun  1 13:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Runs: 0
Jun  1 13:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Long run: 0
Jun  1 13:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Jun  1 13:41:52 moode rngd[445]: stats: HRNG source speed: (min=7.174; avg=75.578; max=854.610)Kibits/s
Jun  1 13:41:52 moode rngd[445]: stats: FIPS tests speed: (min=20.524; avg=887.446; max=26718.536)Kibits/s
Jun  1 13:41:52 moode rngd[445]: stats: Lowest ready-buffers level: 0
Jun  1 13:41:52 moode rngd[445]: stats: Entropy starvations: 2
Jun  1 13:41:52 moode rngd[445]: stats: Time spent starving for entropy: (min=1017; avg=1017.000; max=1017)us
Jun  1 13:41:53 moode winbindd[13604]: [2023/06/01 13:41:53.487841,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:41:55 moode winbindd[13604]: [2023/06/01 13:41:53.489682,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:46:53 moode winbindd[13604]: [2023/06/01 13:46:53.490358,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:46:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:46:53 moode winbindd[13604]: [2023/06/01 13:46:53.492212,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:46:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:50:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 13:50:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 13:50:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 13:51:53 moode winbindd[13604]: [2023/06/01 13:51:53.492517,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:51:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:51:54 moode winbindd[13604]: [2023/06/01 13:51:53.494611,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:51:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:56:53 moode winbindd[13604]: [2023/06/01 13:56:53.494601,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 13:56:54 moode winbindd[13604]: [2023/06/01 13:56:53.496464,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 13:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:00:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 14:00:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 14:00:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 14:01:53 moode winbindd[13604]: [2023/06/01 14:01:53.496288,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:01:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:01:53 moode winbindd[13604]: [2023/06/01 14:01:53.498276,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:01:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:06:53 moode winbindd[13604]: [2023/06/01 14:06:53.498033,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:06:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:06:53 moode winbindd[13604]: [2023/06/01 14:06:53.499904,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:06:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:10:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 14:10:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 14:10:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 14:11:53 moode winbindd[13604]: [2023/06/01 14:11:53.498759,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:11:53 moode winbindd[13604]: [2023/06/01 14:11:53.500620,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:16:53 moode winbindd[13604]: [2023/06/01 14:16:53.499784,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:16:54 moode winbindd[13604]: [2023/06/01 14:16:53.501672,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:20:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 14:20:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 14:20:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 14:21:53 moode winbindd[13604]: [2023/06/01 14:21:53.505436,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:21:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:21:53 moode winbindd[13604]: [2023/06/01 14:21:53.507673,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:21:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:26:53 moode winbindd[13604]: [2023/06/01 14:26:53.510375,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:26:54 moode winbindd[13604]: [2023/06/01 14:26:53.512248,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:30:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 14:30:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 14:30:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 14:31:53 moode winbindd[13604]: [2023/06/01 14:31:53.515675,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:31:54 moode winbindd[13604]: [2023/06/01 14:31:53.517612,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:36:53 moode winbindd[13604]: [2023/06/01 14:36:53.520615,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:36:54 moode winbindd[13604]: [2023/06/01 14:36:53.522540,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:40:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 14:40:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 14:40:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 14:41:52 moode rngd[445]: stats: bits received from HRNG source: 1140064
Jun  1 14:41:52 moode rngd[445]: stats: bits sent to kernel pool: 1100000
Jun  1 14:41:52 moode rngd[445]: stats: entropy added to kernel pool: 1100000
Jun  1 14:41:52 moode rngd[445]: stats: FIPS 140-2 successes: 57
Jun  1 14:41:52 moode rngd[445]: stats: FIPS 140-2 failures: 0
Jun  1 14:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Jun  1 14:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Poker: 0
Jun  1 14:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Runs: 0
Jun  1 14:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Long run: 0
Jun  1 14:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Jun  1 14:41:52 moode rngd[445]: stats: HRNG source speed: (min=7.174; avg=76.805; max=854.610)Kibits/s
Jun  1 14:41:52 moode rngd[445]: stats: FIPS tests speed: (min=20.524; avg=902.757; max=26718.536)Kibits/s
Jun  1 14:41:52 moode rngd[445]: stats: Lowest ready-buffers level: 0
Jun  1 14:41:52 moode rngd[445]: stats: Entropy starvations: 2
Jun  1 14:41:52 moode rngd[445]: stats: Time spent starving for entropy: (min=1017; avg=1017.000; max=1017)us
Jun  1 14:41:53 moode winbindd[13604]: [2023/06/01 14:41:53.525142,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:41:55 moode winbindd[13604]: [2023/06/01 14:41:53.527000,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:46:53 moode winbindd[13604]: [2023/06/01 14:46:53.528854,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:46:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:46:53 moode winbindd[13604]: [2023/06/01 14:46:53.530716,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:46:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:50:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 14:50:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 14:50:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 14:51:26 moode dhcpcd[564]: wlan0: carrier lost
Jun  1 14:51:26 moode dhcpcd[564]: wlan0: deleting address fe80::8f46:8c80:aed7:d249
Jun  1 14:51:26 moode avahi-daemon[408]: Withdrawing address record for fe80::8f46:8c80:aed7:d249 on wlan0.
Jun  1 14:51:26 moode avahi-daemon[408]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::8f46:8c80:aed7:d249.
Jun  1 14:51:26 moode avahi-daemon[408]: Interface wlan0.IPv6 no longer relevant for mDNS.
Jun  1 14:51:26 moode avahi-daemon[408]: Withdrawing address record for 192.168.0.199 on wlan0.
Jun  1 14:51:26 moode avahi-daemon[408]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.199.
Jun  1 14:51:26 moode avahi-daemon[408]: Interface wlan0.IPv4 no longer relevant for mDNS.
Jun  1 14:51:26 moode dhcpcd[564]: wlan0: deleting route to 192.168.0.0/24
Jun  1 14:51:26 moode dhcpcd[564]: wlan0: deleting default route via 192.168.0.1
Jun  1 14:51:27 moode dhcpcd[564]: wlan0: carrier acquired
Jun  1 14:51:27 moode dhcpcd[564]: wlan0: IAID eb:c7:25:80
Jun  1 14:51:27 moode dhcpcd[564]: wlan0: adding address fe80::8f46:8c80:aed7:d249
Jun  1 14:51:27 moode avahi-daemon[408]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::8f46:8c80:aed7:d249.
Jun  1 14:51:27 moode avahi-daemon[408]: New relevant interface wlan0.IPv6 for mDNS.
Jun  1 14:51:27 moode avahi-daemon[408]: Registering new address record for fe80::8f46:8c80:aed7:d249 on wlan0.*.
Jun  1 14:51:27 moode dhcpcd[564]: wlan0: rebinding lease of 192.168.0.199
Jun  1 14:51:27 moode dhcpcd[564]: wlan0: probing address 192.168.0.199/24
Jun  1 14:51:27 moode dhcpcd[564]: wlan0: soliciting an IPv6 router
Jun  1 14:51:32 moode dhcpcd[564]: wlan0: leased 192.168.0.199 for 86400 seconds
Jun  1 14:51:32 moode avahi-daemon[408]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.199.
Jun  1 14:51:32 moode dhcpcd[564]: wlan0: adding route to 192.168.0.0/24
Jun  1 14:51:32 moode dhcpcd[564]: wlan0: adding default route via 192.168.0.1
Jun  1 14:51:32 moode avahi-daemon[408]: New relevant interface wlan0.IPv4 for mDNS.
Jun  1 14:51:32 moode avahi-daemon[408]: Registering new address record for 192.168.0.199 on wlan0.IPv4.
Jun  1 14:51:40 moode dhcpcd[564]: wlan0: no IPv6 Routers available
Jun  1 14:51:53 moode winbindd[13604]: [2023/06/01 14:51:53.532924,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:51:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:51:55 moode winbindd[13604]: [2023/06/01 14:51:53.534743,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:51:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:56:53 moode winbindd[13604]: [2023/06/01 14:56:53.536874,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 14:56:54 moode winbindd[13604]: [2023/06/01 14:56:53.538846,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 14:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:00:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 15:00:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 15:00:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 15:01:53 moode winbindd[13604]: [2023/06/01 15:01:53.540408,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:01:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:01:54 moode winbindd[13604]: [2023/06/01 15:01:53.542443,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:01:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:06:53 moode winbindd[13604]: [2023/06/01 15:06:53.543813,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:06:54 moode winbindd[13604]: [2023/06/01 15:06:53.545825,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:10:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 15:10:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 15:10:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 15:11:53 moode winbindd[13604]: [2023/06/01 15:11:53.544651,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:11:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:11:54 moode winbindd[13604]: [2023/06/01 15:11:53.546656,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:11:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:16:53 moode winbindd[13604]: [2023/06/01 15:16:53.546950,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:16:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:16:53 moode winbindd[13604]: [2023/06/01 15:16:53.548955,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:16:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:20:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 15:20:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 15:20:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 15:21:53 moode winbindd[13604]: [2023/06/01 15:21:53.549408,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:21:54 moode winbindd[13604]: [2023/06/01 15:21:53.551350,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:26:53 moode winbindd[13604]: [2023/06/01 15:26:53.550823,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:26:54 moode winbindd[13604]: [2023/06/01 15:26:53.552815,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:30:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 15:30:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 15:30:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 15:31:53 moode winbindd[13604]: [2023/06/01 15:31:53.552778,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:31:54 moode winbindd[13604]: [2023/06/01 15:31:53.554841,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:36:53 moode winbindd[13604]: [2023/06/01 15:36:53.554452,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:36:54 moode winbindd[13604]: [2023/06/01 15:36:53.556421,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:40:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 15:40:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 15:40:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 15:41:52 moode rngd[445]: stats: bits received from HRNG source: 1180064
Jun  1 15:41:52 moode rngd[445]: stats: bits sent to kernel pool: 1130240
Jun  1 15:41:52 moode rngd[445]: stats: entropy added to kernel pool: 1130240
Jun  1 15:41:52 moode rngd[445]: stats: FIPS 140-2 successes: 59
Jun  1 15:41:52 moode rngd[445]: stats: FIPS 140-2 failures: 0
Jun  1 15:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Jun  1 15:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Poker: 0
Jun  1 15:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Runs: 0
Jun  1 15:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Long run: 0
Jun  1 15:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Jun  1 15:41:52 moode rngd[445]: stats: HRNG source speed: (min=7.174; avg=79.248; max=854.610)Kibits/s
Jun  1 15:41:52 moode rngd[445]: stats: FIPS tests speed: (min=20.524; avg=933.308; max=26718.536)Kibits/s
Jun  1 15:41:52 moode rngd[445]: stats: Lowest ready-buffers level: 0
Jun  1 15:41:52 moode rngd[445]: stats: Entropy starvations: 2
Jun  1 15:41:52 moode rngd[445]: stats: Time spent starving for entropy: (min=1017; avg=1017.000; max=1017)us
Jun  1 15:41:53 moode winbindd[13604]: [2023/06/01 15:41:53.555776,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:41:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:41:54 moode winbindd[13604]: [2023/06/01 15:41:53.557747,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:41:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:46:53 moode winbindd[13604]: [2023/06/01 15:46:53.556686,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:46:54 moode winbindd[13604]: [2023/06/01 15:46:53.558609,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:50:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 15:50:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 15:50:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 15:51:53 moode winbindd[13604]: [2023/06/01 15:51:53.562298,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:51:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:51:54 moode winbindd[13604]: [2023/06/01 15:51:53.564543,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:51:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:56:53 moode winbindd[13604]: [2023/06/01 15:56:53.567230,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:56:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 15:56:55 moode winbindd[13604]: [2023/06/01 15:56:53.569208,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 15:56:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:00:02 moode systemd[1]: Starting system activity accounting tool...
Jun  1 16:00:02 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 16:00:02 moode systemd[1]: Finished system activity accounting tool.
Jun  1 16:01:53 moode winbindd[13604]: [2023/06/01 16:01:53.572502,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:01:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:01:54 moode winbindd[13604]: [2023/06/01 16:01:53.574541,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:01:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:06:53 moode winbindd[13604]: [2023/06/01 16:06:53.576958,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:06:54 moode winbindd[13604]: [2023/06/01 16:06:53.578872,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:10:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 16:10:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 16:10:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 16:11:53 moode winbindd[13604]: [2023/06/01 16:11:53.581622,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:11:53 moode winbindd[13604]: [2023/06/01 16:11:53.583532,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:16:53 moode winbindd[13604]: [2023/06/01 16:16:53.585272,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:16:54 moode winbindd[13604]: [2023/06/01 16:16:53.587159,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:20:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 16:20:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 16:20:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 16:21:53 moode winbindd[13604]: [2023/06/01 16:21:53.588778,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:21:54 moode winbindd[13604]: [2023/06/01 16:21:53.590715,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:26:53 moode winbindd[13604]: [2023/06/01 16:26:53.591823,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:26:54 moode winbindd[13604]: [2023/06/01 16:26:53.593763,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:30:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 16:30:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 16:30:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 16:31:53 moode winbindd[13604]: [2023/06/01 16:31:53.594628,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:31:54 moode winbindd[13604]: [2023/06/01 16:31:53.596588,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:36:53 moode winbindd[13604]: [2023/06/01 16:36:53.597761,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:36:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:36:55 moode winbindd[13604]: [2023/06/01 16:36:53.599760,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:36:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:40:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 16:40:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 16:40:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 16:41:52 moode rngd[445]: stats: bits received from HRNG source: 1200064
Jun  1 16:41:52 moode rngd[445]: stats: bits sent to kernel pool: 1160000
Jun  1 16:41:52 moode rngd[445]: stats: entropy added to kernel pool: 1160000
Jun  1 16:41:52 moode rngd[445]: stats: FIPS 140-2 successes: 60
Jun  1 16:41:52 moode rngd[445]: stats: FIPS 140-2 failures: 0
Jun  1 16:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Jun  1 16:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Poker: 0
Jun  1 16:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Runs: 0
Jun  1 16:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Long run: 0
Jun  1 16:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Jun  1 16:41:52 moode rngd[445]: stats: HRNG source speed: (min=7.174; avg=80.464; max=854.610)Kibits/s
Jun  1 16:41:52 moode rngd[445]: stats: FIPS tests speed: (min=20.524; avg=948.562; max=26718.536)Kibits/s
Jun  1 16:41:52 moode rngd[445]: stats: Lowest ready-buffers level: 0
Jun  1 16:41:52 moode rngd[445]: stats: Entropy starvations: 2
Jun  1 16:41:52 moode rngd[445]: stats: Time spent starving for entropy: (min=1017; avg=1017.000; max=1017)us
Jun  1 16:41:53 moode winbindd[13604]: [2023/06/01 16:41:53.600569,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:41:55 moode winbindd[13604]: [2023/06/01 16:41:53.602479,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:46:53 moode winbindd[13604]: [2023/06/01 16:46:53.602875,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:46:54 moode winbindd[13604]: [2023/06/01 16:46:53.604919,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:50:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 16:50:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 16:50:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 16:51:53 moode winbindd[13604]: [2023/06/01 16:51:53.604758,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:51:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:51:54 moode winbindd[13604]: [2023/06/01 16:51:53.606692,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:51:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:56:53 moode winbindd[13604]: [2023/06/01 16:56:53.606294,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 16:56:54 moode winbindd[13604]: [2023/06/01 16:56:53.608229,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 16:56:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:00:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 17:00:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 17:00:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 17:01:53 moode winbindd[13604]: [2023/06/01 17:01:53.607618,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:01:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:01:54 moode winbindd[13604]: [2023/06/01 17:01:53.609656,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:01:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:06:53 moode winbindd[13604]: [2023/06/01 17:06:53.609152,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:06:54 moode winbindd[13604]: [2023/06/01 17:06:53.611105,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:06:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:10:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 17:10:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 17:10:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 17:11:53 moode winbindd[13604]: [2023/06/01 17:11:53.609450,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:11:53 moode winbindd[13604]: [2023/06/01 17:11:53.611456,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:11:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:16:53 moode winbindd[13604]: [2023/06/01 17:16:53.614488,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:16:54 moode winbindd[13604]: [2023/06/01 17:16:53.616455,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:16:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:20:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 17:20:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 17:20:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 17:21:53 moode winbindd[13604]: [2023/06/01 17:21:53.620036,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:21:54 moode winbindd[13604]: [2023/06/01 17:21:53.622034,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:21:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:26:53 moode winbindd[13604]: [2023/06/01 17:26:53.624643,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:26:54 moode winbindd[13604]: [2023/06/01 17:26:53.626588,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:26:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:30:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 17:30:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 17:30:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 17:31:53 moode winbindd[13604]: [2023/06/01 17:31:53.629231,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:31:54 moode winbindd[13604]: [2023/06/01 17:31:53.631173,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:31:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:36:53 moode winbindd[13604]: [2023/06/01 17:36:53.633244,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:36:54 moode winbindd[13604]: [2023/06/01 17:36:53.635264,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:36:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:40:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 17:40:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 17:40:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 17:41:52 moode rngd[445]: stats: bits received from HRNG source: 1240064
Jun  1 17:41:52 moode rngd[445]: stats: bits sent to kernel pool: 1190240
Jun  1 17:41:52 moode rngd[445]: stats: entropy added to kernel pool: 1190240
Jun  1 17:41:52 moode rngd[445]: stats: FIPS 140-2 successes: 62
Jun  1 17:41:52 moode rngd[445]: stats: FIPS 140-2 failures: 0
Jun  1 17:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Monobit: 0
Jun  1 17:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Poker: 0
Jun  1 17:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Runs: 0
Jun  1 17:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Long run: 0
Jun  1 17:41:52 moode rngd[445]: stats: FIPS 140-2(2001-10-10) Continuous run: 0
Jun  1 17:41:52 moode rngd[445]: stats: HRNG source speed: (min=7.174; avg=82.883; max=854.610)Kibits/s
Jun  1 17:41:52 moode rngd[445]: stats: FIPS tests speed: (min=20.524; avg=979.016; max=26718.536)Kibits/s
Jun  1 17:41:52 moode rngd[445]: stats: Lowest ready-buffers level: 0
Jun  1 17:41:52 moode rngd[445]: stats: Entropy starvations: 2
Jun  1 17:41:52 moode rngd[445]: stats: Time spent starving for entropy: (min=1017; avg=1017.000; max=1017)us
Jun  1 17:41:53 moode winbindd[13604]: [2023/06/01 17:41:53.637442,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:41:55 moode winbindd[13604]: [2023/06/01 17:41:53.639409,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:41:55 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:46:53 moode winbindd[13604]: [2023/06/01 17:46:53.641407,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:46:54 moode winbindd[13604]: [2023/06/01 17:46:53.643349,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:46:54 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:50:03 moode systemd[1]: Starting system activity accounting tool...
Jun  1 17:50:03 moode systemd[1]: sysstat-collect.service: Succeeded.
Jun  1 17:50:03 moode systemd[1]: Finished system activity accounting tool.
Jun  1 17:51:53 moode winbindd[13604]: [2023/06/01 17:51:53.645116,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:51:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR
Jun  1 17:51:53 moode winbindd[13604]: [2023/06/01 17:51:53.647061,  0] ../../source3/rpc_server/rpc_ncacn_np.c:454(rpcint_dispatch)
Jun  1 17:51:53 moode winbindd[13604]:   rpcint_dispatch: DCE/RPC fault in call lsarpc:32 - DCERPC_NCA_S_OP_RNG_ERROR

.
.

# Linux startup files
/media/boot/config.txt
Code:
[cm4]
otg_mode=1

[pi4]
hdmi_force_hotplug:0=1
hdmi_force_hotplug:1=1

[all]
disable_splash=1
disable_overscan=1
hdmi_drive=2
hdmi_blanking=1
hdmi_force_edid_audio=1
hdmi_force_hotplug=1
hdmi_group=0
dtparam=i2c_arm=on
dtparam=i2s=on
dtparam=audio=on
#dtoverlay=disable-wifi
#dtoverlay=disable-bt

/media/boot/cmdline.txt
Code:
console=serial0,115200 console=tty1 root=PARTUUID=e0770475-02 rootfstype=ext4 fsck.repair=yes rootwait

/media/rootfs/etc/rc.local

Code:
#!/bin/sh -e

SQLDB=/var/local/www/db/moode-sqlite3.db

# Unblock WiFi
/usr/sbin/rfkill unblock wifi > /dev/null 2>&1

# Set cpu govenor
CPU_GOV=$(sqlite3 $SQLDB "select value from cfg_system where param='cpugov'")
echo "$CPU_GOV" | tee /sys/devices/system/cpu/cpu*/cpufreq/scaling_governor

# USB storage auto-mounter
/usr/bin/udisks-glue --config=/etc/udisks-glue.conf > /dev/null 2>&1
#/usr/bin/devmon --exec-on-drive "/var/www/util/automount.sh add_mount_devmon %d %f" --exec-on-remove "/var/www/util/automount.sh remove_mount_devmon %f" --always-exec --no-gui > /dev/null 2>&1 &

# Work around for Katana driver load failure on Pi-4B
AUDIO_DEVICE=$(sqlite3 $SQLDB "select value from cfg_system where param='i2sdevice'")
PI_REVNUM_SEGMENT=$(awk '{if ($1=="Revision") print substr($3,3,3)}' /proc/cpuinfo)

if [ "$AUDIO_DEVICE" = "Allo Katana DAC" ] && [ $PI_REVNUM_SEGMENT = "311" ]; then
       rmmod snd_soc_allo_katana_codec
       rmmod snd_soc_audio_graph_card
       modprobe snd_soc_audio_graph_card
       modprobe snd_soc_allo_katana_codec
   echo `date +'%Y%m%d %H%M%S'` "rc.local ran Katana|Pi-4B driver reload" > /var/log/moode_katana.log
fi

# moOde startup and job processor daemon
/var/www/daemon/worker.php > /dev/null 2>&1

exit 0
Reply
#9
(05-31-2023, 07:10 AM)the_bertrum Wrote: This is indeed an odd one.  I thunk it would be useful to post the startup log here which will tell us a lot about your configuration and setup even though it won't capture the fault.


Assuming you mean the output of dmesg.  But please see the later reply from me with more information.  Thanks!
Code:
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 6.1.21-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1642 SMP Mon Apr  3 17:20:52 BST 2023
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] percpu: Embedded 17 pages/cpu s36884 r8192 d24556 u69632
[    0.000000] pcpu-alloc: s36884 r8192 d24556 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=e0770475-02 rootfstype=ext4 fsck.repair=yes rootwait
[    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] Memory: 877920K/970752K available (10240K kernel code, 1452K rwdata, 2900K rodata, 1024K init, 613K bss, 27296K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 34800 entries in 103 pages
[    0.000000] ftrace: allocated 102 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    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] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000019] Switching to timer-based delay loop, resolution 52ns
[    0.000382] Console: colour dummy device 80x30
[    0.001004] printk: console [tty1] enabled
[    0.001067] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.001113] pid_max: default: 32768 minimum: 301
[    0.001272] LSM: Security Framework initializing
[    0.001503] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001547] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002731] cgroup: Disabling memory control group subsystem
[    0.002988] CPU: Testing write buffer coherency: ok
[    0.003436] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004297] cblist_init_generic: Setting adjustable number of callback queues.
[    0.004336] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.004463] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.004615] Setting up static identity map for 0x100000 - 0x10003c
[    0.004771] rcu: Hierarchical SRCU implementation.
[    0.004796] rcu:     Max phase no-delay instances is 1000.
[    0.005514] smp: Bringing up secondary CPUs ...
[    0.006444] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.007393] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.008248] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.008349] smp: Brought up 1 node, 4 CPUs
[    0.008431] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.008458] CPU: All CPU(s) started in HYP mode.
[    0.008477] CPU: Virtualization extensions available.
[    0.009238] devtmpfs: initialized
[    0.024015] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.024258] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.024312] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.027013] pinctrl core: initialized pinctrl subsystem
[    0.028226] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.031405] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.036439] audit: initializing netlink subsys (disabled)
[    0.036703] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[    0.037306] thermal_sys: Registered thermal governor 'step_wise'
[    0.038207] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.038261] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.038515] Serial: AMBA PL011 UART driver
[    0.043341] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.060035] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-03-17T10:52:42, variant start
[    0.070043] raspberrypi-firmware soc:firmware: Firmware hash is 82f3750a65fadae9a38077e3c2e217ad158c8d54
[    0.112480] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    0.118353] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.120017] SCSI subsystem initialized
[    0.120272] usbcore: registered new interface driver usbfs
[    0.120352] usbcore: registered new interface driver hub
[    0.120420] usbcore: registered new device driver usb
[    0.120637] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.120822] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.121105] pps_core: LinuxPPS API ver. 1 registered
[    0.121130] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.121180] PTP clock support registered
[    1.999559] clocksource: Switched to clocksource arch_sys_counter
[    2.097031] VFS: Disk quotas dquot_6.6.0
[    2.097157] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.097328] FS-Cache: Loaded
[    2.097637] CacheFiles: Loaded
[    2.106335] NET: Registered PF_INET protocol family
[    2.106638] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    2.109141] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    2.109201] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    2.109304] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    2.109443] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    2.109831] TCP: Hash tables configured (established 8192 bind 8192)
[    2.109993] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    2.110065] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    2.110305] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    2.110857] RPC: Registered named UNIX socket transport module.
[    2.110885] RPC: Registered udp transport module.
[    2.110908] RPC: Registered tcp transport module.
[    2.110928] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    2.112486] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    2.115578] Initialise system trusted keyrings
[    2.115833] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    2.125051] zbud: loaded
[    2.127417] NFS: Registering the id_resolver key type
[    2.127469] Key type id_resolver registered
[    2.127492] Key type id_legacy registered
[    2.127645] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    2.127674] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    2.128962] Key type asymmetric registered
[    2.128990] Asymmetric key parser 'x509' registered
[    2.129177] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    2.129212] io scheduler mq-deadline registered
[    2.129239] io scheduler kyber registered
[    2.134018] bcm2708_fb soc:fb: FB found 1 display(s)
[    2.144677] Console: switching to colour frame buffer device 80x30
[    2.151065] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
[    2.159668] bcm2835-rng 3f104000.rng: hwrng registered
[    2.162258] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    2.167805] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    2.183932] brd: module loaded
[    2.193657] loop: module loaded
[    2.196571] Loading iSCSI transport class v2.0-870.
[    2.200384] usbcore: registered new interface driver lan78xx
[    2.202807] usbcore: registered new interface driver smsc95xx
[    2.205128] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.935133] Core Release: 2.80a
[    2.937438] Setting default values for core params
[    2.939769] Finished setting default values for core params
[    3.142289] Using Buffer DMA mode
[    3.144535] Periodic Transfer Interrupt Enhancement - disabled
[    3.146842] Multiprocessor Interrupt Enhancement - disabled
[    3.149122] OTG VER PARAM: 0, OTG VER FLAG: 0
[    3.151402] Dedicated Tx FIFOs mode

[    3.154219] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7504000 dma = 0xf7504000 len=9024
[    3.160927] FIQ FSM acceleration enabled for :
              Non-periodic Split Transactions
              Periodic Split Transactions
              High-Speed Isochronous Endpoints
              Interrupt/Control Split Transaction hack enabled
[    3.171540] dwc_otg: Microframe scheduler enabled

[    3.171859] WARN::hcd_init_fiq:457: FIQ on core 1

[    3.175716] WARN::hcd_init_fiq:458: FIQ ASM at 807c4360 length 36

[    3.179605] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
[    3.183577] dwc_otg 3f980000.usb: DWC OTG Controller
[    3.185734] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    3.189889] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
[    3.192076] Init: Port Power? op_state=1
[    3.194183] Init: Power Port (0)
[    3.196462] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01
[    3.200697] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.205070] usb usb1: Product: DWC OTG Controller
[    3.207347] usb usb1: Manufacturer: Linux 6.1.21-v7+ dwc_otg_hcd
[    3.209642] usb usb1: SerialNumber: 3f980000.usb
[    3.212488] hub 1-0:1.0: USB hub found
[    3.214695] hub 1-0:1.0: 1 port detected
[    3.217451] dwc_otg: FIQ enabled
[    3.217462] dwc_otg: NAK holdoff enabled
[    3.217471] dwc_otg: FIQ split-transaction FSM enabled
[    3.217487] Module dwc_common_port init
[    3.217771] usbcore: registered new interface driver usb-storage
[    3.220276] mousedev: PS/2 mouse device common for all mice
[    3.224913] sdhci: Secure Digital Host Controller Interface driver
[    3.227207] sdhci: Copyright(c) Pierre Ossman
[    3.229831] sdhci-pltfm: SDHCI platform and OF driver helper
[    3.234040] ledtrig-cpu: registered to indicate activity on CPUs
[    3.236711] hid: raw HID events driver (C) Jiri Kosina
[    3.239176] usbcore: registered new interface driver usbhid
[    3.241510] usbhid: USB HID core driver
[    3.247964] Initializing XFRM netlink socket
[    3.250320] NET: Registered PF_PACKET protocol family
[    3.252695] Key type dns_resolver registered
[    3.255628] Registering SWP/SWPB emulation handler
[    3.258448] registered taskstats version 1
[    3.260697] Loading compiled-in X.509 certificates
[    3.263658] Key type .fscrypt registered
[    3.265784] Key type fscrypt-provisioning registered
[    3.279105] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    3.281429] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
[    3.287548] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    3.290019] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    3.295570] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    3.297867] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    3.328017] sdhost: log_buf @ 0084c925 (f7507000)
[    3.351793] Indeed it is in host mode hprt0 = 00021501
[    3.387128] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    3.391647] of_cfs_init
[    3.393947] of_cfs_init: OK
[    3.397502] Waiting for root device PARTUUID=e0770475-02...
[    3.523886] mmc1: new high speed SDIO card at address 0001
[    3.559581] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    3.561878] Indeed it is in host mode hprt0 = 00001101
[    3.799803] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
[    3.804268] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.807200] hub 1-1:1.0: USB hub found
[    3.809675] hub 1-1:1.0: 5 ports detected
[    4.129569] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    4.259806] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
[    4.264614] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.272059] smsc95xx v2.0.0
[    4.361329] SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199)
[    4.366906] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:92:70:d5
[    4.469572] usb 1-1.3: new high-speed USB device number 4 using dwc_otg
[    4.611185] usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0302, bcdDevice= 2.00
[    4.616284] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    4.621610] usb 1-1.3: Product: STORE N GO
[    4.624323] usb 1-1.3: Manufacturer: Verbatim
[    4.626996] usb 1-1.3: SerialNumber: 9207012C71493922751
[    4.630343] usb-storage 1-1.3:1.0: USB Mass Storage device detected
[    4.633963] scsi host0: usb-storage 1-1.3:1.0
[    5.692250] scsi 0:0:0:0: Direct-Access     Verbatim STORE N GO       2.00 PQ: 0 ANSI: 4
[    5.698284] sd 0:0:0:0: [sda] 15728640 512-byte logical blocks: (8.05 GB/7.50 GiB)
[    5.703743] sd 0:0:0:0: [sda] Write Protect is off
[    5.706422] sd 0:0:0:0: [sda] Mode Sense: 33 00 00 00
[    5.706609] sd 0:0:0:0: [sda] No Caching mode page found
[    5.709250] sd 0:0:0:0: [sda] Assuming drive cache: write through
[    5.715134]  sda: sda1 sda2
[    5.718313] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    5.764556] EXT4-fs (sda2): mounted filesystem with ordered data mode. Quota mode: none.
[    5.769768] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
[    5.773931] devtmpfs: mounted
[    5.782106] Freeing unused kernel image (initmem) memory: 1024K
[    5.790045] Run /sbin/init as init process
[    5.792510]   with arguments:
[    5.792518]     /sbin/init
[    5.792525]   with environment:
[    5.792532]     HOME=/
[    5.792539]     TERM=linux
[    6.446505] systemd[1]: System time before build time, advancing clock.
[    6.735434] NET: Registered PF_INET6 protocol family
[    6.739496] Segment Routing with IPv6
[    6.741999] In-situ OAM (IOAM) with IPv6
[    6.843965] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    6.864930] systemd[1]: Detected architecture arm.
[    6.902381] systemd[1]: Set hostname to <moode>.
[    7.830789] systemd[1]: Configuration file /etc/systemd/system/haveged.service.d/syscall.conf is marked executable. Please remove executable permission bits. Proceeding anyway.
[    8.256970] systemd[1]: Queued start job for default target Multi-User System.
[    8.324226] systemd[1]: Created slice system-getty.slice.
[    8.331539] systemd[1]: Created slice system-modprobe.slice.
[    8.338414] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    8.345001] systemd[1]: Created slice User and Session Slice.
[    8.351085] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    8.360001] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    8.369719] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    8.378826] systemd[1]: Reached target Local Encrypted Volumes.
[    8.385575] systemd[1]: Reached target Paths.
[    8.391965] systemd[1]: Reached target Slices.
[    8.397953] systemd[1]: Reached target Swap.
[    8.476517] systemd[1]: Listening on RPCbind Server Activation Socket.
[    8.483493] systemd[1]: Listening on Syslog Socket.
[    8.490148] systemd[1]: Listening on fsck to fsckd communication Socket.
[    8.496388] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    8.503495] systemd[1]: Listening on Journal Audit Socket.
[    8.510147] systemd[1]: Listening on Journal Socket (/dev/log).
[    8.516734] systemd[1]: Listening on Journal Socket.
[    8.534331] systemd[1]: Listening on udev Control Socket.
[    8.540849] systemd[1]: Listening on udev Kernel Socket.
[    8.547564] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    8.557377] systemd[1]: Mounting POSIX Message Queue File System...
[    8.568170] systemd[1]: Mounting RPC Pipe File System...
[    8.580421] systemd[1]: Mounting Kernel Debug File System...
[    8.592092] systemd[1]: Mounting Kernel Trace File System...
[    8.598932] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[    8.616267] systemd[1]: Starting Restore / save the current clock...
[    8.632266] systemd[1]: Starting Set the console keyboard layout...
[    8.644231] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    8.660045] systemd[1]: Starting Load Kernel Module configfs...
[    8.672683] systemd[1]: Starting Load Kernel Module drm...
[    8.689737] systemd[1]: Starting Load Kernel Module fuse...
[    8.734829] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    8.745347] systemd[1]: Starting File System Check on Root Device...
[    8.761933] systemd[1]: Starting Journal Service...
[    8.784041] systemd[1]: Starting Load Kernel Modules...
[    8.808587] fuse: init (API version 7.37)
[    8.822304] systemd[1]: Starting Coldplug All udev Devices...
[    8.864084] systemd[1]: Mounted POSIX Message Queue File System.
[    8.877835] systemd[1]: Mounted RPC Pipe File System.
[    8.887276] systemd[1]: Mounted Kernel Debug File System.
[    8.896333] systemd[1]: Mounted Kernel Trace File System.
[    8.908225] systemd[1]: Finished Restore / save the current clock.
[    8.928658] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    8.939876] systemd[1]: modprobe@configfs.service: Succeeded.
[    8.944595] systemd[1]: Finished Load Kernel Module configfs.
[    8.953455] systemd[1]: modprobe@fuse.service: Succeeded.
[    8.958249] systemd[1]: Finished Load Kernel Module fuse.
[    8.970317] i2c_dev: i2c /dev entries driver
[    9.081793] systemd[1]: Mounting FUSE Control File System...
[    9.096191] systemd[1]: Mounting Kernel Configuration File System...
[    9.109177] systemd[1]: Starting pNFS block layout mapping daemon...
[    9.126572] systemd[1]: Started File System Check Daemon to report status.
[    9.144178] systemd[1]: Started Journal Service.
[    9.490200] EXT4-fs (sda2): re-mounted. Quota mode: none.
[    9.577319] systemd-journald[133]: Received client request to flush runtime journal.
[   10.575867] systemd-journald[133]: File /var/log/journal/d31b1e61a11d4c87ac4a32c8784115cd/system.journal corrupted or uncleanly shut down, renaming and replacing.
[   14.058192] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[   14.098826] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[   14.098866] [vc_sm_connected_init]: start
[   14.100675] mc: Linux media interface: v0.10
[   14.101932] [vc_sm_connected_init]: installed successfully
[   14.213686] videodev: Linux video capture interface: v2.00
[   14.267732] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   14.301238] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[   14.308891] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   14.314332] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[   14.314796] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[   14.315172] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[   14.315462] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[   14.315493] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   14.315515] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   14.315532] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   14.315549] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   14.326078] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[   14.326699] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[   14.327150] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[   14.331115] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[   14.331163] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   14.331186] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   14.331204] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   14.331221] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   14.331870] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[   14.392395] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[   14.395124] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[   14.401460] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[   14.419317] bcm2835_audio bcm2835_audio: card created with 4 channels
[   14.421209] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[   14.421269] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[   14.426182] bcm2835_audio bcm2835_audio: card created with 4 channels
[   14.431679] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[   14.431743] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[   14.436551] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[   14.436632] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[   14.441727] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[   14.441789] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[   14.478925] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[   14.478983] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[   14.939824] usbcore: registered new interface driver uas
[   14.982857] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[   15.077252] sd 0:0:0:0: Attached scsi generic sg0 type 0
[   15.125859] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   15.178339] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[   15.369131] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
[   15.398185] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[   15.398857] usbcore: registered new interface driver brcmfmac
[   15.666210] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897
[   16.569663] hwmon hwmon1: Undervoltage detected!
[   18.516150] 8021q: 802.1Q VLAN Support v1.8
[   19.277911] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   19.803686] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   19.806675] smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
[   19.806992] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   20.878483] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   57.476618] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
[   78.854420] Key type cifs.spnego registered
[   78.854463] Key type cifs.idmap registered
[   78.856483] CIFS: Attempting to mount \\192.168.0.242\hoststorage
Reply
#10
(06-01-2023, 08:59 AM)swilkey Wrote:
(05-31-2023, 07:10 AM)the_bertrum Wrote: This is indeed an odd one.  I thunk it would be useful to post the startup log here which will tell us a lot about your configuration and setup even though it won't capture the fault.


Assuming you mean the output of dmesg.  But please see the later reply from me with more information.  Thanks!
Code:
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 6.1.21-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1642 SMP Mon Apr  3 17:20:52 BST 2023
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
[    0.000000] random: crng init done
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
[    0.000000] percpu: Embedded 17 pages/cpu s36884 r8192 d24556 u69632
[    0.000000] pcpu-alloc: s36884 r8192 d24556 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=e0770475-02 rootfstype=ext4 fsck.repair=yes rootwait
[    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] Memory: 877920K/970752K available (10240K kernel code, 1452K rwdata, 2900K rodata, 1024K init, 613K bss, 27296K reserved, 65536K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 34800 entries in 103 pages
[    0.000000] ftrace: allocated 102 pages with 4 groups
[    0.000000] trace event string verifier disabled
[    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] rcu: srcu_init: Setting srcu_struct sizes based on contention.
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000001] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000019] Switching to timer-based delay loop, resolution 52ns
[    0.000382] Console: colour dummy device 80x30
[    0.001004] printk: console [tty1] enabled
[    0.001067] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.001113] pid_max: default: 32768 minimum: 301
[    0.001272] LSM: Security Framework initializing
[    0.001503] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.001547] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
[    0.002731] cgroup: Disabling memory control group subsystem
[    0.002988] CPU: Testing write buffer coherency: ok
[    0.003436] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.004297] cblist_init_generic: Setting adjustable number of callback queues.
[    0.004336] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.004463] cblist_init_generic: Setting shift to 2 and lim to 1.
[    0.004615] Setting up static identity map for 0x100000 - 0x10003c
[    0.004771] rcu: Hierarchical SRCU implementation.
[    0.004796] rcu:     Max phase no-delay instances is 1000.
[    0.005514] smp: Bringing up secondary CPUs ...
[    0.006444] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.007393] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
[    0.008248] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
[    0.008349] smp: Brought up 1 node, 4 CPUs
[    0.008431] SMP: Total of 4 processors activated (153.60 BogoMIPS).
[    0.008458] CPU: All CPU(s) started in HYP mode.
[    0.008477] CPU: Virtualization extensions available.
[    0.009238] devtmpfs: initialized
[    0.024015] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    0.024258] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.024312] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.027013] pinctrl core: initialized pinctrl subsystem
[    0.028226] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.031405] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    0.036439] audit: initializing netlink subsys (disabled)
[    0.036703] audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1
[    0.037306] thermal_sys: Registered thermal governor 'step_wise'
[    0.038207] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    0.038261] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    0.038515] Serial: AMBA PL011 UART driver
[    0.043341] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    0.060035] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-03-17T10:52:42, variant start
[    0.070043] raspberrypi-firmware soc:firmware: Firmware hash is 82f3750a65fadae9a38077e3c2e217ad158c8d54
[    0.112480] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
[    0.118353] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
[    0.120017] SCSI subsystem initialized
[    0.120272] usbcore: registered new interface driver usbfs
[    0.120352] usbcore: registered new interface driver hub
[    0.120420] usbcore: registered new device driver usb
[    0.120637] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.120822] usb_phy_generic phy: dummy supplies not allowed for exclusive requests
[    0.121105] pps_core: LinuxPPS API ver. 1 registered
[    0.121130] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.121180] PTP clock support registered
[    1.999559] clocksource: Switched to clocksource arch_sys_counter
[    2.097031] VFS: Disk quotas dquot_6.6.0
[    2.097157] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.097328] FS-Cache: Loaded
[    2.097637] CacheFiles: Loaded
[    2.106335] NET: Registered PF_INET protocol family
[    2.106638] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
[    2.109141] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear)
[    2.109201] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
[    2.109304] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
[    2.109443] TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear)
[    2.109831] TCP: Hash tables configured (established 8192 bind 8192)
[    2.109993] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
[    2.110065] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
[    2.110305] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    2.110857] RPC: Registered named UNIX socket transport module.
[    2.110885] RPC: Registered udp transport module.
[    2.110908] RPC: Registered tcp transport module.
[    2.110928] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    2.112486] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    2.115578] Initialise system trusted keyrings
[    2.115833] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    2.125051] zbud: loaded
[    2.127417] NFS: Registering the id_resolver key type
[    2.127469] Key type id_resolver registered
[    2.127492] Key type id_legacy registered
[    2.127645] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    2.127674] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    2.128962] Key type asymmetric registered
[    2.128990] Asymmetric key parser 'x509' registered
[    2.129177] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[    2.129212] io scheduler mq-deadline registered
[    2.129239] io scheduler kyber registered
[    2.134018] bcm2708_fb soc:fb: FB found 1 display(s)
[    2.144677] Console: switching to colour frame buffer device 80x30
[    2.151065] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
[    2.159668] bcm2835-rng 3f104000.rng: hwrng registered
[    2.162258] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    2.167805] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    2.183932] brd: module loaded
[    2.193657] loop: module loaded
[    2.196571] Loading iSCSI transport class v2.0-870.
[    2.200384] usbcore: registered new interface driver lan78xx
[    2.202807] usbcore: registered new interface driver smsc95xx
[    2.205128] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    2.935133] Core Release: 2.80a
[    2.937438] Setting default values for core params
[    2.939769] Finished setting default values for core params
[    3.142289] Using Buffer DMA mode
[    3.144535] Periodic Transfer Interrupt Enhancement - disabled
[    3.146842] Multiprocessor Interrupt Enhancement - disabled
[    3.149122] OTG VER PARAM: 0, OTG VER FLAG: 0
[    3.151402] Dedicated Tx FIFOs mode

[    3.154219] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7504000 dma = 0xf7504000 len=9024
[    3.160927] FIQ FSM acceleration enabled for :
              Non-periodic Split Transactions
              Periodic Split Transactions
              High-Speed Isochronous Endpoints
              Interrupt/Control Split Transaction hack enabled
[    3.171540] dwc_otg: Microframe scheduler enabled

[    3.171859] WARN::hcd_init_fiq:457: FIQ on core 1

[    3.175716] WARN::hcd_init_fiq:458: FIQ ASM at 807c4360 length 36

[    3.179605] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
[    3.183577] dwc_otg 3f980000.usb: DWC OTG Controller
[    3.185734] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    3.189889] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
[    3.192076] Init: Port Power? op_state=1
[    3.194183] Init: Power Port (0)
[    3.196462] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01
[    3.200697] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.205070] usb usb1: Product: DWC OTG Controller
[    3.207347] usb usb1: Manufacturer: Linux 6.1.21-v7+ dwc_otg_hcd
[    3.209642] usb usb1: SerialNumber: 3f980000.usb
[    3.212488] hub 1-0:1.0: USB hub found
[    3.214695] hub 1-0:1.0: 1 port detected
[    3.217451] dwc_otg: FIQ enabled
[    3.217462] dwc_otg: NAK holdoff enabled
[    3.217471] dwc_otg: FIQ split-transaction FSM enabled
[    3.217487] Module dwc_common_port init
[    3.217771] usbcore: registered new interface driver usb-storage
[    3.220276] mousedev: PS/2 mouse device common for all mice
[    3.224913] sdhci: Secure Digital Host Controller Interface driver
[    3.227207] sdhci: Copyright(c) Pierre Ossman
[    3.229831] sdhci-pltfm: SDHCI platform and OF driver helper
[    3.234040] ledtrig-cpu: registered to indicate activity on CPUs
[    3.236711] hid: raw HID events driver (C) Jiri Kosina
[    3.239176] usbcore: registered new interface driver usbhid
[    3.241510] usbhid: USB HID core driver
[    3.247964] Initializing XFRM netlink socket
[    3.250320] NET: Registered PF_PACKET protocol family
[    3.252695] Key type dns_resolver registered
[    3.255628] Registering SWP/SWPB emulation handler
[    3.258448] registered taskstats version 1
[    3.260697] Loading compiled-in X.509 certificates
[    3.263658] Key type .fscrypt registered
[    3.265784] Key type fscrypt-provisioning registered
[    3.279105] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    3.281429] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
[    3.287548] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    3.290019] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    3.295570] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    3.297867] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
[    3.328017] sdhost: log_buf @ 0084c925 (f7507000)
[    3.351793] Indeed it is in host mode hprt0 = 00021501
[    3.387128] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    3.391647] of_cfs_init
[    3.393947] of_cfs_init: OK
[    3.397502] Waiting for root device PARTUUID=e0770475-02...
[    3.523886] mmc1: new high speed SDIO card at address 0001
[    3.559581] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    3.561878] Indeed it is in host mode hprt0 = 00001101
[    3.799803] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
[    3.804268] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    3.807200] hub 1-1:1.0: USB hub found
[    3.809675] hub 1-1:1.0: 5 ports detected
[    4.129569] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    4.259806] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
[    4.264614] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.272059] smsc95xx v2.0.0
[    4.361329] SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199)
[    4.366906] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:92:70:d5
[    4.469572] usb 1-1.3: new high-speed USB device number 4 using dwc_otg
[    4.611185] usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0302, bcdDevice= 2.00
[    4.616284] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    4.621610] usb 1-1.3: Product: STORE N GO
[    4.624323] usb 1-1.3: Manufacturer: Verbatim
[    4.626996] usb 1-1.3: SerialNumber: 9207012C71493922751
[    4.630343] usb-storage 1-1.3:1.0: USB Mass Storage device detected
[    4.633963] scsi host0: usb-storage 1-1.3:1.0
[    5.692250] scsi 0:0:0:0: Direct-Access     Verbatim STORE N GO       2.00 PQ: 0 ANSI: 4
[    5.698284] sd 0:0:0:0: [sda] 15728640 512-byte logical blocks: (8.05 GB/7.50 GiB)
[    5.703743] sd 0:0:0:0: [sda] Write Protect is off
[    5.706422] sd 0:0:0:0: [sda] Mode Sense: 33 00 00 00
[    5.706609] sd 0:0:0:0: [sda] No Caching mode page found
[    5.709250] sd 0:0:0:0: [sda] Assuming drive cache: write through
[    5.715134]  sda: sda1 sda2
[    5.718313] sd 0:0:0:0: [sda] Attached SCSI removable disk
[    5.764556] EXT4-fs (sda2): mounted filesystem with ordered data mode. Quota mode: none.
[    5.769768] VFS: Mounted root (ext4 filesystem) readonly on device 8:2.
[    5.773931] devtmpfs: mounted
[    5.782106] Freeing unused kernel image (initmem) memory: 1024K
[    5.790045] Run /sbin/init as init process
[    5.792510]   with arguments:
[    5.792518]     /sbin/init
[    5.792525]   with environment:
[    5.792532]     HOME=/
[    5.792539]     TERM=linux
[    6.446505] systemd[1]: System time before build time, advancing clock.
[    6.735434] NET: Registered PF_INET6 protocol family
[    6.739496] Segment Routing with IPv6
[    6.741999] In-situ OAM (IOAM) with IPv6
[    6.843965] systemd[1]: systemd 247.3-7+rpi1+deb11u1 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +ZSTD +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=unified)
[    6.864930] systemd[1]: Detected architecture arm.
[    6.902381] systemd[1]: Set hostname to <moode>.
[    7.830789] systemd[1]: Configuration file /etc/systemd/system/haveged.service.d/syscall.conf is marked executable. Please remove executable permission bits. Proceeding anyway.
[    8.256970] systemd[1]: Queued start job for default target Multi-User System.
[    8.324226] systemd[1]: Created slice system-getty.slice.
[    8.331539] systemd[1]: Created slice system-modprobe.slice.
[    8.338414] systemd[1]: Created slice system-systemd\x2dfsck.slice.
[    8.345001] systemd[1]: Created slice User and Session Slice.
[    8.351085] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    8.360001] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    8.369719] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[    8.378826] systemd[1]: Reached target Local Encrypted Volumes.
[    8.385575] systemd[1]: Reached target Paths.
[    8.391965] systemd[1]: Reached target Slices.
[    8.397953] systemd[1]: Reached target Swap.
[    8.476517] systemd[1]: Listening on RPCbind Server Activation Socket.
[    8.483493] systemd[1]: Listening on Syslog Socket.
[    8.490148] systemd[1]: Listening on fsck to fsckd communication Socket.
[    8.496388] systemd[1]: Listening on initctl Compatibility Named Pipe.
[    8.503495] systemd[1]: Listening on Journal Audit Socket.
[    8.510147] systemd[1]: Listening on Journal Socket (/dev/log).
[    8.516734] systemd[1]: Listening on Journal Socket.
[    8.534331] systemd[1]: Listening on udev Control Socket.
[    8.540849] systemd[1]: Listening on udev Kernel Socket.
[    8.547564] systemd[1]: Condition check resulted in Huge Pages File System being skipped.
[    8.557377] systemd[1]: Mounting POSIX Message Queue File System...
[    8.568170] systemd[1]: Mounting RPC Pipe File System...
[    8.580421] systemd[1]: Mounting Kernel Debug File System...
[    8.592092] systemd[1]: Mounting Kernel Trace File System...
[    8.598932] systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped.
[    8.616267] systemd[1]: Starting Restore / save the current clock...
[    8.632266] systemd[1]: Starting Set the console keyboard layout...
[    8.644231] systemd[1]: Starting Create list of static device nodes for the current kernel...
[    8.660045] systemd[1]: Starting Load Kernel Module configfs...
[    8.672683] systemd[1]: Starting Load Kernel Module drm...
[    8.689737] systemd[1]: Starting Load Kernel Module fuse...
[    8.734829] systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
[    8.745347] systemd[1]: Starting File System Check on Root Device...
[    8.761933] systemd[1]: Starting Journal Service...
[    8.784041] systemd[1]: Starting Load Kernel Modules...
[    8.808587] fuse: init (API version 7.37)
[    8.822304] systemd[1]: Starting Coldplug All udev Devices...
[    8.864084] systemd[1]: Mounted POSIX Message Queue File System.
[    8.877835] systemd[1]: Mounted RPC Pipe File System.
[    8.887276] systemd[1]: Mounted Kernel Debug File System.
[    8.896333] systemd[1]: Mounted Kernel Trace File System.
[    8.908225] systemd[1]: Finished Restore / save the current clock.
[    8.928658] systemd[1]: Finished Create list of static device nodes for the current kernel.
[    8.939876] systemd[1]: modprobe@configfs.service: Succeeded.
[    8.944595] systemd[1]: Finished Load Kernel Module configfs.
[    8.953455] systemd[1]: modprobe@fuse.service: Succeeded.
[    8.958249] systemd[1]: Finished Load Kernel Module fuse.
[    8.970317] i2c_dev: i2c /dev entries driver
[    9.081793] systemd[1]: Mounting FUSE Control File System...
[    9.096191] systemd[1]: Mounting Kernel Configuration File System...
[    9.109177] systemd[1]: Starting pNFS block layout mapping daemon...
[    9.126572] systemd[1]: Started File System Check Daemon to report status.
[    9.144178] systemd[1]: Started Journal Service.
[    9.490200] EXT4-fs (sda2): re-mounted. Quota mode: none.
[    9.577319] systemd-journald[133]: Received client request to flush runtime journal.
[   10.575867] systemd-journald[133]: File /var/log/journal/d31b1e61a11d4c87ac4a32c8784115cd/system.journal corrupted or uncleanly shut down, renaming and replacing.
[   14.058192] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[   14.098826] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[   14.098866] [vc_sm_connected_init]: start
[   14.100675] mc: Linux media interface: v0.10
[   14.101932] [vc_sm_connected_init]: installed successfully
[   14.213686] videodev: Linux video capture interface: v2.00
[   14.267732] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   14.301238] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[   14.308891] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[   14.314332] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[   14.314796] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[   14.315172] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[   14.315462] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[   14.315493] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   14.315515] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   14.315532] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   14.315549] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   14.326078] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[   14.326699] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[   14.327150] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[   14.331115] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[   14.331163] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[   14.331186] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[   14.331204] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[   14.331221] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[   14.331870] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[   14.392395] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[   14.395124] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[   14.401460] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[   14.419317] bcm2835_audio bcm2835_audio: card created with 4 channels
[   14.421209] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[   14.421269] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[   14.426182] bcm2835_audio bcm2835_audio: card created with 4 channels
[   14.431679] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[   14.431743] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[   14.436551] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[   14.436632] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[   14.441727] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[   14.441789] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[   14.478925] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[   14.478983] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[   14.939824] usbcore: registered new interface driver uas
[   14.982857] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[   15.077252] sd 0:0:0:0: Attached scsi generic sg0 type 0
[   15.125859] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   15.178339] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
[   15.369131] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
[   15.398185] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[   15.398857] usbcore: registered new interface driver brcmfmac
[   15.666210] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897
[   16.569663] hwmon hwmon1: Undervoltage detected!
[   18.516150] 8021q: 802.1Q VLAN Support v1.8
[   19.277911] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[   19.803686] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
[   19.806675] smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
[   19.806992] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   20.878483] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[   57.476618] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
[   78.854420] Key type cifs.spnego registered
[   78.854463] Key type cifs.idmap registered
[   78.856483] CIFS: Attempting to mount \\192.168.0.242\hoststorage

There is an undervoltage detected at 16.569663... it could be a hint that some other could follow and render your system unstable...
Reply


Forum Jump: