[2021/02/04 06:04:11.129153, 0] ../../source3/smbd/server.c:1790(main) smbd version 4.14.0rc1-TrueNAS started. Copyright Andrew Tridgell and the Samba Team 1992-2021 [2021/02/04 06:04:11.133423, 5] ../../lib/util/debug.c:811(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:11.134263, 4] ../../source3/param/loadparm.c:3996(lp_load_ex) pm_process() returned Yes [2021/02/04 06:04:11.134321, 3] ../../source3/param/loadparm.c:1657(lp_add_ipc) adding IPC service [2021/02/04 06:04:11.134373, 5] ../../lib/util/debug.c:811(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:11.135260, 5] ../../lib/util/debug.c:811(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:11.136304, 2] ../../source3/lib/interface.c:344(add_interface) added interface ens19 ip=10.10.10.10 bcast=10.10.10.255 netmask=255.255.255.0 [2021/02/04 06:04:11.136365, 2] ../../source3/lib/interface.c:344(add_interface) added interface ens18 ip=192.168.1.188 bcast=192.168.1.255 netmask=255.255.255.0 [2021/02/04 06:04:11.136423, 3] ../../source3/smbd/server.c:1864(main) loaded services [2021/02/04 06:04:11.136468, 5] ../../source3/lib/util_names.c:149(init_names) Netbios name list:- [2021/02/04 06:04:11.136770, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'g_lock.tdb' with dbid 0x4d2a432b [2021/02/04 06:04:11.136822, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'secrets.tdb' with dbid 0x7132c184 [2021/02/04 06:04:11.136932, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 4 - private_data=(nil) [2021/02/04 06:04:11.136981, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 7 - private_data=(nil) [2021/02/04 06:04:11.137112, 1] ../../source3/profile/profile.c:54(set_profile_level) INFO: Profiling turned OFF from pid 2001079 [2021/02/04 06:04:11.137197, 3] ../../source3/smbd/server.c:1883(main) Standard input is not a socket, assuming -D option [2021/02/04 06:04:11.137268, 3] ../../source3/smbd/server.c:1896(main) Becoming a daemon. [2021/02/04 06:04:11.137688, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2021/02/04 06:04:11.137760, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.137808, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.137856, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.137903, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.137948, 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 [2021/02/04 06:04:11.138385, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:11.138505, 5] ../../source3/passdb/pdb_interface.c:81(smb_register_passdb) Attempting to register passdb backend smbpasswd [2021/02/04 06:04:11.138571, 5] ../../source3/passdb/pdb_interface.c:94(smb_register_passdb) Successfully added passdb backend 'smbpasswd' [2021/02/04 06:04:11.138668, 5] ../../source3/passdb/pdb_interface.c:81(smb_register_passdb) Attempting to register passdb backend tdbsam [2021/02/04 06:04:11.138717, 5] ../../source3/passdb/pdb_interface.c:94(smb_register_passdb) Successfully added passdb backend 'tdbsam' [2021/02/04 06:04:11.138765, 5] ../../source3/passdb/pdb_interface.c:81(smb_register_passdb) Attempting to register passdb backend ldapsam [2021/02/04 06:04:11.138810, 5] ../../source3/passdb/pdb_interface.c:94(smb_register_passdb) Successfully added passdb backend 'ldapsam' [2021/02/04 06:04:11.138854, 5] ../../source3/passdb/pdb_interface.c:81(smb_register_passdb) Attempting to register passdb backend NDS_ldapsam [2021/02/04 06:04:11.139014, 5] ../../source3/passdb/pdb_interface.c:94(smb_register_passdb) Successfully added passdb backend 'NDS_ldapsam' [2021/02/04 06:04:11.139111, 5] ../../source3/passdb/pdb_interface.c:157(make_pdb_method_name) Attempting to find a passdb backend to match tdbsam (tdbsam) [2021/02/04 06:04:11.139157, 5] ../../source3/passdb/pdb_interface.c:178(make_pdb_method_name) Found pdb backend tdbsam [2021/02/04 06:04:11.139206, 5] ../../source3/passdb/pdb_interface.c:189(make_pdb_method_name) pdb backend tdbsam has a valid init [2021/02/04 06:04:11.139255, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.139983, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.140144, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:390(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:390 transaction started on db 0x7132c184 [2021/02/04 06:04:11.140243, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.144390, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.144792, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'smbXsrv_version_global.tdb' with dbid 0x521b7544 [2021/02/04 06:04:11.144895, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_version_global.tdb [2021/02/04 06:04:11.145631, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_version_global.tdb [2021/02/04 06:04:11.146056, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'smbXsrv_client_global.tdb' with dbid 0x477d2e20 [2021/02/04 06:04:11.146228, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'smbXsrv_session_global.tdb' with dbid 0x6b06a26d [2021/02/04 06:04:11.146548, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'smbXsrv_tcon_global.tdb' with dbid 0x68c12c2c [2021/02/04 06:04:11.146778, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'brlock.tdb' with dbid 0x4e66c2b2 [2021/02/04 06:04:11.146982, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'locking.tdb' with dbid 0x7a19d84d [2021/02/04 06:04:11.147173, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'leases.tdb' with dbid 0x6916e77 [2021/02/04 06:04:11.147773, 3] ../../source3/lib/util_procid.c:53(pid_to_procid) pid_to_procid: messaging_dgm_get_unique failed: No such file or directory [2021/02/04 06:04:11.148806, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2021/02/04 06:04:11.148901, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.148959, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.149009, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.149060, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.149111, 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 [2021/02/04 06:04:11.149500, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) [2021/02/04 06:04:11.149506, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) Registered MSG_REQ_POOL_USAGE pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:11.149619, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.149658, 5] ../../source3/passdb/pdb_interface.c:157(make_pdb_method_name) [2021/02/04 06:04:11.149677, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) Attempting to find a passdb backend to match tdbsam (tdbsam) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.149742, 5] ../../source3/passdb/pdb_interface.c:178(make_pdb_method_name) [2021/02/04 06:04:11.149759, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) Found pdb backend tdbsam setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.149860, 5] ../../source3/passdb/pdb_interface.c:189(make_pdb_method_name) [2021/02/04 06:04:11.149864, 5] ../../libcli/security/security_token.c:47(security_token_debug) pdb backend tdbsam has a valid init Security token: (NULL) [2021/02/04 06:04:11.149971, 5] ../../lib/util/debug.c:811(debug_dump_status) [2021/02/04 06:04:11.149980, 5] ../../source3/auth/token_util.c:873(debug_unix_user_token) INFO: Current debug levels: UNIX token of user 0 all: 5 Primary group is 0 and contains 0 supplementary groups tdb: 5 printdrivers: 5 lanman: 5 [2021/02/04 06:04:11.150360, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) smb: 5 pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 rpc_parse: 5 rpc_srv: 5 [2021/02/04 06:04:11.150484, 5] ../../source3/passdb/pdb_interface.c:157(make_pdb_method_name) rpc_cli: 5 Attempting to find a passdb backend to match tdbsam (tdbsam) passdb: 5 [2021/02/04 06:04:11.150593, 5] ../../source3/passdb/pdb_interface.c:178(make_pdb_method_name) sam: 5 Found pdb backend tdbsam auth: 5 winbind: 5 [2021/02/04 06:04:11.150715, 5] ../../source3/passdb/pdb_interface.c:189(make_pdb_method_name) vfs: 5 pdb backend tdbsam has a valid init idmap: 5 [2021/02/04 06:04:11.150835, 5] ../../source3/lib/messages.c:723(messaging_register) quota: 5 Registering messaging pointer for type 13 - private_data=0x560ad9a53e70 acls: 5 [2021/02/04 06:04:11.150945, 5] ../../source3/lib/messages.c:723(messaging_register) locking: 5 Registering messaging pointer for type 788 - private_data=0x560ad9a53e70 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 [2021/02/04 06:04:11.151138, 5] ../../source3/lib/messages.c:723(messaging_register) ldb: 5 Registering messaging pointer for type 789 - private_data=0x560ad9a713b0 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:11.151945, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'g_lock.tdb' with dbid 0x4d2a432b [2021/02/04 06:04:11.152020, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'share_info.tdb' with dbid 0xc3078fba [2021/02/04 06:04:11.152149, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user nobody [2021/02/04 06:04:11.152219, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is nobody [2021/02/04 06:04:11.152278, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 794 - private_data=0x560ad9a4f0f0 [2021/02/04 06:04:11.152358, 5] ../../source3/lib/messages.c:723(messaging_register) [2021/02/04 06:04:11.152423, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Registering messaging pointer for type 795 - private_data=0x560ad9a4f0f0 Get_Pwnam_internals did find user [nobody]! [2021/02/04 06:04:11.152522, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 796 - private_data=0x560ad9a4f0f0 [2021/02/04 06:04:11.152576, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user \nobody [2021/02/04 06:04:11.152671, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) [2021/02/04 06:04:11.152694, 5] ../../source3/lib/messages.c:723(messaging_register) Trying _Get_Pwnam(), username as lowercase is \nobody Registering messaging pointer for type 797 - private_data=0x560ad9a4f0f0 [2021/02/04 06:04:11.152956, 5] ../../source3/lib/username.c:141(Get_Pwnam_internals) Trying _Get_Pwnam(), username as uppercase is \NOBODY [2021/02/04 06:04:11.153400, 5] ../../source3/lib/username.c:153(Get_Pwnam_internals) Checking combinations of 0 uppercase letters in \nobody [2021/02/04 06:04:11.153547, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals didn't find user [\nobody]! [2021/02/04 06:04:11.153796, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user nobody [2021/02/04 06:04:11.153848, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is nobody [2021/02/04 06:04:11.153927, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [nobody]! [2021/02/04 06:04:11.153991, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user nobody [2021/02/04 06:04:11.154047, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is nobody [2021/02/04 06:04:11.154094, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [nobody]! [2021/02/04 06:04:11.154461, 5] ../../source3/lib/gencache.c:89(gencache_init) Opening cache file at /var/run/samba-lock/gencache.tdb [2021/02/04 06:04:11.155740, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.155802, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.155849, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.155894, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.155938, 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 [2021/02/04 06:04:11.156026, 3] ../../source3/auth/token_util.c:688(finalize_local_nt_token) Failed to fetch domain sid for WORKGROUP [2021/02/04 06:04:11.156079, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:11.156526, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'g_lock.tdb' with dbid 0x4d2a432b [2021/02/04 06:04:11.156616, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'group_mapping.tdb' with dbid 0xa1413774 [2021/02/04 06:04:11.156749, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.156800, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.156846, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.156891, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.156936, 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 [2021/02/04 06:04:11.157063, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:11.157343, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'g_lock.tdb' with dbid 0x4d2a432b [2021/02/04 06:04:11.157395, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'account_policy.tdb' with dbid 0x2ca251cf [2021/02/04 06:04:11.157453, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-21-2479029229-654997156-3196209401-501] [2021/02/04 06:04:11.157503, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-21-2479029229-654997156-3196209401-514] [2021/02/04 06:04:11.157561, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-22-2-65534] [2021/02/04 06:04:11.157615, 5] ../../source3/lib/privileges.c:179(get_privileges_for_sids) get_privileges_for_sids: sid = S-1-1-0 Privilege set: 0x0 [2021/02/04 06:04:11.157685, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-2] [2021/02/04 06:04:11.157738, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-32-546] [2021/02/04 06:04:11.158042, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.158094, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.158139, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.158184, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.158229, 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 [2021/02/04 06:04:11.158304, 5] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid) lookup_global_sam_rid: looking up RID 514. [2021/02/04 06:04:11.158352, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:11.158397, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:11.158442, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:11.158487, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.158531, 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 [2021/02/04 06:04:11.158839, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'g_lock.tdb' with dbid 0x4d2a432b [2021/02/04 06:04:11.158891, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'passdb.tdb' with dbid 0x3ef19640 [2021/02/04 06:04:11.158963, 4] ../../source3/passdb/pdb_tdb.c:560(tdbsam_open) tdbsam_open: successfully opened /var/db/system/samba4/private/passdb.tdb [2021/02/04 06:04:11.159029, 5] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid) pdb_getsampwrid (TDB): error looking up RID 514 by key RID_00000202. [2021/02/04 06:04:11.159089, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.159137, 5] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid) Can't find a unix id for an unmapped group [2021/02/04 06:04:11.159182, 5] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id) SID S-1-5-21-2479029229-654997156-3196209401-514 belongs to our domain, but there is no corresponding object in the database. [2021/02/04 06:04:11.159236, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:11.159284, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.159330, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.159375, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.159421, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.159466, 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 [2021/02/04 06:04:11.159544, 5] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid) lookup_global_sam_rid: looking up RID 514. [2021/02/04 06:04:11.159593, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:11.159638, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:11.159683, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:11.159728, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:11.159772, 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 [2021/02/04 06:04:11.159849, 5] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid) pdb_getsampwrid (TDB): error looking up RID 514 by key RID_00000202. [2021/02/04 06:04:11.159906, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:11.159953, 5] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid) Can't find a unix id for an unmapped group [2021/02/04 06:04:11.159998, 5] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id) SID S-1-5-21-2479029229-654997156-3196209401-514 belongs to our domain, but there is no corresponding object in the database. [2021/02/04 06:04:11.160050, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:11.160106, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user nobody [2021/02/04 06:04:11.160153, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is nobody [2021/02/04 06:04:11.160203, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [nobody]! [2021/02/04 06:04:11.160417, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'smbXsrv_open_global.tdb' with dbid 0x66f71b8c [2021/02/04 06:04:11.160559, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'g_lock.tdb' with dbid 0x4d2a432b [2021/02/04 06:04:11.160610, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.161907, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.162020, 5] ../../source3/rpc_server/rpc_service_setup.c:858(dcesrv_init) dcesrv_init: Registering DCE/RPC endpoint servers [2021/02/04 06:04:11.162095, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'winreg' registered [2021/02/04 06:04:11.162161, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'srvsvc' registered [2021/02/04 06:04:11.162210, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'lsarpc' registered [2021/02/04 06:04:11.162261, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'samr' registered [2021/02/04 06:04:11.162313, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'netdfs' registered [2021/02/04 06:04:11.162361, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'dssetup' registered [2021/02/04 06:04:11.162409, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'wkssvc' registered [2021/02/04 06:04:11.162464, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'spoolss' registered [2021/02/04 06:04:11.162513, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'svcctl' registered [2021/02/04 06:04:11.162561, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'ntsvcs' registered [2021/02/04 06:04:11.162612, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'eventlog' registered [2021/02/04 06:04:11.162660, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'initshutdown' registered [2021/02/04 06:04:11.162705, 5] ../../source3/rpc_server/rpc_service_setup.c:932(dcesrv_init) dcesrv_init: Initializing DCE/RPC modules [2021/02/04 06:04:11.162751, 3] ../../source3/rpc_server/rpc_modules.c:64(register_rpc_module) register_rpc_module: Successfully added RPC module 'mdssvc' [2021/02/04 06:04:11.162945, 3] ../../librpc/rpc/dcesrv_core.c:2540(dcerpc_register_ep_server) DCERPC endpoint server 'mdssvc' registered [2021/02/04 06:04:11.162996, 5] ../../source3/rpc_server/rpc_service_setup.c:966(dcesrv_init) dcesrv_init: Initializing DCE/RPC registered endpoint servers [2021/02/04 06:04:11.163058, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'winreg' registered on endpoint 'ncacn_np:[\pipe\winreg]' (single process required) [2021/02/04 06:04:11.163122, 5] ./librpc/gen_ndr/ndr_winreg_scompat.c:1145(winreg__check_register_in_endpoint) winreg__check_register_in_endpoint: Interface 'winreg' not registered in endpoint 'winreg' as service is embedded [2021/02/04 06:04:11.163175, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'winreg' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.163247, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'srvsvc' registered on endpoint 'ncacn_np:[\pipe\srvsvc]' (single process required) [2021/02/04 06:04:11.163298, 5] ./librpc/gen_ndr/ndr_srvsvc_scompat.c:1520(srvsvc__check_register_in_endpoint) srvsvc__check_register_in_endpoint: Interface 'srvsvc' not registered in endpoint 'srvsvc' as service is embedded [2021/02/04 06:04:11.163359, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'srvsvc' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.163411, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'lsarpc' registered on endpoint 'ncacn_np:[\pipe\netlogon]' (single process required) [2021/02/04 06:04:11.163462, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'lsarpc' registered on endpoint 'ncacn_np:[\pipe\lsarpc]' (single process required) [2021/02/04 06:04:11.163517, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'lsarpc' registered on endpoint 'ncacn_np:[\pipe\lsass]' (single process required) [2021/02/04 06:04:11.163566, 5] ./librpc/gen_ndr/ndr_lsa_scompat.c:2256(lsarpc__check_register_in_endpoint) lsarpc__check_register_in_endpoint: Interface 'lsarpc' not registered in endpoint 'lsarpc' as service is embedded [2021/02/04 06:04:11.163617, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'lsarpc' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.163681, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'samr' registered on endpoint 'ncacn_np:[\pipe\samr]' (single process required) [2021/02/04 06:04:11.163731, 5] ./librpc/gen_ndr/ndr_samr_scompat.c:2105(samr__check_register_in_endpoint) samr__check_register_in_endpoint: Interface 'samr' not registered in endpoint 'samr' as service is embedded [2021/02/04 06:04:11.163794, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'samr' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.163852, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'netdfs' registered on endpoint 'ncacn_np:[\pipe\netdfs]' (single process required) [2021/02/04 06:04:11.163901, 5] ./librpc/gen_ndr/ndr_dfs_scompat.c:702(netdfs__check_register_in_endpoint) netdfs__check_register_in_endpoint: Interface 'netdfs' not registered in endpoint 'netdfs' as service is embedded [2021/02/04 06:04:11.164079, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'netdfs' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.164185, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'dssetup' registered on endpoint 'ncacn_np:[\pipe\lsarpc]' (single process required) [2021/02/04 06:04:11.164270, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'dssetup' registered on endpoint 'ncacn_np:[\pipe\lsass]' (single process required) [2021/02/04 06:04:11.164322, 5] ./librpc/gen_ndr/ndr_dssetup_scompat.c:428(dssetup__check_register_in_endpoint) dssetup__check_register_in_endpoint: Interface 'dssetup' not registered in endpoint 'dssetup' as service is embedded [2021/02/04 06:04:11.164374, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'dssetup' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.164444, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'wkssvc' registered on endpoint 'ncacn_np:[\pipe\wkssvc]' (single process required) [2021/02/04 06:04:11.164495, 5] ./librpc/gen_ndr/ndr_wkssvc_scompat.c:945(wkssvc__check_register_in_endpoint) wkssvc__check_register_in_endpoint: Interface 'wkssvc' not registered in endpoint 'wkssvc' as service is embedded [2021/02/04 06:04:11.164546, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'wkssvc' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.164641, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'spoolss' registered on endpoint 'ncacn_np:[\pipe\spoolss]' (single process required) [2021/02/04 06:04:11.164694, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'spoolss' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.164743, 3] ../../source3/rpc_server/svcctl/srv_svcctl_reg.c:565(svcctl_init_winreg) Initialise the svcctl registry keys if needed. [2021/02/04 06:04:11.164793, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.165137, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.165232, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.165808, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.166821, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.168186, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.177244, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.177639, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.177735, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:390(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:390 transaction started on db 0x6cf2837d [2021/02/04 06:04:11.177819, 5] ../../source3/rpc_server/rpc_ncacn_np.c:258(make_internal_ncacn_conn) make_internal_ncacn_conn: Create pipe requested winreg [2021/02/04 06:04:11.177900, 4] ../../source3/rpc_server/rpc_ncacn_np.c:304(make_internal_ncacn_conn) Created internal pipe winreg [2021/02/04 06:04:11.178246, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.178300, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.178360, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.178408, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.178467, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.178514, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.178576, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.178636, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.178709, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.178772, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.178835, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.178885, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.178971, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.179022, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.179095, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.179151, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.179219, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.179269, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.179333, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.179383, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.179444, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.179493, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.179575, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.179623, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.179684, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.179731, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.179790, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.179837, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.179901, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.179948, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.180007, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.180065, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.180134, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.180196, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.180271, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.180333, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.180414, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.180477, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.180560, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.180626, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.180717, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.180804, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.180897, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.180986, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.181074, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.181140, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.181227, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.181294, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.181382, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.181448, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.181539, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.181613, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.181702, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.181767, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.181858, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.181922, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.182010, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.182059, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.182120, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.182168, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.182227, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.182275, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.182340, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.182394, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.182455, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.182520, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.182590, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.182650, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.182729, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.182778, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.182836, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.182883, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.182972, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.183020, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.183082, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.183141, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.183208, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.183269, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.183334, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.183384, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.183448, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.183497, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.183560, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.183609, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.183675, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.183725, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.183789, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.183839, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.183902, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.183952, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.184032, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.184081, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.184143, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.184190, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.184249, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.184296, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.184360, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.184407, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.184465, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.184524, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.184591, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.184652, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.184727, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.184776, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.184835, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.184882, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.184944, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.184991, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.185064, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.185125, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.185193, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.185257, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.185321, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.185371, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.185435, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.185489, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.185561, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.185612, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.185676, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.185725, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.185789, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.185839, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.185906, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.185955, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.186038, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.186087, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.186149, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.186197, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.186256, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.186304, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.186369, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.186416, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 1 -> 2 [2021/02/04 06:04:11.186475, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 2 -> 1 [2021/02/04 06:04:11.186533, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.186601, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:354(db_ctdb_transaction_start) ../../source3/lib/dbwrap/dbwrap_ctdb.c:354 transaction start on db 0x6cf2837d: nesting 0 -> 1 [2021/02/04 06:04:11.186659, 5] ../../source3/lib/dbwrap/dbwrap_ctdb.c:780(db_ctdb_transaction_commit) ../../source3/lib/dbwrap/dbwrap_ctdb.c:780 transaction commit on db 0x6cf2837d: nesting 1 -> 0 [2021/02/04 06:04:11.186738, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 3 for g_lock.tdb [2021/02/04 06:04:11.186837, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 3 for g_lock.tdb [2021/02/04 06:04:11.186949, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'svcctl' registered on endpoint 'ncacn_np:[\pipe\svcctl]' (single process required) [2021/02/04 06:04:11.187009, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'svcctl' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.187066, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'ntsvcs' registered on endpoint 'ncacn_np:[\pipe\ntsvcs]' (single process required) [2021/02/04 06:04:11.187120, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'ntsvcs' registered on endpoint 'ncacn_np:[\pipe\plugplay]' (single process required) [2021/02/04 06:04:11.187169, 3] ../../source3/rpc_server/eventlog/srv_eventlog_reg.c:59(eventlog_init_winreg) Initialise the eventlog registry keys if needed. [2021/02/04 06:04:11.187222, 5] ../../source3/rpc_server/rpc_ncacn_np.c:258(make_internal_ncacn_conn) make_internal_ncacn_conn: Create pipe requested winreg [2021/02/04 06:04:11.187292, 4] ../../source3/rpc_server/rpc_ncacn_np.c:304(make_internal_ncacn_conn) Created internal pipe winreg [2021/02/04 06:04:11.187494, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'eventlog' registered on endpoint 'ncacn_np:[\pipe\eventlog]' (single process required) [2021/02/04 06:04:11.187574, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'initshutdown' registered on endpoint 'ncacn_np:[\pipe\InitShutdown]' (single process required) [2021/02/04 06:04:11.187631, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'mdssvc' registered on endpoint 'ncacn_np:[\pipe\mdssvc]' (single process required) [2021/02/04 06:04:11.187685, 5] ../../librpc/rpc/dcesrv_core.c:412(dcesrv_interface_register) dcesrv_interface_register: Interface 'mdssvc' registered on endpoint 'ncalrpc:' (single process required) [2021/02/04 06:04:11.187732, 5] ../../source3/rpc_server/rpc_service_setup.c:975(dcesrv_init) dcesrv_init: Initializing DCE/RPC connection endpoints [2021/02/04 06:04:11.187840, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\mdssvc]' for 'mdssvc' 'mgmt' [2021/02/04 06:04:11.187966, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\InitShutdown]' for 'initshutdown' 'mgmt' [2021/02/04 06:04:11.188060, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\eventlog]' for 'eventlog' 'mgmt' [2021/02/04 06:04:11.188154, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\plugplay]' for 'ntsvcs' 'mgmt' [2021/02/04 06:04:11.188248, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\ntsvcs]' for 'ntsvcs' 'mgmt' [2021/02/04 06:04:11.188343, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\svcctl]' for 'svcctl' 'mgmt' [2021/02/04 06:04:11.188436, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\spoolss]' for 'spoolss' 'mgmt' [2021/02/04 06:04:11.188529, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\wkssvc]' for 'wkssvc' 'mgmt' [2021/02/04 06:04:11.188621, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\netdfs]' for 'netdfs' 'mgmt' [2021/02/04 06:04:11.188713, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\samr]' for 'samr' 'mgmt' [2021/02/04 06:04:11.188826, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\lsass]' for 'dssetup' 'lsarpc' 'mgmt' [2021/02/04 06:04:11.188931, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\lsarpc]' for 'dssetup' 'lsarpc' 'mgmt' [2021/02/04 06:04:11.189034, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\netlogon]' for 'lsarpc' 'mgmt' [2021/02/04 06:04:11.189130, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\srvsvc]' for 'srvsvc' 'mgmt' [2021/02/04 06:04:11.189215, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncalrpc:[DEFAULT]' for 'mdssvc' 'svcctl' 'spoolss' 'wkssvc' 'dssetup' 'netdfs' 'samr' 'lsarpc' 'srvsvc' 'winreg' 'mgmt' [2021/02/04 06:04:11.189312, 5] ../../source3/rpc_server/rpc_service_setup.c:355(dcesrv_setup_endpoint_sockets) dcesrv_setup_endpoint_sockets: Successfully listening on 'ncacn_np:[\pipe\winreg]' for 'winreg' 'mgmt' [2021/02/04 06:04:11.189395, 0] ../../lib/util/become_daemon.c:135(daemon_ready) daemon_ready: daemon 'smbd' finished starting up and ready to serve connections [2021/02/04 06:04:11.189638, 3] ../../source3/lib/dbwrap/dbwrap_ctdb.c:2003(db_open_ctdb) db_open_ctdb: opened database 'printer_list.tdb' with dbid 0x5bcfcbd7 [2021/02/04 06:04:11.189718, 5] ../../source3/printing/nt_printing.c:86(print_driver_directories_init) No print$ share has been configured. [2021/02/04 06:04:11.189788, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 515 - private_data=(nil) [2021/02/04 06:04:11.189836, 3] ../../source3/printing/queue_process.c:331(start_background_queue) start_background_queue: Starting background LPQ thread [2021/02/04 06:04:11.190697, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 0 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 [2021/02/04 06:04:11.190896, 5] ../../source3/printing/queue_process.c:367(start_background_queue) IPTOS_THROUGHPUT = 0 start_background_queue: background LPQ thread started SO_REUSEPORT = 1 SO_SNDBUF = 16384 SO_RCVBUF = 131072 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 TCP_USER_TIMEOUT = 0 [2021/02/04 06:04:11.191239, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 16384 SO_RCVBUF = 131072 SO_SNDLOWAT = 1 [2021/02/04 06:04:11.191777, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) SO_RCVLOWAT = 1 Registered MSG_REQ_POOL_USAGE SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 [2021/02/04 06:04:11.191878, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) TCP_QUICKACK = 1 push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 TCP_DEFER_ACCEPT = 0 [2021/02/04 06:04:11.191981, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) TCP_USER_TIMEOUT = 0 push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:11.192047, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) [2021/02/04 06:04:11.192051, 5] ../../lib/util/util_net.c:990(print_socket_options) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 Socket options: [2021/02/04 06:04:11.192113, 5] ../../libcli/security/security_token.c:47(security_token_debug) SO_KEEPALIVE = 1 Security token: (NULL) SO_REUSEADDR = 1 [2021/02/04 06:04:11.192185, 5] ../../source3/auth/token_util.c:873(debug_unix_user_token) SO_BROADCAST = 0 UNIX token of user 0 TCP_NODELAY = 0 Primary group is 0 and contains 0 supplementary groups TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 [2021/02/04 06:04:11.192514, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) SO_SNDBUF = 16384 pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 SO_RCVBUF = 131072 SO_SNDLOWAT = 1 [2021/02/04 06:04:11.192604, 5] ../../source3/passdb/pdb_interface.c:157(make_pdb_method_name) SO_RCVLOWAT = 1 Attempting to find a passdb backend to match tdbsam (tdbsam) SO_SNDTIMEO = 0 [2021/02/04 06:04:11.192682, 5] ../../source3/passdb/pdb_interface.c:178(make_pdb_method_name) SO_RCVTIMEO = 0 Found pdb backend tdbsam TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2021/02/04 06:04:11.192768, 5] ../../source3/passdb/pdb_interface.c:189(make_pdb_method_name) TCP_USER_TIMEOUT = 0 pdb backend tdbsam has a valid init [2021/02/04 06:04:11.192832, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: [2021/02/04 06:04:11.192865, 5] ../../lib/util/debug.c:811(debug_dump_status) SO_KEEPALIVE = 1 INFO: Current debug levels: SO_REUSEADDR = 1 all: 5 SO_BROADCAST = 0 tdb: 5 TCP_NODELAY = 1 printdrivers: 5 TCP_KEEPCNT = 9 lanman: 5 TCP_KEEPIDLE = 7200 smb: 5 TCP_KEEPINTVL = 75 rpc_parse: 5 IPTOS_LOWDELAY = 0 rpc_srv: 5 IPTOS_THROUGHPUT = 0 rpc_cli: 5 SO_REUSEPORT = 1 passdb: 5 SO_SNDBUF = 16384 sam: 5 SO_RCVBUF = 131072 auth: 5 SO_SNDLOWAT = 1 winbind: 5 SO_RCVLOWAT = 1 vfs: 5 SO_SNDTIMEO = 0 idmap: 5 SO_RCVTIMEO = 0 quota: 5 TCP_QUICKACK = 1 acls: 5 TCP_DEFER_ACCEPT = 0 locking: 5 TCP_USER_TIMEOUT = 0 msdfs: 5 dmapi: 5 [2021/02/04 06:04:11.193609, 5] ../../lib/util/util_net.c:990(print_socket_options) registry: 5 Socket options: scavenger: 5 SO_KEEPALIVE = 1 dns: 5 SO_REUSEADDR = 1 ldb: 5 SO_BROADCAST = 0 tevent: 5 TCP_NODELAY = 0 auth_audit: 5 TCP_KEEPCNT = 9 auth_json_audit: 5 TCP_KEEPIDLE = 7200 kerberos: 5 TCP_KEEPINTVL = 75 drs_repl: 5 IPTOS_LOWDELAY = 0 smb2: 5 IPTOS_THROUGHPUT = 0 smb2_credits: 5 SO_REUSEPORT = 1 dsdb_audit: 5 SO_SNDBUF = 16384 dsdb_json_audit: 5 SO_RCVBUF = 131072 dsdb_password_audit: 5 SO_SNDLOWAT = 1 dsdb_password_json_audit: 5 SO_RCVLOWAT = 1 dsdb_transaction_audit: 5 SO_SNDTIMEO = 0 dsdb_transaction_json_audit: 5 SO_RCVTIMEO = 0 dsdb_group_audit: 5 TCP_QUICKACK = 1 dsdb_group_json_audit: 5 TCP_DEFER_ACCEPT = 0 TCP_USER_TIMEOUT = 0 [2021/02/04 06:04:11.194344, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 [2021/02/04 06:04:11.194545, 5] ../../source3/lib/messages.c:723(messaging_register) SO_REUSEPORT = 1 Registering messaging pointer for type 33 - private_data=0x560ad9ab2460 SO_SNDBUF = 16384 [2021/02/04 06:04:11.194710, 5] ../../source3/lib/messages.c:723(messaging_register) SO_RCVBUF = 131072 Registering messaging pointer for type 517 - private_data=(nil) SO_SNDLOWAT = 1 [2021/02/04 06:04:11.194824, 5] ../../source3/lib/messages.c:771(messaging_deregister) SO_RCVLOWAT = 1 Deregistering messaging pointer for type 515 - private_data=(nil) SO_SNDTIMEO = 0 [2021/02/04 06:04:11.194947, 5] ../../source3/lib/messages.c:723(messaging_register) SO_RCVTIMEO = 0 Registering messaging pointer for type 515 - private_data=(nil) TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2021/02/04 06:04:11.195132, 3] ../../source3/printing/pcap.c:140(pcap_cache_reload) TCP_USER_TIMEOUT = 0 reloading printcap cache [2021/02/04 06:04:11.195332, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: [2021/02/04 06:04:11.195354, 0] ../../source3/printing/print_standard.c:71(std_pcap_cache_reload) SO_KEEPALIVE = 1 Unable to open printcap file /etc/printcap for read! SO_REUSEADDR = 1 [2021/02/04 06:04:11.195580, 3] ../../source3/printing/pcap.c:194(pcap_cache_reload) SO_BROADCAST = 0 reload status: error TCP_NODELAY = 0 [2021/02/04 06:04:11.195689, 5] ../../source3/printing/queue_process.c:421(start_background_queue) TCP_KEEPCNT = 9 start_background_queue: background LPQ thread waiting for messages TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 16384 SO_RCVBUF = 131072 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 TCP_USER_TIMEOUT = 0 [2021/02/04 06:04:11.196085, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 16384 SO_RCVBUF = 131072 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 TCP_USER_TIMEOUT = 0 [2021/02/04 06:04:11.196517, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 13 - private_data=(nil) [2021/02/04 06:04:11.196567, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 33 - private_data=0x560ad9a4fbf0 [2021/02/04 06:04:11.196613, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 783 - private_data=(nil) [2021/02/04 06:04:11.196659, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 1 - private_data=(nil) [2021/02/04 06:04:11.196705, 5] ../../source3/lib/messages.c:739(messaging_register) Overriding messaging pointer for type 1 - private_data=(nil) [2021/02/04 06:04:11.196756, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 770 - private_data=(nil) [2021/02/04 06:04:11.196801, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 801 - private_data=(nil) [2021/02/04 06:04:11.196846, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 790 - private_data=(nil) [2021/02/04 06:04:11.196891, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 791 - private_data=(nil) [2021/02/04 06:04:11.196936, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 15 - private_data=(nil) [2021/02/04 06:04:11.196985, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 16 - private_data=(nil) [2021/02/04 06:04:11.197041, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 799 - private_data=(nil) [2021/02/04 06:04:11.197410, 1] ../../source3/printing/printer_list.c:234(printer_list_get_last_refresh) Failed to fetch record! [2021/02/04 06:04:11.197507, 2] ../../source3/smbd/server.c:1365(smbd_parent_loop) waiting for connections [2021/02/04 06:04:21.040964, 2] ../../source3/lib/tallocmsg.c:84(register_msg_pool_usage) Registered MSG_REQ_POOL_USAGE [2021/02/04 06:04:21.041087, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:21.041144, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:21.041194, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:21.041244, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:21.041294, 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 [2021/02/04 06:04:21.041547, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:21.041612, 5] ../../source3/passdb/pdb_interface.c:157(make_pdb_method_name) Attempting to find a passdb backend to match tdbsam (tdbsam) [2021/02/04 06:04:21.041662, 5] ../../source3/passdb/pdb_interface.c:178(make_pdb_method_name) Found pdb backend tdbsam [2021/02/04 06:04:21.041723, 5] ../../source3/passdb/pdb_interface.c:189(make_pdb_method_name) pdb backend tdbsam has a valid init [2021/02/04 06:04:21.041815, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 2626560 SO_RCVBUF = 131072 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 TCP_USER_TIMEOUT = 0 [2021/02/04 06:04:21.042246, 5] ../../lib/util/util_net.c:990(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 2626560 SO_RCVBUF = 131072 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 TCP_USER_TIMEOUT = 0 [2021/02/04 06:04:21.042727, 3] ../../lib/util/access.c:370(allow_access) Allowed connection from 127.0.0.1 (127.0.0.1) [2021/02/04 06:04:21.042868, 5] ../../lib/util/debug.c:811(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:21.043912, 3] ../../source3/smbd/oplock.c:1427(init_oplocks) init_oplocks: initializing messages. [2021/02/04 06:04:21.043967, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 774 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044015, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 778 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044060, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 770 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044105, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 801 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044151, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 787 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044196, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 779 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044242, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 15 - private_data=(nil) [2021/02/04 06:04:21.044288, 5] ../../source3/lib/messages.c:739(messaging_register) Overriding messaging pointer for type 15 - private_data=(nil) [2021/02/04 06:04:21.044334, 5] ../../source3/lib/messages.c:771(messaging_deregister) Deregistering messaging pointer for type 16 - private_data=(nil) [2021/02/04 06:04:21.044379, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 16 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044425, 5] ../../source3/lib/messages.c:771(messaging_deregister) Deregistering messaging pointer for type 33 - private_data=0x560ad9a4fbf0 [2021/02/04 06:04:21.044474, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 33 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044521, 5] ../../source3/lib/messages.c:771(messaging_deregister) Deregistering messaging pointer for type 790 - private_data=(nil) [2021/02/04 06:04:21.044566, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 790 - private_data=0x560ad9a7a700 [2021/02/04 06:04:21.044612, 5] ../../source3/lib/messages.c:771(messaging_deregister) Deregistering messaging pointer for type 791 - private_data=(nil) [2021/02/04 06:04:21.044658, 5] ../../source3/lib/messages.c:771(messaging_deregister) Deregistering messaging pointer for type 1 - private_data=(nil) [2021/02/04 06:04:21.044703, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 1 - private_data=(nil) [2021/02/04 06:04:21.044801, 3] ../../source3/smbd/process.c:1956(process_smb) Transaction 0 of length 214 (0 toread) [2021/02/04 06:04:21.044871, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:21.044919, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:21.044963, 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 [2021/02/04 06:04:21.045035, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:21.045094, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:21.045141, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:21.045186, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:21.045231, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:21.045275, 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 [2021/02/04 06:04:21.045360, 5] ../../source3/lib/gencache.c:89(gencache_init) Opening cache file at /var/run/samba-lock/gencache.tdb [2021/02/04 06:04:21.045429, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:21.045498, 5] ../../lib/util/debug.c:811(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:21.046350, 3] ../../source3/smbd/smb2_negprot.c:293(smbd_smb2_request_process_negprot) Selected protocol SMB3_11 [2021/02/04 06:04:21.046639, 5] ../../source3/auth/auth.c:550(make_auth3_context_for_ntlm) Making default auth method list for server role = 'standalone server', encrypt passwords = yes [2021/02/04 06:04:21.046704, 5] ../../source3/auth/auth.c:52(smb_register_auth) Attempting to register auth backend anonymous [2021/02/04 06:04:21.046753, 5] ../../source3/auth/auth.c:64(smb_register_auth) Successfully added auth method 'anonymous' [2021/02/04 06:04:21.046798, 5] ../../source3/auth/auth.c:52(smb_register_auth) Attempting to register auth backend sam [2021/02/04 06:04:21.046843, 5] ../../source3/auth/auth.c:64(smb_register_auth) Successfully added auth method 'sam' [2021/02/04 06:04:21.046886, 5] ../../source3/auth/auth.c:52(smb_register_auth) Attempting to register auth backend sam_ignoredomain [2021/02/04 06:04:21.046962, 5] ../../source3/auth/auth.c:64(smb_register_auth) Successfully added auth method 'sam_ignoredomain' [2021/02/04 06:04:21.047020, 5] ../../source3/auth/auth.c:52(smb_register_auth) Attempting to register auth backend sam_netlogon3 [2021/02/04 06:04:21.047076, 5] ../../source3/auth/auth.c:64(smb_register_auth) Successfully added auth method 'sam_netlogon3' [2021/02/04 06:04:21.047121, 5] ../../source3/auth/auth.c:52(smb_register_auth) Attempting to register auth backend winbind [2021/02/04 06:04:21.047165, 5] ../../source3/auth/auth.c:64(smb_register_auth) Successfully added auth method 'winbind' [2021/02/04 06:04:21.047209, 5] ../../source3/auth/auth.c:52(smb_register_auth) Attempting to register auth backend unix [2021/02/04 06:04:21.047253, 5] ../../source3/auth/auth.c:64(smb_register_auth) Successfully added auth method 'unix' [2021/02/04 06:04:21.047297, 5] ../../source3/auth/auth.c:425(load_auth_module) load_auth_module: Attempting to find an auth method to match anonymous [2021/02/04 06:04:21.047342, 5] ../../source3/auth/auth.c:450(load_auth_module) load_auth_module: auth method anonymous has a valid init [2021/02/04 06:04:21.047386, 5] ../../source3/auth/auth.c:425(load_auth_module) load_auth_module: Attempting to find an auth method to match sam_ignoredomain [2021/02/04 06:04:21.047447, 5] ../../source3/auth/auth.c:450(load_auth_module) load_auth_module: auth method sam_ignoredomain has a valid init [2021/02/04 06:04:21.047910, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'gssapi_spnego' registered [2021/02/04 06:04:21.047982, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'gssapi_krb5' registered [2021/02/04 06:04:21.048029, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'gssapi_krb5_sasl' registered [2021/02/04 06:04:21.048084, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'spnego' registered [2021/02/04 06:04:21.048131, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'schannel' registered [2021/02/04 06:04:21.048177, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'naclrpc_as_system' registered [2021/02/04 06:04:21.048222, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'sasl-EXTERNAL' registered [2021/02/04 06:04:21.048276, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'ntlmssp' registered [2021/02/04 06:04:21.048323, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'ntlmssp_resume_ccache' registered [2021/02/04 06:04:21.048368, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'http_basic' registered [2021/02/04 06:04:21.048415, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'http_ntlm' registered [2021/02/04 06:04:21.048461, 3] ../../auth/gensec/gensec_start.c:1088(gensec_register) GENSEC backend 'http_negotiate' registered [2021/02/04 06:04:21.048562, 5] ../../auth/gensec/gensec_start.c:849(gensec_start_mech) Starting GENSEC mechanism spnego [2021/02/04 06:04:21.048664, 5] ../../auth/gensec/gensec_start.c:849(gensec_start_mech) Starting GENSEC submechanism ntlmssp [2021/02/04 06:04:22.356917, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.357035, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.357110, 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 [2021/02/04 06:04:22.357187, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.357275, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.358241, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.358308, 5] ../../source3/auth/auth.c:550(make_auth3_context_for_ntlm) Making default auth method list for server role = 'standalone server', encrypt passwords = yes [2021/02/04 06:04:22.358359, 5] ../../source3/auth/auth.c:425(load_auth_module) load_auth_module: Attempting to find an auth method to match anonymous [2021/02/04 06:04:22.358407, 5] ../../source3/auth/auth.c:450(load_auth_module) load_auth_module: auth method anonymous has a valid init [2021/02/04 06:04:22.358454, 5] ../../source3/auth/auth.c:425(load_auth_module) load_auth_module: Attempting to find an auth method to match sam_ignoredomain [2021/02/04 06:04:22.358500, 5] ../../source3/auth/auth.c:450(load_auth_module) load_auth_module: auth method sam_ignoredomain has a valid init [2021/02/04 06:04:22.358615, 5] ../../auth/gensec/gensec_start.c:849(gensec_start_mech) Starting GENSEC mechanism spnego [2021/02/04 06:04:22.358667, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.358723, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.358774, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.358823, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.358869, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.358936, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.358992, 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 [2021/02/04 06:04:22.359095, 5] ../../auth/gensec/gensec_start.c:849(gensec_start_mech) Starting GENSEC submechanism ntlmssp [2021/02/04 06:04:22.359152, 3] ../../auth/ntlmssp/ntlmssp_util.c:72(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0x62088215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY NTLMSSP_NEGOTIATE_VERSION NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2021/02/04 06:04:22.359429, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.359503, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.359553, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.359600, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.359646, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.359691, 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 [2021/02/04 06:04:22.359771, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.360039, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.360103, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.360153, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.360200, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.360246, 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 [2021/02/04 06:04:22.360320, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.360370, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.360423, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.360474, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.360521, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.360567, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.360613, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.360658, 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 [2021/02/04 06:04:22.360737, 3] ../../auth/ntlmssp/ntlmssp_server.c:509(ntlmssp_server_preauth) Got user=[caleb] domain=[WORKGROUP] workstation=[] len1=24 len2=234 [2021/02/04 06:04:22.360824, 5] ../../source3/auth/auth_util.c:123(make_user_info_map) Mapping user [WORKGROUP]\[caleb] from workstation [] [2021/02/04 06:04:22.360889, 5] ../../source3/auth/user_info.c:64(make_user_info) attempting to make a user_info for caleb (caleb) [2021/02/04 06:04:22.360936, 5] ../../source3/auth/user_info.c:72(make_user_info) making strings for caleb's user_info struct [2021/02/04 06:04:22.360983, 5] ../../source3/auth/user_info.c:117(make_user_info) making blobs for caleb's user_info struct [2021/02/04 06:04:22.361031, 3] ../../source3/auth/auth.c:201(auth_check_ntlm_password) check_ntlm_password: Checking password for unmapped user [WORKGROUP]\[caleb]@[] with the new password interface [2021/02/04 06:04:22.361078, 3] ../../source3/auth/auth.c:204(auth_check_ntlm_password) check_ntlm_password: mapped user is: [WORKGROUP]\[caleb]@[] [2021/02/04 06:04:22.361124, 5] ../../lib/util/util.c:570(dump_data) [0000] B7 B9 E0 80 BC BF 51 E9 ......Q. [2021/02/04 06:04:22.361180, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.361227, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:22.361273, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.361319, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.361364, 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 [2021/02/04 06:04:22.361464, 4] ../../source3/lib/substitute.c:345(automount_server) Home server: [2021/02/04 06:04:22.361531, 4] ../../source3/lib/substitute.c:345(automount_server) Home server: [2021/02/04 06:04:22.361596, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.361643, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2021/02/04 06:04:22.361688, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.361734, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.361778, 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 [2021/02/04 06:04:22.361879, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.361934, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.361981, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2021/02/04 06:04:22.362026, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.362071, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.362115, 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 [2021/02/04 06:04:22.362198, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.362247, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user caleb [2021/02/04 06:04:22.362292, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is caleb [2021/02/04 06:04:22.362345, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [caleb]! [2021/02/04 06:04:22.363243, 3] ../../source3/passdb/lookup_sid.c:1631(get_primary_group_sid) Forcing Primary Group to 'Domain Users' for caleb [2021/02/04 06:04:22.363303, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.363348, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2021/02/04 06:04:22.363392, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.363436, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.363479, 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 [2021/02/04 06:04:22.363589, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.363645, 4] ../../source3/lib/substitute.c:345(automount_server) Home server: [2021/02/04 06:04:22.363694, 4] ../../source3/lib/substitute.c:345(automount_server) Home server: [2021/02/04 06:04:22.363743, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.363789, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2021/02/04 06:04:22.363833, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.363876, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.363935, 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 [2021/02/04 06:04:22.364027, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.364084, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.364132, 4] ../../libcli/auth/ntlm_check.c:406(ntlm_password_check) ntlm_password_check: Checking NTLMv2 password with domain [WORKGROUP] [2021/02/04 06:04:22.364228, 4] ../../source3/auth/check_samsec.c:183(sam_account_ok) sam_account_ok: Checking SMB password for user caleb [2021/02/04 06:04:22.364277, 5] ../../source3/auth/check_samsec.c:164(logon_hours_ok) logon_hours_ok: user caleb allowed to logon at this time (Thu Feb 4 14:04:22 2021 ) [2021/02/04 06:04:22.364340, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.364384, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:22.364428, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.364472, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.364515, 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 [2021/02/04 06:04:22.364593, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.364640, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.364685, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:22.364728, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.364772, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.364815, 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 [2021/02/04 06:04:22.364884, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user caleb [2021/02/04 06:04:22.364928, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is caleb [2021/02/04 06:04:22.364973, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [caleb]! [2021/02/04 06:04:22.365036, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.365082, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2021/02/04 06:04:22.365139, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.365183, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.365231, 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 [2021/02/04 06:04:22.365308, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.365355, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.365400, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 2 [2021/02/04 06:04:22.365443, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3 [2021/02/04 06:04:22.365487, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.365562, 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 [2021/02/04 06:04:22.365652, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.365700, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user caleb [2021/02/04 06:04:22.365744, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is caleb [2021/02/04 06:04:22.365789, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [caleb]! [2021/02/04 06:04:22.366619, 5] ../../source3/auth/server_info_sam.c:121(make_server_info_sam) make_server_info_sam: made server info for user caleb -> caleb [2021/02/04 06:04:22.366680, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.366728, 3] ../../source3/auth/auth.c:268(auth_check_ntlm_password) auth_check_ntlm_password: sam_ignoredomain authentication for user [caleb] succeeded [2021/02/04 06:04:22.366777, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.366822, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:22.366866, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.366909, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.367001, 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 [2021/02/04 06:04:22.367082, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.367128, 5] ../../source3/auth/auth.c:295(auth_check_ntlm_password) check_ntlm_password: PAM Account for user [caleb] succeeded [2021/02/04 06:04:22.367209, 3] ../../auth/auth_log.c:635(log_authentication_event_human_readable) Auth: [SMB2,(null)] user [WORKGROUP]\[caleb] at [Thu, 04 Feb 2021 06:04:22.367176 PST] with [NTLMv2] status [NT_STATUS_OK] workstation [] remote host [ipv4:127.0.0.1:54206] became []\[caleb] [S-1-5-21-2479029229-654997156-3196209401-1001]. local host [ipv4:127.0.0.1:445] {"timestamp": "2021-02-04T06:04:22.367302-0800", "type": "Authentication", "Authentication": {"version": {"major": 1, "minor": 2}, "eventId": 4624, "logonId": "0", "logonType": 3, "status": "NT_STATUS_OK", "localAddress": "ipv4:127.0.0.1:445", "remoteAddress": "ipv4:127.0.0.1:54206", "serviceDescription": "SMB2", "authDescription": null, "clientDomain": "WORKGROUP", "clientAccount": "caleb", "workstation": "", "becameAccount": "caleb", "becameDomain": "", "becameSid": "S-1-5-21-2479029229-654997156-3196209401-1001", "mappedAccount": "caleb", "mappedDomain": "WORKGROUP", "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "NTLMv2", "duration": 8956}} [2021/02/04 06:04:22.367361, 2] ../../source3/auth/auth.c:324(auth_check_ntlm_password) check_ntlm_password: authentication for user [caleb] -> [caleb] -> [caleb] succeeded [2021/02/04 06:04:22.367408, 3] ../../source3/param/loadparm.c:3954(lp_load_ex) lp_load_ex: refreshing parameters [2021/02/04 06:04:22.367454, 5] ../../source3/param/loadparm.c:1380(free_param_opts) Freeing parametrics: [2021/02/04 06:04:22.367544, 3] ../../source3/param/loadparm.c:553(init_globals) Initialising global parameters [2021/02/04 06:04:22.367676, 3] ../../source3/param/loadparm.c:2856(lp_do_section) Processing section "[global]" doing parameter clustering = Yes doing parameter ctdb:registry.tdb = Yes doing parameter include = registry doing parameter registry shares = yes [2021/02/04 06:04:22.367817, 5] ../../source3/param/loadparm.c:2201(process_registry_service) process_registry_service: service name global [2021/02/04 06:04:22.368042, 3] ../../source3/param/loadparm.c:2856(lp_do_section) Processing section "[global]" doing parameter tn:sid = doing parameter guest account = nobody doing parameter create mask = 0775 doing parameter netbios aliases = doing parameter tn:admin_group = doing parameter workgroup = WORKGROUP doing parameter tn:netbiosname = truenas doing parameter server string = doing parameter syslog only = No [2021/02/04 06:04:22.368317, 1] ../../lib/param/loadparm.c:1871(lpcfg_do_global_parameter) lpcfg_do_global_parameter: WARNING: The "syslog only" option is deprecated doing parameter netbios name = doing parameter server min protocol = SMB2_02 doing parameter directory mask = 0775 doing parameter local master = No doing parameter logging = file doing parameter unix charset = UTF8 doing parameter tn:netbiosname_b = truenas-b doing parameter tn:fruit_enabled = No doing parameter ntlm auth = No doing parameter bind interfaces only = Yes doing parameter idmap config * : backend = autorid doing parameter idmap config * : range = 90000001 - 100000000 doing parameter log level = 5 [2021/02/04 06:04:22.368684, 5] ../../lib/util/debug.c:811(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:22.369507, 4] ../../source3/param/loadparm.c:3996(lp_load_ex) pm_process() returned Yes [2021/02/04 06:04:22.369595, 5] ../../source3/param/loadparm.c:2201(process_registry_service) process_registry_service: service name IPC$ [2021/02/04 06:04:22.369666, 3] ../../source3/param/loadparm.c:1657(lp_add_ipc) adding IPC service [2021/02/04 06:04:22.369725, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.369790, 3] ../../auth/ntlmssp/ntlmssp_sign.c:623(ntlmssp_sign_reset) NTLMSSP Sign/Seal - Initialising with flags: [2021/02/04 06:04:22.369835, 3] ../../auth/ntlmssp/ntlmssp_util.c:72(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0x62088215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY NTLMSSP_NEGOTIATE_VERSION NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2021/02/04 06:04:22.370062, 3] ../../auth/ntlmssp/ntlmssp_sign.c:623(ntlmssp_sign_reset) NTLMSSP Sign/Seal - Initialising with flags: [2021/02/04 06:04:22.370107, 3] ../../auth/ntlmssp/ntlmssp_util.c:72(debug_ntlmssp_flags) Got NTLMSSP neg_flags=0x62088215 NTLMSSP_NEGOTIATE_UNICODE NTLMSSP_REQUEST_TARGET NTLMSSP_NEGOTIATE_SIGN NTLMSSP_NEGOTIATE_NTLM NTLMSSP_NEGOTIATE_ALWAYS_SIGN NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY NTLMSSP_NEGOTIATE_VERSION NTLMSSP_NEGOTIATE_128 NTLMSSP_NEGOTIATE_KEY_EXCH [2021/02/04 06:04:22.370326, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.370371, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.370415, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.370459, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.370502, 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 [2021/02/04 06:04:22.370576, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.370684, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.370732, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.370776, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.370819, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.370863, 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 [2021/02/04 06:04:22.370970, 3] ../../source3/auth/token_util.c:688(finalize_local_nt_token) Failed to fetch domain sid for WORKGROUP [2021/02/04 06:04:22.371023, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.371084, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.371129, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.371173, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.371217, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.371265, 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 [2021/02/04 06:04:22.371384, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.371451, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-21-2479029229-654997156-3196209401-1001] [2021/02/04 06:04:22.371505, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-21-2479029229-654997156-3196209401-513] [2021/02/04 06:04:22.371566, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-22-2-1000] [2021/02/04 06:04:22.371643, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-22-2-0] [2021/02/04 06:04:22.371694, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-22-2-545] [2021/02/04 06:04:22.371742, 5] ../../source3/lib/privileges.c:179(get_privileges_for_sids) get_privileges_for_sids: sid = S-1-1-0 Privilege set: 0x0 [2021/02/04 06:04:22.371809, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-2] [2021/02/04 06:04:22.371856, 4] ../../source3/lib/privileges.c:99(get_privileges) get_privileges: No privileges assigned to SID [S-1-5-11] [2021/02/04 06:04:22.372123, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.372175, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.372220, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.372264, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.372308, 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 [2021/02/04 06:04:22.372381, 5] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid) lookup_global_sam_rid: looking up RID 513. [2021/02/04 06:04:22.372428, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.372472, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:22.372516, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.372560, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.372603, 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 [2021/02/04 06:04:22.372682, 5] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid) pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201. [2021/02/04 06:04:22.372740, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.372786, 5] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid) Can't find a unix id for an unmapped group [2021/02/04 06:04:22.372830, 5] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id) SID S-1-5-21-2479029229-654997156-3196209401-513 belongs to our domain, but there is no corresponding object in the database. [2021/02/04 06:04:22.372881, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.372927, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.372971, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:22.373015, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.373075, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.373132, 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 [2021/02/04 06:04:22.373202, 5] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid) lookup_global_sam_rid: looking up RID 513. [2021/02/04 06:04:22.373249, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.373293, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(0) : conn_ctx_stack_ndx = 1 [2021/02/04 06:04:22.373336, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2 [2021/02/04 06:04:22.373380, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.373423, 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 [2021/02/04 06:04:22.373496, 5] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid) pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201. [2021/02/04 06:04:22.373595, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:22.373641, 5] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid) Can't find a unix id for an unmapped group [2021/02/04 06:04:22.373685, 5] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id) SID S-1-5-21-2479029229-654997156-3196209401-513 belongs to our domain, but there is no corresponding object in the database. [2021/02/04 06:04:22.373735, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.373797, 4] ../../auth/auth_log.c:740(log_successful_authz_event_human_readable) Successful AuthZ: [SMB2,NTLMSSP] user []\[caleb] [S-1-5-21-2479029229-654997156-3196209401-1001] at [Thu, 04 Feb 2021 06:04:22.373788 PST] Remote host [ipv4:127.0.0.1:54206] local host [ipv4:127.0.0.1:445] {"timestamp": "2021-02-04T06:04:22.373852-0800", "type": "Authorization", "Authorization": {"version": {"major": 1, "minor": 1}, "localAddress": "ipv4:127.0.0.1:445", "remoteAddress": "ipv4:127.0.0.1:54206", "serviceDescription": "SMB2", "authType": "NTLMSSP", "domain": "", "account": "caleb", "sid": "S-1-5-21-2479029229-654997156-3196209401-1001", "sessionId": "45012e48-96ca-46e8-bd78-15eae4173d5d", "logonServer": "", "transportProtection": "SMB", "accountFlags": "0x00000010"}} [2021/02/04 06:04:22.373931, 5] ../../source3/lib/username.c:182(Get_Pwnam_alloc) Finding user caleb [2021/02/04 06:04:22.373978, 5] ../../source3/lib/username.c:121(Get_Pwnam_internals) Trying _Get_Pwnam(), username as lowercase is caleb [2021/02/04 06:04:22.374023, 5] ../../source3/lib/username.c:159(Get_Pwnam_internals) Get_Pwnam_internals did find user [caleb]! [2021/02/04 06:04:22.374072, 3] ../../source3/smbd/password.c:84(register_homes_share) Adding homes service for user 'caleb' using home directory: '/nonexistent' [2021/02/04 06:04:22.374148, 5] ../../source3/param/loadparm.c:2201(process_registry_service) process_registry_service: service name homes [2021/02/04 06:04:22.374213, 5] ../../lib/util/debug.c:811(debug_dump_status) INFO: Current debug levels: all: 5 tdb: 5 printdrivers: 5 lanman: 5 smb: 5 rpc_parse: 5 rpc_srv: 5 rpc_cli: 5 passdb: 5 sam: 5 auth: 5 winbind: 5 vfs: 5 idmap: 5 quota: 5 acls: 5 locking: 5 msdfs: 5 dmapi: 5 registry: 5 scavenger: 5 dns: 5 ldb: 5 tevent: 5 auth_audit: 5 auth_json_audit: 5 kerberos: 5 drs_repl: 5 smb2: 5 smb2_credits: 5 dsdb_audit: 5 dsdb_json_audit: 5 dsdb_password_audit: 5 dsdb_password_json_audit: 5 dsdb_transaction_audit: 5 dsdb_transaction_json_audit: 5 dsdb_group_audit: 5 dsdb_group_json_audit: 5 [2021/02/04 06:04:22.375126, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.375206, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.375294, 5] ../../libcli/smb/smb2_signing.c:173(smb2_signing_sign_pdu) signed SMB2 message [2021/02/04 06:04:22.375564, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.375628, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:22.375678, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.375722, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.375765, 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 [2021/02/04 06:04:22.375834, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.375891, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.376051, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.376118, 3] ../../lib/util/access.c:370(allow_access) Allowed connection from 127.0.0.1 (127.0.0.1) [2021/02/04 06:04:22.376185, 3] ../../source3/smbd/service.c:609(make_connection_snum) make_connection_snum: Connect path is '/tmp' for service [IPC$] [2021/02/04 06:04:22.376268, 3] ../../source3/smbd/vfs.c:115(vfs_init_default) Initialising default vfs hooks [2021/02/04 06:04:22.376318, 5] ../../source3/smbd/vfs.c:105(smb_register_vfs) Successfully added vfs backend '/[Default VFS]/' [2021/02/04 06:04:22.376363, 5] ../../source3/smbd/vfs.c:105(smb_register_vfs) Successfully added vfs backend 'vfs_not_implemented' [2021/02/04 06:04:22.376407, 5] ../../source3/smbd/vfs.c:105(smb_register_vfs) Successfully added vfs backend 'posixacl' [2021/02/04 06:04:22.376467, 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 [2021/02/04 06:04:22.376592, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.376656, 5] ../../libcli/security/security_token.c:51(security_token_debug) Security token SIDs (12): SID[ 0]: S-1-5-21-2479029229-654997156-3196209401-1001 SID[ 1]: S-1-5-21-2479029229-654997156-3196209401-513 SID[ 2]: S-1-22-2-1000 SID[ 3]: S-1-22-2-0 SID[ 4]: S-1-22-2-545 SID[ 5]: S-1-1-0 SID[ 6]: S-1-5-2 SID[ 7]: S-1-5-11 SID[ 8]: S-1-22-1-1000 SID[ 9]: S-1-22-2-90100000 SID[ 10]: S-1-22-2-90099991 SID[ 11]: S-1-22-2-90099983 Privileges (0x 0): Rights (0x 0): [2021/02/04 06:04:22.376985, 5] ../../source3/auth/token_util.c:873(debug_unix_user_token) UNIX token of user 1000 Primary group is 1000 and contains 6 supplementary groups Group[ 0]: 1000 Group[ 1]: 0 Group[ 2]: 545 Group[ 3]: 90100000 Group[ 4]: 90099991 Group[ 5]: 90099983 [2021/02/04 06:04:22.377222, 5] ../../source3/smbd/uid.c:293(print_impersonation_info) print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,1000), cwd=[/] [2021/02/04 06:04:22.377274, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.377318, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.377363, 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 [2021/02/04 06:04:22.377436, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.377494, 3] ../../source3/smbd/service.c:853(make_connection_snum) 127.0.0.1 (ipv4:127.0.0.1:54206) connect to service IPC$ initially as user caleb (uid=1000, gid=1000) (pid 2001215) [2021/02/04 06:04:22.377591, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.377643, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.377938, 5] ../../libcli/smb/smb2_signing.c:173(smb2_signing_sign_pdu) signed SMB2 message [2021/02/04 06:04:22.378083, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.378134, 5] ../../libcli/security/security_token.c:51(security_token_debug) Security token SIDs (12): SID[ 0]: S-1-5-21-2479029229-654997156-3196209401-1001 SID[ 1]: S-1-5-21-2479029229-654997156-3196209401-513 SID[ 2]: S-1-22-2-1000 SID[ 3]: S-1-22-2-0 SID[ 4]: S-1-22-2-545 SID[ 5]: S-1-1-0 SID[ 6]: S-1-5-2 SID[ 7]: S-1-5-11 SID[ 8]: S-1-22-1-1000 SID[ 9]: S-1-22-2-90100000 SID[ 10]: S-1-22-2-90099991 SID[ 11]: S-1-22-2-90099983 Privileges (0x 0): Rights (0x 0): [2021/02/04 06:04:22.378504, 5] ../../source3/auth/token_util.c:873(debug_unix_user_token) UNIX token of user 1000 Primary group is 1000 and contains 6 supplementary groups Group[ 0]: 1000 Group[ 1]: 0 Group[ 2]: 545 Group[ 3]: 90100000 Group[ 4]: 90099991 Group[ 5]: 90099983 [2021/02/04 06:04:22.378696, 4] ../../source3/smbd/vfs.c:936(vfs_ChDir) vfs_ChDir to /tmp [2021/02/04 06:04:22.378753, 5] ../../source3/smbd/vfs.c:998(vfs_ChDir) vfs_ChDir: vfs_ChDir got /tmp [2021/02/04 06:04:22.378805, 5] ../../source3/smbd/uid.c:293(print_impersonation_info) print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,1000), cwd=[/tmp] [2021/02/04 06:04:22.378853, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.378906, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.378986, 5] ../../source3/param/loadparm.c:2201(process_registry_service) process_registry_service: service name gluster-test [2021/02/04 06:04:22.379093, 2] ../../source3/param/loadparm.c:2873(lp_do_section) Processing section "[gluster-test]" doing parameter path = / doing parameter vfs objects = glusterfs doing parameter glusterfs:volume = gvol4 [2021/02/04 06:04:22.379232, 3] ../../source3/smbd/msdfs.c:1020(get_referred_path) get_referred_path: |gluster-test| in dfs path \127.0.0.1\gluster-test is not a dfs root. [2021/02/04 06:04:22.379282, 3] ../../source3/smbd/smb2_server.c:3872(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_NOT_FOUND] || at ../../source3/smbd/smb2_ioctl.c:318 [2021/02/04 06:04:22.379402, 5] ../../source3/smbd/uid.c:326(change_to_user_impersonate) change_to_user_impersonate: Skipping user change - already user [2021/02/04 06:04:22.379459, 5] ../../source3/smbd/uid.c:293(print_impersonation_info) print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,1000), cwd=[/tmp] [2021/02/04 06:04:22.379525, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.379572, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.379630, 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 [2021/02/04 06:04:22.379707, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.379765, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.379812, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.379856, 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 [2021/02/04 06:04:22.379928, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.379976, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.380021, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.380066, 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 [2021/02/04 06:04:22.380136, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.380183, 3] ../../source3/smbd/service.c:1124(close_cnum) 127.0.0.1 (ipv4:127.0.0.1:54206) closed connection to service IPC$ [2021/02/04 06:04:22.380230, 4] ../../source3/smbd/vfs.c:936(vfs_ChDir) vfs_ChDir to / [2021/02/04 06:04:22.380284, 5] ../../source3/smbd/vfs.c:998(vfs_ChDir) vfs_ChDir: vfs_ChDir got / [2021/02/04 06:04:22.380332, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.380378, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.380423, 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 [2021/02/04 06:04:22.380493, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.380544, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.380629, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.380750, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.380801, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.380846, 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 [2021/02/04 06:04:22.380917, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.380973, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.381567, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.381663, 3] ../../lib/util/access.c:370(allow_access) Allowed connection from 127.0.0.1 (127.0.0.1) [2021/02/04 06:04:22.381737, 3] ../../source3/smbd/service.c:609(make_connection_snum) make_connection_snum: Connect path is '/' for service [gluster-test] [2021/02/04 06:04:22.381802, 3] ../../source3/smbd/vfs.c:115(vfs_init_default) Initialising default vfs hooks [2021/02/04 06:04:22.381855, 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 [2021/02/04 06:04:22.381923, 3] ../../source3/smbd/vfs.c:141(vfs_init_custom) Initialising custom vfs hooks from [glusterfs] [2021/02/04 06:04:22.381970, 5] ../../source3/smbd/vfs.c:180(vfs_init_custom) vfs module [glusterfs] not loaded - trying to load... [2021/02/04 06:04:22.382021, 5] ../../lib/util/modules.c:158(load_module_absolute_path) load_module_absolute_path: Loading module '/usr/lib/x86_64-linux-gnu/samba/vfs/glusterfs.so' [2021/02/04 06:04:22.392276, 3] ../../lib/util/modules.c:167(load_module_absolute_path) load_module_absolute_path: Module '/usr/lib/x86_64-linux-gnu/samba/vfs/glusterfs.so' loaded [2021/02/04 06:04:22.392362, 5] ../../source3/smbd/vfs.c:105(smb_register_vfs) Successfully added vfs backend 'glusterfs' Successfully loaded vfs module [glusterfs] with the new modules system [2021/02/04 06:04:22.393165, 5] ../../source3/modules/vfs_glusterfs.c:171(vfs_gluster_set_volfile_servers) vfs_gluster_set_volfile_servers: servers list localhost [2021/02/04 06:04:22.393224, 5] ../../source3/modules/vfs_glusterfs.c:179(vfs_gluster_set_volfile_servers) vfs_gluster_set_volfile_servers: server 1 localhost [2021/02/04 06:04:22.393271, 5] ../../source3/modules/vfs_glusterfs.c:238(vfs_gluster_set_volfile_servers) vfs_gluster_set_volfile_servers: Calling set volfile server with params transport=tcp, host=localhost, port=0 [2021/02/04 06:04:22.429110, 0] ../../source3/modules/vfs_glusterfs.c:504(vfs_gluster_connect) vfs_gluster_connect: gvol4: Initialized volume from servers localhost [2021/02/04 06:04:22.429242, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 784 - private_data=0x560ad9b47600 [2021/02/04 06:04:22.429294, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 793 - private_data=0x560ad9a7a700 [2021/02/04 06:04:22.429342, 5] ../../source3/lib/messages.c:723(messaging_register) Registering messaging pointer for type 799 - private_data=0x560ad9a7a700 [2021/02/04 06:04:22.429476, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.429555, 5] ../../libcli/security/security_token.c:51(security_token_debug) Security token SIDs (12): SID[ 0]: S-1-5-21-2479029229-654997156-3196209401-1001 SID[ 1]: S-1-5-21-2479029229-654997156-3196209401-513 SID[ 2]: S-1-22-2-1000 SID[ 3]: S-1-22-2-0 SID[ 4]: S-1-22-2-545 SID[ 5]: S-1-1-0 SID[ 6]: S-1-5-2 SID[ 7]: S-1-5-11 SID[ 8]: S-1-22-1-1000 SID[ 9]: S-1-22-2-90100000 SID[ 10]: S-1-22-2-90099991 SID[ 11]: S-1-22-2-90099983 Privileges (0x 0): Rights (0x 0): [2021/02/04 06:04:22.429901, 5] ../../source3/auth/token_util.c:873(debug_unix_user_token) UNIX token of user 1000 Primary group is 1000 and contains 6 supplementary groups Group[ 0]: 1000 Group[ 1]: 0 Group[ 2]: 545 Group[ 3]: 90100000 Group[ 4]: 90099991 Group[ 5]: 90099983 [2021/02/04 06:04:22.430137, 5] ../../source3/smbd/uid.c:293(print_impersonation_info) print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,1000), cwd=[/] [2021/02/04 06:04:22.430196, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.430243, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.430289, 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 [2021/02/04 06:04:22.430366, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:22.430456, 2] ../../source3/smbd/service.c:853(make_connection_snum) 127.0.0.1 (ipv4:127.0.0.1:54206) connect to service gluster-test initially as user caleb (uid=1000, gid=1000) (pid 2001215) [2021/02/04 06:04:22.430516, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.430575, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:22.430635, 5] ../../libcli/smb/smb2_signing.c:173(smb2_signing_sign_pdu) signed SMB2 message [2021/02/04 06:04:22.531512, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:22.531603, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:22.531652, 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 [2021/02/04 06:04:22.531737, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:23.003291, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:23.003409, 5] ../../libcli/security/security_token.c:51(security_token_debug) Security token SIDs (12): SID[ 0]: S-1-5-21-2479029229-654997156-3196209401-1001 SID[ 1]: S-1-5-21-2479029229-654997156-3196209401-513 SID[ 2]: S-1-22-2-1000 SID[ 3]: S-1-22-2-0 SID[ 4]: S-1-22-2-545 SID[ 5]: S-1-1-0 SID[ 6]: S-1-5-2 SID[ 7]: S-1-5-11 SID[ 8]: S-1-22-1-1000 SID[ 9]: S-1-22-2-90100000 SID[ 10]: S-1-22-2-90099991 SID[ 11]: S-1-22-2-90099983 Privileges (0x 0): Rights (0x 0): [2021/02/04 06:04:23.003796, 5] ../../source3/auth/token_util.c:873(debug_unix_user_token) UNIX token of user 1000 Primary group is 1000 and contains 6 supplementary groups Group[ 0]: 1000 Group[ 1]: 0 Group[ 2]: 545 Group[ 3]: 90100000 Group[ 4]: 90099991 Group[ 5]: 90099983 [2021/02/04 06:04:23.004075, 5] ../../source3/smbd/uid.c:293(print_impersonation_info) print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,1000), cwd=[/] [2021/02/04 06:04:23.004151, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:23.004227, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:23.004409, 5] ../../source3/smbd/vfs.c:1492(check_reduced_name) check_reduced_name: . reduced to /. [2021/02/04 06:04:23.004471, 5] ../../source3/smbd/files.c:74(fsp_new) fsp_new: allocated files structure (1 used) [2021/02/04 06:04:23.004604, 5] ../../source3/smbd/vfs.c:1492(check_reduced_name) check_reduced_name: . reduced to /. [2021/02/04 06:04:23.004660, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(1000, 1000) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:23.004710, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(1238000322) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:23.004758, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:23.004806, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:23.004853, 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 [2021/02/04 06:04:23.005806, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:23.006241, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_open_global.tdb [2021/02/04 06:04:23.007091, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_open_global.tdb [2021/02/04 06:04:23.007169, 5] ../../source3/smbd/open.c:4480(open_directory) open_directory: opening directory ., access_mask = 0x81, share_access = 0x3 create_options = 0x1, create_disposition = 0x1, file_attributes = 0x10 [2021/02/04 06:04:23.010204, 5] ../../source3/smbd/vfs.c:1492(check_reduced_name) check_reduced_name: . reduced to /. [2021/02/04 06:04:23.012596, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for locking.tdb [2021/02/04 06:04:23.015930, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for locking.tdb [2021/02/04 06:04:23.016769, 5] ../../source3/smbd/dosmode.c:386(fget_ea_dos_attribute) fget_ea_dos_attribute: Cannot get attribute from EA on file .: Error = No data available [2021/02/04 06:04:23.016917, 5] ../../source3/smbd/dosmode.c:72(dos_mode_debug_print) dos_mode_debug_print: fdos_mode returning (0x10): "d" [2021/02/04 06:04:23.017085, 5] ../../source3/smbd/uid.c:326(change_to_user_impersonate) change_to_user_impersonate: Skipping user change - already user [2021/02/04 06:04:23.017342, 5] ../../source3/smbd/uid.c:293(print_impersonation_info) print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,1000), cwd=[/] [2021/02/04 06:04:23.017444, 5] ../../source3/smbd/dir.c:215(dptr_create) dptr_create: dir=. [2021/02/04 06:04:23.017501, 5] ../../source3/smbd/dir.c:316(dptr_create) dptr_create: creating new dirptr [0] for path [.], expect_close = 0 [2021/02/04 06:04:23.017822, 5] ../../source3/smbd/files.c:74(fsp_new) fsp_new: allocated files structure (2 used) [2021/02/04 06:04:23.017882, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(1000, 1000) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:23.017934, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(1238000322) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:23.017982, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:23.018031, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:23.018091, 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 [2021/02/04 06:04:23.018293, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:23.018391, 5] ../../source3/smbd/files.c:1045(file_free) freed files structure 0 (1 used) [2021/02/04 06:04:23.018451, 5] ../../source3/smbd/files.c:74(fsp_new) fsp_new: allocated files structure (2 used) [2021/02/04 06:04:23.018565, 4] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx) push_sec_ctx(1000, 1000) : sec_ctx_stack_ndx = 1 [2021/02/04 06:04:23.018620, 4] ../../source3/smbd/uid.c:561(push_conn_ctx) push_conn_ctx(1238000322) : conn_ctx_stack_ndx = 0 [2021/02/04 06:04:23.018671, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1 [2021/02/04 06:04:23.018719, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:23.018767, 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 [2021/02/04 06:04:23.018897, 4] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx) pop_sec_ctx (1000, 1000) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:23.019138, 5] ../../source3/smbd/files.c:1045(file_free) freed files structure 0 (1 used) [2021/02/04 06:04:23.021396, 3] ../../source3/smbd/smb2_server.c:3872(smbd_smb2_request_error_ex) smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_NO_SUCH_FILE] || at ../../source3/smbd/smb2_query_directory.c:160 [2021/02/04 06:04:23.021680, 5] ../../source3/smbd/uid.c:326(change_to_user_impersonate) change_to_user_impersonate: Skipping user change - already user [2021/02/04 06:04:23.021920, 5] ../../source3/smbd/uid.c:293(print_impersonation_info) print_impersonation_info: Impersonated user: uid=(1000,1000), gid=(0,1000), cwd=[/] [2021/02/04 06:04:23.022631, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for locking.tdb [2021/02/04 06:04:23.022718, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for locking.tdb [2021/02/04 06:04:23.022784, 5] ../../source3/smbd/dir.c:343(dptr_CloseDir) dptr_CloseDir: closing dptr key 0 [2021/02/04 06:04:23.022998, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_open_global.tdb [2021/02/04 06:04:23.023209, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_open_global.tdb [2021/02/04 06:04:23.023269, 5] ../../source3/smbd/files.c:1045(file_free) freed files structure 3891574191 (0 used) [2021/02/04 06:04:26.212520, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:26.212807, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:26.212874, 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 [2021/02/04 06:04:26.212973, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:26.213038, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:26.213092, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:26.213193, 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 [2021/02/04 06:04:26.213271, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:26.213324, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:26.213386, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:26.213449, 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 [2021/02/04 06:04:26.213532, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:26.213602, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:26.213648, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:26.213694, 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 [2021/02/04 06:04:26.213766, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:26.213815, 2] ../../source3/smbd/service.c:1124(close_cnum) 127.0.0.1 (ipv4:127.0.0.1:54206) closed connection to service gluster-test [2021/02/04 06:04:32.425900, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:32.425986, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:32.426036, 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 [2021/02/04 06:04:32.426114, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:32.426172, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:32.426248, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_tcon_global.tdb [2021/02/04 06:04:32.426305, 5] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock) dbwrap_lock_order_lock: check lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:32.426370, 5] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock) dbwrap_lock_order_unlock: release lock order 1 for smbXsrv_session_global.tdb [2021/02/04 06:04:32.426423, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:32.426471, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:32.426518, 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 [2021/02/04 06:04:32.426593, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:32.426646, 4] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2021/02/04 06:04:32.426694, 5] ../../libcli/security/security_token.c:47(security_token_debug) Security token: (NULL) [2021/02/04 06:04:32.426741, 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 [2021/02/04 06:04:32.426815, 5] ../../source3/smbd/uid.c:493(smbd_change_to_root_user) change_to_root_user: now uid=(0,0) gid=(0,0) [2021/02/04 06:04:32.426866, 5] ../../source3/lib/messages.c:771(messaging_deregister) Deregistering messaging pointer for type 784 - private_data=0x560ad9b47600 [2021/02/04 06:04:32.427057, 3] ../../source3/smbd/server_exit.c:239(exit_server_common) Server exit (NT_STATUS_END_OF_FILE)