[2018/04/16 12:09:11.606829, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:157(msg_dgm_ref_destructor) [2018/04/16 12:09:11.608143, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:157(msg_dgm_ref_destructor) msg_dgm_ref_destructor: refs=(nil) msg_dgm_ref_destructor: refs=(nil) [2018/04/16 12:09:11.611352, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:157(msg_dgm_ref_destructor) msg_dgm_ref_destructor: refs=(nil) [2018/04/16 12:09:11.616763, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:79(messaging_dgm_ref) [2018/04/16 12:09:11.617570, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:79(messaging_dgm_ref) messaging_dgm_ref: messaging_dgm_init returned Success [2018/04/16 12:09:11.617606, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:79(messaging_dgm_ref) messaging_dgm_ref: messaging_dgm_init returned Success messaging_dgm_ref: messaging_dgm_init returned Success [2018/04/16 12:09:11.619897, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:108(messaging_dgm_ref) [2018/04/16 12:09:11.619633, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:108(messaging_dgm_ref) messaging_dgm_ref: unique = 12364940510690501478 messaging_dgm_ref: unique = 13745570865061940904 [2018/04/16 12:09:11.620311, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:108(messaging_dgm_ref) messaging_dgm_ref: unique = 5209022097553964357 [2018/04/16 12:09:11.627720, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:534(smbXsrv_client_create) [2018/04/16 12:09:11.629360, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:542(smbXsrv_client_create) smbXsrv_client_create: client_guid[00000000-0000-0000-0000-000000000000] stored [2018/04/16 12:09:11.630881, 1, pid=4254, effective(0, 0), real(0, 0), class=rpc_parse] ../librpc/ndr/ndr.c:422(ndr_print_debug) [2018/04/16 12:09:11.629919, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:534(smbXsrv_client_create) &client_blob: struct smbXsrv_clientB version : SMBXSRV_VERSION_0 (0) reserved : 0x00000000 (0) info : union smbXsrv_clientU(case 0) info0 : * [2018/04/16 12:09:11.632045, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:542(smbXsrv_client_create) info0: struct smbXsrv_client table : * smbXsrv_client_create: client_guid[00000000-0000-0000-0000-000000000000] stored ev_ctx : * [2018/04/16 12:09:11.632734, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:534(smbXsrv_client_create) msg_ctx : * global : * global: struct smbXsrv_client_global0 db_rec : NULL server_id: struct server_id [2018/04/16 12:09:11.634638, 1, pid=4253, effective(0, 0), real(0, 0), class=rpc_parse] ../librpc/ndr/ndr.c:422(ndr_print_debug) pid : 0x000000000000109e (4254) [2018/04/16 12:09:11.634852, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:542(smbXsrv_client_create) task_id : 0x00000000 (0) &client_blob: struct smbXsrv_clientB vnn : 0xffffffff (4294967295) smbXsrv_client_create: client_guid[00000000-0000-0000-0000-000000000000] stored unique_id : 0xab9917e1d88b6366 (-6081803563019050138) version : SMBXSRV_VERSION_0 (0) local_address : NULL remote_address : NULL reserved : 0x00000000 (0) remote_name : NULL info : union smbXsrv_clientU(case 0) initial_connect_time : Mon Apr 16 12:09:12 PM 2018 CEST client_guid : 00000000-0000-0000-0000-000000000000 info0 : * [2018/04/16 12:09:11.637617, 1, pid=4252, effective(0, 0), real(0, 0), class=rpc_parse] ../librpc/ndr/ndr.c:422(ndr_print_debug) stored : 0x00 (0) sconn : NULL info0: struct smbXsrv_client &client_blob: struct smbXsrv_clientB session_table : NULL last_session_id : 0x0000000000000000 (0) table : * version : SMBXSRV_VERSION_0 (0) tcon_table : NULL open_table : NULL ev_ctx : * reserved : 0x00000000 (0) connections : NULL server_multi_channel_enabled: 0x00 (0) msg_ctx : * info : union smbXsrv_clientU(case 0) global : * info0 : * global: struct smbXsrv_client_global0 info0: struct smbXsrv_client db_rec : NULL table : * server_id: struct server_id ev_ctx : * [2018/04/16 12:09:11.643867, 5, pid=4254, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) pid : 0x000000000000109d (4253) msg_ctx : * Socket options: task_id : 0x00000000 (0) SO_KEEPALIVE = 1 global : * SO_REUSEADDR = 1 vnn : 0xffffffff (4294967295) global: struct smbXsrv_client_global0 SO_BROADCAST = 0 unique_id : 0xbec213dfe6f9cea8 (-4701173208647610712) db_rec : NULL TCP_NODELAY = 1 local_address : NULL TCP_KEEPCNT = 9 server_id: struct server_id TCP_KEEPIDLE = 7200 remote_address : NULL pid : 0x000000000000109c (4252) TCP_KEEPINTVL = 75 remote_name : NULL task_id : 0x00000000 (0) IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 vnn : 0xffffffff (4294967295) initial_connect_time : Mon Apr 16 12:09:12 PM 2018 CEST SO_REUSEPORT = 1 unique_id : 0x484a29ffc7dd3d45 (5209022097553964357) client_guid : 00000000-0000-0000-0000-000000000000 SO_SNDBUF = 87040 local_address : NULL SO_RCVBUF = 374400 stored : 0x00 (0) SO_SNDLOWAT = 1 remote_address : NULL sconn : NULL SO_RCVLOWAT = 1 remote_name : NULL session_table : NULL SO_SNDTIMEO = 0 last_session_id : 0x0000000000000000 (0) initial_connect_time : Mon Apr 16 12:09:12 PM 2018 CEST SO_RCVTIMEO = 0 TCP_QUICKACK = 1 tcon_table : NULL client_guid : 00000000-0000-0000-0000-000000000000 TCP_DEFER_ACCEPT = 0 open_table : NULL stored : 0x00 (0) connections : NULL sconn : NULL [2018/04/16 12:09:11.654124, 5, pid=4254, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) server_multi_channel_enabled: 0x00 (0) session_table : NULL Socket options: SO_KEEPALIVE = 1 last_session_id : 0x0000000000000000 (0) SO_REUSEADDR = 1 tcon_table : NULL SO_BROADCAST = 0 open_table : NULL TCP_NODELAY = 1 TCP_KEEPCNT = 9 connections : NULL TCP_KEEPIDLE = 7200 server_multi_channel_enabled: 0x00 (0) TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 87040 [2018/04/16 12:09:11.659016, 5, pid=4253, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Socket options: SO_RCVBUF = 374400 SO_SNDLOWAT = 1 SO_KEEPALIVE = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_REUSEADDR = 1 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 [2018/04/16 12:09:11.662213, 5, pid=4252, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) SO_BROADCAST = 0 TCP_DEFER_ACCEPT = 0 Socket options: TCP_NODELAY = 1 SO_KEEPALIVE = 1 [2018/04/16 12:09:11.665041, 3, pid=4254, effective(0, 0), real(0, 0)] ../lib/util/access.c:365(allow_access) TCP_KEEPCNT = 9 Allowed connection from 192.168.89.1 (192.168.89.1) SO_REUSEADDR = 1 TCP_KEEPIDLE = 7200 [2018/04/16 12:09:11.666442, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:3840(smbd_add_connection) SO_BROADCAST = 0 Connection allowed from ipv4:192.168.89.1:61032 to ipv4:192.168.89.129:445 TCP_KEEPINTVL = 75 TCP_NODELAY = 1 IPTOS_LOWDELAY = 0 TCP_KEEPCNT = 9 IPTOS_THROUGHPUT = 0 TCP_KEEPIDLE = 7200 SO_REUSEPORT = 1 [2018/04/16 12:09:11.669247, 6, pid=4254, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() TCP_KEEPINTVL = 75 file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Mon Apr 16 12:07:40 2018 SO_SNDBUF = 87040 IPTOS_LOWDELAY = 0 SO_RCVBUF = 374400 IPTOS_THROUGHPUT = 0 SO_SNDLOWAT = 1 [2018/04/16 12:09:11.671624, 3, pid=4254, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1340(init_oplocks) init_oplocks: initializing messages. SO_REUSEPORT = 1 SO_RCVLOWAT = 1 [2018/04/16 12:09:11.673285, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 774 - private_data=0x55e77210f320 SO_SNDTIMEO = 0 SO_SNDBUF = 87040 SO_RCVTIMEO = 0 [2018/04/16 12:09:11.674569, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) SO_RCVBUF = 374400 Registering messaging pointer for type 778 - private_data=0x55e77210f320 TCP_QUICKACK = 1 SO_SNDLOWAT = 1 [2018/04/16 12:09:11.675805, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 770 - private_data=0x55e77210f320 TCP_DEFER_ACCEPT = 0 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 [2018/04/16 12:09:11.677093, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 787 - private_data=0x55e77210f320 SO_RCVTIMEO = 0 [2018/04/16 12:09:11.678334, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) [2018/04/16 12:09:11.677474, 5, pid=4253, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Registering messaging pointer for type 779 - private_data=0x55e77210f320 TCP_QUICKACK = 1 Socket options: TCP_DEFER_ACCEPT = 0 [2018/04/16 12:09:11.679568, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) SO_KEEPALIVE = 1 Registering messaging pointer for type 15 - private_data=(nil) SO_REUSEADDR = 1 [2018/04/16 12:09:11.680806, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:688(messaging_register) Overriding messaging pointer for type 15 - private_data=(nil) SO_BROADCAST = 0 [2018/04/16 12:09:11.680833, 5, pid=4252, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) TCP_NODELAY = 1 Socket options: [2018/04/16 12:09:11.682127, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 16 - private_data=(nil) TCP_KEEPCNT = 9 SO_KEEPALIVE = 1 [2018/04/16 12:09:11.683610, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) TCP_KEEPIDLE = 7200 SO_REUSEADDR = 1 Registering messaging pointer for type 16 - private_data=0x55e77210f320 TCP_KEEPINTVL = 75 SO_BROADCAST = 0 [2018/04/16 12:09:11.684896, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) IPTOS_LOWDELAY = 0 Deregistering messaging pointer for type 33 - private_data=0x55e7720e59b0 TCP_NODELAY = 1 IPTOS_THROUGHPUT = 0 TCP_KEEPCNT = 9 [2018/04/16 12:09:11.686414, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 33 - private_data=0x55e77210f320 SO_REUSEPORT = 1 TCP_KEEPIDLE = 7200 [2018/04/16 12:09:11.688221, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) SO_SNDBUF = 87040 TCP_KEEPINTVL = 75 Deregistering messaging pointer for type 790 - private_data=(nil) SO_RCVBUF = 374400 IPTOS_LOWDELAY = 0 [2018/04/16 12:09:11.689897, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 790 - private_data=0x55e77210f320 SO_SNDLOWAT = 1 IPTOS_THROUGHPUT = 0 [2018/04/16 12:09:11.691573, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) SO_RCVLOWAT = 1 SO_REUSEPORT = 1 Deregistering messaging pointer for type 791 - private_data=(nil) SO_SNDTIMEO = 0 SO_SNDBUF = 87040 [2018/04/16 12:09:11.693224, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 1 - private_data=(nil) SO_RCVTIMEO = 0 SO_RCVBUF = 374400 TCP_QUICKACK = 1 [2018/04/16 12:09:11.695013, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) SO_SNDLOWAT = 1 Registering messaging pointer for type 1 - private_data=(nil) TCP_DEFER_ACCEPT = 0 SO_RCVLOWAT = 1 [2018/04/16 12:09:11.696872, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) SO_SNDTIMEO = 0 event_add_idle: idle_evt(keepalive) 0x55e772110430 SO_RCVTIMEO = 0 [2018/04/16 12:09:11.698298, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) [2018/04/16 12:09:11.697745, 3, pid=4253, effective(0, 0), real(0, 0)] ../lib/util/access.c:365(allow_access) event_add_idle: idle_evt(deadtime) 0x55e772110680 TCP_QUICKACK = 1 Allowed connection from 192.168.89.1 (192.168.89.1) [2018/04/16 12:09:11.699520, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) TCP_DEFER_ACCEPT = 0 event_add_idle: idle_evt(housekeeping) 0x55e7721108d0 [2018/04/16 12:09:11.700059, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:3840(smbd_add_connection) Connection allowed from ipv4:192.168.89.1:61031 to ipv4:192.168.89.129:139 [2018/04/16 12:09:11.701226, 3, pid=4252, effective(0, 0), real(0, 0)] ../lib/util/access.c:365(allow_access) Allowed connection from 192.168.89.1 (192.168.89.1) [2018/04/16 12:09:11.702948, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:248(read_smb_length_return_keepalive) got smb length of 69 [2018/04/16 12:09:11.704303, 6, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1956(process_smb) [2018/04/16 12:09:11.703450, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:3840(smbd_add_connection) got message type 0x0 of len 0x45 Connection allowed from ipv4:192.168.89.1:61030 to ipv4:192.168.89.129:445 [2018/04/16 12:09:11.704328, 6, pid=4253, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) [2018/04/16 12:09:11.705530, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1958(process_smb) Transaction 0 of length 73 (0 toread) lp_file_list_changed() [2018/04/16 12:09:11.706752, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/util.c:184(show_msg) file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Mon Apr 16 12:07:40 2018 [2018/04/16 12:09:11.707714, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/util.c:194(show_msg) size=69 smb_com=0x72 smb_rcls=0 smb_reh=0 smb_err=0 smb_flg=8 [2018/04/16 12:09:11.708338, 6, pid=4252, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) [2018/04/16 12:09:11.708713, 3, pid=4253, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1340(init_oplocks) smb_flg2=51201 smb_tid=65535 lp_file_list_changed() init_oplocks: initializing messages. smb_pid=1 smb_uid=65535 smb_mid=0 file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Mon Apr 16 12:07:40 2018 smt_wct=0 smb_bcc=34 [2018/04/16 12:09:11.711119, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) [2018/04/16 12:09:11.712213, 10, pid=4254, effective(0, 0), real(0, 0)] ../lib/util/util.c:514(dump_data) [0000] 02 4E 54 20 4C 4D 20 30 2E 31 32 00 02 53 4D 42 .NT LM 0 .12..SMB Registering messaging pointer for type 774 - private_data=0x55e77210f320 [0010] 20 32 2E 30 30 32 00 02 53 4D 42 20 32 2E 3F 3F 2.002.. SMB 2.?? [0020] 3F 00 ?. [2018/04/16 12:09:11.712786, 3, pid=4252, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1340(init_oplocks) init_oplocks: initializing messages. [2018/04/16 12:09:11.714143, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1538(switch_message) [2018/04/16 12:09:11.713601, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) switch message SMBnegprot (pid 4254) conn 0x0 Registering messaging pointer for type 778 - private_data=0x55e77210f320 [2018/04/16 12:09:11.715406, 4, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.715152, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 774 - private_data=0x55e77210f320 [2018/04/16 12:09:11.715778, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) [2018/04/16 12:09:11.716697, 5, pid=4254, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) Registering messaging pointer for type 770 - private_data=0x55e77210f320 [2018/04/16 12:09:11.717919, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 [2018/04/16 12:09:11.717606, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Primary group is 0 and contains 0 supplementary groups Registering messaging pointer for type 778 - private_data=0x55e77210f320 [2018/04/16 12:09:11.718147, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 787 - private_data=0x55e77210f320 [2018/04/16 12:09:11.720678, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) [2018/04/16 12:09:11.719954, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 770 - private_data=0x55e77210f320 [2018/04/16 12:09:11.720577, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) change_to_root_user: now uid=(0,0) gid=(0,0) Registering messaging pointer for type 779 - private_data=0x55e77210f320 [2018/04/16 12:09:11.722385, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) [2018/04/16 12:09:11.722957, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 787 - private_data=0x55e77210f320 Registering messaging pointer for type 15 - private_data=(nil) [2018/04/16 12:09:11.725285, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:688(messaging_register) [2018/04/16 12:09:11.725213, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Overriding messaging pointer for type 15 - private_data=(nil) Registering messaging pointer for type 779 - private_data=0x55e77210f320 [2018/04/16 12:09:11.728459, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:628(reply_negprot) [2018/04/16 12:09:11.728208, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) [2018/04/16 12:09:11.728368, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Requested protocol [NT LM 0.12] Deregistering messaging pointer for type 16 - private_data=(nil) Registering messaging pointer for type 15 - private_data=(nil) [2018/04/16 12:09:11.730227, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:628(reply_negprot) [2018/04/16 12:09:11.730265, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) [2018/04/16 12:09:11.730398, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:688(messaging_register) Requested protocol [SMB 2.002] Registering messaging pointer for type 16 - private_data=0x55e77210f320 Overriding messaging pointer for type 15 - private_data=(nil) [2018/04/16 12:09:11.731762, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:628(reply_negprot) [2018/04/16 12:09:11.731800, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) [2018/04/16 12:09:11.731936, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Requested protocol [SMB 2.???] Deregistering messaging pointer for type 33 - private_data=0x55e7720e59b0 Deregistering messaging pointer for type 16 - private_data=(nil) [2018/04/16 12:09:11.733260, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1349(set_remote_arch) [2018/04/16 12:09:11.733299, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) [2018/04/16 12:09:11.733455, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) set_remote_arch: Client arch is 'OSX' Registering messaging pointer for type 33 - private_data=0x55e77210f320 Registering messaging pointer for type 16 - private_data=0x55e77210f320 [2018/04/16 12:09:11.735014, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) [2018/04/16 12:09:11.735134, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 790 - private_data=(nil) Deregistering messaging pointer for type 33 - private_data=0x55e7720e59b0 [2018/04/16 12:09:11.736240, 6, pid=4254, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) [2018/04/16 12:09:11.736557, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) [2018/04/16 12:09:11.736684, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) lp_file_list_changed() Registering messaging pointer for type 790 - private_data=0x55e77210f320 Registering messaging pointer for type 33 - private_data=0x55e77210f320 file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Mon Apr 16 12:07:40 2018 [2018/04/16 12:09:11.738116, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) [2018/04/16 12:09:11.738243, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 791 - private_data=(nil) Deregistering messaging pointer for type 790 - private_data=(nil) [2018/04/16 12:09:11.740176, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) [2018/04/16 12:09:11.740382, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Deregistering messaging pointer for type 1 - private_data=(nil) Registering messaging pointer for type 790 - private_data=0x55e77210f320 [2018/04/16 12:09:11.740901, 6, pid=4254, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() [2018/04/16 12:09:11.741716, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Mon Apr 16 12:07:40 2018 [2018/04/16 12:09:11.742063, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Registering messaging pointer for type 1 - private_data=(nil) Deregistering messaging pointer for type 791 - private_data=(nil) [2018/04/16 12:09:11.744344, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3518(smbd_smb2_process_negprot) [2018/04/16 12:09:11.744292, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) smbd_smb2_first_negprot: packet length 102 event_add_idle: idle_evt(keepalive) 0x55e772110430 [2018/04/16 12:09:11.745013, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 1 - private_data=(nil) [2018/04/16 12:09:11.746923, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) [2018/04/16 12:09:11.747256, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:671(smb2_validate_sequence_number) [2018/04/16 12:09:11.747533, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) smb2_validate_sequence_number: clearing id 0 (position 0) from bitmap event_add_idle: idle_evt(deadtime) 0x55e772110680 Registering messaging pointer for type 1 - private_data=(nil) [2018/04/16 12:09:11.749631, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2310(smbd_smb2_request_dispatch) [2018/04/16 12:09:11.749675, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) smbd_smb2_request_dispatch: opcode[SMB2_OP_NEGPROT] mid = 0 [2018/04/16 12:09:11.749965, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x55e7721108d0 event_add_idle: idle_evt(keepalive) 0x55e772110430 [2018/04/16 12:09:11.751156, 4, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.751515, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(deadtime) 0x55e772110680 [2018/04/16 12:09:11.752350, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:134(read_fd_with_timeout) read_fd_with_timeout: blocking read. EOF from client. [2018/04/16 12:09:11.752750, 5, pid=4254, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/16 12:09:11.753170, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x55e7721108d0 [2018/04/16 12:09:11.753877, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:554(receive_smb_talloc) receive_smb_raw_talloc failed for client ipv4:192.168.89.1:61031 read error = NT_STATUS_END_OF_FILE. [2018/04/16 12:09:11.754259, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.755405, 4, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.755910, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:134(read_fd_with_timeout) read_fd_with_timeout: blocking read. EOF from client. [2018/04/16 12:09:11.756941, 5, pid=4253, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) [2018/04/16 12:09:11.757226, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) Security token: (NULL) [2018/04/16 12:09:11.757497, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:554(receive_smb_talloc) receive_smb_raw_talloc failed for client ipv4:192.168.89.1:61030 read error = NT_STATUS_END_OF_FILE. change_to_root_user: now uid=(0,0) gid=(0,0) [2018/04/16 12:09:11.758448, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.759151, 4, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.760696, 5, pid=4252, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) [2018/04/16 12:09:11.760765, 6, pid=4254, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) Security token: (NULL) lp_file_list_changed() [2018/04/16 12:09:11.761527, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Mon Apr 16 12:07:40 2018 [2018/04/16 12:09:11.762276, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) change_to_root_user: now uid=(0,0) gid=(0,0) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.763330, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_negprot.c:291(smbd_smb2_request_process_negprot) Selected protocol SMB2_FF [2018/04/16 12:09:11.763819, 4, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.764970, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:524(make_auth3_context_for_ntlm) Making default auth method list for server role = 'standalone server', encrypt passwords = yes [2018/04/16 12:09:11.765546, 5, pid=4253, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) [2018/04/16 12:09:11.765581, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) Security token: (NULL) [2018/04/16 12:09:11.766607, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) change_to_root_user: now uid=(0,0) gid=(0,0) Attempting to register auth backend guest [2018/04/16 12:09:11.767226, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 [2018/04/16 12:09:11.767996, 4, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.768092, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Successfully added auth method 'guest' [2018/04/16 12:09:11.769579, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) [2018/04/16 12:09:11.769569, 5, pid=4252, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Attempting to register auth backend sam Security token: (NULL) [2018/04/16 12:09:11.770372, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) [2018/04/16 12:09:11.771098, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) [2018/04/16 12:09:11.771102, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) Successfully added auth method 'sam' change_to_root_user: now uid=(0,0) gid=(0,0) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.772585, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) [2018/04/16 12:09:11.772720, 4, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) Attempting to register auth backend sam_ignoredomain setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.774106, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) [2018/04/16 12:09:11.774238, 5, pid=4253, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Successfully added auth method 'sam_ignoredomain' [2018/04/16 12:09:11.774279, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) Security token: (NULL) change_to_root_user: now uid=(0,0) gid=(0,0) [2018/04/16 12:09:11.775518, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend sam_netlogon3 [2018/04/16 12:09:11.775817, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.776553, 4, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) [2018/04/16 12:09:11.776958, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 Successfully added auth method 'sam_netlogon3' [2018/04/16 12:09:11.778192, 5, pid=4252, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) [2018/04/16 12:09:11.778471, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) Security token: (NULL) Attempting to register auth backend winbind [2018/04/16 12:09:11.778955, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) [2018/04/16 12:09:11.779942, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) [2018/04/16 12:09:11.780027, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) change_to_root_user: now uid=(0,0) gid=(0,0) UNIX token of user 0 Successfully added auth method 'winbind' Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.781202, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1764(smbXsrv_session_logoff_all) [2018/04/16 12:09:11.781530, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:400(load_auth_module) smbXsrv_session_logoff_all: empty session_table, nothing to do. load_auth_module: Attempting to find an auth method to match guest [2018/04/16 12:09:11.782852, 4, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) [2018/04/16 12:09:11.783142, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:425(load_auth_module) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.783232, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) load_auth_module: auth method guest has a valid init change_to_root_user: now uid=(0,0) gid=(0,0) [2018/04/16 12:09:11.784368, 5, pid=4253, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) [2018/04/16 12:09:11.784691, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:400(load_auth_module) Security token: (NULL) load_auth_module: Attempting to find an auth method to match sam_ignoredomain [2018/04/16 12:09:11.785444, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1764(smbXsrv_session_logoff_all) smbXsrv_session_logoff_all: empty session_table, nothing to do. [2018/04/16 12:09:11.785947, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) [2018/04/16 12:09:11.786161, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:425(load_auth_module) UNIX token of user 0 load_auth_module: auth method sam_ignoredomain has a valid init Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.787067, 4, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:11.789024, 5, pid=4252, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) [2018/04/16 12:09:11.789348, 5, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/uid.c:425(smbd_change_to_root_user) Security token: (NULL) change_to_root_user: now uid=(0,0) gid=(0,0) [2018/04/16 12:09:11.790593, 5, pid=4252, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:11.792610, 10, pid=4253, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:157(msg_dgm_ref_destructor) msg_dgm_ref_destructor: refs=(nil) [2018/04/16 12:09:11.793827, 5, pid=4252, 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) [2018/04/16 12:09:11.796780, 3, pid=4253, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:236(exit_server_common) [2018/04/16 12:09:11.797375, 10, pid=4252, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:157(msg_dgm_ref_destructor) Server exit (failed to receive smb request) msg_dgm_ref_destructor: refs=(nil) [2018/04/16 12:09:11.803261, 3, pid=4252, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:236(exit_server_common) Server exit (failed to receive smb request) [2018/04/16 12:09:11.820084, 10, pid=4226, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm.c:1430(messaging_dgm_send) messaging_dgm_send: Sending message to 4230 [2018/04/16 12:09:11.822581, 10, pid=4230, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb) [2018/04/16 12:09:11.822625, 10, pid=4226, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm.c:1430(messaging_dgm_send) messaging_recv_cb: Received message 0x314 len 0 (num_fds:0) from 4226 messaging_dgm_send: Sending message to 4230 [2018/04/16 12:09:11.826696, 10, pid=4230, effective(0, 0), real(0, 0)] ../source3/smbd/smbd_cleanupd.c:196(smbd_cleanupd_process_exited) smbd_cleanupd_process_exited: cleaned up pid 4253 [2018/04/16 12:09:11.827549, 10, pid=4230, effective(0, 0), real(0, 0)] ../source3/smbd/smbd_cleanupd.c:196(smbd_cleanupd_process_exited) smbd_cleanupd_process_exited: cleaned up pid 4252 [2018/04/16 12:09:11.828082, 10, pid=4230, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb) messaging_recv_cb: Received message 0x314 len 0 (num_fds:0) from 4226 [2018/04/16 12:09:26.828782, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'gssapi_spnego' registered [2018/04/16 12:09:26.829389, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'gssapi_krb5' registered [2018/04/16 12:09:26.830036, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'gssapi_krb5_sasl' registered [2018/04/16 12:09:26.830549, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'spnego' registered [2018/04/16 12:09:26.831042, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'schannel' registered [2018/04/16 12:09:26.831581, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'naclrpc_as_system' registered [2018/04/16 12:09:26.832078, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'sasl-EXTERNAL' registered [2018/04/16 12:09:26.832589, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'ntlmssp' registered [2018/04/16 12:09:26.833083, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'ntlmssp_resume_ccache' registered [2018/04/16 12:09:26.833624, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'http_basic' registered [2018/04/16 12:09:26.834136, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'http_ntlm' registered [2018/04/16 12:09:26.834647, 3, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'http_negotiate' registered [2018/04/16 12:09:26.835180, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:739(gensec_start_mech) Starting GENSEC mechanism spnego [2018/04/16 12:09:26.835872, 5, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:739(gensec_start_mech) Starting GENSEC submechanism ntlmssp [2018/04/16 12:09:26.836390, 10, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec.c:440(gensec_update_send) gensec_update_send: spnego[0x55e772112450]: subreq: 0x55e77211cde0 [2018/04/16 12:09:26.836925, 10, pid=4254, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec.c:498(gensec_update_done) gensec_update_done: spnego[0x55e772112450]: NT_STATUS_MORE_PROCESSING_REQUIRED tevent_req[0x55e77211cde0/../auth/gensec/spnego.c:1601]: state[2] error[0 (0x0)] state[struct gensec_spnego_update_state (0x55e77211cf70)] timer[(nil)] finish[../auth/gensec/spnego.c:2070] [2018/04/16 12:09:26.837555, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3043(smbd_smb2_request_done_ex) smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[64] dyn[yes:74] at ../source3/smbd/smb2_negprot.c:617 [2018/04/16 12:09:26.838068, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:923(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 [2018/04/16 12:09:26.838825, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:761(reply_negprot) Selected protocol SMB 2.??? [2018/04/16 12:09:26.839357, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:763(reply_negprot) negprot index=2 [2018/04/16 12:09:26.840182, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:445(smbd_smb2_inbuf_parse_compound) 41 bytes left, expected at least 64 [2018/04/16 12:09:26.840708, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:1080(smbd_server_connection_terminate_ex) smbd_server_connection_terminate_ex: conn[ipv4:192.168.89.1:61032] reason[NT_STATUS_INVALID_PARAMETER] at ../source3/smbd/smb2_server.c:3967 [2018/04/16 12:09:26.841209, 4, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:26.841723, 5, pid=4254, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/16 12:09:26.842212, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:26.843223, 5, pid=4254, 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) [2018/04/16 12:09:26.843980, 4, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:26.844488, 5, pid=4254, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/16 12:09:26.844979, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:26.846044, 5, pid=4254, 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) [2018/04/16 12:09:26.846766, 4, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:26.847285, 5, pid=4254, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/16 12:09:26.847854, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:26.848835, 5, pid=4254, 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) [2018/04/16 12:09:26.849549, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_session.c:1764(smbXsrv_session_logoff_all) smbXsrv_session_logoff_all: empty session_table, nothing to do. [2018/04/16 12:09:26.850040, 4, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal) setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0 [2018/04/16 12:09:26.850549, 5, pid=4254, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/16 12:09:26.851044, 5, pid=4254, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:651(debug_unix_user_token) UNIX token of user 0 Primary group is 0 and contains 0 supplementary groups [2018/04/16 12:09:26.852075, 5, pid=4254, 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) [2018/04/16 12:09:26.853098, 10, pid=4254, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:157(msg_dgm_ref_destructor) msg_dgm_ref_destructor: refs=(nil) [2018/04/16 12:09:26.854591, 3, pid=4254, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:236(exit_server_common) Server exit (NT_STATUS_INVALID_PARAMETER) [2018/04/16 12:09:26.859630, 10, pid=4226, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm.c:1430(messaging_dgm_send) messaging_dgm_send: Sending message to 4230 [2018/04/16 12:09:26.860654, 10, pid=4230, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:400(messaging_recv_cb) messaging_recv_cb: Received message 0x314 len 0 (num_fds:0) from 4226 [2018/04/16 12:09:26.861654, 10, pid=4230, effective(0, 0), real(0, 0)] ../source3/smbd/smbd_cleanupd.c:196(smbd_cleanupd_process_exited) smbd_cleanupd_process_exited: cleaned up pid 4254