samba - Nov 2022 - 4.17.1 rpcd_winreg, rpcd_spoolss and printing

Dear All,

After upgrading my print server to 4.17.1 and having to remove and re-add it to
the domain, the new rpcd_winreg process is spawning itself multiple times,
consuming all my CPU resources and generating huge log files telling me the
following multiple times a second:
[2022/10/25 09:34:58,  2] ../../source3/param/loadparm.c:2896(lp_do_section)
  Processing section "[Printer1]"
[2022/10/25 09:34:58,  2] ../../source3/param/loadparm.c:2896(lp_do_section)
  Processing section "[Printer2]"
[2022/10/25 09:34:58,  2] ../../source3/param/loadparm.c:2896(lp_do_section)
  Processing section "[Printer3]"
[2022/10/25 09:34:58,  3] ../../source3/param/loadparm.c:1680(lp_add_ipc)
  adding IPC service
[2022/10/25 09:34:58,  3]
../../source3/rpc_server/rpc_server.c:257(ncacn_terminate_connection)
  ncacn_terminate_connection: Terminating connection - 'dcesrv:
NT_STATUS_CONNECTION_DISCONNECTED'
[2022/10/25 09:34:58,  3]
../../source3/rpc_server/rpc_server.c:257(ncacn_terminate_connection)
  ncacn_terminate_connection: Terminating connection - 'dcesrv:
NT_STATUS_CONNECTION_DISCONNECTED'

I also notice that my log.rpcd_spoolss file is getting bigger every second with
entries like this:
[2022/10/25 09:36:06,  2]
../../source3/rpc_client/cli_winreg_spoolss.c:2262(winreg_get_printer_dataex)
  winreg_get_printer_dataex: Could not query value V4_Driver_Hardware_IDs:
WERR_FILE_NOT_FOUND
[2022/10/25 09:36:07,  2]
../../source3/rpc_client/cli_winreg_spoolss.c:2262(winreg_get_printer_dataex)
  winreg_get_printer_dataex: Could not query value MinimumSupportedClientBuild:
WERR_FILE_NOT_FOUND
[2022/10/25 09:36:07,  2]
../../source3/rpc_client/cli_winreg_spoolss.c:2262(winreg_get_printer_dataex)
  winreg_get_printer_dataex: Could not query value DriverPolicy:
WERR_FILE_NOT_FOUND
[2022/10/25 09:36:07,  3]
../../source3/rpc_server/spoolss/srv_spoolss_nt.c:5875(_spoolss_GetPrinterDriver2)
  _spoolss_GetPrinterDriver2: v4 driver requested, downgrading to v3

Needless to say printing doesn't work anymore..
How do I find out what file isn't found? And would that stop it from trying
to load the printer list every millisecond?

Many thanks,

Tim

Dear All,

I've made things a tiny bit better by reducing the log level to 1, so that
the rpcd_winreg and rpcd_spoolss files don't grow by several Gb every day,
and restarting sernet-samba-ad every couple of hours. People can mostly print
after the restart, but quite soon the rpcd_winreg process starts to consume the
server and it starts grinding to a halt.

I'm still getting lots of rpcd_winreg processes hanging around consuming
resources. I was hoping it was something todo with this:
https://bugzilla.samba.org/show_bug.cgi?id=15201

However, I'm running the latest sernet package 4.17.2.

I'm a bit stuck as to know what to do next...

I've tried building a new print server from scratch on Rocky 9, however that
started to exhibit the same rpcd_winreg issue once I'd added a few printers
to it, so I stopped as it was going to go the same way.

Any pointers from anyone?.......

Here's my 'top' output after I've restarted sernet-samba-ad,
FWIW:
top - 15:59:58 up 15 days,  6:59,  1 user,  load average: 16.92, 14.22, 11.98
Tasks: 573 total,  28 running, 545 sleeping,   0 stopped,   0 zombie
%Cpu(s): 78.8 us, 12.4 sy,  0.0 ni,  7.2 id,  0.3 wa,  0.0 hi,  1.3 si,  0.0 st
KiB Mem : 32745508 total,  7403460 free, 11249884 used, 14092164 buff/cache
KiB Swap: 33390588 total, 32957692 free,   432896 used. 19759024 avail Mem 

  PID USER      PR  NI    VIRT    RES    SHR S  %CPU %MEM     TIME+ COMMAND
30204 root      20   0  799224  91776  18452 S  45.4  0.3  12:29.58 rpcd_winreg
30206 root      20   0  791060  81660  18456 R  44.4  0.2   9:39.31 rpcd_winreg
30205 root      20   0  794112  86668  18456 R  43.1  0.3  11:07.90 rpcd_winreg
30207 root      20   0  783456  76004  18452 R  40.1  0.2   7:56.12 rpcd_winreg
30208 root      20   0  777612  70172  18456 R  37.8  0.2   6:22.22 rpcd_winreg
29943 root      20   0  723228  41184  19168 R  33.9  0.1   9:02.29 winbindd
30209 root      20   0  771808  64364  18456 R  31.2  0.2   4:58.71 rpcd_winreg
30211 root      20   0  765756  58312  18452 R  28.6  0.2   3:46.60 rpcd_winreg
30215 root      20   0  760232  52676  18452 R  26.0  0.2   2:50.37 rpcd_winreg
30216 root      20   0  756712  47304  18452 R  22.4  0.1   2:04.59 rpcd_winreg
17746 root      20   0 2701088 183588   3960 S  19.1  0.6 532:48.41 glusterfsd
30218 root      20   0  751600  42180  18452 R  17.1  0.1   1:27.60 rpcd_winreg
30219 root      20   0  750088  40632  18452 R  16.4  0.1   0:59.86 rpcd_winreg
30220 root      20   0  748124  40624  18452 S  14.1  0.1   0:39.95 rpcd_winreg
 1723 DOMAIN+  20   0  789184  63580  41184 R  13.2  0.2   0:07.39 smbd
 2710 DOMAIN+  20   0  789200  63680  41284 R  12.5  0.2   0:05.15 smbd
30221 root      20   0  750088  40624  18452 R   9.9  0.1   0:26.12 rpcd_winreg
 2801 DOMAIN+  20   0  789200  63728  41332 S   8.9  0.2   0:04.46 smbd
29965 root      20   0  731556  29220   7152 S   8.6  0.1   2:04.22 winbindd
 3236 DOMAIN+  20   0  789204  63616  41220 S   7.6  0.2   0:02.48 smbd
30063 root      20   0  756736  51424  29008 R   7.6  0.2   1:02.51 rpcd_spoolss
30290 DOMAIN+  20   0  788984  62012  39604 S   7.6  0.2   0:05.64 smbd
30329 DOMAIN+  20   0  797208  63756  41340 S   6.9  0.2   0:12.80 smbd
30222 root      20   0  748124  40624  18452 S   6.2  0.1   0:17.10 rpcd_winreg
32142 DOMAIN+  20   0  797220  63820  41404 S   6.2  0.2   0:10.41 smbd
29967 root      20   0  725368  26928   4896 S   5.9  0.1   1:19.05 winbindd
  574 DOMAIN+  20   0  789184  63972  41564 S   5.6  0.2   0:09.85 smbd
 2626 root      20   0  787484  26836   3368 S   5.6  0.1 111:56.21 glusterfs
29970 root      20   0  701736  35132  13196 S   5.3  0.1   1:12.44
samba-dcerpcd

Many thanks,

Tim