Attachment 14135 Details for Bug 13384 – complete logfile of login-attempt

complete logfile of login-attempt

log.smbd (text/plain), 48.14 KB, created by andreas on 2018-04-13 19:30:14 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: andreas

Created: 2018-04-13 19:30:14 UTC

Size: 48.14 KB

patch

obsolete

>
>[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

Actions: View

Attachments on bug 13384: 14132 | 14134 | 14135 | 14136 | 14137 | 14138 | 14139 | 14140