[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: 2:/usr/var/samba/lock/serverid.tdb 3: [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: 2: 3: [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: 3: [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: 2: 3: [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: 3: [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: 2: 3: [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: 3: [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: 2: 3: [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: 3: [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: 2: 3: [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: 3: [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: 2: 3: [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: 3: [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: 2: 3: [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)