On 18/10/2024 11:50, Rowland Penny via samba wrote:>> 1. I added those two lines in Global, reloaded the file: Still slow
>> client min protocol = SMB2_02
>> server min protocol = SMB2_02
> You didn't need to, they are the defaults and have been so since Samba
> 4.11.0
I added those per the suggestion above in the thread.
> Nothing there about SMBv1.
> I would raise the log level to at least 5 and see if something
'pops'
> out during normal use, other than that, I would be looking closely at
> Apache, could it be trying to use SMBv1 ?
It's Nginx not Apache, and it's not web-related anyway: The delay occurs
when I simply double-click on the Samba host in Windows' Network section
to see its shares. FWIW, I didn't have that problem with Debian 11; I
had to install a fresh Debian 12 from scratch after the USB thumb drive
from which it ran failed :-/
With "log level=6":
=============[2024/10/18 12:08:45.063876,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 12:08:45.064190,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:08:45.064335,? 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 12:08:45.064547,? 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 12:08:45.064876,? 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 12:08:45.065168,? 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 12:08:45.065441,? 3] ../../lib/util/access.c:372(allow_access)
? Allowed connection from 192.168.0.17 (192.168.0.17)
[2024/10/18 12:08:45.065852,? 3]
../../source3/smbd/smb2_service.c:610(make_connection_snum)
? make_connection_snum: Connect path is '/tmp' for service [IPC$]
[2024/10/18 12:08:45.066112,? 3]
../../source3/smbd/vfs.c:115(vfs_init_default)
? Initialising default vfs hooks
[2024/10/18 12:08:45.066253,? 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 12:08:45.066802,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 12:08:45.066958,? 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 12:08:45.067741,? 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 12:08:45.071388,? 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 12:08:45.071586,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 12:08:45.071729,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:08:45.071854,? 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 12:08:45.072149,? 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 12:08:45.072368,? 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 12:08:45.338391,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 12:08:45.338625,? 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 12:08:45.339351,? 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 12:08:45.339653,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 12:08:45.339890,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 12:08:45.342635,? 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:45.343535,? 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 12:08:45.343830,? 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 12:08:45.344106,? 5] ../../source3/smbd/files.c:76(fsp_new)
? fsp_new: allocated files structure (2 used)
[2024/10/18 12:08:45.344293,? 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:45.344575,? 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:45.344738,? 5] ../../source3/smbd/files.c:178(file_new)
? file_new: new file fnum 1017083592
[2024/10/18 12:08:45.345326,? 4]
../../source3/smbd/sec_ctx.c:206(push_sec_ctx)
? push_sec_ctx(33, 33) : sec_ctx_stack_ndx = 1
[2024/10/18 12:08:45.345519,? 4] ../../source3/smbd/uid.c:566(push_conn_ctx)
? push_conn_ctx(2675924220) : conn_ctx_stack_ndx = 0
[2024/10/18 12:08:45.345661,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2024/10/18 12:08:45.345791,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:08:45.345912,? 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 12:08:45.346295,? 4]
../../source3/smbd/sec_ctx.c:443(pop_sec_ctx)
? pop_sec_ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 12:08:45.346625,? 4]
../../source3/smbd/sec_ctx.c:206(push_sec_ctx)
? push_sec_ctx(33, 33) : sec_ctx_stack_ndx = 1
[2024/10/18 12:08:45.346793,? 4] ../../source3/smbd/uid.c:566(push_conn_ctx)
? push_conn_ctx(2675924220) : conn_ctx_stack_ndx = 0
[2024/10/18 12:08:45.346929,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2024/10/18 12:08:45.347059,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:08:45.347179,? 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 12:08:45.351461,? 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
[2024/10/18 12:08:54.237936,? 4]
../../source3/smbd/sec_ctx.c:206(push_sec_ctx)
? push_sec_ctx(33, 33) : sec_ctx_stack_ndx = 1
[2024/10/18 12:08:54.239035,? 4] ../../source3/smbd/uid.c:566(push_conn_ctx)
? push_conn_ctx(2675924220) : conn_ctx_stack_ndx = 0
[2024/10/18 12:08:54.239877,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
[2024/10/18 12:08:54.240774,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:08:54.241629,? 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 12:08:54.243311,? 4]
../../source3/smbd/sec_ctx.c:443(pop_sec_ctx)
? pop_sec_ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 12:08:54.514860,? 5]
../../source3/smbd/uid.c:327(change_to_user_impersonate)
? change_to_user_impersonate: Skipping user change - already user
[2024/10/18 12:08:54.515873,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 12:08:54.516885,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 12:08:54.517772,? 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:54.518673,? 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:54.519662,? 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:54.786687,? 5]
../../source3/smbd/uid.c:327(change_to_user_impersonate)
? change_to_user_impersonate: Skipping user change - already user
[2024/10/18 12:08:54.787817,? 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: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
[2024/10/18 12:09:03.704304,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 12:09:03.704451,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:09:03.704580,? 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 12:09:03.704814,? 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 12:09:06.750290,? 2]
../../source3/lib/tallocmsg.c:84(register_msg_pool_usage)
? Registered MSG_REQ_POOL_USAGE
[2024/10/18 12:09:06.757459,? 2]
../../source3/smbd/server.c:832(remove_child_pid)
? Could not find child 7746 -- ignoring
[2024/10/18 12:09:09.038957,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (33, 33) - sec_ctx_stack_ndx = 0
[2024/10/18 12:09:09.040019,? 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 12:09:09.042921,? 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 12:09:09.043256,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 12:09:09.043502,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 12:09:09.043721,? 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:09:09.043872,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 12:09:09.044078,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:09:09.044207,? 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 12:09:09.044427,? 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 12:09:09.044653,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 12:09:09.044792,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:09:09.044987,? 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 12:09:09.045195,? 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 12:09:09.045334,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /tmp
[2024/10/18 12:09:09.045548,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /tmp
[2024/10/18 12:09:09.045691,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 12:09:09.045819,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:09:09.045939,? 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 12:09:09.046131,? 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 12:09:09.046264,? 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 12:09:09.046422,? 4] ../../source3/smbd/vfs.c:938(vfs_ChDir)
? vfs_ChDir to /
[2024/10/18 12:09:09.046629,? 5] ../../source3/smbd/vfs.c:1000(vfs_ChDir)
? vfs_ChDir: vfs_ChDir got /
[2024/10/18 12:09:09.046786,? 4]
../../source3/smbd/sec_ctx.c:317(set_sec_ctx_internal)
? setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
[2024/10/18 12:09:09.046915,? 5]
../../libcli/security/security_token.c:47(security_token_debug)
? Security token: (NULL)
[2024/10/18 12:09:09.047034,? 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 12:09:09.047228,? 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 12:09:09.047407,? 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 12:09:09.047628,? 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
^C
=============
If it's not wsdd-related and there's nothing fishy in smb.conf, where
else could I look?