[2018/04/13 21:24:28.707078, 10, pid=2357, 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/13 21:24:28.707721, 10, pid=2357, 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/13 21:24:28.707787, 10, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:108(messaging_dgm_ref) messaging_dgm_ref: unique = 17010834837026990115 [2018/04/13 21:24:28.708562, 10, pid=2357, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:534(smbXsrv_client_create) [2018/04/13 21:24:28.708590, 10, pid=2357, 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/13 21:24:28.708630, 1, pid=2357, effective(0, 0), real(0, 0), class=rpc_parse] ../librpc/ndr/ndr.c:422(ndr_print_debug) &client_blob: struct smbXsrv_clientB version : SMBXSRV_VERSION_0 (0) reserved : 0x00000000 (0) info : union smbXsrv_clientU(case 0) info0 : * info0: struct smbXsrv_client table : * ev_ctx : * msg_ctx : * global : * global: struct smbXsrv_client_global0 db_rec : NULL server_id: struct server_id pid : 0x0000000000000935 (2357) task_id : 0x00000000 (0) vnn : 0xffffffff (4294967295) unique_id : 0xec12a02831d56823 (-1435909236682561501) local_address : NULL remote_address : NULL remote_name : NULL initial_connect_time : Fri Apr 13 09:24:29 PM 2018 CEST client_guid : 00000000-0000-0000-0000-000000000000 stored : 0x00 (0) sconn : NULL session_table : NULL last_session_id : 0x0000000000000000 (0) tcon_table : NULL open_table : NULL connections : NULL server_multi_channel_enabled: 0x01 (1) [2018/04/13 21:24:28.708972, 5, pid=2357, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 87040 SO_RCVBUF = 374400 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2018/04/13 21:24:28.709103, 5, pid=2357, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 87040 SO_RCVBUF = 374400 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2018/04/13 21:24:28.709286, 3, pid=2357, 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/13 21:24:28.709310, 10, pid=2357, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:3840(smbd_add_connection) Connection allowed from ipv4:192.168.89.1:53028 to ipv4:192.168.89.129:445 [2018/04/13 21:24:28.709516, 6, pid=2357, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Apr 12 22:20:08 2018 [2018/04/13 21:24:28.709664, 3, pid=2357, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1340(init_oplocks) init_oplocks: initializing messages. [2018/04/13 21:24:28.709691, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 774 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709710, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 778 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709727, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 770 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709744, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 787 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709778, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 779 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709795, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 15 - private_data=(nil) [2018/04/13 21:24:28.709812, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:688(messaging_register) Overriding messaging pointer for type 15 - private_data=(nil) [2018/04/13 21:24:28.709829, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 16 - private_data=(nil) [2018/04/13 21:24:28.709845, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 16 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709862, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 33 - private_data=0x55bba4cb3920 [2018/04/13 21:24:28.709879, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 33 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709895, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 790 - private_data=(nil) [2018/04/13 21:24:28.709912, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 790 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.709929, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 791 - private_data=(nil) [2018/04/13 21:24:28.709945, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 1 - private_data=(nil) [2018/04/13 21:24:28.709962, 5, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 1 - private_data=(nil) [2018/04/13 21:24:28.709984, 10, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(keepalive) 0x55bba4cde950 [2018/04/13 21:24:28.710002, 10, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(deadtime) 0x55bba4cdeba0 [2018/04/13 21:24:28.710025, 10, pid=2357, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x55bba4cdf280 [2018/04/13 21:24:28.710144, 5, pid=2357, 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/13 21:24:28.710165, 5, pid=2357, 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:53028 read error = NT_STATUS_END_OF_FILE. [2018/04/13 21:24:28.710209, 4, pid=2357, 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/13 21:24:28.710238, 5, pid=2357, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.710264, 5, pid=2357, 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/13 21:24:28.710306, 5, pid=2357, 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/13 21:24:28.710342, 4, pid=2357, 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/13 21:24:28.710358, 5, pid=2357, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.710374, 5, pid=2357, 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/13 21:24:28.710402, 5, pid=2357, 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/13 21:24:28.710424, 4, pid=2357, 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/13 21:24:28.710440, 5, pid=2357, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.710455, 5, pid=2357, 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/13 21:24:28.710484, 5, pid=2357, 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/13 21:24:28.710505, 10, pid=2357, 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/13 21:24:28.710521, 4, pid=2357, 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/13 21:24:28.710537, 5, pid=2357, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.710552, 5, pid=2357, 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/13 21:24:28.710580, 5, pid=2357, 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/13 21:24:28.710842, 10, pid=2357, 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/13 21:24:28.710984, 3, pid=2357, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:236(exit_server_common) Server exit (failed to receive smb request) [2018/04/13 21:24:28.714447, 10, pid=2358, 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/13 21:24:28.714816, 10, pid=2358, 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/13 21:24:28.714863, 10, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:108(messaging_dgm_ref) messaging_dgm_ref: unique = 7256211529664017423 [2018/04/13 21:24:28.715017, 10, pid=2358, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:534(smbXsrv_client_create) [2018/04/13 21:24:28.715034, 10, pid=2358, 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/13 21:24:28.715066, 1, pid=2358, effective(0, 0), real(0, 0), class=rpc_parse] ../librpc/ndr/ndr.c:422(ndr_print_debug) &client_blob: struct smbXsrv_clientB version : SMBXSRV_VERSION_0 (0) reserved : 0x00000000 (0) info : union smbXsrv_clientU(case 0) info0 : * info0: struct smbXsrv_client table : * ev_ctx : * msg_ctx : * global : * global: struct smbXsrv_client_global0 db_rec : NULL server_id: struct server_id pid : 0x0000000000000936 (2358) task_id : 0x00000000 (0) vnn : 0xffffffff (4294967295) unique_id : 0x64b33df0f0b7b40f (7256211529664017423) local_address : NULL remote_address : NULL remote_name : NULL initial_connect_time : Fri Apr 13 09:24:29 PM 2018 CEST client_guid : 00000000-0000-0000-0000-000000000000 stored : 0x00 (0) sconn : NULL session_table : NULL last_session_id : 0x0000000000000000 (0) tcon_table : NULL open_table : NULL connections : NULL server_multi_channel_enabled: 0x01 (1) [2018/04/13 21:24:28.715359, 5, pid=2358, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 87040 SO_RCVBUF = 374400 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2018/04/13 21:24:28.715511, 5, pid=2358, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 87040 SO_RCVBUF = 374400 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2018/04/13 21:24:28.715669, 3, pid=2358, 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/13 21:24:28.715690, 10, pid=2358, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:3840(smbd_add_connection) Connection allowed from ipv4:192.168.89.1:53029 to ipv4:192.168.89.129:139 [2018/04/13 21:24:28.715795, 6, pid=2358, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Apr 12 22:20:08 2018 [2018/04/13 21:24:28.715868, 3, pid=2358, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1340(init_oplocks) init_oplocks: initializing messages. [2018/04/13 21:24:28.715889, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 774 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.715908, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 778 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.715925, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 770 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.715941, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 787 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.715957, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 779 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.715973, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 15 - private_data=(nil) [2018/04/13 21:24:28.715990, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:688(messaging_register) Overriding messaging pointer for type 15 - private_data=(nil) [2018/04/13 21:24:28.716006, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 16 - private_data=(nil) [2018/04/13 21:24:28.716023, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 16 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.716039, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 33 - private_data=0x55bba4cb3920 [2018/04/13 21:24:28.716056, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 33 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.716072, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 790 - private_data=(nil) [2018/04/13 21:24:28.716088, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 790 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.716104, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 791 - private_data=(nil) [2018/04/13 21:24:28.716120, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 1 - private_data=(nil) [2018/04/13 21:24:28.716136, 5, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 1 - private_data=(nil) [2018/04/13 21:24:28.716155, 10, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(keepalive) 0x55bba4cde950 [2018/04/13 21:24:28.716173, 10, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(deadtime) 0x55bba4cdeba0 [2018/04/13 21:24:28.716194, 10, pid=2358, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x55bba4cdf280 [2018/04/13 21:24:28.716306, 5, pid=2358, 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/13 21:24:28.716327, 5, pid=2358, 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:53029 read error = NT_STATUS_END_OF_FILE. [2018/04/13 21:24:28.716371, 4, pid=2358, 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/13 21:24:28.716399, 5, pid=2358, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.716424, 5, pid=2358, 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/13 21:24:28.716470, 5, pid=2358, 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/13 21:24:28.716491, 4, pid=2358, 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/13 21:24:28.716508, 5, pid=2358, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.716523, 5, pid=2358, 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/13 21:24:28.716552, 5, pid=2358, 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/13 21:24:28.716592, 4, pid=2358, 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/13 21:24:28.716626, 5, pid=2358, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.716643, 5, pid=2358, 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/13 21:24:28.716674, 5, pid=2358, 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/13 21:24:28.716695, 10, pid=2358, 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/13 21:24:28.716712, 4, pid=2358, 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/13 21:24:28.716728, 5, pid=2358, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.716744, 5, pid=2358, 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/13 21:24:28.716786, 5, pid=2358, 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/13 21:24:28.717031, 10, pid=2358, 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/13 21:24:28.717174, 3, pid=2358, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:236(exit_server_common) Server exit (failed to receive smb request) [2018/04/13 21:24:28.722654, 10, pid=1616, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm.c:1430(messaging_dgm_send) messaging_dgm_send: Sending message to 1619 [2018/04/13 21:24:28.722789, 10, pid=1616, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm.c:1430(messaging_dgm_send) messaging_dgm_send: Sending message to 1619 [2018/04/13 21:24:28.722825, 10, pid=1619, 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 1616 [2018/04/13 21:24:28.722829, 10, pid=2361, 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/13 21:24:28.722940, 10, pid=1619, effective(0, 0), real(0, 0)] ../source3/smbd/smbd_cleanupd.c:196(smbd_cleanupd_process_exited) smbd_cleanupd_process_exited: cleaned up pid 2358 [2018/04/13 21:24:28.723016, 10, pid=1619, effective(0, 0), real(0, 0)] ../source3/smbd/smbd_cleanupd.c:196(smbd_cleanupd_process_exited) smbd_cleanupd_process_exited: cleaned up pid 2357 [2018/04/13 21:24:28.723051, 10, pid=1619, 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 1616 [2018/04/13 21:24:28.723110, 10, pid=2361, 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/13 21:24:28.723143, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm_ref.c:108(messaging_dgm_ref) messaging_dgm_ref: unique = 4934808841592576197 [2018/04/13 21:24:28.723797, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/smbXsrv_client.c:534(smbXsrv_client_create) [2018/04/13 21:24:28.723821, 10, pid=2361, 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/13 21:24:28.723859, 1, pid=2361, effective(0, 0), real(0, 0), class=rpc_parse] ../librpc/ndr/ndr.c:422(ndr_print_debug) &client_blob: struct smbXsrv_clientB version : SMBXSRV_VERSION_0 (0) reserved : 0x00000000 (0) info : union smbXsrv_clientU(case 0) info0 : * info0: struct smbXsrv_client table : * ev_ctx : * msg_ctx : * global : * global: struct smbXsrv_client_global0 db_rec : NULL server_id: struct server_id pid : 0x0000000000000939 (2361) task_id : 0x00000000 (0) vnn : 0xffffffff (4294967295) unique_id : 0x447bf67ee59518c5 (4934808841592576197) local_address : NULL remote_address : NULL remote_name : NULL initial_connect_time : Fri Apr 13 09:24:29 PM 2018 CEST client_guid : 00000000-0000-0000-0000-000000000000 stored : 0x00 (0) sconn : NULL session_table : NULL last_session_id : 0x0000000000000000 (0) tcon_table : NULL open_table : NULL connections : NULL server_multi_channel_enabled: 0x01 (1) [2018/04/13 21:24:28.724131, 5, pid=2361, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 87040 SO_RCVBUF = 374400 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2018/04/13 21:24:28.724243, 5, pid=2361, effective(0, 0), real(0, 0)] ../lib/util/util_net.c:1055(print_socket_options) Socket options: SO_KEEPALIVE = 1 SO_REUSEADDR = 1 SO_BROADCAST = 0 TCP_NODELAY = 1 TCP_KEEPCNT = 9 TCP_KEEPIDLE = 7200 TCP_KEEPINTVL = 75 IPTOS_LOWDELAY = 0 IPTOS_THROUGHPUT = 0 SO_REUSEPORT = 1 SO_SNDBUF = 87040 SO_RCVBUF = 374400 SO_SNDLOWAT = 1 SO_RCVLOWAT = 1 SO_SNDTIMEO = 0 SO_RCVTIMEO = 0 TCP_QUICKACK = 1 TCP_DEFER_ACCEPT = 0 [2018/04/13 21:24:28.724404, 3, pid=2361, 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/13 21:24:28.724425, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:3840(smbd_add_connection) Connection allowed from ipv4:192.168.89.1:53030 to ipv4:192.168.89.129:445 [2018/04/13 21:24:28.724494, 6, pid=2361, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Apr 12 22:20:08 2018 [2018/04/13 21:24:28.724548, 3, pid=2361, effective(0, 0), real(0, 0), class=locking] ../source3/smbd/oplock.c:1340(init_oplocks) init_oplocks: initializing messages. [2018/04/13 21:24:28.724568, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 774 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724586, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 778 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724603, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 770 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724619, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 787 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724635, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 779 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724651, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 15 - private_data=(nil) [2018/04/13 21:24:28.724667, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:688(messaging_register) Overriding messaging pointer for type 15 - private_data=(nil) [2018/04/13 21:24:28.724683, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 16 - private_data=(nil) [2018/04/13 21:24:28.724699, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 16 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724716, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 33 - private_data=0x55bba4cb3920 [2018/04/13 21:24:28.724732, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 33 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724748, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 790 - private_data=(nil) [2018/04/13 21:24:28.724781, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 790 - private_data=0x55bba4cdd340 [2018/04/13 21:24:28.724798, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 791 - private_data=(nil) [2018/04/13 21:24:28.724814, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:720(messaging_deregister) Deregistering messaging pointer for type 1 - private_data=(nil) [2018/04/13 21:24:28.724830, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/messages.c:673(messaging_register) Registering messaging pointer for type 1 - private_data=(nil) [2018/04/13 21:24:28.724853, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(keepalive) 0x55bba4cde950 [2018/04/13 21:24:28.724871, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(deadtime) 0x55bba4cdeba0 [2018/04/13 21:24:28.724892, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:99(event_add_idle) event_add_idle: idle_evt(housekeeping) 0x55bba4cdf280 [2018/04/13 21:24:28.725011, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/util_sock.c:248(read_smb_length_return_keepalive) got smb length of 69 [2018/04/13 21:24:28.725037, 6, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1956(process_smb) got message type 0x0 of len 0x45 [2018/04/13 21:24:28.725054, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1958(process_smb) Transaction 0 of length 73 (0 toread) [2018/04/13 21:24:28.725071, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/util.c:184(show_msg) [2018/04/13 21:24:28.725084, 5, pid=2361, 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 smb_flg2=51201 smb_tid=65535 smb_pid=1 smb_uid=65535 smb_mid=0 smt_wct=0 smb_bcc=34 [2018/04/13 21:24:28.725139, 10, pid=2361, 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 [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/13 21:24:28.725191, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:1538(switch_message) switch message SMBnegprot (pid 2361) conn 0x0 [2018/04/13 21:24:28.725216, 4, pid=2361, 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/13 21:24:28.725244, 5, pid=2361, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.725270, 5, pid=2361, 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/13 21:24:28.725309, 5, pid=2361, 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/13 21:24:28.726226, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:628(reply_negprot) Requested protocol [NT LM 0.12] [2018/04/13 21:24:28.726259, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:628(reply_negprot) Requested protocol [SMB 2.002] [2018/04/13 21:24:28.726276, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:628(reply_negprot) Requested protocol [SMB 2.???] [2018/04/13 21:24:28.726293, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/lib/util.c:1349(set_remote_arch) set_remote_arch: Client arch is 'OSX' [2018/04/13 21:24:28.726330, 6, pid=2361, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Apr 12 22:20:08 2018 [2018/04/13 21:24:28.726379, 6, pid=2361, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Apr 12 22:20:08 2018 [2018/04/13 21:24:28.726411, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:3518(smbd_smb2_process_negprot) smbd_smb2_first_negprot: packet length 102 [2018/04/13 21:24:28.726451, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:671(smb2_validate_sequence_number) smb2_validate_sequence_number: clearing id 0 (position 0) from bitmap [2018/04/13 21:24:28.726477, 10, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_server.c:2310(smbd_smb2_request_dispatch) smbd_smb2_request_dispatch: opcode[SMB2_OP_NEGPROT] mid = 0 [2018/04/13 21:24:28.726496, 4, pid=2361, 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/13 21:24:28.726513, 5, pid=2361, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:28.726529, 5, pid=2361, 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/13 21:24:28.726560, 5, pid=2361, 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/13 21:24:28.726606, 6, pid=2361, effective(0, 0), real(0, 0)] ../source3/param/loadparm.c:2332(lp_file_list_changed) lp_file_list_changed() file /etc/samba/smb.conf -> /etc/samba/smb.conf last mod_time: Thu Apr 12 22:20:08 2018 [2018/04/13 21:24:28.726641, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/smb2_negprot.c:291(smbd_smb2_request_process_negprot) Selected protocol SMB2_FF [2018/04/13 21:24:28.726660, 5, pid=2361, 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/13 21:24:28.726681, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend guest [2018/04/13 21:24:28.726703, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'guest' [2018/04/13 21:24:28.726722, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend sam [2018/04/13 21:24:28.726738, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'sam' [2018/04/13 21:24:28.726772, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend sam_ignoredomain [2018/04/13 21:24:28.726789, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'sam_ignoredomain' [2018/04/13 21:24:28.726805, 5, pid=2361, 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/13 21:24:28.726822, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'sam_netlogon3' [2018/04/13 21:24:28.726838, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:48(smb_register_auth) Attempting to register auth backend winbind [2018/04/13 21:24:28.726854, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:60(smb_register_auth) Successfully added auth method 'winbind' [2018/04/13 21:24:28.726870, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:400(load_auth_module) load_auth_module: Attempting to find an auth method to match guest [2018/04/13 21:24:28.726887, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:425(load_auth_module) load_auth_module: auth method guest has a valid init [2018/04/13 21:24:28.726904, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:400(load_auth_module) load_auth_module: Attempting to find an auth method to match sam_ignoredomain [2018/04/13 21:24:28.726920, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../source3/auth/auth.c:425(load_auth_module) load_auth_module: auth method sam_ignoredomain has a valid init [2018/04/13 21:24:43.766541, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'gssapi_spnego' registered [2018/04/13 21:24:43.766613, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'gssapi_krb5' registered [2018/04/13 21:24:43.766642, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'gssapi_krb5_sasl' registered [2018/04/13 21:24:43.766669, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'spnego' registered [2018/04/13 21:24:43.766695, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'schannel' registered [2018/04/13 21:24:43.766720, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'naclrpc_as_system' registered [2018/04/13 21:24:43.766747, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'sasl-EXTERNAL' registered [2018/04/13 21:24:43.766802, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'ntlmssp' registered [2018/04/13 21:24:43.766829, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'ntlmssp_resume_ccache' registered [2018/04/13 21:24:43.766855, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'http_basic' registered [2018/04/13 21:24:43.766887, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'http_ntlm' registered [2018/04/13 21:24:43.766916, 3, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:977(gensec_register) GENSEC backend 'http_negotiate' registered [2018/04/13 21:24:43.766997, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:739(gensec_start_mech) Starting GENSEC mechanism spnego [2018/04/13 21:24:43.767054, 5, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec_start.c:739(gensec_start_mech) Starting GENSEC submechanism ntlmssp [2018/04/13 21:24:43.767109, 10, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec.c:440(gensec_update_send) gensec_update_send: spnego[0x55bba4ce0d90]: subreq: 0x55bba4ceaf40 [2018/04/13 21:24:43.767151, 10, pid=2361, effective(0, 0), real(0, 0), class=auth] ../auth/gensec/gensec.c:498(gensec_update_done) gensec_update_done: spnego[0x55bba4ce0d90]: NT_STATUS_MORE_PROCESSING_REQUIRED tevent_req[0x55bba4ceaf40/../auth/gensec/spnego.c:1601]: state[2] error[0 (0x0)] state[struct gensec_spnego_update_state (0x55bba4ceb0d0)] timer[(nil)] finish[../auth/gensec/spnego.c:2070] [2018/04/13 21:24:43.767226, 10, pid=2361, 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/13 21:24:43.767273, 10, pid=2361, 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/13 21:24:43.767503, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:761(reply_negprot) Selected protocol SMB 2.??? [2018/04/13 21:24:43.767589, 5, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/negprot.c:763(reply_negprot) negprot index=2 [2018/04/13 21:24:43.767725, 10, pid=2361, 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/13 21:24:43.767777, 10, pid=2361, 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:53030] reason[NT_STATUS_INVALID_PARAMETER] at ../source3/smbd/smb2_server.c:3967 [2018/04/13 21:24:43.767810, 4, pid=2361, 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/13 21:24:43.767832, 5, pid=2361, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:43.767852, 5, pid=2361, 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/13 21:24:43.767892, 5, pid=2361, 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/13 21:24:43.767919, 4, pid=2361, 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/13 21:24:43.767938, 5, pid=2361, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:43.767957, 5, pid=2361, 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/13 21:24:43.767990, 5, pid=2361, 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/13 21:24:43.768015, 4, pid=2361, 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/13 21:24:43.768035, 5, pid=2361, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:43.768055, 5, pid=2361, 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/13 21:24:43.768089, 5, pid=2361, 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/13 21:24:43.768114, 10, pid=2361, 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/13 21:24:43.768135, 4, pid=2361, 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/13 21:24:43.768154, 5, pid=2361, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug) Security token: (NULL) [2018/04/13 21:24:43.768172, 5, pid=2361, 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/13 21:24:43.768206, 5, pid=2361, 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/13 21:24:43.768266, 10, pid=2361, 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/13 21:24:43.768457, 3, pid=2361, effective(0, 0), real(0, 0)] ../source3/smbd/server_exit.c:236(exit_server_common) Server exit (NT_STATUS_INVALID_PARAMETER) [2018/04/13 21:24:43.772496, 10, pid=1616, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm.c:1430(messaging_dgm_send) messaging_dgm_send: Sending message to 1619 [2018/04/13 21:24:43.772579, 10, pid=1619, 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 1616 [2018/04/13 21:24:43.772686, 10, pid=1619, effective(0, 0), real(0, 0)] ../source3/smbd/smbd_cleanupd.c:196(smbd_cleanupd_process_exited) smbd_cleanupd_process_exited: cleaned up pid 2361