On 18/10/2024 14:47, Gilles via samba wrote:> On 18/10/2024 14:27, Rowland Penny via samba wrote:
>> I am not sure where the time is being wasted, but I do not think it is
>> Samba wasting it.
> Thanks for the info. If it's neither wsdd nor Samba, and Smb1 is
> disabled on Windows? I don't know where else to look. I guess I'll
> just give up unless someone has another idea.
When I double-click on the Samba server, the log files shows four steps:
======================== Step 1
[2024/10/18 14:47:54.509691,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 14:47:54.509976,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:47:54.510124,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:47:54.510343,? 5]
../../source3/smbd/uid.c:494(smbd_change_to_root_user)
? change_to_root_user: now uid=(0,0) gid=(0,0)
[2024/10/18 14:47:54.510612,? 5]
../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock)
? dbwrap_lock_order_lock: check lock order 1 for
/run/samba/smbXsrv_tcon_global.tdb
[2024/10/18 14:47:54.510904,? 5]
../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock)
? dbwrap_lock_order_unlock: release lock order 1 for
/run/samba/smbXsrv_tcon_global.tdb
[2024/10/18 14:47:54.511185,? 3] ../../lib/util/access.c:372(allow_access)
? Allowed connection from 192.168.0.17 (192.168.0.17)
[2024/10/18 14:47:54.511579,? 3]
../../source3/smbd/smb2_service.c:610(make_connection_snum)
? make_connection_snum: Connect path is '/tmp' for service [IPC$]
[2024/10/18 14:47:54.511842,? 3]
../../source3/smbd/vfs.c:115(vfs_init_default)
? Initialising default vfs hooks
[2024/10/18 14:47:54.512054,? 3]
../../source3/smbd/vfs.c:141(vfs_init_custom)
? Initialising custom vfs hooks from [/[Default VFS]/]
? Successfully loaded vfs module [/[Default VFS]/] with the new modules
system
[2024/10/18 14:47:54.512626,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 14:47:54.512788,? 5]
../../libcli/security/security_token.c:51(security_token_debug)
? Security token SIDs (7):
??? SID[? 0]: S-1-5-21-2444798247-1888365312-2057922249-501
??? SID[? 1]: S-1-5-21-2444798247-1888365312-2057922249-514
??? SID[? 2]: S-1-22-2-33
??? SID[? 3]: S-1-1-0
??? SID[? 4]: S-1-5-2
??? SID[? 5]: S-1-5-32-546
??? SID[? 6]: S-1-22-1-33
?? Privileges (0x?????????????? 0):
?? Rights (0x?????????????? 0):
[2024/10/18 14:47:54.513599,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 33
? Primary group is 33 and contains 1 supplementary groups
? Group[? 0]: 33
[2024/10/18 14:47:54.513994,? 5]
../../source3/smbd/uid.c:294(print_impersonation_info)
? print_impersonation_info: Impersonated user: uid=(33,33), gid=(0,33),
cwd=[/mnt/sdcard]
[2024/10/18 14:47:54.514166,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 14:47:54.514302,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:47:54.514425,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:47:54.514642,? 5]
../../source3/smbd/uid.c:494(smbd_change_to_root_user)
? change_to_root_user: now uid=(0,0) gid=(0,0)
[2024/10/18 14:47:54.514850,? 3]
../../source3/smbd/smb2_service.c:840(make_connection_snum)
? mini-lenovo (ipv4:192.168.0.17:57960) connect to service IPC$
initially as user www-data (uid=33, gid=33) (pid 7345)
[2024/10/18 14:47:54.519475,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 14:47:54.520649,? 5]
../../libcli/security/security_token.c:51(security_token_debug)
? Security token SIDs (7):
??? SID[? 0]: S-1-5-21-2444798247-1888365312-2057922249-501
??? SID[? 1]: S-1-5-21-2444798247-1888365312-2057922249-514
??? SID[? 2]: S-1-22-2-33
??? SID[? 3]: S-1-1-0
??? SID[? 4]: S-1-5-2
??? SID[? 5]: S-1-5-32-546
??? SID[? 6]: S-1-22-1-33
?? Privileges (0x?????????????? 0):
?? Rights (0x?????????????? 0):
[2024/10/18 14:47:54.522323,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 33
? Primary group is 33 and contains 1 supplementary groups
? Group[? 0]: 33
[2024/10/18 14:47:54.522650,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 14:47:54.522895,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 14:47:54.523111,? 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 14:47:54.523264,? 5]
../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock)
? dbwrap_lock_order_lock: check lock order 1 for
/run/samba/smbXsrv_tcon_global.tdb
[2024/10/18 14:47:54.523503,? 5]
../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock)
? dbwrap_lock_order_unlock: release lock order 1 for
/run/samba/smbXsrv_tcon_global.tdb
[2024/10/18 14:47:54.523707,? 5] ../../source3/smbd/files.c:76(fsp_new)
? fsp_new: allocated files structure (2 used)
[2024/10/18 14:47:54.523885,? 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 14:47:54.527310,? 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 14:47:54.528942,? 5] ../../source3/smbd/files.c:178(file_new)
? file_new: new file fnum 749407915
[2024/10/18 14:47:54.529733,? 4]
../../source3/smbd/sec_ctx.c:206(push_sec_ctx)
? push_sec_ctx(33, 33) : sec_ctx_stack_ndx = 1
[2024/10/18 14:47:54.529942,? 4] ../../source3/smbd/uid.c:566(push_conn_ctx)
? push_conn_ctx(2675924220) : conn_ctx_stack_ndx = 0
[2024/10/18 14:47:54.530087,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2024/10/18 14:47:54.530222,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:47:54.530345,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:47:54.530660,? 4]
../../source3/smbd/sec_ctx.c:443(pop_sec_ctx)
? pop_sec_ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 14:47:54.531069,? 4]
../../source3/smbd/sec_ctx.c:206(push_sec_ctx)
? push_sec_ctx(33, 33) : sec_ctx_stack_ndx = 1
[2024/10/18 14:47:54.531244,? 4] ../../source3/smbd/uid.c:566(push_conn_ctx)
? push_conn_ctx(2675924220) : conn_ctx_stack_ndx = 0
[2024/10/18 14:47:54.531384,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2024/10/18 14:47:54.531517,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:47:54.531644,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:47:54.535349,? 4]
../../source3/smbd/sec_ctx.c:443(pop_sec_ctx)
? pop_sec_ctx (33, 33) - sec_ctx_stack_ndx = 0
INFO: Current debug levels:
? all: 6
? tdb: 6
? printdrivers: 6
? lanman: 6
? smb: 6
? rpc_parse: 6
? rpc_srv: 6
? rpc_cli: 6
? passdb: 6
? sam: 6
? auth: 6
? winbind: 6
? vfs: 6
? idmap: 6
? quota: 6
? acls: 6
? locking: 6
? msdfs: 6
? dmapi: 6
? registry: 6
? scavenger: 6
? dns: 6
? ldb: 6
? tevent: 6
? auth_audit: 6
? auth_json_audit: 6
? kerberos: 6
? drs_repl: 6
? smb2: 6
? smb2_credits: 6
? dsdb_audit: 6
? dsdb_json_audit: 6
? dsdb_password_audit: 6
? dsdb_password_json_audit: 6
? dsdb_transaction_audit: 6
? dsdb_transaction_json_audit: 6
? dsdb_group_audit: 6
? dsdb_group_json_audit: 6
======================== Step2
[2024/10/18 14:48:03.508401,? 4]
../../source3/smbd/sec_ctx.c:206(push_sec_ctx)
? push_sec_ctx(33, 33) : sec_ctx_stack_ndx = 1
[2024/10/18 14:48:03.509504,? 4] ../../source3/smbd/uid.c:566(push_conn_ctx)
? push_conn_ctx(2675924220) : conn_ctx_stack_ndx = 0
[2024/10/18 14:48:03.510361,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2024/10/18 14:48:03.511321,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:48:03.512229,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:48:03.513526,? 4]
../../source3/smbd/sec_ctx.c:443(pop_sec_ctx)
? pop_sec_ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 14:48:03.523644,? 5]
../../source3/smbd/uid.c:327(change_to_user_impersonate)
? change_to_user_impersonate: Skipping user change - already user
[2024/10/18 14:48:03.524698,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 14:48:03.525637,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 14:48:03.526649,? 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 14:48:03.527516,? 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 14:48:03.528698,? 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 14:48:03.530646,? 5]
../../source3/smbd/uid.c:327(change_to_user_impersonate)
? change_to_user_impersonate: Skipping user change - already user
[2024/10/18 14:48:03.531694,? 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 14:48:03.532708,? 5]
../../source3/rpc_server/srv_pipe_hnd.c:138(np_write_send)
? np_write_send: len: 160
[2024/10/18 14:48:03.536128,? 5]
../../source3/smbd/uid.c:327(change_to_user_impersonate)
? change_to_user_impersonate: Skipping user change - already user
[2024/10/18 14:48:03.537226,? 5]
../../source3/smbd/uid.c:294(print_impersonation_info)
? print_impersonation_info: Impersonated user: uid=(33,33), gid=(0,33),
cwd=[/tmp]
======================== Step 3
[2024/10/18 14:48:06.233193,? 5]
../../source3/smbd/uid.c:327(change_to_user_impersonate)
? change_to_user_impersonate: Skipping user change - already user
[2024/10/18 14:48:06.234357,? 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 14:48:06.235246,? 5]
../../source3/rpc_server/srv_pipe_hnd.c:138(np_write_send)
? np_write_send: len: 96
[2024/10/18 14:48:06.256512,? 5]
../../source3/smbd/uid.c:327(change_to_user_impersonate)
? change_to_user_impersonate: Skipping user change - already user
[2024/10/18 14:48:06.257607,? 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 14:48:06.258619,? 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 14:48:06.259539,? 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 14:48:06.260743,? 5] ../../source3/smbd/files.c:1800(file_free)
? file_free: freed files structure 749407915 (1 used)
[2024/10/18 14:48:13.032041,? 5]
../../source3/smbd/smb2_process.c:1582(housekeeping_fn)
? housekeeping
[2024/10/18 14:48:13.033055,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 14:48:13.033883,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:48:13.034833,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:48:13.036642,? 5]
../../source3/smbd/uid.c:494(smbd_change_to_root_user)
? change_to_root_user: now uid=(0,0) gid=(0,0)
======================== Step 4
[2024/10/18 14:48:16.997976,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 14:48:16.999006,? 5]
../../libcli/security/security_token.c:51(security_token_debug)
? Security token SIDs (7):
??? SID[? 0]: S-1-5-21-2444798247-1888365312-2057922249-501
??? SID[? 1]: S-1-5-21-2444798247-1888365312-2057922249-514
??? SID[? 2]: S-1-22-2-33
??? SID[? 3]: S-1-1-0
??? SID[? 4]: S-1-5-2
??? SID[? 5]: S-1-5-32-546
??? SID[? 6]: S-1-22-1-33
?? Privileges (0x?????????????? 0):
?? Rights (0x?????????????? 0):
[2024/10/18 14:48:17.002704,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 33
? Primary group is 33 and contains 1 supplementary groups
? Group[? 0]: 33
[2024/10/18 14:48:17.003050,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 14:48:17.003299,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 14:48:17.003518,? 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 14:48:17.003669,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 14:48:17.003805,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:48:17.005855,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:48:17.007018,? 5]
../../source3/smbd/uid.c:494(smbd_change_to_root_user)
? change_to_root_user: now uid=(0,0) gid=(0,0)
[2024/10/18 14:48:17.007289,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 14:48:17.007437,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:48:17.007567,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:48:17.007769,? 5]
../../source3/smbd/uid.c:494(smbd_change_to_root_user)
? change_to_root_user: now uid=(0,0) gid=(0,0)
[2024/10/18 14:48:17.007962,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 14:48:17.008290,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 14:48:17.008446,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 14:48:17.008575,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:48:17.008697,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:48:17.008895,? 5]
../../source3/smbd/uid.c:494(smbd_change_to_root_user)
? change_to_root_user: now uid=(0,0) gid=(0,0)
[2024/10/18 14:48:17.009029,? 3]
../../source3/smbd/smb2_service.c:933(close_cnum)
? mini-lenovo (ipv4:192.168.0.17:57960) closed connection to service IPC$
[2024/10/18 14:48:17.009188,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /
[2024/10/18 14:48:17.009398,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /
[2024/10/18 14:48:17.009555,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 14:48:17.009684,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 14:48:17.009805,? 5]
../../source3/auth/token_util.c:873(debug_unix_user_token)
? UNIX token of user 0
? Primary group is 0 and contains 0 supplementary groups
[2024/10/18 14:48:17.010001,? 5]
../../source3/smbd/uid.c:494(smbd_change_to_root_user)
? change_to_root_user: now uid=(0,0) gid=(0,0)
[2024/10/18 14:48:17.010184,? 5]
../../lib/dbwrap/dbwrap.c:143(dbwrap_lock_order_lock)
? dbwrap_lock_order_lock: check lock order 1 for
/run/samba/smbXsrv_tcon_global.tdb
[2024/10/18 14:48:17.010404,? 5]
../../lib/dbwrap/dbwrap.c:175(dbwrap_lock_order_unlock)
? dbwrap_lock_order_unlock: release lock order 1 for
/run/samba/smbXsrv_tcon_global.tdb
========================