Attachment 14139 Details for Bug 13384 – log.smbd level 10

log.smbd level 10

log.smbd (text/plain), 48.14 KB, created by andreas on 2018-04-16 10:12:37 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: andreas

Created: 2018-04-16 10:12:37 UTC

Size: 48.14 KB

patch

obsolete

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

Actions: View

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