Attachment 12781 Details for Bug 12488 – Level 10 debug log file

Level 10 debug log file

10.99.0.81.log (text/x-log), 93.61 KB, created by Marc Muehlfeld on 2016-12-29 18:42:49 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Marc Muehlfeld

Created: 2016-12-29 18:42:49 UTC

Size: 93.61 KB

patch

obsolete

>[2016/12/29 19:32:48.141304,  6, pid=21622, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2222(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba//smb.conf -> /etc/samba//smb.conf  last mod_time: Thu Dec 29 19:31:25 2016
>  
>[2016/12/29 19:32:48.141362,  3, pid=21622, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1322(init_oplocks)
>  init_oplocks: initializing messages.
>[2016/12/29 19:32:48.141377,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 774 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141390,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 778 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141401,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 770 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141412,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 787 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141423,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 779 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141434,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 15 - private_data=(nil)
>[2016/12/29 19:32:48.141445,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:371(messaging_register)
>  Overriding messaging pointer for type 15 - private_data=(nil)
>[2016/12/29 19:32:48.141455,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 16 - private_data=(nil)
>[2016/12/29 19:32:48.141466,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 16 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141478,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 33 - private_data=0x7fc2a0584140
>[2016/12/29 19:32:48.141489,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 33 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141500,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 790 - private_data=(nil)
>[2016/12/29 19:32:48.141511,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 790 - private_data=0x7fc2a05a1f80
>[2016/12/29 19:32:48.141522,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 791 - private_data=(nil)
>[2016/12/29 19:32:48.141533,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 1 - private_data=(nil)
>[2016/12/29 19:32:48.141565,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 1 - private_data=(nil)
>[2016/12/29 19:32:48.141585, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle)
>  event_add_idle: idle_evt(keepalive) 0x7fc2a059e880
>[2016/12/29 19:32:48.141598, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle)
>  event_add_idle: idle_evt(deadtime) 0x7fc2a059ead0
>[2016/12/29 19:32:48.141611, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle)
>  event_add_idle: idle_evt(housekeeping) 0x7fc2a059ed20
>[2016/12/29 19:32:48.141648, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:248(read_smb_length_return_keepalive)
>  got smb length of 155
>[2016/12/29 19:32:48.141681,  6, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1955(process_smb)
>  got message type 0x0 of len 0x9b
>[2016/12/29 19:32:48.141707,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1957(process_smb)
>  Transaction 0 of length 159 (0 toread)
>[2016/12/29 19:32:48.141723,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util.c:171(show_msg)
>[2016/12/29 19:32:48.141733,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util.c:181(show_msg)
>  size=155
>  smb_com=0x72
>  smb_rcls=0
>  smb_reh=0
>  smb_err=0
>  smb_flg=24
>  smb_flg2=51283
>  smb_tid=65535
>  smb_pid=65279
>  smb_uid=0
>  smb_mid=0
>  smt_wct=0
>  smb_bcc=120
>[2016/12/29 19:32:48.141764, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/util/util.c:555(dump_data)
>  [0000] 02 50 43 20 4E 45 54 57   4F 52 4B 20 50 52 4F 47   .PC NETW ORK PROG
>  [0010] 52 41 4D 20 31 2E 30 00   02 4C 41 4E 4D 41 4E 31   RAM 1.0. .LANMAN1
>  [0020] 2E 30 00 02 57 69 6E 64   6F 77 73 20 66 6F 72 20   .0..Wind ows for 
>  [0030] 57 6F 72 6B 67 72 6F 75   70 73 20 33 2E 31 61 00   Workgrou ps 3.1a.
>  [0040] 02 4C 4D 31 2E 32 58 30   30 32 00 02 4C 41 4E 4D   .LM1.2X0 02..LANM
>  [0050] 41 4E 32 2E 31 00 02 4E   54 20 4C 4D 20 30 2E 31   AN2.1..N T LM 0.1
>  [0060] 32 00 02 53 4D 42 20 32   2E 30 30 32 00 02 53 4D   2..SMB 2 .002..SM
>  [0070] 42 20 32 2E 3F 3F 3F 00                             B 2.???. 
>[2016/12/29 19:32:48.141912,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1538(switch_message)
>  switch message SMBnegprot (pid 21622) conn 0x0
>[2016/12/29 19:32:48.141934,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.141951,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.141967,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.141994,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.142678,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [PC NETWORK PROGRAM 1.0]
>[2016/12/29 19:32:48.142717,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [LANMAN1.0]
>[2016/12/29 19:32:48.142732,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [Windows for Workgroups 3.1a]
>[2016/12/29 19:32:48.142744,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [LM1.2X002]
>[2016/12/29 19:32:48.142756,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [LANMAN2.1]
>[2016/12/29 19:32:48.142768,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [NT LM 0.12]
>[2016/12/29 19:32:48.142779,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [SMB 2.002]
>[2016/12/29 19:32:48.142791,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:601(reply_negprot)
>  Requested protocol [SMB 2.???]
>[2016/12/29 19:32:48.142808, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1325(set_remote_arch)
>  set_remote_arch: Client arch is 'UNKNOWN'
>[2016/12/29 19:32:48.142852,  6, pid=21622, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2222(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba//smb.conf -> /etc/samba//smb.conf  last mod_time: Thu Dec 29 19:31:25 2016
>  
>[2016/12/29 19:32:48.142882,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_check_lock_order)
>  check lock order 2 for /usr/var/samba/lock/serverid.tdb
>[2016/12/29 19:32:48.142901, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:/usr/var/samba/lock/serverid.tdb 3:<none>
>[2016/12/29 19:32:48.142917, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Locking key 7654000000000000FFFF
>[2016/12/29 19:32:48.142934, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x7fc2a05a3120
>[2016/12/29 19:32:48.142954, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Unlocking key 7654000000000000FFFF
>[2016/12/29 19:32:48.142967,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:127(dbwrap_lock_order_state_destructor)
>  release lock order 2 for /usr/var/samba/lock/serverid.tdb
>[2016/12/29 19:32:48.142979, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:<none> 3:<none>
>[2016/12/29 19:32:48.143011,  6, pid=21622, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2222(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba//smb.conf -> /etc/samba//smb.conf  last mod_time: Thu Dec 29 19:31:25 2016
>  
>[2016/12/29 19:32:48.143041, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3463(smbd_smb2_process_negprot)
>  smbd_smb2_first_negprot: packet length 102
>[2016/12/29 19:32:48.143070, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:660(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: clearing id 0 (position 0) from bitmap
>[2016/12/29 19:32:48.143091, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2257(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_NEGPROT] mid = 0
>[2016/12/29 19:32:48.143108,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.143120,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.143130,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.143149,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.143174, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1325(set_remote_arch)
>  set_remote_arch: Client arch is 'Vista'
>[2016/12/29 19:32:48.143205,  6, pid=21622, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2222(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba//smb.conf -> /etc/samba//smb.conf  last mod_time: Thu Dec 29 19:31:25 2016
>  
>[2016/12/29 19:32:48.143229,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_negprot.c:290(smbd_smb2_request_process_negprot)
>  Selected protocol SMB2_FF
>[2016/12/29 19:32:48.143250,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:477(make_auth_context_subsystem)
>  Making default auth method list for server role = 'domain member'
>[2016/12/29 19:32:48.143279,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend trustdomain
>[2016/12/29 19:32:48.143297,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'trustdomain'
>[2016/12/29 19:32:48.143308,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend ntdomain
>[2016/12/29 19:32:48.143319,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'ntdomain'
>[2016/12/29 19:32:48.143337,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend guest
>[2016/12/29 19:32:48.143349,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'guest'
>[2016/12/29 19:32:48.143360,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend sam
>[2016/12/29 19:32:48.143370,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'sam'
>[2016/12/29 19:32:48.143381,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend sam_ignoredomain
>[2016/12/29 19:32:48.143391,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'sam_ignoredomain'
>[2016/12/29 19:32:48.143403,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend winbind
>[2016/12/29 19:32:48.143415,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'winbind'
>[2016/12/29 19:32:48.143427,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend unix
>[2016/12/29 19:32:48.143439,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'unix'
>[2016/12/29 19:32:48.143449,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend wbc
>[2016/12/29 19:32:48.143460,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'wbc'
>[2016/12/29 19:32:48.143472,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend samba4
>[2016/12/29 19:32:48.143484,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'samba4'
>[2016/12/29 19:32:48.143494,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match guest
>[2016/12/29 19:32:48.143506,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method guest has a valid init
>[2016/12/29 19:32:48.143517,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam
>[2016/12/29 19:32:48.143528,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method sam has a valid init
>[2016/12/29 19:32:48.143560,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match winbind:ntdomain
>[2016/12/29 19:32:48.143574,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match ntdomain
>[2016/12/29 19:32:48.143586,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method ntdomain has a valid init
>[2016/12/29 19:32:48.143596,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method winbind has a valid init
>[2016/12/29 19:32:48.143821,  5, pid=21622, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC mechanism spnego
>[2016/12/29 19:32:48.143879,  5, pid=21622, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC submechanism gse_krb5
>[2016/12/29 19:32:48.245997, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/librpc/crypto/gse_krb5.c:312(fill_mem_keytab_from_secrets)
>  ../source3/librpc/crypto/gse_krb5.c:312: no prev machine password
>[2016/12/29 19:32:48.246272, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2988(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[64] dyn[yes:96] at ../source3/smbd/smb2_negprot.c:702
>[2016/12/29 19:32:48.246295, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:912(smb2_set_operation_credit)
>  smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 512/512, total granted/max/low/range 1/8192/1/1
>[2016/12/29 19:32:48.246341,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:711(reply_negprot)
>  Selected protocol SMB 2.???
>[2016/12/29 19:32:48.246351,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:718(reply_negprot)
>  negprot index=7
>[2016/12/29 19:32:48.246851, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3848(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2016/12/29 19:32:48.246876, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:660(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: clearing id 1 (position 1) from bitmap
>[2016/12/29 19:32:48.246888, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2257(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_NEGPROT] mid = 1
>[2016/12/29 19:32:48.246900,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.246909,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.246917,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.246934,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.246948,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.246968,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.246975,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.246982,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.246989,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.247036, 10, pid=21622, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:326(gencache_set_data_blob)
>  Adding cache entry with key=[RA/2cf133f5-cdf1-11e6-8272-9514b749f6fe] and timeout=[Do Jan  1 01:00:00 1970 CET] (-1483036368 seconds in the past)
>[2016/12/29 19:32:48.247080, 10, pid=21622, effective(0, 0), real(0, 0), class=tdb] ../source3/lib/gencache.c:326(gencache_set_data_blob)
>  Adding cache entry with key=[RA/2cf133f5-cdf1-11e6-8272-9514b749f6fe] and timeout=[Do Jan  5 19:32:48 2017 CET] (604800 seconds ahead)
>[2016/12/29 19:32:48.247101,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.247151,  6, pid=21622, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2222(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba//smb.conf -> /etc/samba//smb.conf  last mod_time: Thu Dec 29 19:31:25 2016
>  
>[2016/12/29 19:32:48.247173,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_negprot.c:290(smbd_smb2_request_process_negprot)
>  Selected protocol SMB3_02
>[2016/12/29 19:32:48.247184,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:477(make_auth_context_subsystem)
>  Making default auth method list for server role = 'domain member'
>[2016/12/29 19:32:48.247197,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match guest
>[2016/12/29 19:32:48.247207,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method guest has a valid init
>[2016/12/29 19:32:48.247215,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam
>[2016/12/29 19:32:48.247223,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method sam has a valid init
>[2016/12/29 19:32:48.247230,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match winbind:ntdomain
>[2016/12/29 19:32:48.247238,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match ntdomain
>[2016/12/29 19:32:48.247246,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method ntdomain has a valid init
>[2016/12/29 19:32:48.247254,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method winbind has a valid init
>[2016/12/29 19:32:48.247295,  5, pid=21622, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC mechanism spnego
>[2016/12/29 19:32:48.247324,  5, pid=21622, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC submechanism gse_krb5
>[2016/12/29 19:32:48.326826, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/librpc/crypto/gse_krb5.c:312(fill_mem_keytab_from_secrets)
>  ../source3/librpc/crypto/gse_krb5.c:312: no prev machine password
>[2016/12/29 19:32:48.326950, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2988(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[64] dyn[yes:96] at ../source3/smbd/smb2_negprot.c:657
>[2016/12/29 19:32:48.326964, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:912(smb2_set_operation_credit)
>  smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 512/512, total granted/max/low/range 1/8192/2/1
>[2016/12/29 19:32:48.328015, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3848(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2016/12/29 19:32:48.328038, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:660(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: clearing id 2 (position 2) from bitmap
>[2016/12/29 19:32:48.328047, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2257(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_SESSSETUP] mid = 2
>[2016/12/29 19:32:48.328056,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.328071,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.328077,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.328090,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.328111,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_check_lock_order)
>  check lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:48.328119, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:/usr/var/samba/lock/smbXsrv_session_global.tdb 2:<none> 3:<none>
>[2016/12/29 19:32:48.328130, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Locking key 3830B901
>[2016/12/29 19:32:48.328143, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x7fc2a059fdd0
>[2016/12/29 19:32:48.328707, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:943(smbXsrv_session_global_store)
>[2016/12/29 19:32:48.328723, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:945(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key '3830B901' stored
>[2016/12/29 19:32:48.328733,  1, pid=21622, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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        : 0x3830b901 (942717185)
>                  session_wire_id          : 0x000000003830b901 (942717185)
>                  creation_time            : Do Dez 29 19:32:48 2016 CET
>                  expiration_time          : Do Jan  1 01:00:00 1970 CET
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0302 (770)
>                  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
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x0000000000005476 (21622)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0xf5c0281f4c73e243 (-738546223997001149)
>                          local_address            : 'ipv4:10.99.0.5:445'
>                          remote_address           : 'ipv4:10.99.0.81:49266'
>                          remote_name              : '10.99.0.81'
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2016/12/29 19:32:48.328846,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:127(dbwrap_lock_order_state_destructor)
>  release lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:48.328854, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:<none> 3:<none>
>[2016/12/29 19:32:48.328863, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Unlocking key 3830B901
>[2016/12/29 19:32:48.328871, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1310(smbXsrv_session_create)
>[2016/12/29 19:32:48.328876, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1318(smbXsrv_session_create)
>  smbXsrv_session_create: global_id (0x3830b901) stored
>[2016/12/29 19:32:48.328882,  1, pid=21622, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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                 : 0x3830b901 (942717185)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0x3830b901 (942717185)
>                          session_wire_id          : 0x000000003830b901 (942717185)
>                          creation_time            : Do Dez 29 19:32:48 2016 CET
>                          expiration_time          : Do Jan  1 01:00:00 1970 CET
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0302 (770)
>                          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
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x0000000000005476 (21622)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0xf5c0281f4c73e243 (-738546223997001149)
>                                  local_address            : 'ipv4:10.99.0.5:445'
>                                  remote_address           : 'ipv4:10.99.0.81:49266'
>                                  remote_name              : '10.99.0.81'
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Do Dez 29 19:32:48 2016 CET
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  compat                   : NULL
>                  tcon_table               : *
>                  pending_auth             : NULL
>[2016/12/29 19:32:48.329039,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:477(make_auth_context_subsystem)
>  Making default auth method list for server role = 'domain member'
>[2016/12/29 19:32:48.329051,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match guest
>[2016/12/29 19:32:48.329059,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method guest has a valid init
>[2016/12/29 19:32:48.329065,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam
>[2016/12/29 19:32:48.329071,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method sam has a valid init
>[2016/12/29 19:32:48.329077,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match winbind:ntdomain
>[2016/12/29 19:32:48.329083,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match ntdomain
>[2016/12/29 19:32:48.329089,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method ntdomain has a valid init
>[2016/12/29 19:32:48.329095,  5, pid=21622, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method winbind has a valid init
>[2016/12/29 19:32:48.329133,  5, pid=21622, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC mechanism spnego
>[2016/12/29 19:32:48.329143,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_check_lock_order)
>  check lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:48.329149, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:/usr/var/samba/lock/smbXsrv_session_global.tdb 2:<none> 3:<none>
>[2016/12/29 19:32:48.329158, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Locking key 3830B901
>[2016/12/29 19:32:48.329168, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x7fc2a05a9a40
>[2016/12/29 19:32:48.329186, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:943(smbXsrv_session_global_store)
>[2016/12/29 19:32:48.329192, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:945(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key '3830B901' stored
>[2016/12/29 19:32:48.329198,  1, pid=21622, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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        : 0x3830b901 (942717185)
>                  session_wire_id          : 0x000000003830b901 (942717185)
>                  creation_time            : Do Dez 29 19:32:48 2016 CET
>                  expiration_time          : Do Jan  1 01:00:00 1970 CET
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0302 (770)
>                  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
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x0000000000005476 (21622)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0xf5c0281f4c73e243 (-738546223997001149)
>                          local_address            : 'ipv4:10.99.0.5:445'
>                          remote_address           : 'ipv4:10.99.0.81:49266'
>                          remote_name              : '10.99.0.81'
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2016/12/29 19:32:48.329299,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:127(dbwrap_lock_order_state_destructor)
>  release lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:48.329306, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:<none> 3:<none>
>[2016/12/29 19:32:48.329314, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Unlocking key 3830B901
>[2016/12/29 19:32:48.329322, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1405(smbXsrv_session_update)
>[2016/12/29 19:32:48.329326, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1413(smbXsrv_session_update)
>  smbXsrv_session_update: global_id (0x3830b901) stored
>[2016/12/29 19:32:48.329332,  1, pid=21622, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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                 : 0x3830b901 (942717185)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0x3830b901 (942717185)
>                          session_wire_id          : 0x000000003830b901 (942717185)
>                          creation_time            : Do Dez 29 19:32:48 2016 CET
>                          expiration_time          : Do Jan  1 01:00:00 1970 CET
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0302 (770)
>                          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
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x0000000000005476 (21622)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0xf5c0281f4c73e243 (-738546223997001149)
>                                  local_address            : 'ipv4:10.99.0.5:445'
>                                  remote_address           : 'ipv4:10.99.0.81:49266'
>                                  remote_name              : '10.99.0.81'
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Do Dez 29 19:32:48 2016 CET
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  compat                   : NULL
>                  tcon_table               : *
>                  pending_auth             : *
>                      pending_auth: struct smbXsrv_session_auth0
>                          prev                     : *
>                          next                     : NULL
>                          session                  : *
>                          connection               : *
>                          gensec                   : *
>                          preauth                  : NULL
>                          in_flags                 : 0x00 (0)
>                          in_security_mode         : 0x01 (1)
>                          creation_time            : Do Dez 29 19:32:48 2016 CET
>                          idle_time                : Do Dez 29 19:32:48 2016 CET
>[2016/12/29 19:32:48.329503,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.329510,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.329516,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.329522,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.329527,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.329555,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>  smbd_smb2_request_pending_queue: req->current_idx = 1
>  	req->in.vector[0].iov_len = 0
>  	req->in.vector[1].iov_len = 0
>  	req->in.vector[2].iov_len = 64
>  	req->in.vector[3].iov_len = 24
>  	req->in.vector[4].iov_len = 1855
>  	req->out.vector[0].iov_len = 4
>  	req->out.vector[1].iov_len = 0
>  	req->out.vector[2].iov_len = 64
>  	req->out.vector[3].iov_len = 8
>  	req->out.vector[4].iov_len = 0
>[2016/12/29 19:32:48.329630,  5, pid=21622, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC submechanism gse_krb5
>[2016/12/29 19:32:48.399175, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/librpc/crypto/gse_krb5.c:312(fill_mem_keytab_from_secrets)
>  ../source3/librpc/crypto/gse_krb5.c:312: no prev machine password
>[2016/12/29 19:32:48.399763,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.399779,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.399785,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.399791,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.399797,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.399810,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.399862,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.399870,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.399876,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:48.399881,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.399887,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.400870,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.400895,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3097(smbd_smb2_request_error_ex)
>  smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_UNSUCCESSFUL] || at ../source3/smbd/smb2_sesssetup.c:134
>[2016/12/29 19:32:48.400904, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2988(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_UNSUCCESSFUL] body[8] dyn[yes:1] at ../source3/smbd/smb2_server.c:3145
>[2016/12/29 19:32:48.400913, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:912(smb2_set_operation_credit)
>  smb2_set_operation_credit: requested 31, charge 1, granted 1, current possible/max 512/512, total granted/max/low/range 1/8192/3/1
>[2016/12/29 19:32:48.400943,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_check_lock_order)
>  check lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:48.400959, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:/usr/var/samba/lock/smbXsrv_session_global.tdb 2:<none> 3:<none>
>[2016/12/29 19:32:48.400969, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Locking key 3830B901
>[2016/12/29 19:32:48.400980, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x7fc2a05a5d90
>[2016/12/29 19:32:48.400992,  5, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:127(dbwrap_lock_order_state_destructor)
>  release lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:48.401021, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:<none> 3:<none>
>[2016/12/29 19:32:48.401029, 10, pid=21622, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Unlocking key 3830B901
>[2016/12/29 19:32:48.401834, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:1069(smbd_server_connection_terminate_ex)
>  smbd_server_connection_terminate_ex: conn[ipv4:10.99.0.81:49266] reason[NT_STATUS_CONNECTION_RESET] at ../source3/smbd/smb2_server.c:3900
>[2016/12/29 19:32:48.401867,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.401875,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.401881,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.401892,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.401899,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.401905,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.401910,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.401918,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.401925,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.401931,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.401936,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.401945,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.401952,  4, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:48.401958,  5, pid=21622, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:48.401963,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:48.401971,  5, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:48.402004, 10, pid=21622, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:142(msg_dgm_ref_destructor)
>  msg_dgm_ref_destructor: refs=(nil)
>[2016/12/29 19:32:48.402109,  3, pid=21622, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:246(exit_server_common)
>  Server exit (NT_STATUS_CONNECTION_RESET)
>[2016/12/29 19:32:49.500492,  6, pid=21626, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2222(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba//smb.conf -> /etc/samba//smb.conf  last mod_time: Thu Dec 29 19:31:25 2016
>  
>[2016/12/29 19:32:49.500582,  3, pid=21626, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1322(init_oplocks)
>  init_oplocks: initializing messages.
>[2016/12/29 19:32:49.500606,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 774 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500621,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 778 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500634,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 770 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500648,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 787 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500661,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 779 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500674,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 15 - private_data=(nil)
>[2016/12/29 19:32:49.500687,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:371(messaging_register)
>  Overriding messaging pointer for type 15 - private_data=(nil)
>[2016/12/29 19:32:49.500715,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 16 - private_data=(nil)
>[2016/12/29 19:32:49.500729,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 16 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500744,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 33 - private_data=0x7fc2a0584140
>[2016/12/29 19:32:49.500757,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 33 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500770,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 790 - private_data=(nil)
>[2016/12/29 19:32:49.500783,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 790 - private_data=0x7fc2a05a1ef0
>[2016/12/29 19:32:49.500797,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 791 - private_data=(nil)
>[2016/12/29 19:32:49.500810,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:403(messaging_deregister)
>  Deregistering messaging pointer for type 1 - private_data=(nil)
>[2016/12/29 19:32:49.500823,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:356(messaging_register)
>  Registering messaging pointer for type 1 - private_data=(nil)
>[2016/12/29 19:32:49.500845, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle)
>  event_add_idle: idle_evt(keepalive) 0x7fc2a059eb90
>[2016/12/29 19:32:49.500861, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle)
>  event_add_idle: idle_evt(deadtime) 0x7fc2a059ede0
>[2016/12/29 19:32:49.500876, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle)
>  event_add_idle: idle_evt(housekeeping) 0x7fc2a059f030
>[2016/12/29 19:32:49.500920, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:248(read_smb_length_return_keepalive)
>  got smb length of 108
>[2016/12/29 19:32:49.500951,  6, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1955(process_smb)
>  got message type 0x0 of len 0x6c
>[2016/12/29 19:32:49.500974,  3, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1957(process_smb)
>  Transaction 0 of length 112 (0 toread)
>[2016/12/29 19:32:49.500991, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3463(smbd_smb2_process_negprot)
>  smbd_smb2_first_negprot: packet length 108
>[2016/12/29 19:32:49.501025, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:660(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: clearing id 0 (position 0) from bitmap
>[2016/12/29 19:32:49.501052, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2257(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_NEGPROT] mid = 0
>[2016/12/29 19:32:49.501082,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.501102,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.501122,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.501153,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:49.501191,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.501208,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.501221,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.501233,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.501245,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.501310,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1374(ra_parser)
>  ra_parser: Got remote arch [Vista] from cache
>[2016/12/29 19:32:49.501340, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1325(set_remote_arch)
>  set_remote_arch: Client arch is 'Vista'
>[2016/12/29 19:32:49.501359,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.501402,  6, pid=21626, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2222(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba//smb.conf -> /etc/samba//smb.conf  last mod_time: Thu Dec 29 19:31:25 2016
>  
>[2016/12/29 19:32:49.501434,  3, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_negprot.c:290(smbd_smb2_request_process_negprot)
>  Selected protocol SMB3_02
>[2016/12/29 19:32:49.501459,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:477(make_auth_context_subsystem)
>  Making default auth method list for server role = 'domain member'
>[2016/12/29 19:32:49.501487,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend trustdomain
>[2016/12/29 19:32:49.501509,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'trustdomain'
>[2016/12/29 19:32:49.501522,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend ntdomain
>[2016/12/29 19:32:49.501535,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'ntdomain'
>[2016/12/29 19:32:49.501581,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend guest
>[2016/12/29 19:32:49.501596,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'guest'
>[2016/12/29 19:32:49.501609,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend sam
>[2016/12/29 19:32:49.501621,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'sam'
>[2016/12/29 19:32:49.501633,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend sam_ignoredomain
>[2016/12/29 19:32:49.501646,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'sam_ignoredomain'
>[2016/12/29 19:32:49.501660,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend winbind
>[2016/12/29 19:32:49.501673,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'winbind'
>[2016/12/29 19:32:49.501687,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend unix
>[2016/12/29 19:32:49.501717,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'unix'
>[2016/12/29 19:32:49.501737,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend wbc
>[2016/12/29 19:32:49.501749,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'wbc'
>[2016/12/29 19:32:49.501763,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth)
>  Attempting to register auth backend samba4
>[2016/12/29 19:32:49.501776,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth)
>  Successfully added auth method 'samba4'
>[2016/12/29 19:32:49.501788,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match guest
>[2016/12/29 19:32:49.501807,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method guest has a valid init
>[2016/12/29 19:32:49.501821,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam
>[2016/12/29 19:32:49.501835,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method sam has a valid init
>[2016/12/29 19:32:49.501847,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match winbind:ntdomain
>[2016/12/29 19:32:49.501860,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match ntdomain
>[2016/12/29 19:32:49.501872,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method ntdomain has a valid init
>[2016/12/29 19:32:49.501884,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method winbind has a valid init
>[2016/12/29 19:32:49.502163,  5, pid=21626, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC mechanism spnego
>[2016/12/29 19:32:49.502229,  5, pid=21626, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC submechanism gse_krb5
>[2016/12/29 19:32:49.619470, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/librpc/crypto/gse_krb5.c:312(fill_mem_keytab_from_secrets)
>  ../source3/librpc/crypto/gse_krb5.c:312: no prev machine password
>[2016/12/29 19:32:49.619758, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2988(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[64] dyn[yes:96] at ../source3/smbd/smb2_negprot.c:657
>[2016/12/29 19:32:49.619784, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:912(smb2_set_operation_credit)
>  smb2_set_operation_credit: requested 31, charge 1, granted 1, current possible/max 512/512, total granted/max/low/range 1/8192/1/1
>[2016/12/29 19:32:49.621220, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3848(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2016/12/29 19:32:49.621255, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:660(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: clearing id 1 (position 1) from bitmap
>[2016/12/29 19:32:49.621267, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2257(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_SESSSETUP] mid = 1
>[2016/12/29 19:32:49.621278,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.621287,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.621295,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.621311,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:49.621343,  5, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_check_lock_order)
>  check lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:49.621353, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:/usr/var/samba/lock/smbXsrv_session_global.tdb 2:<none> 3:<none>
>[2016/12/29 19:32:49.621366, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Locking key AB0DD338
>[2016/12/29 19:32:49.621412, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x7fc2a0599b90
>[2016/12/29 19:32:49.621504, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:943(smbXsrv_session_global_store)
>[2016/12/29 19:32:49.621516, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:945(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key 'AB0DD338' stored
>[2016/12/29 19:32:49.621526,  1, pid=21626, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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        : 0xab0dd338 (2869809976)
>                  session_wire_id          : 0x00000000ab0dd338 (2869809976)
>                  creation_time            : Do Dez 29 19:32:50 2016 CET
>                  expiration_time          : Do Jan  1 01:00:00 1970 CET
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0302 (770)
>                  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
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x000000000000547a (21626)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0x02b9125b69dac127 (196208242594595111)
>                          local_address            : 'ipv4:10.99.0.5:445'
>                          remote_address           : 'ipv4:10.99.0.81:49268'
>                          remote_name              : '10.99.0.81'
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2016/12/29 19:32:49.621713,  5, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:127(dbwrap_lock_order_state_destructor)
>  release lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:49.621724, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:<none> 3:<none>
>[2016/12/29 19:32:49.621741, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Unlocking key AB0DD338
>[2016/12/29 19:32:49.621752, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1310(smbXsrv_session_create)
>[2016/12/29 19:32:49.621758, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1318(smbXsrv_session_create)
>  smbXsrv_session_create: global_id (0xab0dd338) stored
>[2016/12/29 19:32:49.621765,  1, pid=21626, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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                 : 0xab0dd338 (2869809976)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0xab0dd338 (2869809976)
>                          session_wire_id          : 0x00000000ab0dd338 (2869809976)
>                          creation_time            : Do Dez 29 19:32:50 2016 CET
>                          expiration_time          : Do Jan  1 01:00:00 1970 CET
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0302 (770)
>                          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
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x000000000000547a (21626)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0x02b9125b69dac127 (196208242594595111)
>                                  local_address            : 'ipv4:10.99.0.5:445'
>                                  remote_address           : 'ipv4:10.99.0.81:49268'
>                                  remote_name              : '10.99.0.81'
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Do Dez 29 19:32:50 2016 CET
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  compat                   : NULL
>                  tcon_table               : *
>                  pending_auth             : NULL
>[2016/12/29 19:32:49.621969,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:477(make_auth_context_subsystem)
>  Making default auth method list for server role = 'domain member'
>[2016/12/29 19:32:49.621986,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match guest
>[2016/12/29 19:32:49.621998,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method guest has a valid init
>[2016/12/29 19:32:49.622006,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match sam
>[2016/12/29 19:32:49.622014,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method sam has a valid init
>[2016/12/29 19:32:49.622021,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match winbind:ntdomain
>[2016/12/29 19:32:49.622030,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:378(load_auth_module)
>  load_auth_module: Attempting to find an auth method to match ntdomain
>[2016/12/29 19:32:49.622037,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method ntdomain has a valid init
>[2016/12/29 19:32:49.622045,  5, pid=21626, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:403(load_auth_module)
>  load_auth_module: auth method winbind has a valid init
>[2016/12/29 19:32:49.622089,  5, pid=21626, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC mechanism spnego
>[2016/12/29 19:32:49.622105,  5, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_check_lock_order)
>  check lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:49.622114, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:/usr/var/samba/lock/smbXsrv_session_global.tdb 2:<none> 3:<none>
>[2016/12/29 19:32:49.622125, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Locking key AB0DD338
>[2016/12/29 19:32:49.622137, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x7fc2a05ab390
>[2016/12/29 19:32:49.622159, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:943(smbXsrv_session_global_store)
>[2016/12/29 19:32:49.622167, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:945(smbXsrv_session_global_store)
>  smbXsrv_session_global_store: key 'AB0DD338' stored
>[2016/12/29 19:32:49.622176,  1, pid=21626, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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        : 0xab0dd338 (2869809976)
>                  session_wire_id          : 0x00000000ab0dd338 (2869809976)
>                  creation_time            : Do Dez 29 19:32:50 2016 CET
>                  expiration_time          : Do Jan  1 01:00:00 1970 CET
>                  auth_time                : NTTIME(0)
>                  auth_session_info_seqnum : 0x00000000 (0)
>                  auth_session_info        : NULL
>                  connection_dialect       : 0x0302 (770)
>                  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
>                  num_channels             : 0x00000001 (1)
>                  channels: ARRAY(1)
>                      channels: struct smbXsrv_channel_global0
>                          server_id: struct server_id
>                              pid                      : 0x000000000000547a (21626)
>                              task_id                  : 0x00000000 (0)
>                              vnn                      : 0xffffffff (4294967295)
>                              unique_id                : 0x02b9125b69dac127 (196208242594595111)
>                          local_address            : 'ipv4:10.99.0.5:445'
>                          remote_address           : 'ipv4:10.99.0.81:49268'
>                          remote_name              : '10.99.0.81'
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          connection               : *
>                          encryption_cipher        : 0x0000 (0)
>[2016/12/29 19:32:49.622302,  5, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:127(dbwrap_lock_order_state_destructor)
>  release lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:49.622310, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:<none> 3:<none>
>[2016/12/29 19:32:49.622320, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Unlocking key AB0DD338
>[2016/12/29 19:32:49.622331, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1405(smbXsrv_session_update)
>[2016/12/29 19:32:49.622341, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1413(smbXsrv_session_update)
>  smbXsrv_session_update: global_id (0xab0dd338) stored
>[2016/12/29 19:32:49.622350,  1, pid=21626, effective(0, 0), real(0, 0)] ../librpc/ndr/ndr.c:413(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                 : 0xab0dd338 (2869809976)
>                  global                   : *
>                      global: struct smbXsrv_session_global0
>                          db_rec                   : NULL
>                          session_global_id        : 0xab0dd338 (2869809976)
>                          session_wire_id          : 0x00000000ab0dd338 (2869809976)
>                          creation_time            : Do Dez 29 19:32:50 2016 CET
>                          expiration_time          : Do Jan  1 01:00:00 1970 CET
>                          auth_time                : NTTIME(0)
>                          auth_session_info_seqnum : 0x00000000 (0)
>                          auth_session_info        : NULL
>                          connection_dialect       : 0x0302 (770)
>                          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
>                          num_channels             : 0x00000001 (1)
>                          channels: ARRAY(1)
>                              channels: struct smbXsrv_channel_global0
>                                  server_id: struct server_id
>                                      pid                      : 0x000000000000547a (21626)
>                                      task_id                  : 0x00000000 (0)
>                                      vnn                      : 0xffffffff (4294967295)
>                                      unique_id                : 0x02b9125b69dac127 (196208242594595111)
>                                  local_address            : 'ipv4:10.99.0.5:445'
>                                  remote_address           : 'ipv4:10.99.0.81:49268'
>                                  remote_name              : '10.99.0.81'
>                                  auth_session_info_seqnum : 0x00000000 (0)
>                                  connection               : *
>                                  encryption_cipher        : 0x0000 (0)
>                  status                   : NT_STATUS_MORE_PROCESSING_REQUIRED
>                  idle_time                : Do Dez 29 19:32:50 2016 CET
>                  nonce_high_random        : 0x0000000000000000 (0)
>                  nonce_high_max           : 0x0000000000000000 (0)
>                  nonce_high               : 0x0000000000000000 (0)
>                  nonce_low                : 0x0000000000000000 (0)
>                  compat                   : NULL
>                  tcon_table               : *
>                  pending_auth             : *
>                      pending_auth: struct smbXsrv_session_auth0
>                          prev                     : *
>                          next                     : NULL
>                          session                  : *
>                          connection               : *
>                          gensec                   : *
>                          preauth                  : NULL
>                          in_flags                 : 0x00 (0)
>                          in_security_mode         : 0x01 (1)
>                          creation_time            : Do Dez 29 19:32:50 2016 CET
>                          idle_time                : Do Dez 29 19:32:50 2016 CET
>[2016/12/29 19:32:49.622620,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.622629,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.622637,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.622644,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.622651,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.622668,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>  smbd_smb2_request_pending_queue: req->current_idx = 1
>  	req->in.vector[0].iov_len = 0
>  	req->in.vector[1].iov_len = 0
>  	req->in.vector[2].iov_len = 64
>  	req->in.vector[3].iov_len = 24
>  	req->in.vector[4].iov_len = 1855
>  	req->out.vector[0].iov_len = 4
>  	req->out.vector[1].iov_len = 0
>  	req->out.vector[2].iov_len = 64
>  	req->out.vector[3].iov_len = 8
>  	req->out.vector[4].iov_len = 0
>[2016/12/29 19:32:49.622766,  5, pid=21626, effective(0, 0), real(0, 0)] ../auth/gensec/gensec_start.c:681(gensec_start_mech)
>  Starting GENSEC submechanism gse_krb5
>[2016/12/29 19:32:49.703737, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/librpc/crypto/gse_krb5.c:312(fill_mem_keytab_from_secrets)
>  ../source3/librpc/crypto/gse_krb5.c:312: no prev machine password
>[2016/12/29 19:32:49.704330,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.704342,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.704349,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.704355,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.704361,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.704375,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.704429,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:217(push_sec_ctx)
>  push_sec_ctx(0, 0) : sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.704437,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:491(push_conn_ctx)
>  push_conn_ctx(0) : conn_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.704443,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 1
>[2016/12/29 19:32:49.704449,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.704454,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.704930,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:439(pop_sec_ctx)
>  pop_sec_ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.704955,  3, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3097(smbd_smb2_request_error_ex)
>  smbd_smb2_request_error_ex: smbd_smb2_request_error_ex: idx[1] status[NT_STATUS_UNSUCCESSFUL] || at ../source3/smbd/smb2_sesssetup.c:134
>[2016/12/29 19:32:49.704965, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2988(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_UNSUCCESSFUL] body[8] dyn[yes:1] at ../source3/smbd/smb2_server.c:3145
>[2016/12/29 19:32:49.704974, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:912(smb2_set_operation_credit)
>  smb2_set_operation_credit: requested 31, charge 1, granted 1, current possible/max 512/512, total granted/max/low/range 1/8192/2/1
>[2016/12/29 19:32:49.705001,  5, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_check_lock_order)
>  check lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:49.705010, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:/usr/var/samba/lock/smbXsrv_session_global.tdb 2:<none> 3:<none>
>[2016/12/29 19:32:49.705020, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Locking key AB0DD338
>[2016/12/29 19:32:49.705031, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:143(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x7fc2a05a4ff0
>[2016/12/29 19:32:49.705044,  5, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:127(dbwrap_lock_order_state_destructor)
>  release lock order 1 for /usr/var/samba/lock/smbXsrv_session_global.tdb
>[2016/12/29 19:32:49.705051, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap.c:114(debug_lock_order)
>  lock order:  1:<none> 2:<none> 3:<none>
>[2016/12/29 19:32:49.705066, 10, pid=21626, effective(0, 0), real(0, 0)] ../lib/dbwrap/dbwrap_tdb.c:59(db_tdb_log_key)
>  Unlocking key AB0DD338
>[2016/12/29 19:32:49.705523, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:1069(smbd_server_connection_terminate_ex)
>  smbd_server_connection_terminate_ex: conn[ipv4:10.99.0.81:49268] reason[NT_STATUS_CONNECTION_RESET] at ../source3/smbd/smb2_server.c:3900
>[2016/12/29 19:32:49.705565,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.705574,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.705580,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.705591,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:49.705598,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.705604,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.705609,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.705617,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:49.705625,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.705635,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.705640,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.705649,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:49.705656,  4, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:321(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2016/12/29 19:32:49.705662,  5, pid=21626, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2016/12/29 19:32:49.705667,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:639(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2016/12/29 19:32:49.705675,  5, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2016/12/29 19:32:49.705720, 10, pid=21626, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:142(msg_dgm_ref_destructor)
>  msg_dgm_ref_destructor: refs=(nil)
>[2016/12/29 19:32:49.705851,  3, pid=21626, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:246(exit_server_common)
>  Server exit (NT_STATUS_CONNECTION_RESET)

Actions: View

Attachments on bug 12488: 12781 | 12782 | 12783 | 12784