On Fri, 18 Oct 2024 13:38:00 +0200
Gilles via samba <samba at lists.samba.org> wrote:
> I suppose 12:08:54 is when I double-clicked on the Samba server.
>
> Notice the seconds until "housekeeping":
> ?
> [2024/10/18 12:08:54.788927,? 5]
> ../../source3/rpc_server/srv_pipe_hnd.c:138(np_write_send)
> ? np_write_send: len: 160
> [2024/10/18 12:08:55.055207,? 5]
> ../../source3/smbd/uid.c:327(change_to_user_impersonate)
> ? change_to_user_impersonate: Skipping user change - already user
> [2024/10/18 12:08:55.056418,? 5]
> ../../source3/smbd/uid.c:294(print_impersonation_info)
> ? print_impersonation_info: Impersonated user: uid=(33,33),
> gid=(0,33), cwd=[/tmp]
> [2024/10/18 12:08:57.743997,? 5]
> ../../source3/smbd/uid.c:327(change_to_user_impersonate)
> ? change_to_user_impersonate: Skipping user change - already user
> [2024/10/18 12:08:57.745187,? 5]
> ../../source3/smbd/uid.c:294(print_impersonation_info)
> ? print_impersonation_info: Impersonated user: uid=(33,33),
> gid=(0,33), cwd=[/tmp]
> [2024/10/18 12:08:57.746080,? 5]
> ../../source3/rpc_server/srv_pipe_hnd.c:138(np_write_send)
> ? np_write_send: len: 96
> [2024/10/18 12:08:58.029081,? 5]
> ../../source3/smbd/uid.c:327(change_to_user_impersonate)
> ? change_to_user_impersonate: Skipping user change - already user
> [2024/10/18 12:08:58.030182,? 5]
> ../../source3/smbd/uid.c:294(print_impersonation_info)
> ? print_impersonation_info: Impersonated user: uid=(33,33),
> gid=(0,33), cwd=[/tmp]
> [2024/10/18 12:08:58.031075,? 5]
> ../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock)
> ? dbwrap_lock_order_lock: check lock order 1 for
> /run/samba/smbXsrv_open_global.tdb
> [2024/10/18 12:08:58.032027,? 5]
> ../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock)
> ? dbwrap_lock_order_unlock: release lock order 1 for
> /run/samba/smbXsrv_open_global.tdb
> [2024/10/18 12:08:58.033117,? 5]
> ../../source3/smbd/files.c:1800(file_free) file_free: freed files
> structure 1017083592 (1 used) [2024/10/18 12:09:03.704039,? 5]
> ../../source3/smbd/smb2_process.c:1582(housekeeping_fn)
> ? housekeeping
> ?
>
OK, if you examine the times from your log fragment, there is approx 25
seconds difference between the start and end time, but I think this
might be because of the raised log level. I say this because if you
look at the log fragment you posted earlier (before you raised the log
level) it starts at:
[2024/10/17 19:02:13.434025,? 2]
and ends at:
[2024/10/17 19:02:13.489202,? 2]
Very little difference.
I am not sure where the time is being wasted, but I do not think it is
Samba wasting it.
Rowland