Attachment 1458765 Details for Bug 1601031 – samba client logs.

samba client logs.

log.172.16.169.34 (text/plain), 27.39 KB, created by Niranjan Mallapadi Raghavender on 2018-07-13 16:13:51 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Niranjan Mallapadi Raghavender

Created: 2018-07-13 16:13:51 UTC

Size: 27.39 KB

patch

obsolete

>[2018/07/13 12:04:02.381195, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/messages_dgm.c:1430(messaging_dgm_send)
>  messaging_dgm_send: Sending message to 5286
>[2018/07/13 12:04:02.381643,  4, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/smbd/sec_ctx.c:438(pop_sec_ctx)
>  pop_sec_ctx (842012700, 842000513) - sec_ctx_stack_ndx = 0
>[2018/07/13 12:04:02.381662, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2] ../source3/smbd/smb2_server.c:3062(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[2] dyn[no:0] at ../source3/smbd/smb2_setinfo.c:157
>[2018/07/13 12:04:02.381673, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:936(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 2, charge 1, granted 2, current possible/max 324/512, total granted/max/low/range 190/8192/295/190
>[2018/07/13 12:04:02.381963, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2] ../source3/smbd/smb2_server.c:3934(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2018/07/13 12:04:02.381981, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:678(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 295 (position 295) from bitmap
>[2018/07/13 12:04:02.381990, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2] ../source3/smbd/smb2_server.c:2327(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_CLOSE] mid = 295
>[2018/07/13 12:04:02.382000,  4, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/smbd/uid.c:384(change_to_user)
>  Skipping user change - already user
>[2018/07/13 12:04:02.382011, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2] ../source3/smbd/smb2_close.c:230(smbd_smb2_close)
>  smbd_smb2_close: file1.txt - fnum 1479348989
>[2018/07/13 12:04:02.382022,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap.c:130(dbwrap_lock_order_lock)
>  dbwrap_lock_order_lock: check lock order 1 for /var/lib/samba/lock/locking.tdb
>[2018/07/13 12:04:02.382031, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap.c:116(debug_lock_order)
>  lock order:  1:/var/lib/samba/lock/locking.tdb 2:<none> 3:<none>
>[2018/07/13 12:04:02.382042, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap_tdb.c:61(db_tdb_log_key)
>  Locking key 01FD000000000000C018
>[2018/07/13 12:04:02.382051, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap_tdb.c:145(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x55f5d417f320
>[2018/07/13 12:04:02.382061, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=locking] ../source3/locking/share_mode_lock.c:285(share_mode_memcache_fetch)
>  fetched entry for file file1.txt seq 0x4362671332241627431 key fd01:24018c0:0
>[2018/07/13 12:04:02.382071, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=locking] ../source3/locking/locking.c:1226(find_delete_on_close_token)
>  find_delete_on_close_token: name_hash = 0x6d2a1bde
>[2018/07/13 12:04:02.382079, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=locking] ../source3/locking/share_mode_lock.c:368(unparse_share_modes)
>  unparse_share_modes:
>[2018/07/13 12:04:02.382087,  1, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=rpc_parse] ../librpc/ndr/ndr.c:422(ndr_print_debug)
>       d: struct share_mode_data
>          sequence_number          : 0x3c8b5276560ad927 (4362671332241627431)
>          servicepath              : *
>              servicepath              : '/mnt/samba/share1'
>          base_name                : *
>              base_name                : 'file1.txt'
>          stream_name              : NULL
>          num_share_modes          : 0x00000000 (0)
>          share_modes: ARRAY(0)
>          num_leases               : 0x00000000 (0)
>          leases: ARRAY(0)
>          num_delete_tokens        : 0x00000000 (0)
>          delete_tokens: ARRAY(0)
>          old_write_time           : Fri Jul 13 10:11:17 AM 2018 EDT.602992300
>          changed_write_time       : Wed Dec 31 07:00:00 PM 1969 EST.0
>          fresh                    : 0x00 (0)
>          modified                 : 0x01 (1)
>          record                   : *
>          id: struct file_id
>              devid                    : 0x000000000000fd01 (64769)
>              inode                    : 0x00000000024018c0 (37755072)
>              extid                    : 0x0000000000000000 (0)
>[2018/07/13 12:04:02.382173, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=locking] ../source3/locking/share_mode_lock.c:157(share_mode_memcache_delete)
>  deleting entry for file file1.txt seq 0x4362671332241627431 key fd01:24018c0:0
>[2018/07/13 12:04:02.382183, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=locking] ../source3/locking/share_mode_lock.c:380(unparse_share_modes)
>  No used share mode found
>[2018/07/13 12:04:02.382192,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_lock_order_unlock)
>  dbwrap_lock_order_unlock: release lock order 1 for /var/lib/samba/lock/locking.tdb
>[2018/07/13 12:04:02.382202, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap_tdb.c:61(db_tdb_log_key)
>  Unlocking key 01FD000000000000C018
>[2018/07/13 12:04:02.382213,  2, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/smbd/close.c:805(close_normal_file)
>  idm_perf_user1@juno.test closed file file1.txt (numopen=0) NT_STATUS_OK
>[2018/07/13 12:04:02.382222,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap.c:130(dbwrap_lock_order_lock)
>  dbwrap_lock_order_lock: check lock order 1 for /var/lib/samba/lock/smbXsrv_open_global.tdb
>[2018/07/13 12:04:02.382231, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap.c:116(debug_lock_order)
>  lock order:  1:/var/lib/samba/lock/smbXsrv_open_global.tdb 2:<none> 3:<none>
>[2018/07/13 12:04:02.382240, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap_tdb.c:61(db_tdb_log_key)
>  Locking key 7B4BDECE
>[2018/07/13 12:04:02.382249, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap_tdb.c:145(db_tdb_fetch_locked_internal)
>  Allocated locked data 0x0x55f5d4171640
>[2018/07/13 12:04:02.382261, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap_tdb.c:61(db_tdb_log_key)
>  Unlocking key 7B4BDECE
>[2018/07/13 12:04:02.382269,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../lib/dbwrap/dbwrap.c:159(dbwrap_lock_order_unlock)
>  dbwrap_lock_order_unlock: release lock order 1 for /var/lib/samba/lock/smbXsrv_open_global.tdb
>[2018/07/13 12:04:02.382310,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/smbd/files.c:563(file_free)
>  freed files structure 1479348989 (0 used)
>[2018/07/13 12:04:02.382323, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2] ../source3/smbd/smb2_server.c:3062(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[60] dyn[no:0] at ../source3/smbd/smb2_close.c:147
>[2018/07/13 12:04:02.382333, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:936(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 2, charge 1, granted 2, current possible/max 323/512, total granted/max/low/range 191/8192/296/191
>[2018/07/13 12:04:06.510731, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2018/07/13 12:04:06.510829, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2018/07/13 12:04:06.510860, 10, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2018/07/13 12:04:06.510871,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/smbd/process.c:2898(housekeeping_fn)
>  housekeeping
>[2018/07/13 12:04:06.510882,  4, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/smbd/sec_ctx.c:320(set_sec_ctx_internal)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2018/07/13 12:04:06.510892,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:04:06.510902,  5, pid=5370, effective(842012700, 842000513), real(842012700, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:04:06.510929,  5, pid=5370, 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/07/13 12:04:06.510944, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) rescheduled
>[2018/07/13 12:05:06.571136, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2018/07/13 12:05:06.571485, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2018/07/13 12:05:06.571507, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2018/07/13 12:05:06.571516,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:2898(housekeeping_fn)
>  housekeeping
>[2018/07/13 12:05:06.571528,  4, pid=5370, 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/07/13 12:05:06.571539,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:05:06.571548,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:05:06.571595,  5, pid=5370, 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/07/13 12:05:06.571737,  6, pid=5370, 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: Fri Jul 13 09:55:28 2018
>  
>[2018/07/13 12:05:06.571820, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) rescheduled
>[2018/07/13 12:05:19.569659, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3934(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2018/07/13 12:05:19.569726, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:678(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 296 (position 296) from bitmap
>[2018/07/13 12:05:19.569740, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:2327(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_KEEPALIVE] mid = 296
>[2018/07/13 12:05:19.569753,  4, pid=5370, 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/07/13 12:05:19.569776,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:05:19.569785,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:05:19.569807,  5, pid=5370, 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/07/13 12:05:19.569819, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3062(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[4] dyn[no:0] at ../source3/smbd/smb2_keepalive.c:49
>[2018/07/13 12:05:19.569832, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:936(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 322/512, total granted/max/low/range 191/8192/297/191
>[2018/07/13 12:06:06.619006, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2018/07/13 12:06:06.619059, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2018/07/13 12:06:06.619078, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2018/07/13 12:06:06.619088,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:2898(housekeeping_fn)
>  housekeeping
>[2018/07/13 12:06:06.619098,  4, pid=5370, 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/07/13 12:06:06.619108,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:06:06.619118,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:06:06.619139,  5, pid=5370, 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/07/13 12:06:06.619153, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) rescheduled
>[2018/07/13 12:06:19.730102, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3934(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2018/07/13 12:06:19.730164, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:678(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 297 (position 297) from bitmap
>[2018/07/13 12:06:19.730178, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:2327(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_KEEPALIVE] mid = 297
>[2018/07/13 12:06:19.730190,  4, pid=5370, 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/07/13 12:06:19.730201,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:06:19.730210,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:06:19.730239,  5, pid=5370, 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/07/13 12:06:19.730273, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3062(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[4] dyn[no:0] at ../source3/smbd/smb2_keepalive.c:49
>[2018/07/13 12:06:19.730323, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:936(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 322/512, total granted/max/low/range 191/8192/298/191
>[2018/07/13 12:07:06.666406, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2018/07/13 12:07:06.666611, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2018/07/13 12:07:06.666631, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2018/07/13 12:07:06.666641,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:2898(housekeeping_fn)
>  housekeeping
>[2018/07/13 12:07:06.666651,  4, pid=5370, 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/07/13 12:07:06.666662,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:07:06.666671,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:07:06.666693,  5, pid=5370, 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/07/13 12:07:06.666708, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) rescheduled
>[2018/07/13 12:07:19.890406, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3934(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2018/07/13 12:07:19.890467, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:678(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 298 (position 298) from bitmap
>[2018/07/13 12:07:19.890481, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:2327(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_KEEPALIVE] mid = 298
>[2018/07/13 12:07:19.890494,  4, pid=5370, 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/07/13 12:07:19.890505,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:07:19.890514,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:07:19.890536,  5, pid=5370, 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/07/13 12:07:19.890548, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3062(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[4] dyn[no:0] at ../source3/smbd/smb2_keepalive.c:49
>[2018/07/13 12:07:19.890560, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:936(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 322/512, total granted/max/low/range 191/8192/299/191
>[2018/07/13 12:08:06.713506, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2018/07/13 12:08:06.713560, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2018/07/13 12:08:06.713579, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2018/07/13 12:08:06.713588,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:2898(housekeeping_fn)
>  housekeeping
>[2018/07/13 12:08:06.713599,  4, pid=5370, 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/07/13 12:08:06.713609,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:08:06.713618,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:08:06.713641,  5, pid=5370, 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/07/13 12:08:06.713692,  6, pid=5370, 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: Fri Jul 13 09:55:28 2018
>  
>[2018/07/13 12:08:06.713716, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) rescheduled
>[2018/07/13 12:08:20.050782, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3934(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2018/07/13 12:08:20.050846, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:678(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 299 (position 299) from bitmap
>[2018/07/13 12:08:20.050860, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:2327(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_KEEPALIVE] mid = 299
>[2018/07/13 12:08:20.050873,  4, pid=5370, 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/07/13 12:08:20.050883,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:08:20.050892,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:08:20.050915,  5, pid=5370, 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/07/13 12:08:20.050926, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3062(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[4] dyn[no:0] at ../source3/smbd/smb2_keepalive.c:49
>[2018/07/13 12:08:20.050939, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:936(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 322/512, total granted/max/low/range 191/8192/300/191
>[2018/07/13 12:09:06.721400, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2018/07/13 12:09:06.721462, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2018/07/13 12:09:06.721481, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:42(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2018/07/13 12:09:06.721491,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/smbd/process.c:2898(housekeeping_fn)
>  housekeeping
>[2018/07/13 12:09:06.721501,  4, pid=5370, 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/07/13 12:09:06.721511,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:09:06.721520,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:09:06.721540,  5, pid=5370, 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/07/13 12:09:06.721711, 10, pid=5370, effective(0, 0), real(0, 0)] ../source3/lib/util_event.c:53(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) rescheduled
>[2018/07/13 12:09:20.210988, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3934(smbd_smb2_io_handler)
>  smbd_smb2_request idx[1] of 5 vectors
>[2018/07/13 12:09:20.211050, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:678(smb2_validate_sequence_number)
>  smb2_validate_sequence_number: smb2_validate_sequence_number: clearing id 300 (position 300) from bitmap
>[2018/07/13 12:09:20.211065, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:2327(smbd_smb2_request_dispatch)
>  smbd_smb2_request_dispatch: opcode[SMB2_OP_KEEPALIVE] mid = 300
>[2018/07/13 12:09:20.211078,  4, pid=5370, 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/07/13 12:09:20.211088,  5, pid=5370, effective(0, 0), real(0, 0)] ../libcli/security/security_token.c:53(security_token_debug)
>  Security token: (NULL)
>[2018/07/13 12:09:20.211105,  5, pid=5370, effective(0, 0), real(0, 0)] ../source3/auth/token_util.c:810(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2018/07/13 12:09:20.211130,  5, pid=5370, 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/07/13 12:09:20.211143, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2] ../source3/smbd/smb2_server.c:3062(smbd_smb2_request_done_ex)
>  smbd_smb2_request_done_ex: idx[1] status[NT_STATUS_OK] body[4] dyn[no:0] at ../source3/smbd/smb2_keepalive.c:49
>[2018/07/13 12:09:20.211156, 10, pid=5370, effective(0, 0), real(0, 0), class=smb2_credits] ../source3/smbd/smb2_server.c:936(smb2_set_operation_credit)
>  smb2_set_operation_credit: smb2_set_operation_credit: requested 1, charge 1, granted 1, current possible/max 322/512, total granted/max/low/range 191/8192/301/191

Actions: View

Attachments on bug 1601031: 1458765 | 1458767