Attachment 16490 Details for Bug 14636 – debug level 10 log of panic

debug level 10 log of panic

log.127.0.0.1.592878 (text/plain), 207.63 KB, created by Jeremy Allison on 2021-03-03 01:50:19 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jeremy Allison

Created: 2021-03-03 01:50:19 UTC

Size: 207.63 KB

patch

obsolete

>[2021/03/02 17:46:51.432461,  6, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2368(lp_file_list_changed)
>  lp_file_list_changed()
>  file /usr/local/samba/etc/smb.conf -> /usr/local/samba/etc/smb.conf  last mod_time: Tue Mar  2 16:23:56 2021
>  
>[2021/03/02 17:46:51.432517,  3, pid=592878, effective(0, 0), real(0, 0), class=locking] ../../source3/smbd/oplock.c:1427(init_oplocks)
>  init_oplocks: initializing messages.
>[2021/03/02 17:46:51.432536,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 774 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432553,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 778 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432566,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 770 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432581,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 801 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432595,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 787 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432610,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 779 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432625,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 15 - private_data=(nil)
>[2021/03/02 17:46:51.432637,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:739(messaging_register)
>  Overriding messaging pointer for type 15 - private_data=(nil)
>[2021/03/02 17:46:51.432654,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:771(messaging_deregister)
>  Deregistering messaging pointer for type 16 - private_data=(nil)
>[2021/03/02 17:46:51.432669,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 16 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432682,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:771(messaging_deregister)
>  Deregistering messaging pointer for type 33 - private_data=0x564b5f525300
>[2021/03/02 17:46:51.432696,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 33 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432709,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:771(messaging_deregister)
>  Deregistering messaging pointer for type 790 - private_data=(nil)
>[2021/03/02 17:46:51.432723,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 790 - private_data=0x564b5f542110
>[2021/03/02 17:46:51.432739,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:771(messaging_deregister)
>  Deregistering messaging pointer for type 791 - private_data=(nil)
>[2021/03/02 17:46:51.432753,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:771(messaging_deregister)
>  Deregistering messaging pointer for type 1 - private_data=(nil)
>[2021/03/02 17:46:51.432767,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/messages.c:723(messaging_register)
>  Registering messaging pointer for type 1 - private_data=(nil)
>[2021/03/02 17:46:51.432788, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/util_event.c:100(event_add_idle)
>  event_add_idle: idle_evt(keepalive) 0x564b5f525560
>[2021/03/02 17:46:51.432806, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/util_event.c:100(event_add_idle)
>  event_add_idle: idle_evt(deadtime) 0x564b5f5178c0
>[2021/03/02 17:46:51.432827, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/util_event.c:100(event_add_idle)
>  event_add_idle: idle_evt(housekeeping) 0x564b5f544a90
>[2021/03/02 17:46:51.432890, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/util_sock.c:179(read_smb_length_return_keepalive)
>  got smb length of 210
>[2021/03/02 17:46:51.432908,  6, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/process.c:1954(process_smb)
>  got message type 0x0 of len 0xd2
>[2021/03/02 17:46:51.432922,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/process.c:1956(process_smb)
>  Transaction 0 of length 214 (0 toread)
>[2021/03/02 17:46:51.432934, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:4449(smbd_smb2_process_negprot)
>  smbd_smb2_first_negprot: packet length 210
>[2021/03/02 17:46:51.432967, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:712(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 0 (position 0) from bitmap
>[2021/03/02 17:46:51.432985, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:2916(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_NEGPROT] mid = 0
>[2021/03/02 17:46:51.433007,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.433028,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.433048,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.433080,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:493(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2021/03/02 17:46:51.433110,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.433129,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.433143,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.433157,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.433169,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.433216,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.433230, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/util.c:1208(set_remote_arch)
>  set_remote_arch: Client arch is 'Vista'
>[2021/03/02 17:46:51.433253,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/util/debug.c:811(debug_dump_status)
>  INFO: Current debug levels:
>    all: 10
>    tdb: 10
>    printdrivers: 10
>    lanman: 10
>    smb: 10
>    rpc_parse: 10
>    rpc_srv: 10
>    rpc_cli: 10
>    passdb: 10
>    sam: 10
>    auth: 10
>    winbind: 10
>    vfs: 10
>    idmap: 10
>    quota: 10
>    acls: 10
>    locking: 10
>    msdfs: 10
>    dmapi: 10
>    registry: 10
>    scavenger: 10
>    dns: 10
>    ldb: 10
>    tevent: 10
>    auth_audit: 10
>    auth_json_audit: 10
>    kerberos: 10
>    drs_repl: 10
>    smb2: 10
>    smb2_credits: 10
>    dsdb_audit: 10
>    dsdb_json_audit: 10
>    dsdb_password_audit: 10
>    dsdb_password_json_audit: 10
>    dsdb_transaction_audit: 10
>    dsdb_transaction_json_audit: 10
>    dsdb_group_audit: 10
>    dsdb_group_json_audit: 10
>[2021/03/02 17:46:51.433479,  6, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2368(lp_file_list_changed)
>  lp_file_list_changed()
>  file /usr/local/samba/etc/smb.conf -> /usr/local/samba/etc/smb.conf  last mod_time: Tue Mar  2 16:23:56 2021
>  
>[2021/03/02 17:46:51.433511,  3, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_negprot.c:293(smbd_smb2_request_process_negprot)
>  Selected protocol SMB3_11
>[2021/03/02 17:46:51.433532,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:550(make_auth3_context_for_ntlm)
>  Making default auth method list for server role = 'standalone server', encrypt passwords = yes
>[2021/03/02 17:46:51.433559,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend anonymous
>[2021/03/02 17:46:51.433578,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'anonymous'
>[2021/03/02 17:46:51.433593,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend name_to_ntstatus
>[2021/03/02 17:46:51.433607,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'name_to_ntstatus'
>[2021/03/02 17:46:51.433621,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend sam
>[2021/03/02 17:46:51.433635,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'sam'
>[2021/03/02 17:46:51.433648,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend sam_ignoredomain
>[2021/03/02 17:46:51.433661,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'sam_ignoredomain'
>[2021/03/02 17:46:51.433673,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend sam_netlogon3
>[2021/03/02 17:46:51.433685,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'sam_netlogon3'
>[2021/03/02 17:46:51.433697,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend winbind
>[2021/03/02 17:46:51.433710,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'winbind'
>[2021/03/02 17:46:51.433723,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend unix
>[2021/03/02 17:46:51.433736,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'unix'
>[2021/03/02 17:46:51.433748,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:52(smb_register_auth)
>  Attempting to register auth backend samba4
>[2021/03/02 17:46:51.433766,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:64(smb_register_auth)
>  Successfully added auth method 'samba4'
>[2021/03/02 17:46:51.433779,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:425(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match anonymous
>[2021/03/02 17:46:51.433793,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:450(load_auth_module)
>  load_auth_module: auth method anonymous has a valid init
>[2021/03/02 17:46:51.433810,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:425(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam_ignoredomain
>[2021/03/02 17:46:51.433826,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:450(load_auth_module)
>  load_auth_module: auth method sam_ignoredomain has a valid init
>[2021/03/02 17:46:51.441822,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'gssapi_spnego' registered
>[2021/03/02 17:46:51.441846,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'gssapi_krb5' registered
>[2021/03/02 17:46:51.441861,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'gssapi_krb5_sasl' registered
>[2021/03/02 17:46:51.441875,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'spnego' registered
>[2021/03/02 17:46:51.441890,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'schannel' registered
>[2021/03/02 17:46:51.441905,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'naclrpc_as_system' registered
>[2021/03/02 17:46:51.441920,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'sasl-EXTERNAL' registered
>[2021/03/02 17:46:51.441935,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'ntlmssp' registered
>[2021/03/02 17:46:51.441951,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'ntlmssp_resume_ccache' registered
>[2021/03/02 17:46:51.441966,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'http_basic' registered
>[2021/03/02 17:46:51.441981,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'http_ntlm' registered
>[2021/03/02 17:46:51.441996,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'http_negotiate' registered
>[2021/03/02 17:46:51.442013,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'krb5' registered
>[2021/03/02 17:46:51.442027,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:1088(gensec_register)
>  GENSEC backend 'fake_gssapi_krb5' registered
>[2021/03/02 17:46:51.442125,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:849(gensec_start_mech)
>  Starting GENSEC mechanism spnego
>[2021/03/02 17:46:51.442194,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:849(gensec_start_mech)
>  Starting GENSEC submechanism ntlmssp
>[2021/03/02 17:46:51.442239, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:455(gensec_update_send)
>  gensec_update_send: spnego[0x564b5f555c00]: subreq: 0x564b5f55a4b0
>[2021/03/02 17:46:51.442259, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:547(gensec_update_done)
>  gensec_update_done: spnego[0x564b5f555c00]: NT_STATUS_MORE_PROCESSING_REQUIRED tevent_req[0x564b5f55a4b0/../../auth/gensec/spnego.c:1631]: state[2] error[0 (0x0)]  state[struct gensec_spnego_update_state (0x564b5f55a660)] timer[(nil)] finish[../../auth/gensec/spnego.c:2116]
>[2021/03/02 17:46:51.442324, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:3758(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: mid [0] idx[1] status[NT_STATUS_OK] body[64] dyn[yes:140] at ../../source3/smbd/smb2_negprot.c:667
>[2021/03/02 17:46:51.442353, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:969(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 31, charge 1, granted 1, current possible/max 8192/8192, total granted/max/low/range 1/8192/1/1
>[2021/03/02 17:46:51.445399, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:4900(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2021/03/02 17:46:51.445419, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:712(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 1 (position 1) from bitmap
>[2021/03/02 17:46:51.445434, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:2916(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_SESSSETUP] mid = 1
>[2021/03/02 17:46:51.445450,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.445465,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.445479,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.445504,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:493(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2021/03/02 17:46:51.445536,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock)
>  dbwrap_lock_order_lock: check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.445557, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:131(debug_lock_order)
>  lock order:  1:/usr/local/samba/var/lock/smbXsrv_session_global.tdb 2:<none> 3:<none> 4:<none>
>[2021/03/02 17:46:51.445575, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Locking key 28E211F9
>[2021/03/02 17:46:51.445597, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal)
>  db_tdb_fetch_locked_internal: Allocated locked data 0x564b5f547f70
>[2021/03/02 17:46:51.445636, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:452(dbwrap_watched_subrec_wakeup_fn)
>  dbwrap_watched_subrec_wakeup_fn: No watchers
>[2021/03/02 17:46:51.445677, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:949(smbXsrv_session_global_store)
>[2021/03/02 17:46:51.445689, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:950(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key '28E211F9' stored
>[2021/03/02 17:46:51.445703,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &global_blob: struct smbXsrv_session_globalB
>          version                  : SMBXSRV_VERSION_0 (0)
>          seqnum                   : 0x00000001 (1)
>          info                     : union smbXsrv_session_globalU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session_global0
>                  db_rec                   : *
>                  session_global_id        : 0x28e211f9 (685904377)
>                  session_wire_id          : 0x0000000028e211f9 (685904377)
>                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                  expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0311 (785)
>                  signing_flags            : 0x00 (0)
>                         0: SMBXSRV_SIGNING_REQUIRED 
>                         0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                         0: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                  encryption_flags         : 0x00 (0)
>                         0: SMBXSRV_ENCRYPTION_REQUIRED
>                         0: SMBXSRV_ENCRYPTION_DESIRED
>                         0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                         0: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                  signing_key              : NULL
>                  encryption_key           : NULL
>                  decryption_key           : NULL
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x0000000000090bee (592878)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                          channel_id               : 0x0000000000000000 (0)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          local_address            : 'ipv4:127.0.0.1:445'
>                          remote_address           : 'ipv4:127.0.0.1:46096'
>                          remote_name              : '127.0.0.1'
>                          signing_key              : NULL
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2021/03/02 17:46:51.445948,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock)
>  dbwrap_lock_order_unlock: release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.445962, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Unlocking key 28E211F9
>[2021/03/02 17:46:51.445976, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1325(smbXsrv_session_create)
>[2021/03/02 17:46:51.445986, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1331(smbXsrv_session_create)
>  smbXsrv_session_create: global_id (0x28e211f9) stored
>[2021/03/02 17:46:51.445999,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &session_blob: struct smbXsrv_sessionB
>          version                  : SMBXSRV_VERSION_0 (0)
>          reserved                 : 0x00000000 (0)
>          info                     : union smbXsrv_sessionU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session
>                  table                    : *
>                  db_rec                   : NULL
>                  client                   : *
>                  local_id                 : 0x28e211f9 (685904377)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0x28e211f9 (685904377)
>                          session_wire_id          : 0x0000000028e211f9 (685904377)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0311 (785)
>                          signing_flags            : 0x00 (0)
>                                 0: SMBXSRV_SIGNING_REQUIRED 
>                                 0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                                 0: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                          encryption_flags         : 0x00 (0)
>                                 0: SMBXSRV_ENCRYPTION_REQUIRED
>                                 0: SMBXSRV_ENCRYPTION_DESIRED
>                                 0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                                 0: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                          signing_key              : NULL
>                          encryption_key           : NULL
>                          decryption_key           : NULL
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x0000000000090bee (592878)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                                  channel_id               : 0x0000000000000000 (0)
>                                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                                  local_address            : 'ipv4:127.0.0.1:445'
>                                  remote_address           : 'ipv4:127.0.0.1:46096'
>                                  remote_name              : '127.0.0.1'
>                                  signing_key              : NULL
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Tue Mar  2 05:46:51 PM 2021 PST
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  tcon_table               : *
>                  homes_snum               : 0xffffffff (4294967295)
>                  pending_auth             : NULL
>[2021/03/02 17:46:51.446338,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:550(make_auth3_context_for_ntlm)
>  Making default auth method list for server role = 'standalone server', encrypt passwords = yes
>[2021/03/02 17:46:51.446363,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:425(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match anonymous
>[2021/03/02 17:46:51.446379,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:450(load_auth_module)
>  load_auth_module: auth method anonymous has a valid init
>[2021/03/02 17:46:51.446394,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:425(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam_ignoredomain
>[2021/03/02 17:46:51.446409,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:450(load_auth_module)
>  load_auth_module: auth method sam_ignoredomain has a valid init
>[2021/03/02 17:46:51.446490,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:849(gensec_start_mech)
>  Starting GENSEC mechanism spnego
>[2021/03/02 17:46:51.446507,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock)
>  dbwrap_lock_order_lock: check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.446525, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:131(debug_lock_order)
>  lock order:  1:/usr/local/samba/var/lock/smbXsrv_session_global.tdb 2:<none> 3:<none> 4:<none>
>[2021/03/02 17:46:51.446543, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Locking key 28E211F9
>[2021/03/02 17:46:51.446558, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal)
>  db_tdb_fetch_locked_internal: Allocated locked data 0x564b5f53a930
>[2021/03/02 17:46:51.446586, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:452(dbwrap_watched_subrec_wakeup_fn)
>  dbwrap_watched_subrec_wakeup_fn: No watchers
>[2021/03/02 17:46:51.446603, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:949(smbXsrv_session_global_store)
>[2021/03/02 17:46:51.446613, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:950(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key '28E211F9' stored
>[2021/03/02 17:46:51.446627,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &global_blob: struct smbXsrv_session_globalB
>          version                  : SMBXSRV_VERSION_0 (0)
>          seqnum                   : 0x00000002 (2)
>          info                     : union smbXsrv_session_globalU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session_global0
>                  db_rec                   : *
>                  session_global_id        : 0x28e211f9 (685904377)
>                  session_wire_id          : 0x0000000028e211f9 (685904377)
>                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                  expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0311 (785)
>                  signing_flags            : 0x00 (0)
>                         0: SMBXSRV_SIGNING_REQUIRED 
>                         0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                         0: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                  encryption_flags         : 0x00 (0)
>                         0: SMBXSRV_ENCRYPTION_REQUIRED
>                         0: SMBXSRV_ENCRYPTION_DESIRED
>                         0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                         0: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                  signing_key              : NULL
>                  encryption_key           : NULL
>                  decryption_key           : NULL
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x0000000000090bee (592878)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                          channel_id               : 0x0000000000000000 (0)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          local_address            : 'ipv4:127.0.0.1:445'
>                          remote_address           : 'ipv4:127.0.0.1:46096'
>                          remote_name              : '127.0.0.1'
>                          signing_key              : NULL
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2021/03/02 17:46:51.447751,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock)
>  dbwrap_lock_order_unlock: release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.447768, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Unlocking key 28E211F9
>[2021/03/02 17:46:51.447782, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1422(smbXsrv_session_update)
>[2021/03/02 17:46:51.447792, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1428(smbXsrv_session_update)
>  smbXsrv_session_update: global_id (0x28e211f9) stored
>[2021/03/02 17:46:51.447804,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &session_blob: struct smbXsrv_sessionB
>          version                  : SMBXSRV_VERSION_0 (0)
>          reserved                 : 0x00000000 (0)
>          info                     : union smbXsrv_sessionU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session
>                  table                    : *
>                  db_rec                   : NULL
>                  client                   : *
>                  local_id                 : 0x28e211f9 (685904377)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0x28e211f9 (685904377)
>                          session_wire_id          : 0x0000000028e211f9 (685904377)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0311 (785)
>                          signing_flags            : 0x00 (0)
>                                 0: SMBXSRV_SIGNING_REQUIRED 
>                                 0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                                 0: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                          encryption_flags         : 0x00 (0)
>                                 0: SMBXSRV_ENCRYPTION_REQUIRED
>                                 0: SMBXSRV_ENCRYPTION_DESIRED
>                                 0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                                 0: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                          signing_key              : NULL
>                          encryption_key           : NULL
>                          decryption_key           : NULL
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x0000000000090bee (592878)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                                  channel_id               : 0x0000000000000000 (0)
>                                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                                  local_address            : 'ipv4:127.0.0.1:445'
>                                  remote_address           : 'ipv4:127.0.0.1:46096'
>                                  remote_name              : '127.0.0.1'
>                                  signing_key              : NULL
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Tue Mar  2 05:46:51 PM 2021 PST
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  tcon_table               : *
>                  homes_snum               : 0xffffffff (4294967295)
>                  pending_auth             : *
>                      pending_auth: struct smbXsrv_session_auth0
>                          prev                     : *
>                          next                     : NULL
>                          session                  : *
>                          connection               : *
>                          gensec                   : *
>                          preauth                  : *
>                          in_flags                 : 0x00 (0)
>                          in_security_mode         : 0x01 (1)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          idle_time                : Tue Mar  2 05:46:51 PM 2021 PST
>                          channel_id               : 0x0000000000000000 (0)
>[2021/03/02 17:46:51.448205,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.448219,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.448233,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.448246,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.448259,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.448334,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec_start.c:849(gensec_start_mech)
>  Starting GENSEC submechanism ntlmssp
>[2021/03/02 17:46:51.448356,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../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/03/02 17:46:51.448645,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       negotiate: struct NEGOTIATE_MESSAGE
>          Signature                : 'NTLMSSP'
>          MessageType              : NtLmNegotiate (1)
>          NegotiateFlags           : 0x62088215 (1644724757)
>                 1: NTLMSSP_NEGOTIATE_UNICODE
>                 0: NTLMSSP_NEGOTIATE_OEM    
>                 1: NTLMSSP_REQUEST_TARGET   
>                 1: NTLMSSP_NEGOTIATE_SIGN   
>                 0: NTLMSSP_NEGOTIATE_SEAL   
>                 0: NTLMSSP_NEGOTIATE_DATAGRAM
>                 0: NTLMSSP_NEGOTIATE_LM_KEY 
>                 0: NTLMSSP_NEGOTIATE_NETWARE
>                 1: NTLMSSP_NEGOTIATE_NTLM   
>                 0: NTLMSSP_NEGOTIATE_NT_ONLY
>                 0: NTLMSSP_ANONYMOUS        
>                 0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED
>                 0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED
>                 0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL
>                 1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
>                 0: NTLMSSP_TARGET_TYPE_DOMAIN
>                 0: NTLMSSP_TARGET_TYPE_SERVER
>                 0: NTLMSSP_TARGET_TYPE_SHARE
>                 1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
>                 0: NTLMSSP_NEGOTIATE_IDENTIFY
>                 0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY
>                 0: NTLMSSP_NEGOTIATE_TARGET_INFO
>                 1: NTLMSSP_NEGOTIATE_VERSION
>                 1: NTLMSSP_NEGOTIATE_128    
>                 1: NTLMSSP_NEGOTIATE_KEY_EXCH
>                 0: NTLMSSP_NEGOTIATE_56     
>          DomainNameLen            : 0x0000 (0)
>          DomainNameMaxLen         : 0x0000 (0)
>          DomainName               : *
>              DomainName               : ''
>          WorkstationLen           : 0x0000 (0)
>          WorkstationMaxLen        : 0x0000 (0)
>          Workstation              : *
>              Workstation              : ''
>          Version: struct ntlmssp_VERSION
>              ProductMajorVersion      : NTLMSSP_WINDOWS_MAJOR_VERSION_6 (6)
>              ProductMinorVersion      : NTLMSSP_WINDOWS_MINOR_VERSION_1 (1)
>              ProductBuild             : 0x0000 (0)
>              Reserved: ARRAY(3)
>                  [0]                      : 0x00 (0)
>                  [1]                      : 0x00 (0)
>                  [2]                      : 0x00 (0)
>              NTLMRevisionCurrent      : NTLMSSP_REVISION_W2K3 (15)
>[2021/03/02 17:46:51.448929,  6, pid=592878, effective(0, 0), real(0, 0)] ../../librpc/ndr/ndr_string.c:178(ndr_pull_string)
>  short string '', sent with NULL termination despite NOTERM flag in IDL
>[2021/03/02 17:46:51.448951,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       challenge: struct CHALLENGE_MESSAGE
>          Signature                : 'NTLMSSP'
>          MessageType              : NtLmChallenge (0x2)
>          TargetNameLen            : 0x0018 (24)
>          TargetNameMaxLen         : 0x0018 (24)
>          TargetName               : *
>              TargetName               : 'JEREMY-ACERB'
>          NegotiateFlags           : 0x628a8215 (1653244437)
>                 1: NTLMSSP_NEGOTIATE_UNICODE
>                 0: NTLMSSP_NEGOTIATE_OEM    
>                 1: NTLMSSP_REQUEST_TARGET   
>                 1: NTLMSSP_NEGOTIATE_SIGN   
>                 0: NTLMSSP_NEGOTIATE_SEAL   
>                 0: NTLMSSP_NEGOTIATE_DATAGRAM
>                 0: NTLMSSP_NEGOTIATE_LM_KEY 
>                 0: NTLMSSP_NEGOTIATE_NETWARE
>                 1: NTLMSSP_NEGOTIATE_NTLM   
>                 0: NTLMSSP_NEGOTIATE_NT_ONLY
>                 0: NTLMSSP_ANONYMOUS        
>                 0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED
>                 0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED
>                 0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL
>                 1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
>                 0: NTLMSSP_TARGET_TYPE_DOMAIN
>                 1: NTLMSSP_TARGET_TYPE_SERVER
>                 0: NTLMSSP_TARGET_TYPE_SHARE
>                 1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
>                 0: NTLMSSP_NEGOTIATE_IDENTIFY
>                 0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY
>                 1: NTLMSSP_NEGOTIATE_TARGET_INFO
>                 1: NTLMSSP_NEGOTIATE_VERSION
>                 1: NTLMSSP_NEGOTIATE_128    
>                 1: NTLMSSP_NEGOTIATE_KEY_EXCH
>                 0: NTLMSSP_NEGOTIATE_56     
>          ServerChallenge          : 2889ce1dd5f66a5c
>          Reserved                 : 0000000000000000
>          TargetInfoLen            : 0x0068 (104)
>          TargetInfoMaxLen         : 0x0068 (104)
>          TargetInfo               : *
>              TargetInfo: struct AV_PAIR_LIST
>                  count                    : 0x00000006 (6)
>                  pair: ARRAY(6)
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvNbDomainName (0x2)
>                          AvLen                    : 0x0018 (24)
>                          Value                    : union ntlmssp_AvValue(case 0x2)
>                          AvNbDomainName           : 'JEREMY-ACERB'
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvNbComputerName (0x1)
>                          AvLen                    : 0x0018 (24)
>                          Value                    : union ntlmssp_AvValue(case 0x1)
>                          AvNbComputerName         : 'JEREMY-ACERB'
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvDnsDomainName (0x4)
>                          AvLen                    : 0x0002 (2)
>                          Value                    : union ntlmssp_AvValue(case 0x4)
>                          AvDnsDomainName          : ''
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvDnsComputerName (0x3)
>                          AvLen                    : 0x0016 (22)
>                          Value                    : union ntlmssp_AvValue(case 0x3)
>                          AvDnsComputerName        : 'jeremy-acer'
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvTimestamp (0x7)
>                          AvLen                    : 0x0008 (8)
>                          Value                    : union ntlmssp_AvValue(case 0x7)
>                          AvTimestamp              : Tue Mar  2 05:46:51 PM 2021 PST
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvEOL (0x0)
>                          AvLen                    : 0x0000 (0)
>                          Value                    : union ntlmssp_AvValue(case 0x0)
>          Version: struct ntlmssp_VERSION
>              ProductMajorVersion      : NTLMSSP_WINDOWS_MAJOR_VERSION_6 (0x6)
>              ProductMinorVersion      : NTLMSSP_WINDOWS_MINOR_VERSION_1 (0x1)
>              ProductBuild             : 0x0000 (0)
>              Reserved                 : 000000
>              NTLMRevisionCurrent      : NTLMSSP_REVISION_W2K3 (0xF)
>[2021/03/02 17:46:51.449330, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:455(gensec_update_send)
>  gensec_update_send: ntlmssp[0x564b5f559f80]: subreq: 0x564b5f546060
>[2021/03/02 17:46:51.449346, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:455(gensec_update_send)
>  gensec_update_send: spnego[0x564b5f56bd40]: subreq: 0x564b5f578030
>[2021/03/02 17:46:51.449368,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.449399, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:547(gensec_update_done)
>  gensec_update_done: ntlmssp[0x564b5f559f80]: NT_STATUS_MORE_PROCESSING_REQUIRED tevent_req[0x564b5f546060/../../auth/ntlmssp/ntlmssp.c:180]: state[2] error[0 (0x0)]  state[struct gensec_ntlmssp_update_state (0x564b5f546210)] timer[(nil)] finish[../../auth/ntlmssp/ntlmssp.c:215]
>[2021/03/02 17:46:51.449433, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:547(gensec_update_done)
>  gensec_update_done: spnego[0x564b5f56bd40]: NT_STATUS_MORE_PROCESSING_REQUIRED tevent_req[0x564b5f578030/../../auth/gensec/spnego.c:1631]: state[2] error[0 (0x0)]  state[struct gensec_spnego_update_state (0x564b5f5781e0)] timer[(nil)] finish[../../auth/gensec/spnego.c:2116]
>[2021/03/02 17:46:51.449452,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.449466,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.449479,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.449492,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.449508,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.449538,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.449555, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:3758(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: mid [1] idx[1] status[NT_STATUS_MORE_PROCESSING_REQUIRED] body[8] dyn[yes:215] at ../../source3/smbd/smb2_sesssetup.c:183
>[2021/03/02 17:46:51.449573, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:969(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 8192, charge 1, granted 1, current possible/max 8192/8192, total granted/max/low/range 1/8192/2/1
>[2021/03/02 17:46:51.451498, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:4900(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2021/03/02 17:46:51.451517, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2_credits] ../../source3/smbd/smb2_server.c:712(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 2 (position 2) from bitmap
>[2021/03/02 17:46:51.451532, 10, pid=592878, effective(0, 0), real(0, 0), class=smb2] ../../source3/smbd/smb2_server.c:2916(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_SESSSETUP] mid = 2
>[2021/03/02 17:46:51.451548,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock)
>  dbwrap_lock_order_lock: check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.451561, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:131(debug_lock_order)
>  lock order:  1:/usr/local/samba/var/lock/smbXsrv_session_global.tdb 2:<none> 3:<none> 4:<none>
>[2021/03/02 17:46:51.451577, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Locking key 28E211F9
>[2021/03/02 17:46:51.451592, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal)
>  db_tdb_fetch_locked_internal: Allocated locked data 0x564b5f53a930
>[2021/03/02 17:46:51.451617, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:452(dbwrap_watched_subrec_wakeup_fn)
>  dbwrap_watched_subrec_wakeup_fn: No watchers
>[2021/03/02 17:46:51.451634, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:949(smbXsrv_session_global_store)
>[2021/03/02 17:46:51.451645, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:950(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key '28E211F9' stored
>[2021/03/02 17:46:51.451660,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &global_blob: struct smbXsrv_session_globalB
>          version                  : SMBXSRV_VERSION_0 (0)
>          seqnum                   : 0x00000003 (3)
>          info                     : union smbXsrv_session_globalU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session_global0
>                  db_rec                   : *
>                  session_global_id        : 0x28e211f9 (685904377)
>                  session_wire_id          : 0x0000000028e211f9 (685904377)
>                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                  expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0311 (785)
>                  signing_flags            : 0x04 (4)
>                         0: SMBXSRV_SIGNING_REQUIRED 
>                         0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                         1: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                  encryption_flags         : 0x08 (8)
>                         0: SMBXSRV_ENCRYPTION_REQUIRED
>                         0: SMBXSRV_ENCRYPTION_DESIRED
>                         0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                         1: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                  signing_key              : NULL
>                  encryption_key           : NULL
>                  decryption_key           : NULL
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x0000000000090bee (592878)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                          channel_id               : 0x0000000000000000 (0)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          local_address            : 'ipv4:127.0.0.1:445'
>                          remote_address           : 'ipv4:127.0.0.1:46096'
>                          remote_name              : '127.0.0.1'
>                          signing_key              : NULL
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2021/03/02 17:46:51.451904,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock)
>  dbwrap_lock_order_unlock: release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.451920, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Unlocking key 28E211F9
>[2021/03/02 17:46:51.451934, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1422(smbXsrv_session_update)
>[2021/03/02 17:46:51.451944, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1428(smbXsrv_session_update)
>  smbXsrv_session_update: global_id (0x28e211f9) stored
>[2021/03/02 17:46:51.451956,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &session_blob: struct smbXsrv_sessionB
>          version                  : SMBXSRV_VERSION_0 (0)
>          reserved                 : 0x00000000 (0)
>          info                     : union smbXsrv_sessionU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session
>                  table                    : *
>                  db_rec                   : NULL
>                  client                   : *
>                  local_id                 : 0x28e211f9 (685904377)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0x28e211f9 (685904377)
>                          session_wire_id          : 0x0000000028e211f9 (685904377)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0311 (785)
>                          signing_flags            : 0x04 (4)
>                                 0: SMBXSRV_SIGNING_REQUIRED 
>                                 0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                                 1: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                          encryption_flags         : 0x08 (8)
>                                 0: SMBXSRV_ENCRYPTION_REQUIRED
>                                 0: SMBXSRV_ENCRYPTION_DESIRED
>                                 0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                                 1: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                          signing_key              : NULL
>                          encryption_key           : NULL
>                          decryption_key           : NULL
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x0000000000090bee (592878)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                                  channel_id               : 0x0000000000000000 (0)
>                                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                                  local_address            : 'ipv4:127.0.0.1:445'
>                                  remote_address           : 'ipv4:127.0.0.1:46096'
>                                  remote_name              : '127.0.0.1'
>                                  signing_key              : NULL
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Tue Mar  2 05:46:51 PM 2021 PST
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  tcon_table               : *
>                  homes_snum               : 0xffffffff (4294967295)
>                  pending_auth             : *
>                      pending_auth: struct smbXsrv_session_auth0
>                          prev                     : *
>                          next                     : NULL
>                          session                  : *
>                          connection               : *
>                          gensec                   : *
>                          preauth                  : *
>                          in_flags                 : 0x00 (0)
>                          in_security_mode         : 0x01 (1)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          idle_time                : Tue Mar  2 05:46:51 PM 2021 PST
>                          channel_id               : 0x0000000000000000 (0)
>[2021/03/02 17:46:51.452352,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.452366,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.452379,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.452401,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:493(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2021/03/02 17:46:51.452422,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:146(dbwrap_lock_order_lock)
>  dbwrap_lock_order_lock: check lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.452441, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:131(debug_lock_order)
>  lock order:  1:/usr/local/samba/var/lock/smbXsrv_session_global.tdb 2:<none> 3:<none> 4:<none>
>[2021/03/02 17:46:51.452458, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Locking key 28E211F9
>[2021/03/02 17:46:51.452472, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:153(db_tdb_fetch_locked_internal)
>  db_tdb_fetch_locked_internal: Allocated locked data 0x564b5f53a930
>[2021/03/02 17:46:51.452499, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/dbwrap/dbwrap_watch.c:452(dbwrap_watched_subrec_wakeup_fn)
>  dbwrap_watched_subrec_wakeup_fn: No watchers
>[2021/03/02 17:46:51.452516, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:949(smbXsrv_session_global_store)
>[2021/03/02 17:46:51.452525, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:950(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key '28E211F9' stored
>[2021/03/02 17:46:51.452537,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &global_blob: struct smbXsrv_session_globalB
>          version                  : SMBXSRV_VERSION_0 (0)
>          seqnum                   : 0x00000004 (4)
>          info                     : union smbXsrv_session_globalU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session_global0
>                  db_rec                   : *
>                  session_global_id        : 0x28e211f9 (685904377)
>                  session_wire_id          : 0x0000000028e211f9 (685904377)
>                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                  expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0311 (785)
>                  signing_flags            : 0x04 (4)
>                         0: SMBXSRV_SIGNING_REQUIRED 
>                         0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                         1: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                  encryption_flags         : 0x08 (8)
>                         0: SMBXSRV_ENCRYPTION_REQUIRED
>                         0: SMBXSRV_ENCRYPTION_DESIRED
>                         0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                         1: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                  signing_key              : NULL
>                  encryption_key           : NULL
>                  decryption_key           : NULL
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x0000000000090bee (592878)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                          channel_id               : 0x0000000000000000 (0)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          local_address            : 'ipv4:127.0.0.1:445'
>                          remote_address           : 'ipv4:127.0.0.1:46096'
>                          remote_name              : '127.0.0.1'
>                          signing_key              : NULL
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2021/03/02 17:46:51.452765,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap.c:178(dbwrap_lock_order_unlock)
>  dbwrap_lock_order_unlock: release lock order 1 for /usr/local/samba/var/lock/smbXsrv_session_global.tdb
>[2021/03/02 17:46:51.452781, 10, pid=592878, effective(0, 0), real(0, 0)] ../../lib/dbwrap/dbwrap_tdb.c:60(db_tdb_log_key)
>  db_tdb_log_key: Unlocking key 28E211F9
>[2021/03/02 17:46:51.452795, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1422(smbXsrv_session_update)
>[2021/03/02 17:46:51.452804, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/smbXsrv_session.c:1428(smbXsrv_session_update)
>  smbXsrv_session_update: global_id (0x28e211f9) stored
>[2021/03/02 17:46:51.452816,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &session_blob: struct smbXsrv_sessionB
>          version                  : SMBXSRV_VERSION_0 (0)
>          reserved                 : 0x00000000 (0)
>          info                     : union smbXsrv_sessionU(case 0)
>          info0                    : *
>              info0: struct smbXsrv_session
>                  table                    : *
>                  db_rec                   : NULL
>                  client                   : *
>                  local_id                 : 0x28e211f9 (685904377)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0x28e211f9 (685904377)
>                          session_wire_id          : 0x0000000028e211f9 (685904377)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          expiration_time          : Wed Sep 13 07:48:05 PM 30828 PDT
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0311 (785)
>                          signing_flags            : 0x04 (4)
>                                 0: SMBXSRV_SIGNING_REQUIRED 
>                                 0: SMBXSRV_PROCESSED_SIGNED_PACKET
>                                 1: SMBXSRV_PROCESSED_UNSIGNED_PACKET
>                          encryption_flags         : 0x08 (8)
>                                 0: SMBXSRV_ENCRYPTION_REQUIRED
>                                 0: SMBXSRV_ENCRYPTION_DESIRED
>                                 0: SMBXSRV_PROCESSED_ENCRYPTED_PACKET
>                                 1: SMBXSRV_PROCESSED_UNENCRYPTED_PACKET
>                          signing_key              : NULL
>                          encryption_key           : NULL
>                          decryption_key           : NULL
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x0000000000090bee (592878)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0x1a9d5632e20f54a9 (1917783792870708393)
>                                  channel_id               : 0x0000000000000000 (0)
>                                  creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                                  local_address            : 'ipv4:127.0.0.1:445'
>                                  remote_address           : 'ipv4:127.0.0.1:46096'
>                                  remote_name              : '127.0.0.1'
>                                  signing_key              : NULL
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Tue Mar  2 05:46:51 PM 2021 PST
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  tcon_table               : *
>                  homes_snum               : 0xffffffff (4294967295)
>                  pending_auth             : *
>                      pending_auth: struct smbXsrv_session_auth0
>                          prev                     : *
>                          next                     : NULL
>                          session                  : *
>                          connection               : *
>                          gensec                   : *
>                          preauth                  : *
>                          in_flags                 : 0x00 (0)
>                          in_security_mode         : 0x01 (1)
>                          creation_time            : Tue Mar  2 05:46:51 PM 2021 PST
>                          idle_time                : Tue Mar  2 05:46:51 PM 2021 PST
>                          channel_id               : 0x0000000000000000 (0)
>[2021/03/02 17:46:51.453215,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.453231,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.453244,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.453257,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.453270,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.453326,  6, pid=592878, effective(0, 0), real(0, 0)] ../../librpc/ndr/ndr_string.c:178(ndr_pull_string)
>  short string '', sent with NULL termination despite NOTERM flag in IDL
>[2021/03/02 17:46:51.453357,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       authenticate: struct AUTHENTICATE_MESSAGE
>          Signature                : 'NTLMSSP'
>          MessageType              : NtLmAuthenticate (3)
>          LmChallengeResponseLen   : 0x0018 (24)
>          LmChallengeResponseMaxLen: 0x0018 (24)
>          LmChallengeResponse      : *
>              LmChallengeResponse      : union ntlmssp_LM_RESPONSE_with_len(case 24)
>              v1: struct LM_RESPONSE
>                  Response                 : 000000000000000000000000000000000000000000000000
>          NtChallengeResponseLen   : 0x0104 (260)
>          NtChallengeResponseMaxLen: 0x0104 (260)
>          NtChallengeResponse      : *
>              NtChallengeResponse      : union ntlmssp_NTLM_RESPONSE_with_len(case 260)
>              v2: struct NTLMv2_RESPONSE
>                  Response                 : 276c9a481e94bc8fd6487bc8bf833479
>                  Challenge: struct NTLMv2_CLIENT_CHALLENGE
>                      RespType                 : 0x01 (1)
>                      HiRespType               : 0x01 (1)
>                      Reserved1                : 0x0000 (0)
>                      Reserved2                : 0x00000000 (0)
>                      TimeStamp                : Tue Mar  2 05:46:51 PM 2021 PST
>                      ChallengeFromClient      : 57002d03437dd2fc
>                      Reserved3                : 0x00000000 (0)
>                      AvPairs: struct AV_PAIR_LIST
>                          count                    : 0x0000000a (10)
>                          pair: ARRAY(10)
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvNbDomainName (0x2)
>                                  AvLen                    : 0x0018 (24)
>                                  Value                    : union ntlmssp_AvValue(case 0x2)
>                                  AvNbDomainName           : 'JEREMY-ACERB'
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvNbComputerName (0x1)
>                                  AvLen                    : 0x0018 (24)
>                                  Value                    : union ntlmssp_AvValue(case 0x1)
>                                  AvNbComputerName         : 'JEREMY-ACERB'
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvDnsDomainName (0x4)
>                                  AvLen                    : 0x0002 (2)
>                                  Value                    : union ntlmssp_AvValue(case 0x4)
>                                  AvDnsDomainName          : ''
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvDnsComputerName (0x3)
>                                  AvLen                    : 0x0016 (22)
>                                  Value                    : union ntlmssp_AvValue(case 0x3)
>                                  AvDnsComputerName        : 'jeremy-acer'
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvTimestamp (0x7)
>                                  AvLen                    : 0x0008 (8)
>                                  Value                    : union ntlmssp_AvValue(case 0x7)
>                                  AvTimestamp              : Tue Mar  2 05:46:51 PM 2021 PST
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvFlags (0x6)
>                                  AvLen                    : 0x0004 (4)
>                                  Value                    : union ntlmssp_AvValue(case 0x6)
>                                  AvFlags                  : 0x00000002 (2)
>                                         0: NTLMSSP_AVFLAG_CONSTRAINTED_ACCOUNT
>                                         1: NTLMSSP_AVFLAG_MIC_IN_AUTHENTICATE_MESSAGE
>                                         0: NTLMSSP_AVFLAG_TARGET_SPN_FROM_UNTRUSTED_SOURCE
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvSingleHost (0x8)
>                                  AvLen                    : 0x0030 (48)
>                                  Value                    : union ntlmssp_AvValue(case 0x8)
>                                  AvSingleHost: struct ntlmssp_SingleHostData
>                                      Size                     : 0x00000030 (48)
>                                      Z4                       : 0x00000000 (0)
>                                      token_info: struct LSAP_TOKEN_INFO_INTEGRITY
>                                          Flags                    : 0x00000000 (0)
>                                          TokenIL                  : 0x00000000 (0)
>                                          MachineId                : f1cfb2d50640c84913dbea8115ecb52366b1a73e9d01d7b534b105c8fc2e05e0
>                                      remaining                : DATA_BLOB length=0
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvChannelBindings (0xA)
>                                  AvLen                    : 0x0010 (16)
>                                  Value                    : union ntlmssp_AvValue(case 0xA)
>                                  ChannelBindings          : 00000000000000000000000000000000
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvTargetName (0x9)
>                                  AvLen                    : 0x001c (28)
>                                  Value                    : union ntlmssp_AvValue(case 0x9)
>                                  AvTargetName             : 'cifs/127.0.0.1'
>                              pair: struct AV_PAIR
>                                  AvId                     : MsvAvEOL (0x0)
>                                  AvLen                    : 0x0000 (0)
>                                  Value                    : union ntlmssp_AvValue(case 0x0)
>          DomainNameLen            : 0x0012 (18)
>          DomainNameMaxLen         : 0x0012 (18)
>          DomainName               : *
>              DomainName               : 'WORKGROUP'
>          UserNameLen              : 0x000c (12)
>          UserNameMaxLen           : 0x000c (12)
>          UserName                 : *
>              UserName                 : 'jeremy'
>          WorkstationLen           : 0x0018 (24)
>          WorkstationMaxLen        : 0x0018 (24)
>          Workstation              : *
>              Workstation              : 'JEREMY-ACERB'
>          EncryptedRandomSessionKeyLen: 0x0010 (16)
>          EncryptedRandomSessionKeyMaxLen: 0x0010 (16)
>          EncryptedRandomSessionKey: *
>              EncryptedRandomSessionKey: DATA_BLOB length=16
>  [0000] D5 10 8B 64 0E A9 48 6A   0F BE 36 B3 83 5A 12 0A   ...d..Hj ..6..Z..
>          NegotiateFlags           : 0x62088215 (1644724757)
>                 1: NTLMSSP_NEGOTIATE_UNICODE
>                 0: NTLMSSP_NEGOTIATE_OEM    
>                 1: NTLMSSP_REQUEST_TARGET   
>                 1: NTLMSSP_NEGOTIATE_SIGN   
>                 0: NTLMSSP_NEGOTIATE_SEAL   
>                 0: NTLMSSP_NEGOTIATE_DATAGRAM
>                 0: NTLMSSP_NEGOTIATE_LM_KEY 
>                 0: NTLMSSP_NEGOTIATE_NETWARE
>                 1: NTLMSSP_NEGOTIATE_NTLM   
>                 0: NTLMSSP_NEGOTIATE_NT_ONLY
>                 0: NTLMSSP_ANONYMOUS        
>                 0: NTLMSSP_NEGOTIATE_OEM_DOMAIN_SUPPLIED
>                 0: NTLMSSP_NEGOTIATE_OEM_WORKSTATION_SUPPLIED
>                 0: NTLMSSP_NEGOTIATE_THIS_IS_LOCAL_CALL
>                 1: NTLMSSP_NEGOTIATE_ALWAYS_SIGN
>                 0: NTLMSSP_TARGET_TYPE_DOMAIN
>                 0: NTLMSSP_TARGET_TYPE_SERVER
>                 0: NTLMSSP_TARGET_TYPE_SHARE
>                 1: NTLMSSP_NEGOTIATE_EXTENDED_SESSIONSECURITY
>                 0: NTLMSSP_NEGOTIATE_IDENTIFY
>                 0: NTLMSSP_REQUEST_NON_NT_SESSION_KEY
>                 0: NTLMSSP_NEGOTIATE_TARGET_INFO
>                 1: NTLMSSP_NEGOTIATE_VERSION
>                 1: NTLMSSP_NEGOTIATE_128    
>                 1: NTLMSSP_NEGOTIATE_KEY_EXCH
>                 0: NTLMSSP_NEGOTIATE_56     
>          Version: struct ntlmssp_VERSION
>              ProductMajorVersion      : NTLMSSP_WINDOWS_MAJOR_VERSION_6 (6)
>              ProductMinorVersion      : NTLMSSP_WINDOWS_MINOR_VERSION_1 (1)
>              ProductBuild             : 0x0000 (0)
>              Reserved: ARRAY(3)
>                  [0]                      : 0x00 (0)
>                  [1]                      : 0x00 (0)
>                  [2]                      : 0x00 (0)
>              NTLMRevisionCurrent      : NTLMSSP_REVISION_W2K3 (15)
>[2021/03/02 17:46:51.454100,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_server.c:509(ntlmssp_server_preauth)
>  Got user=[jeremy] domain=[WORKGROUP] workstation=[JEREMY-ACERB] len1=24 len2=260
>[2021/03/02 17:46:51.454121,  6, pid=592878, effective(0, 0), real(0, 0)] ../../librpc/ndr/ndr_string.c:178(ndr_pull_string)
>  short string '', sent with NULL termination despite NOTERM flag in IDL
>[2021/03/02 17:46:51.454143, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_server.c:544(ntlmssp_server_preauth)
>[2021/03/02 17:46:51.454154,  1, pid=592878, effective(0, 0), real(0, 0), class=rpc_parse] ../../librpc/ndr/ndr.c:429(ndr_print_debug)
>       &v2_resp: struct NTLMv2_RESPONSE
>          Response                 : 276c9a481e94bc8fd6487bc8bf833479
>          Challenge: struct NTLMv2_CLIENT_CHALLENGE
>              RespType                 : 0x01 (1)
>              HiRespType               : 0x01 (1)
>              Reserved1                : 0x0000 (0)
>              Reserved2                : 0x00000000 (0)
>              TimeStamp                : Tue Mar  2 05:46:51 PM 2021 PST
>              ChallengeFromClient      : 57002d03437dd2fc
>              Reserved3                : 0x00000000 (0)
>              AvPairs: struct AV_PAIR_LIST
>                  count                    : 0x0000000a (10)
>                  pair: ARRAY(10)
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvNbDomainName (0x2)
>                          AvLen                    : 0x0018 (24)
>                          Value                    : union ntlmssp_AvValue(case 0x2)
>                          AvNbDomainName           : 'JEREMY-ACERB'
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvNbComputerName (0x1)
>                          AvLen                    : 0x0018 (24)
>                          Value                    : union ntlmssp_AvValue(case 0x1)
>                          AvNbComputerName         : 'JEREMY-ACERB'
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvDnsDomainName (0x4)
>                          AvLen                    : 0x0002 (2)
>                          Value                    : union ntlmssp_AvValue(case 0x4)
>                          AvDnsDomainName          : ''
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvDnsComputerName (0x3)
>                          AvLen                    : 0x0016 (22)
>                          Value                    : union ntlmssp_AvValue(case 0x3)
>                          AvDnsComputerName        : 'jeremy-acer'
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvTimestamp (0x7)
>                          AvLen                    : 0x0008 (8)
>                          Value                    : union ntlmssp_AvValue(case 0x7)
>                          AvTimestamp              : Tue Mar  2 05:46:51 PM 2021 PST
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvFlags (0x6)
>                          AvLen                    : 0x0004 (4)
>                          Value                    : union ntlmssp_AvValue(case 0x6)
>                          AvFlags                  : 0x00000002 (2)
>                                 0: NTLMSSP_AVFLAG_CONSTRAINTED_ACCOUNT
>                                 1: NTLMSSP_AVFLAG_MIC_IN_AUTHENTICATE_MESSAGE
>                                 0: NTLMSSP_AVFLAG_TARGET_SPN_FROM_UNTRUSTED_SOURCE
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvSingleHost (0x8)
>                          AvLen                    : 0x0030 (48)
>                          Value                    : union ntlmssp_AvValue(case 0x8)
>                          AvSingleHost: struct ntlmssp_SingleHostData
>                              Size                     : 0x00000030 (48)
>                              Z4                       : 0x00000000 (0)
>                              token_info: struct LSAP_TOKEN_INFO_INTEGRITY
>                                  Flags                    : 0x00000000 (0)
>                                  TokenIL                  : 0x00000000 (0)
>                                  MachineId                : f1cfb2d50640c84913dbea8115ecb52366b1a73e9d01d7b534b105c8fc2e05e0
>                              remaining                : DATA_BLOB length=0
>                      pair: struct AV_PAIR
>                          AvId                     : MsvChannelBindings (0xA)
>                          AvLen                    : 0x0010 (16)
>                          Value                    : union ntlmssp_AvValue(case 0xA)
>                          ChannelBindings          : 00000000000000000000000000000000
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvTargetName (0x9)
>                          AvLen                    : 0x001c (28)
>                          Value                    : union ntlmssp_AvValue(case 0x9)
>                          AvTargetName             : 'cifs/127.0.0.1'
>                      pair: struct AV_PAIR
>                          AvId                     : MsvAvEOL (0x0)
>                          AvLen                    : 0x0000 (0)
>                          Value                    : union ntlmssp_AvValue(case 0x0)
>[2021/03/02 17:46:51.454567,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:123(make_user_info_map)
>  Mapping user [WORKGROUP]\[jeremy] from workstation [JEREMY-ACERB]
>[2021/03/02 17:46:51.454583,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/user_info.c:64(make_user_info)
>  attempting to make a user_info for jeremy (jeremy)
>[2021/03/02 17:46:51.454596,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/user_info.c:72(make_user_info)
>  making strings for jeremy's user_info struct
>[2021/03/02 17:46:51.454613,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/user_info.c:117(make_user_info)
>  making blobs for jeremy's user_info struct
>[2021/03/02 17:46:51.454628, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/user_info.c:163(make_user_info)
>  made a user_info for jeremy (jeremy)
>[2021/03/02 17:46:51.454643,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:201(auth_check_ntlm_password)
>  check_ntlm_password:  Checking password for unmapped user [WORKGROUP]\[jeremy]@[JEREMY-ACERB] with the new password interface
>[2021/03/02 17:46:51.454657,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:204(auth_check_ntlm_password)
>  check_ntlm_password:  mapped user is: [WORKGROUP]\[jeremy]@[JEREMY-ACERB]
>[2021/03/02 17:46:51.454671, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:214(auth_check_ntlm_password)
>  check_ntlm_password: auth_context challenge created by random
>[2021/03/02 17:46:51.454684, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:217(auth_check_ntlm_password)
>  challenge is: 
>[2021/03/02 17:46:51.454696,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/util/util.c:570(dump_data)
>  [0000] 28 89 CE 1D D5 F6 6A 5C                             (.....j\ 
>[2021/03/02 17:46:51.454718, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_builtin.c:42(check_anonymous_security)
>  Check auth for: [jeremy]
>[2021/03/02 17:46:51.454731, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:250(auth_check_ntlm_password)
>  auth_check_ntlm_password: anonymous had nothing to say
>[2021/03/02 17:46:51.454744, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_sam.c:46(auth_sam_ignoredomain_auth)
>  auth_sam_ignoredomain_auth: Check auth for: [WORKGROUP]\[jeremy]
>[2021/03/02 17:46:51.454763,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.454778,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.454790,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.454803,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.454873,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.454917, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username jeremy, was 
>[2021/03/02 17:46:51.454933, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain JEREMY-ACERB, was 
>[2021/03/02 17:46:51.454947, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username , was 
>[2021/03/02 17:46:51.454960, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name Jeremy, was 
>[2021/03/02 17:46:51.454975,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/substitute.c:345(automount_server)
>  Home server: JEREMY-ACERB
>[2021/03/02 17:46:51.454992, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\JEREMY-ACERB\jeremy, was 
>[2021/03/02 17:46:51.455006, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2021/03/02 17:46:51.455021, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2021/03/02 17:46:51.455035,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/substitute.c:345(automount_server)
>  Home server: JEREMY-ACERB
>[2021/03/02 17:46:51.455051, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\JEREMY-ACERB\jeremy\profile, was 
>[2021/03/02 17:46:51.455065, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2021/03/02 17:46:51.455081,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455095,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455108,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455121,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.455133,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.455164, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/account_pol.c:364(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2021/03/02 17:46:51.455183,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455202, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:496(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-1914782597-1700840410-671320589-1000
>[2021/03/02 17:46:51.455217, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_compat.c:73(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-1914782597-1700840410-671320589-1000 from rid 1000
>[2021/03/02 17:46:51.455242,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455259,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455273,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455286,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.455298,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.455325, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/account_pol.c:364(account_policy_get)
>  account_policy_get: name: maximum password age, val: -1
>[2021/03/02 17:46:51.455343,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455358,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:182(Get_Pwnam_alloc)
>  Finding user jeremy
>[2021/03/02 17:46:51.455371,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:121(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is jeremy
>[2021/03/02 17:46:51.455420,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:159(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [jeremy]!
>[2021/03/02 17:46:51.455473,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455488,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455501,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455514,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.455526,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.455564,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455580, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 1000 -> S-1-22-2-1000 fallback
>[2021/03/02 17:46:51.455601,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1631(get_primary_group_sid)
>  Forcing Primary Group to 'Domain Users' for jeremy
>[2021/03/02 17:46:51.455618,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455632,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455646,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455658,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.455671,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.455697, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/account_pol.c:364(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2021/03/02 17:46:51.455715,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455744, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:572(pdb_set_username)
>  pdb_set_username: setting username jeremy, was 
>[2021/03/02 17:46:51.455759, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:595(pdb_set_domain)
>  pdb_set_domain: setting domain JEREMY-ACERB, was 
>[2021/03/02 17:46:51.455773, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:618(pdb_set_nt_username)
>  pdb_set_nt_username: setting nt username , was 
>[2021/03/02 17:46:51.455786, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:641(pdb_set_fullname)
>  pdb_set_full_name: setting full name Jeremy, was 
>[2021/03/02 17:46:51.455800,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/substitute.c:345(automount_server)
>  Home server: JEREMY-ACERB
>[2021/03/02 17:46:51.455818, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:734(pdb_set_homedir)
>  pdb_set_homedir: setting home dir \\JEREMY-ACERB\jeremy, was 
>[2021/03/02 17:46:51.455832, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:710(pdb_set_dir_drive)
>  pdb_set_dir_drive: setting dir drive , was NULL
>[2021/03/02 17:46:51.455846, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:664(pdb_set_logon_script)
>  pdb_set_logon_script: setting logon script , was 
>[2021/03/02 17:46:51.455860,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/substitute.c:345(automount_server)
>  Home server: JEREMY-ACERB
>[2021/03/02 17:46:51.455876, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:687(pdb_set_profile_path)
>  pdb_set_profile_path: setting profile path \\JEREMY-ACERB\jeremy\profile, was 
>[2021/03/02 17:46:51.455891, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:777(pdb_set_workstations)
>  pdb_set_workstations: setting workstations , was 
>[2021/03/02 17:46:51.455906,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455920,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.455933,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.455945,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.455958,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.455984, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/account_pol.c:364(account_policy_get)
>  account_policy_get: name: password history, val: 0
>[2021/03/02 17:46:51.456002,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456018, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:496(pdb_set_user_sid)
>  pdb_set_user_sid: setting user sid S-1-5-21-1914782597-1700840410-671320589-1000
>[2021/03/02 17:46:51.456034, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_compat.c:73(pdb_set_user_sid_from_rid)
>  pdb_set_user_sid_from_rid:
>  	setting user sid S-1-5-21-1914782597-1700840410-671320589-1000 from rid 1000
>[2021/03/02 17:46:51.456057, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_get_set.c:559(pdb_set_group_sid)
>  pdb_set_group_sid: setting group sid S-1-5-21-1914782597-1700840410-671320589-513
>[2021/03/02 17:46:51.456086,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.456105,  4, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/auth/ntlm_check.c:406(ntlm_password_check)
>  ntlm_password_check: Checking NTLMv2 password with domain [WORKGROUP]
>[2021/03/02 17:46:51.456145,  4, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/check_samsec.c:183(sam_account_ok)
>  sam_account_ok: Checking SMB password for user jeremy
>[2021/03/02 17:46:51.456161,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/check_samsec.c:164(logon_hours_ok)
>  logon_hours_ok: user jeremy allowed to logon at this time (Wed Mar  3 01:46:51 2021
>  )
>[2021/03/02 17:46:51.456180,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456193,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.456207,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456220,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.456233,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.456262, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/account_pol.c:364(account_policy_get)
>  account_policy_get: name: maximum password age, val: -1
>[2021/03/02 17:46:51.456281,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.456298,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456311,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.456325,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456338,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.456352,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.456376,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:182(Get_Pwnam_alloc)
>  Finding user jeremy
>[2021/03/02 17:46:51.456389,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:121(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is jeremy
>[2021/03/02 17:46:51.456406,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:159(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [jeremy]!
>[2021/03/02 17:46:51.456423,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.456438,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456451,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.456464,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.456483,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.456511, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/account_pol.c:364(account_policy_get)
>  account_policy_get: name: minimum password age, val: 0
>[2021/03/02 17:46:51.456531,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456547,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.456560,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456573,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.456586,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.456599,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.456625, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/account_pol.c:364(account_policy_get)
>  account_policy_get: name: maximum password age, val: -1
>[2021/03/02 17:46:51.456645,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.456662,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:182(Get_Pwnam_alloc)
>  Finding user jeremy
>[2021/03/02 17:46:51.456677,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:121(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is jeremy
>[2021/03/02 17:46:51.456693,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:159(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [jeremy]!
>[2021/03/02 17:46:51.456719, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/system_smbd.c:176(sys_getgrouplist)
>  sys_getgrouplist: user [jeremy]
>[2021/03/02 17:46:51.467822,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.467843,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.467856,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.467874,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.467888,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.467929,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.467947, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 1000 -> S-1-22-2-1000 fallback
>[2021/03/02 17:46:51.467981,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.467996,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468013,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468027,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.468040,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.468074,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468091, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 4 -> S-1-22-2-4 fallback
>[2021/03/02 17:46:51.468117,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468131,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468145,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468158,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.468171,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.468203,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468220, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 24 -> S-1-22-2-24 fallback
>[2021/03/02 17:46:51.468243,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468257,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468270,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468283,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.468295,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.468327,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468343, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 27 -> S-1-22-2-27 fallback
>[2021/03/02 17:46:51.468371,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468386,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468399,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468411,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.468423,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.468458,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468473, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 30 -> S-1-22-2-30 fallback
>[2021/03/02 17:46:51.468497,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468510,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468524,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468537,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.468550,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.468583,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468599, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 46 -> S-1-22-2-46 fallback
>[2021/03/02 17:46:51.468622,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468636,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.468648,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.468661,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.469229,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.469268,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.469285, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 118 -> S-1-22-2-118 fallback
>[2021/03/02 17:46:51.469317,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.469333,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.469345,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.469358,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.469371,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.469405,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.469422, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 129 -> S-1-22-2-129 fallback
>[2021/03/02 17:46:51.469454,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.469470,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.469484,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 3
>[2021/03/02 17:46:51.469498,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.469511,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.469544,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.469560, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 134 -> S-1-22-2-134 fallback
>[2021/03/02 17:46:51.469585,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/server_info_sam.c:121(make_server_info_sam)
>  make_server_info_sam: made server info for user jeremy -> jeremy
>[2021/03/02 17:46:51.469607,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.469628,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:268(auth_check_ntlm_password)
>  auth_check_ntlm_password: sam_ignoredomain authentication for user [jeremy] succeeded
>[2021/03/02 17:46:51.469647,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.469661,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.469674,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.469687,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.469700,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.469727,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.469742,  5, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:295(auth_check_ntlm_password)
>  check_ntlm_password:  PAM Account for user [jeremy] succeeded
>[2021/03/02 17:46:51.469776,  3, pid=592878, effective(0, 0), real(0, 0), class=auth_audit] ../../auth/auth_log.c:635(log_authentication_event_human_readable)
>  Auth: [SMB2,(null)] user [WORKGROUP]\[jeremy] at [Tue, 02 Mar 2021 17:46:51.469762 PST] with [NTLMv2] status [NT_STATUS_OK] workstation [JEREMY-ACERB] remote host [ipv4:127.0.0.1:46096] became [JEREMY-ACERB]\[jeremy] [S-1-5-21-1914782597-1700840410-671320589-1000]. local host [ipv4:127.0.0.1:445] 
>  {"timestamp": "2021-03-02T17:46:51.469858-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:46096", "serviceDescription": "SMB2", "authDescription": null, "clientDomain": "WORKGROUP", "clientAccount": "jeremy", "workstation": "JEREMY-ACERB", "becameAccount": "jeremy", "becameDomain": "JEREMY-ACERB", "becameSid": "S-1-5-21-1914782597-1700840410-671320589-1000", "mappedAccount": "jeremy", "mappedDomain": "WORKGROUP", "netlogonComputer": null, "netlogonTrustAccount": null, "netlogonNegotiateFlags": "0x00000000", "netlogonSecureChannelType": 0, "netlogonTrustAccountSid": null, "passwordType": "NTLMv2", "duration": 23503}}
>[2021/03/02 17:46:51.469909,  2, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth.c:324(auth_check_ntlm_password)
>  check_ntlm_password:  authentication for user [jeremy] -> [jeremy] -> [jeremy] succeeded
>[2021/03/02 17:46:51.469927,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:3950(lp_load_ex)
>  lp_load_ex: refreshing parameters
>[2021/03/02 17:46:51.469947,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:1376(free_param_opts)
>  Freeing parametrics:
>[2021/03/02 17:46:51.470017,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:553(init_globals)
>  Initialising global parameters
>[2021/03/02 17:46:51.470097,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/util/debug.c:811(debug_dump_status)
>  INFO: Current debug levels:
>    all: 10
>    tdb: 10
>    printdrivers: 10
>    lanman: 10
>    smb: 10
>    rpc_parse: 10
>    rpc_srv: 10
>    rpc_cli: 10
>    passdb: 10
>    sam: 10
>    auth: 10
>    winbind: 10
>    vfs: 10
>    idmap: 10
>    quota: 10
>    acls: 10
>    locking: 10
>    msdfs: 10
>    dmapi: 10
>    registry: 10
>    scavenger: 10
>    dns: 10
>    ldb: 10
>    tevent: 10
>    auth_audit: 10
>    auth_json_audit: 10
>    kerberos: 10
>    drs_repl: 10
>    smb2: 10
>    smb2_credits: 10
>    dsdb_audit: 10
>    dsdb_json_audit: 10
>    dsdb_password_audit: 10
>    dsdb_password_json_audit: 10
>    dsdb_transaction_audit: 10
>    dsdb_transaction_json_audit: 10
>    dsdb_group_audit: 10
>    dsdb_group_json_audit: 10
>[2021/03/02 17:46:51.470273,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2852(lp_do_section)
>  Processing section "[global]"
>  doing parameter max log size = 0
>  doing parameter workgroup = WORKGROUP
>  doing parameter realm = WORKGROUP.COM
>  doing parameter passdb backend = tdbsam
>  doing parameter netbios name = jeremy-acerb
>  doing parameter security = user
>  doing parameter log file = /usr/local/samba/var/log.%m.%d
>  doing parameter panic action = "/bin/sleep 90000"
>  doing parameter create mode = 07777
>  doing parameter directory mask = 07777
>  doing parameter kernel oplocks = no
>  doing parameter ea support = yes
>  doing parameter store dos attributes = yes
>  doing parameter map readonly = no
>  doing parameter map archive = no
>  doing parameter map system = no
>  doing parameter server signing = auto
>  doing parameter domain master = no
>  doing parameter local master = no
>  doing parameter preferred master = no
>  doing parameter domain logons = no
>[2021/03/02 17:46:51.470585,  1, pid=592878, effective(0, 0), real(0, 0)] ../../lib/param/loadparm.c:1871(lpcfg_do_global_parameter)
>  lpcfg_do_global_parameter: WARNING: The "domain logons" option is deprecated
>  doing parameter map to guest = Bad Password
>  doing parameter guest account = nobody
>  doing parameter ntlm auth = yes
>  doing parameter load printers = no
>  doing parameter printing = bsd
>  doing parameter printcap name = /dev/null
>  doing parameter disable spoolss = yes
>  doing parameter usershare max shares = 100
>[2021/03/02 17:46:51.470726,  2, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2869(lp_do_section)
>  Processing section "[charset]"
>  doing parameter path = /tmp/charset
>  doing parameter guest ok = yes
>  doing parameter read only = no
>  doing parameter force user = jeremy
>[2021/03/02 17:46:51.470827,  2, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2869(lp_do_section)
>  Processing section "[tmp1]"
>[2021/03/02 17:46:51.470846,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:1376(free_param_opts)
>  Freeing parametrics:
>  doing parameter wide links = yes
>  doing parameter vfs objects = aio_pthread
>  doing parameter aio_pthread:aio open = true
>  doing parameter strict sync = yes
>  doing parameter sync always = yes
>  doing parameter path = /./tmp/////
>  doing parameter read only = no
>  doing parameter guest ok = yes
>[2021/03/02 17:46:51.471039,  2, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2869(lp_do_section)
>  Processing section "[tmp-snap]"
>[2021/03/02 17:46:51.471056,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:1376(free_param_opts)
>  Freeing parametrics:
>  doing parameter path = /tmp
>  doing parameter read only = no
>  doing parameter vfs objects = shadow_copy2
>  doing parameter shadow:snapdir = .snapshots
>  doing parameter shadow:snapdirseverywhere = yes
>[2021/03/02 17:46:51.471185,  2, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2869(lp_do_section)
>  Processing section "[tmp]"
>  doing parameter path = /tmp
>  doing parameter read only = no
>[2021/03/02 17:46:51.471240,  2, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2869(lp_do_section)
>  Processing section "[aio_delay_inject]"
>[2021/03/02 17:46:51.471256,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:1376(free_param_opts)
>  Freeing parametrics:
>  doing parameter copy = tmp
>[2021/03/02 17:46:51.471282,  3, pid=592878, effective(0, 0), real(0, 0)] ../../lib/param/loadparm.c:1242(handle_copy)
>  Copying service from service tmp
>  doing parameter vfs objects = delay_inject
>  doing parameter delay_inject:pread_send = 2000
>  doing parameter delay_inject:pwrite_send = 2000
>[2021/03/02 17:46:51.471387,  2, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2869(lp_do_section)
>  Processing section "[uring1]"
>  doing parameter path = /home/jeremy/tmp/uring
>  doing parameter read only = no
>[2021/03/02 17:46:51.471443,  2, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:2869(lp_do_section)
>  Processing section "[ptest]"
>  doing parameter path = /tmp/posix_test
>  doing parameter read only = no
>  doing parameter admin users = jeremy
>[2021/03/02 17:46:51.471516,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:3992(lp_load_ex)
>  pm_process() returned Yes
>[2021/03/02 17:46:51.471546,  7, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:4327(lp_servicenumber)
>  lp_servicenumber: couldn't find homes
>[2021/03/02 17:46:51.471571,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:1653(lp_add_ipc)
>  adding IPC service
>[2021/03/02 17:46:51.471603, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/auth/auth_ntlmssp.c:260(auth3_check_password_send)
>  auth3_check_password_send: Got NT session key of length 16
>[2021/03/02 17:46:51.471620, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/auth/auth_ntlmssp.c:269(auth3_check_password_send)
>  auth3_check_password_send: Got LM session key of length 8
>[2021/03/02 17:46:51.471636, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:455(gensec_update_send)
>  gensec_update_send: ntlmssp[0x564b5f559f80]: subreq: 0x564b5f546060
>[2021/03/02 17:46:51.471654, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:455(gensec_update_send)
>  gensec_update_send: spnego[0x564b5f56bd40]: subreq: 0x564b5f578030
>[2021/03/02 17:46:51.471676,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.471714, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:321(create_local_nt_token_from_info3)
>  Create local NT token for jeremy
>[2021/03/02 17:46:51.471763, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:58(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: value=[1000:U]
>[2021/03/02 17:46:51.471780, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:76(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: id=[1000], endptr=[:U]
>[2021/03/02 17:46:51.471800, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1441(sid_to_uid)
>  sid S-1-5-21-1914782597-1700840410-671320589-1000 -> uid 1000
>[2021/03/02 17:46:51.471846, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/system_smbd.c:176(sys_getgrouplist)
>  sys_getgrouplist: user [jeremy]
>[2021/03/02 17:46:51.472213,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472230,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472244,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472258,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.472271,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.472307,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472324, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 1000 -> S-1-22-2-1000 fallback
>[2021/03/02 17:46:51.472347,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472362,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472375,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472388,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.472402,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.472435,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472450, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 4 -> S-1-22-2-4 fallback
>[2021/03/02 17:46:51.472474,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472489,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472502,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472516,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.472529,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.472559,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472575, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 24 -> S-1-22-2-24 fallback
>[2021/03/02 17:46:51.472598,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472618,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472633,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472646,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.472660,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.472693,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472708, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 27 -> S-1-22-2-27 fallback
>[2021/03/02 17:46:51.472731,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472747,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472760,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472774,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.472787,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.472821,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472837, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 30 -> S-1-22-2-30 fallback
>[2021/03/02 17:46:51.472859,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472875,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472889,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.472902,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.472915,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.472949,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.472964, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 46 -> S-1-22-2-46 fallback
>[2021/03/02 17:46:51.472987,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473002,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473015,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473037,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.473050,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.473085,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473101, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 118 -> S-1-22-2-118 fallback
>[2021/03/02 17:46:51.473124,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473139,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473152,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473165,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.473178,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.473209,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473225, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 129 -> S-1-22-2-129 fallback
>[2021/03/02 17:46:51.473247,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473262,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473276,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473289,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.473302,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.473335,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473351, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 134 -> S-1-22-2-134 fallback
>[2021/03/02 17:46:51.473372,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473387,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473400,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473413,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.473425,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.473466,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:688(finalize_local_nt_token)
>  Failed to fetch domain sid for WORKGROUP
>[2021/03/02 17:46:51.473491,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473514,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473530,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473542,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473556,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.473569,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.473598, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1574(pdb_default_sid_to_id)
>  Could not find map for sid S-1-5-32-544
>[2021/03/02 17:46:51.473619,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/pdb_util.c:162(create_builtin_administrators)
>  create_builtin_administrators: Failed to create Administrators
>[2021/03/02 17:46:51.473639,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473661,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473676,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473689,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473703,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.473716,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.473745, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1574(pdb_default_sid_to_id)
>  Could not find map for sid S-1-5-32-545
>[2021/03/02 17:46:51.473765,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/pdb_util.c:132(create_builtin_users)
>  create_builtin_users: Failed to create Users
>[2021/03/02 17:46:51.473784,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473805,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473819,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473833,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473846,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.473860,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.473892, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1574(pdb_default_sid_to_id)
>  Could not find map for sid S-1-5-32-546
>[2021/03/02 17:46:51.473917,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/pdb_util.c:205(create_builtin_guests)
>  create_builtin_guests: Failed to create Guests
>[2021/03/02 17:46:51.473936,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473954,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473969,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.473982,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.473996,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.474010,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.474236,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.474261,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1914782597-1700840410-671320589-1000]
>[2021/03/02 17:46:51.474281,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1914782597-1700840410-671320589-513]
>[2021/03/02 17:46:51.474299,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-1000]
>[2021/03/02 17:46:51.474317,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-4]
>[2021/03/02 17:46:51.474335,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-24]
>[2021/03/02 17:46:51.474353,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-27]
>[2021/03/02 17:46:51.474371,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-30]
>[2021/03/02 17:46:51.474389,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-46]
>[2021/03/02 17:46:51.474408,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-118]
>[2021/03/02 17:46:51.474426,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-129]
>[2021/03/02 17:46:51.474445,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-134]
>[2021/03/02 17:46:51.474464,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:179(get_privileges_for_sids)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set: 0x0
>[2021/03/02 17:46:51.474485,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2021/03/02 17:46:51.474503,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2021/03/02 17:46:51.474530, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:58(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: value=[1000:U]
>[2021/03/02 17:46:51.474545, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:76(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: id=[1000], endptr=[:U]
>[2021/03/02 17:46:51.474588, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1318(sids_to_unixids)
>  wbcSidsToUnixIds returned WBC_ERR_WINBIND_NOT_AVAILABLE
>[2021/03/02 17:46:51.474605,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.474619,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.474633,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.474647,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.474660,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.474685,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid)
>  lookup_global_sam_rid: looking up RID 513.
>[2021/03/02 17:46:51.474701,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.474715,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.474728,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.474743,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.474756,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.474785,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid)
>  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
>[2021/03/02 17:46:51.474831,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.474849,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid)
>  Can't find a unix id for an unmapped group
>[2021/03/02 17:46:51.474864,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id)
>  SID S-1-5-21-1914782597-1700840410-671320589-513 belongs to our domain, but there is no corresponding object in the database.
>[2021/03/02 17:46:51.474888,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.474903, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-21-1914782597-1700840410-671320589-513
>[2021/03/02 17:46:51.474919,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.474938,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.474953,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.474966,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.474978,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475002,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid)
>  lookup_global_sam_rid: looking up RID 513.
>[2021/03/02 17:46:51.475018,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.475031,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475044,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.475058,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.475071,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475099,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid)
>  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
>[2021/03/02 17:46:51.475125,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475139,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid)
>  Can't find a unix id for an unmapped group
>[2021/03/02 17:46:51.475153,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id)
>  SID S-1-5-21-1914782597-1700840410-671320589-513 belongs to our domain, but there is no corresponding object in the database.
>[2021/03/02 17:46:51.475174,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475189, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-21-1914782597-1700840410-671320589-513
>[2021/03/02 17:46:51.475204,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475218,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475231,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475244,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.475256,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475284,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475302, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-1-0
>[2021/03/02 17:46:51.475317,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475332,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475344,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475357,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.475369,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475399,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475414, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-1-0
>[2021/03/02 17:46:51.475428,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475443,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475456,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475470,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.475483,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475512,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475529, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-2
>[2021/03/02 17:46:51.475542,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475557,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475568,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475581,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.475595,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475622,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475637, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-2
>[2021/03/02 17:46:51.475650,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475665,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475682,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475695,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.475707,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475736,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475752, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-11
>[2021/03/02 17:46:51.475765,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475778,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475791,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.475804,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.475816,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.475845,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.475860, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-11
>[2021/03/02 17:46:51.475875, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-5-21-1914782597-1700840410-671320589-513 to gid, ignoring it
>[2021/03/02 17:46:51.475891, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-1-0 to gid, ignoring it
>[2021/03/02 17:46:51.475905, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-5-2 to gid, ignoring it
>[2021/03/02 17:46:51.475918, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-5-11 to gid, ignoring it
>[2021/03/02 17:46:51.475937, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../libcli/security/security_token.c:51(security_token_debug)
>  Security token SIDs (15):
>    SID[  0]: S-1-5-21-1914782597-1700840410-671320589-1000
>    SID[  1]: S-1-5-21-1914782597-1700840410-671320589-513
>    SID[  2]: S-1-22-2-1000
>    SID[  3]: S-1-22-2-4
>    SID[  4]: S-1-22-2-24
>    SID[  5]: S-1-22-2-27
>    SID[  6]: S-1-22-2-30
>    SID[  7]: S-1-22-2-46
>    SID[  8]: S-1-22-2-118
>    SID[  9]: S-1-22-2-129
>    SID[ 10]: S-1-22-2-134
>    SID[ 11]: S-1-1-0
>    SID[ 12]: S-1-5-2
>    SID[ 13]: S-1-5-11
>    SID[ 14]: S-1-22-1-1000
>   Privileges (0x               0):
>   Rights (0x               0):
>[2021/03/02 17:46:51.476013, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/token_util.c:873(debug_unix_user_token)
>  UNIX token of user 1000
>  Primary group is 1000 and contains 9 supplementary groups
>  Group[  0]: 1000
>  Group[  1]: 4
>  Group[  2]: 24
>  Group[  3]: 27
>  Group[  4]: 30
>  Group[  5]: 46
>  Group[  6]: 118
>  Group[  7]: 129
>  Group[  8]: 134
>[2021/03/02 17:46:51.476073, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_server.c:965(ntlmssp_server_postauth)
>  ntlmssp_server_auth: Using unmodified nt session key.
>[2021/03/02 17:46:51.476098,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_sign.c:623(ntlmssp_sign_reset)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2021/03/02 17:46:51.476112,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../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/03/02 17:46:51.476157, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_sign.c:52(dump_arc4_state)
>  dump_arc4_state: NTLMSSP send seal arc4 state:
>  
>[2021/03/02 17:46:51.476178, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_sign.c:52(dump_arc4_state)
>  dump_arc4_state: NTLMSSP recv seal arc4 state:
>  
>[2021/03/02 17:46:51.476202, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:547(gensec_update_done)
>  gensec_update_done: ntlmssp[0x564b5f559f80]: NT_STATUS_OK tevent_req[0x564b5f546060/../../auth/ntlmssp/ntlmssp.c:180]: state[2] error[0 (0x0)]  state[struct gensec_ntlmssp_update_state (0x564b5f546210)] timer[(nil)] finish[../../auth/ntlmssp/ntlmssp.c:244]
>[2021/03/02 17:46:51.476223, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_sign.c:320(ntlmssp_check_packet)
>  ntlmssp_check_packet: NTLMSSP signature OK !
>[2021/03/02 17:46:51.476245,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_sign.c:623(ntlmssp_sign_reset)
>  NTLMSSP Sign/Seal - Initialising with flags:
>[2021/03/02 17:46:51.476259,  3, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../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/03/02 17:46:51.476303, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_sign.c:52(dump_arc4_state)
>  dump_arc4_state: NTLMSSP send seal arc4 state:
>  
>[2021/03/02 17:46:51.476323, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/ntlmssp/ntlmssp_sign.c:52(dump_arc4_state)
>  dump_arc4_state: NTLMSSP recv seal arc4 state:
>  
>[2021/03/02 17:46:51.476343, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../auth/gensec/gensec.c:547(gensec_update_done)
>  gensec_update_done: spnego[0x564b5f56bd40]: NT_STATUS_OK tevent_req[0x564b5f578030/../../auth/gensec/spnego.c:1631]: state[2] error[0 (0x0)]  state[struct gensec_spnego_update_state (0x564b5f5781e0)] timer[(nil)] finish[../../auth/gensec/spnego.c:2116]
>[2021/03/02 17:46:51.476360,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.476375,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.476388,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.476400,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.476413,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.476446,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.476467, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:321(create_local_nt_token_from_info3)
>  Create local NT token for jeremy
>[2021/03/02 17:46:51.476494, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:58(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: value=[1000:U]
>[2021/03/02 17:46:51.476508, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:76(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: id=[1000], endptr=[:U]
>[2021/03/02 17:46:51.476521, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1441(sid_to_uid)
>  sid S-1-5-21-1914782597-1700840410-671320589-1000 -> uid 1000
>[2021/03/02 17:46:51.476565, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/system_smbd.c:176(sys_getgrouplist)
>  sys_getgrouplist: user [jeremy]
>[2021/03/02 17:46:51.482937,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.482957,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.482972,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.482986,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483000,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483039,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483057, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 1000 -> S-1-22-2-1000 fallback
>[2021/03/02 17:46:51.483083,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483098,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483112,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483126,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483139,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483177,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483194, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 4 -> S-1-22-2-4 fallback
>[2021/03/02 17:46:51.483219,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483235,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483249,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483268,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483281,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483316,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483331, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 24 -> S-1-22-2-24 fallback
>[2021/03/02 17:46:51.483355,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483370,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483384,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483398,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483411,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483445,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483462, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 27 -> S-1-22-2-27 fallback
>[2021/03/02 17:46:51.483486,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483501,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483515,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483529,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483543,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483579,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483595, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 30 -> S-1-22-2-30 fallback
>[2021/03/02 17:46:51.483617,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483632,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483646,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483659,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483673,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483710,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483726, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 46 -> S-1-22-2-46 fallback
>[2021/03/02 17:46:51.483751,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483767,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483781,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483794,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483808,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483841,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483858, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 118 -> S-1-22-2-118 fallback
>[2021/03/02 17:46:51.483882,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483898,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483912,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.483925,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.483939,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.483973,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.483990, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 129 -> S-1-22-2-129 fallback
>[2021/03/02 17:46:51.484014,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484029,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484042,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484056,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.484069,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.484106,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484123, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1245(xid_to_sid)
>  xid_to_sid: GID 134 -> S-1-22-2-134 fallback
>[2021/03/02 17:46:51.484144,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484164,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484178,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484192,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.484205,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.484233,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/auth/token_util.c:688(finalize_local_nt_token)
>  Failed to fetch domain sid for WORKGROUP
>[2021/03/02 17:46:51.484252,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484274,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484290,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484304,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484318,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.484331,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.484362, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1574(pdb_default_sid_to_id)
>  Could not find map for sid S-1-5-32-544
>[2021/03/02 17:46:51.484383,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/pdb_util.c:162(create_builtin_administrators)
>  create_builtin_administrators: Failed to create Administrators
>[2021/03/02 17:46:51.484403,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484426,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484441,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484455,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484469,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.484482,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.484512, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1574(pdb_default_sid_to_id)
>  Could not find map for sid S-1-5-32-545
>[2021/03/02 17:46:51.484534,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/pdb_util.c:132(create_builtin_users)
>  create_builtin_users: Failed to create Users
>[2021/03/02 17:46:51.484555,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484576,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484596,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484609,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484623,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.484636,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.484664, 10, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1574(pdb_default_sid_to_id)
>  Could not find map for sid S-1-5-32-546
>[2021/03/02 17:46:51.484683,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/pdb_util.c:205(create_builtin_guests)
>  create_builtin_guests: Failed to create Guests
>[2021/03/02 17:46:51.484703,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484722,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484737,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484751,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.484764,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.484778,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.484918,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.484941,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1914782597-1700840410-671320589-1000]
>[2021/03/02 17:46:51.484961,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-21-1914782597-1700840410-671320589-513]
>[2021/03/02 17:46:51.484979,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-1000]
>[2021/03/02 17:46:51.484997,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-4]
>[2021/03/02 17:46:51.485015,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-24]
>[2021/03/02 17:46:51.485034,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-27]
>[2021/03/02 17:46:51.485052,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-30]
>[2021/03/02 17:46:51.485071,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-46]
>[2021/03/02 17:46:51.485090,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-118]
>[2021/03/02 17:46:51.485112,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-129]
>[2021/03/02 17:46:51.485129,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-22-2-134]
>[2021/03/02 17:46:51.485148,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:179(get_privileges_for_sids)
>  get_privileges_for_sids: sid = S-1-1-0
>  Privilege set: 0x0
>[2021/03/02 17:46:51.485169,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-2]
>[2021/03/02 17:46:51.485188,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/privileges.c:99(get_privileges)
>  get_privileges: No privileges assigned to SID [S-1-5-11]
>[2021/03/02 17:46:51.485215, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:58(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: value=[1000:U]
>[2021/03/02 17:46:51.485231, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/idmap_cache.c:76(idmap_cache_find_sid2unixid)
>  Parsing value for key [IDMAP/SID2XID/S-1-5-21-1914782597-1700840410-671320589-1000]: id=[1000], endptr=[:U]
>[2021/03/02 17:46:51.485263, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1318(sids_to_unixids)
>  wbcSidsToUnixIds returned WBC_ERR_WINBIND_NOT_AVAILABLE
>[2021/03/02 17:46:51.485278,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485293,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.485306,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485318,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.485331,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.485356,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid)
>  lookup_global_sam_rid: looking up RID 513.
>[2021/03/02 17:46:51.485372,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.485387,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485400,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.485413,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.485426,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.485453,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid)
>  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
>[2021/03/02 17:46:51.485482,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485498,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid)
>  Can't find a unix id for an unmapped group
>[2021/03/02 17:46:51.485516,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id)
>  SID S-1-5-21-1914782597-1700840410-671320589-513 belongs to our domain, but there is no corresponding object in the database.
>[2021/03/02 17:46:51.485539,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.485554, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-21-1914782597-1700840410-671320589-513
>[2021/03/02 17:46:51.485569,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485583,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.485596,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485609,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.485622,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.485644,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1761(lookup_global_sam_rid)
>  lookup_global_sam_rid: looking up RID 513.
>[2021/03/02 17:46:51.485660,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.485674,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485688,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 2
>[2021/03/02 17:46:51.485701,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.485713,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.485740,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_tdb.c:660(tdbsam_getsampwrid)
>  pdb_getsampwrid (TDB): error looking up RID 513 by key RID_00000201.
>[2021/03/02 17:46:51.485766,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485781,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1838(lookup_global_sam_rid)
>  Can't find a unix id for an unmapped group
>[2021/03/02 17:46:51.485795,  5, pid=592878, effective(0, 0), real(0, 0), class=passdb] ../../source3/passdb/pdb_interface.c:1545(pdb_default_sid_to_id)
>  SID S-1-5-21-1914782597-1700840410-671320589-513 belongs to our domain, but there is no corresponding object in the database.
>[2021/03/02 17:46:51.485815,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.485830, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-21-1914782597-1700840410-671320589-513
>[2021/03/02 17:46:51.485846,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485864,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.485877,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485890,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.485902,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.485931,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.485946, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-1-0
>[2021/03/02 17:46:51.485961,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.485975,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.485989,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486002,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.486014,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.486041,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486056, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-1-0
>[2021/03/02 17:46:51.486070,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486083,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486096,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486110,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.486122,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.486150,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486165, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-2
>[2021/03/02 17:46:51.486180,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486193,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486206,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486219,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.486236,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.486264,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486279, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-2
>[2021/03/02 17:46:51.486294,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486308,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486321,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486334,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.486346,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.486375,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486390, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-11
>[2021/03/02 17:46:51.486405,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:215(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486419,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/uid.c:561(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486433,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:319(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2021/03/02 17:46:51.486446,  5, pid=592878, effective(0, 0), real(0, 0)] ../../libcli/security/security_token.c:47(security_token_debug)
>  Security token: (NULL)
>[2021/03/02 17:46:51.486459,  5, pid=592878, effective(0, 0), real(0, 0)] ../../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/03/02 17:46:51.486485,  4, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/sec_ctx.c:437(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2021/03/02 17:46:51.486500, 10, pid=592878, effective(0, 0), real(0, 0)] ../../source3/passdb/lookup_sid.c:1144(legacy_sid_to_unixid)
>  LEGACY: mapping failed for sid S-1-5-11
>[2021/03/02 17:46:51.486515, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-5-21-1914782597-1700840410-671320589-513 to gid, ignoring it
>[2021/03/02 17:46:51.486532, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-1-0 to gid, ignoring it
>[2021/03/02 17:46:51.486546, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-5-2 to gid, ignoring it
>[2021/03/02 17:46:51.486560, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/auth_util.c:636(create_local_token)
>  Could not convert SID S-1-5-11 to gid, ignoring it
>[2021/03/02 17:46:51.486581, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../libcli/security/security_token.c:51(security_token_debug)
>  Security token SIDs (15):
>    SID[  0]: S-1-5-21-1914782597-1700840410-671320589-1000
>    SID[  1]: S-1-5-21-1914782597-1700840410-671320589-513
>    SID[  2]: S-1-22-2-1000
>    SID[  3]: S-1-22-2-4
>    SID[  4]: S-1-22-2-24
>    SID[  5]: S-1-22-2-27
>    SID[  6]: S-1-22-2-30
>    SID[  7]: S-1-22-2-46
>    SID[  8]: S-1-22-2-118
>    SID[  9]: S-1-22-2-129
>    SID[ 10]: S-1-22-2-134
>    SID[ 11]: S-1-1-0
>    SID[ 12]: S-1-5-2
>    SID[ 13]: S-1-5-11
>    SID[ 14]: S-1-22-1-1000
>   Privileges (0x               0):
>   Rights (0x               0):
>[2021/03/02 17:46:51.486662, 10, pid=592878, effective(0, 0), real(0, 0), class=auth] ../../source3/auth/token_util.c:873(debug_unix_user_token)
>  UNIX token of user 1000
>  Primary group is 1000 and contains 9 supplementary groups
>  Group[  0]: 1000
>  Group[  1]: 4
>  Group[  2]: 24
>  Group[  3]: 27
>  Group[  4]: 30
>  Group[  5]: 46
>  Group[  6]: 118
>  Group[  7]: 129
>  Group[  8]: 134
>[2021/03/02 17:46:51.486729,  4, pid=592878, effective(0, 0), real(0, 0), class=auth_audit] ../../auth/auth_log.c:740(log_successful_authz_event_human_readable)
>  Successful AuthZ: [SMB2,NTLMSSP] user [JEREMY-ACERB]\[jeremy] [S-1-5-21-1914782597-1700840410-671320589-1000] at [Tue, 02 Mar 2021 17:46:51.486719 PST] Remote host [ipv4:127.0.0.1:46096] local host [ipv4:127.0.0.1:445]
>  {"timestamp": "2021-03-02T17:46:51.486767-0800", "type": "Authorization", "Authorization": {"version": {"major": 1, "minor": 1}, "localAddress": "ipv4:127.0.0.1:445", "remoteAddress": "ipv4:127.0.0.1:46096", "serviceDescription": "SMB2", "authType": "NTLMSSP", "domain": "JEREMY-ACERB", "account": "jeremy", "sid": "S-1-5-21-1914782597-1700840410-671320589-1000", "sessionId": "eb3a4ee7-6d63-4013-9af5-3d0b16cff1b3", "logonServer": "JEREMY-ACERB", "transportProtection": "SMB", "accountFlags": "0x00000010"}}
>[2021/03/02 17:46:51.486860,  7, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:4327(lp_servicenumber)
>  lp_servicenumber: couldn't find jeremy
>[2021/03/02 17:46:51.486877,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:182(Get_Pwnam_alloc)
>  Finding user jeremy
>[2021/03/02 17:46:51.486891,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:121(Get_Pwnam_internals)
>  Trying _Get_Pwnam(), username as lowercase is jeremy
>[2021/03/02 17:46:51.486907,  5, pid=592878, effective(0, 0), real(0, 0)] ../../source3/lib/username.c:159(Get_Pwnam_internals)
>  Get_Pwnam_internals did find user [jeremy]!
>[2021/03/02 17:46:51.486925,  3, pid=592878, effective(0, 0), real(0, 0)] ../../source3/smbd/password.c:84(register_homes_share)
>  Adding homes service for user 'jeremy' using home directory: '/home/jeremy'
>[2021/03/02 17:46:51.486951,  7, pid=592878, effective(0, 0), real(0, 0)] ../../source3/param/loadparm.c:4327(lp_servicenumber)
>  lp_servicenumber: couldn't find homes
>[2021/03/02 17:46:51.486976,  5, pid=592878, effective(0, 0), real(0, 0)] ../../lib/util/debug.c:811(debug_dump_status)
>  INFO: Current debug levels:
>    all: 10
>    tdb: 10
>    printdrivers: 10
>    lanman: 10
>    smb: 10
>    rpc_parse: 10
>    rpc_srv: 10
>    rpc_cli: 10
>    passdb: 10
>    sam: 10
>    auth: 10
>    winbind: 10
>    vfs: 10
>    idmap: 10
>    quota: 10
>    acls: 10
>    locking: 10
>    msdfs: 10
>    dmapi: 10
>    registry: 10
>    scavenger: 10
>    dns: 10
>    ldb: 10
>    tevent: 10
>    auth_audit: 10
>    auth_json_audit: 10
>    kerberos: 10
>    drs_repl: 10
>    smb2: 10
>    smb2_credits: 10
>    dsdb_audit: 10
>    dsdb_json_audit: 10
>    dsdb_password_audit: 10
>    dsdb_password_json_audit: 10
>    dsdb_transaction_audit: 10
>    dsdb_transaction_json_audit: 10
>    dsdb_group_audit: 10
>    dsdb_group_json_audit: 10

Actions: View

Attachments on bug 14636: 16489 | 16490 | 16491 | 16492 | 16495 | 16496 | 16497 | 16498 | 16502 | 16503 | 16504