Attachment 4890 Details for Bug 6828 – Output of debug level 10 when I applied patch to Samba 3.4.1 and ran my program has described in defect.

Output of debug level 10 when I applied patch to Samba 3.4.1 and ran my program has described in defect.

opbrlbug (text/plain), 11.73 KB, created by Barry Sabsevitz (mail address dead) on 2009-10-26 19:20:20 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Barry Sabsevitz (mail address dead)

Created: 2009-10-26 19:20:20 UTC

Size: 11.73 KB

patch

obsolete

>[2009/10/26 17:01:14, 10] locking/brlock.c:1849(brl_get_locks_internal)
>  brl_get_locks_internal: 0 current locks on file_id 18:4000017:0
>[2009/10/26 17:01:14,  5] locking/posix.c:1201(set_posix_lock_posix_flavour)
>  set_posix_lock_posix_flavour: File foo1, offset = 0, count = 9223372036854775808, type = WRITE
>[2009/10/26 17:01:14, 10] locking/posix.c:172(posix_lock_in_range)
>  posix_lock_in_range: offset_out = 0, count_out = 9223372036854775808
>[2009/10/26 17:01:14,  8] locking/posix.c:189(posix_fcntl_lock)
>  posix_fcntl_lock 33 6 0 9223372036854775808 1
>[2009/10/26 17:01:14,  8] ../lib/util/util.c:243(fcntl_lock)
>  fcntl_lock 33 6 0 9223372036854775808 1
>[2009/10/26 17:01:14,  3] ../lib/util/util.c:254(fcntl_lock)
>  fcntl_lock: fcntl lock gave errno 11 (Resource temporarily unavailable)
>[2009/10/26 17:01:14,  3] ../lib/util/util.c:273(fcntl_lock)
>  fcntl_lock: lock failed at offset 0 count 9223372036854775808 op 6 type 1 (Resource temporarily unavailable)
>[2009/10/26 17:01:14,  8] locking/posix.c:219(posix_fcntl_lock)
>  posix_fcntl_lock: Lock call failed
>[2009/10/26 17:01:14,  5] locking/posix.c:1215(set_posix_lock_posix_flavour)
>  set_posix_lock_posix_flavour: Lock fail !: Type = WRITE: offset = 0, count = 9223372036854775808. Errno = Resource temporarily unavailable
>[2009/10/26 17:01:14, 10] locking/locking.c:282(do_lock)
>  do_lock: returning status=NT_STATUS_FILE_LOCK_CONFLICT
>[2009/10/26 17:01:14, 10] smbd/blocking.c:103(recalc_brl_timeout)
>[2009/10/26 17:01:14, 10] smbd/blocking.c:109(recalc_brl_timeout)
>  Next timeout = 9.999985 seconds from now.
>[2009/10/26 17:01:14, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Added timed event "brl_timeout_fn": 0x2ba68eb97440
>[2009/10/26 17:01:14,  3] smbd/blocking.c:215(push_blocking_lock_request)
>  push_blocking_lock_request: lock request blocked with expiry time (0 sec. 0 usec) (+-1 msec) for fnum = 5030, name = foo1
>[2009/10/26 17:01:14, 10] libsmb/smb_signing.c:67(store_sequence_for_reply)
>  store_sequence_for_reply: stored seq = 19 mid = 12836
>[2009/10/26 17:01:14, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 18000000000000001700
>[2009/10/26 17:01:24, 10] lib/events.c:105(run_events)
>  Running timed event "brl_timeout_fn" 0x2ba68eb97440
>[2009/10/26 17:01:24, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Destroying timer event 0x2ba68eb97440 "brl_timeout_fn"
>[2009/10/26 17:01:24,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/26 17:01:24,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/10/26 17:01:24,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/26 17:01:24,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/26 17:01:24, 10] smbd/blocking.c:671(process_blocking_lock_queue)
>  Processing BLR = 0x2ba68eb8ef30
>[2009/10/26 17:01:24, 10] locking/locking.c:262(do_lock)
>  do_lock: lock flavour POSIX_LOCK lock type WRITE start=0 len=9223372036854775808 blocking_lock=true requested for fnum 5030 file foo1
>[2009/10/26 17:01:24, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key 18000000000000001700
>[2009/10/26 17:01:24, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x2ba68ebad8c0
>[2009/10/26 17:01:24, 10] locking/brlock.c:1849(brl_get_locks_internal)
>  brl_get_locks_internal: 1 current locks on file_id 18:4000017:0
>[2009/10/26 17:01:24, 10] locking/brlock.c:48(print_lock_struct)
>  [0]: smbpid = 16085, tid = 1, pid = 32748, start = 0, size = 9223372036854775808, fnum = 5030, PENDING_WRITE POSIX_LOCK
>[2009/10/26 17:01:24,  5] locking/posix.c:1201(set_posix_lock_posix_flavour)
>  set_posix_lock_posix_flavour: File foo1, offset = 0, count = 9223372036854775808, type = WRITE
>[2009/10/26 17:01:24, 10] locking/posix.c:172(posix_lock_in_range)
>  posix_lock_in_range: offset_out = 0, count_out = 9223372036854775808
>[2009/10/26 17:01:24,  8] locking/posix.c:189(posix_fcntl_lock)
>  posix_fcntl_lock 33 6 0 9223372036854775808 1
>[2009/10/26 17:01:24,  8] ../lib/util/util.c:243(fcntl_lock)
>  fcntl_lock 33 6 0 9223372036854775808 1
>[2009/10/26 17:01:24,  3] ../lib/util/util.c:254(fcntl_lock)
>  fcntl_lock: fcntl lock gave errno 11 (Resource temporarily unavailable)
>[2009/10/26 17:01:24,  3] ../lib/util/util.c:273(fcntl_lock)
>  fcntl_lock: lock failed at offset 0 count 9223372036854775808 op 6 type 1 (Resource temporarily unavailable)
>[2009/10/26 17:01:24,  8] locking/posix.c:219(posix_fcntl_lock)
>  posix_fcntl_lock: Lock call failed
>[2009/10/26 17:01:24,  5] locking/posix.c:1215(set_posix_lock_posix_flavour)
>  set_posix_lock_posix_flavour: Lock fail !: Type = WRITE: offset = 0, count = 9223372036854775808. Errno = Resource temporarily unavailable
>[2009/10/26 17:01:24, 10] locking/locking.c:282(do_lock)
>  do_lock: returning status=NT_STATUS_FILE_LOCK_CONFLICT
>[2009/10/26 17:01:24, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key 18000000000000001700
>[2009/10/26 17:01:26, 10] lib/events.c:105(run_events)
>  Running timed event "smbd_idle_event_handler" 0x2ba68eb2c240
>[2009/10/26 17:01:26, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Destroying timer event 0x2ba68eb2c240 "smbd_idle_event_handler"
>[2009/10/26 17:01:26, 10] smbd/process.c:642(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2009/10/26 17:01:26,  2] smbd/process.c:1959(deadtime_fn)
>  Closing idle connection
>[2009/10/26 17:01:26, 10] lib/messages_local.c:237(messaging_tdb_store)
>  messaging_tdb_store:
>[2009/10/26 17:01:26,  1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
>       array: struct messaging_array
>          num_messages             : 0x00000001 (1)
>          messages: ARRAY(1)
>              messages: struct messaging_rec
>                  msg_version              : 0x00000002 (2)
>                  msg_type                 : MSG_SHUTDOWN (13)
>                  dest: struct server_id
>                      id                       : 0x00007fe9 (32745)
>                      vnn                      : 0xffffffff (4294967295)
>                  src: struct server_id
>                      id                       : 0x00007fe9 (32745)
>                      vnn                      : 0xffffffff (4294967295)
>                  buf                      : DATA_BLOB length=0
>[2009/10/26 17:01:26, 10] smbd/process.c:646(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) stopped
>[2009/10/26 17:01:26, 10] lib/messages_local.c:73(messaging_tdb_signal_handler)
>  messaging_tdb_signal_handler: sig[10] count[1] msgs[1]
>[2009/10/26 17:01:26, 10] lib/messages_local.c:447(message_dispatch)
>  message_dispatch: received_messages = 1
>[2009/10/26 17:01:26, 10] lib/messages_local.c:196(messaging_tdb_fetch)
>  messaging_tdb_fetch:
>[2009/10/26 17:01:26,  1] ../librpc/ndr/ndr.c:214(ndr_print_debug)
>       result: struct messaging_array
>          num_messages             : 0x00000001 (1)
>          messages: ARRAY(1)
>              messages: struct messaging_rec
>                  msg_version              : 0x00000002 (2)
>                  msg_type                 : MSG_SHUTDOWN (13)
>                  dest: struct server_id
>                      id                       : 0x00007fe9 (32745)
>                      vnn                      : 0xffffffff (4294967295)
>                  src: struct server_id
>                      id                       : 0x00007fe9 (32745)
>                      vnn                      : 0xffffffff (4294967295)
>                  buf                      : DATA_BLOB length=0
>[2009/10/26 17:01:26,  3] smbd/server.c:146(msg_exit_server)
>  got a SHUTDOWN message
>[2009/10/26 17:01:26,  3] smbd/sec_ctx.c:310(set_sec_ctx)
>  setting sec ctx (0, 0) - sec_ctx_stack_ndx = 0
>[2009/10/26 17:01:26,  5] auth/token_util.c:522(debug_nt_user_token)
>  NT user token: (NULL)
>[2009/10/26 17:01:26,  5] auth/token_util.c:548(debug_unix_user_token)
>  UNIX token of user 0
>  Primary group is 0 and contains 0 supplementary groups
>[2009/10/26 17:01:26,  5] smbd/uid.c:368(change_to_root_user)
>  change_to_root_user: now uid=(0,0) gid=(0,0)
>[2009/10/26 17:01:26,  3] smbd/connection.c:31(yield_connection)
>  Yielding connection to 
>[2009/10/26 17:01:26, 10] lib/dbwrap_tdb.c:100(db_tdb_fetch_locked)
>  Locking key E97F0000FFFFFFFFFFFF
>[2009/10/26 17:01:26, 10] lib/dbwrap_tdb.c:129(db_tdb_fetch_locked)
>  Allocated locked data 0x0x2ba68eb914b0
>[2009/10/26 17:01:26,  3] smbd/connection.c:42(yield_connection)
>  deleting connection record returned NT_STATUS_NOT_FOUND
>[2009/10/26 17:01:26, 10] lib/dbwrap_tdb.c:42(db_tdb_record_destr)
>  Unlocking key E97F0000FFFFFFFFFFFF
>[2009/10/26 17:01:26,  3] smbd/server.c:845(exit_server_common)
>  Server exit (normal exit)
>[2009/10/26 17:01:26,  5] lib/util_sock.c:371(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_SNDBUF = 16384
>  	SO_RCVBUF = 87380
>  	SO_SNDLOWAT = 1
>  	SO_RCVLOWAT = 1
>  	SO_SNDTIMEO = 0
>  	SO_RCVTIMEO = 0
>[2009/10/26 17:01:26,  5] lib/util_sock.c:371(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_SNDBUF = 16384
>  	SO_RCVBUF = 87380
>  	SO_SNDLOWAT = 1
>  	SO_RCVLOWAT = 1
>  	SO_SNDTIMEO = 0
>  	SO_RCVTIMEO = 0
>[2009/10/26 17:01:26,  6] param/loadparm.c:6989(lp_file_list_changed)
>  lp_file_list_changed()
>  file /etc/samba/shares.conf -> /etc/samba/shares.conf  last mod_time: Fri Sep 18 11:30:33 2009
>  
>  file /etc/samba/idmap.conf -> /etc/samba/idmap.conf  last mod_time: Fri Sep 18 11:32:50 2009
>  
>  file /etc/samba/smb.conf -> /etc/samba/smb.conf  last mod_time: Mon Oct 26 13:47:32 2009
>  
>[2009/10/26 17:01:26,  3] smbd/oplock.c:911(init_oplocks)
>  init_oplocks: initializing messages.
>[2009/10/26 17:01:26,  3] smbd/oplock_linux.c:223(linux_init_kernel_oplocks)
>  Linux kernel oplocks enabled
>[2009/10/26 17:01:26, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Added timed event "smbd_idle_event_handler": 0x2ba68eb80460
>[2009/10/26 17:01:26, 10] smbd/process.c:699(event_add_idle)
>  event_add_idle: idle_evt(keepalive) 0x2ba68eb80460
>[2009/10/26 17:01:26, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Added timed event "smbd_idle_event_handler": 0x2ba68eb2c240
>[2009/10/26 17:01:26, 10] smbd/process.c:699(event_add_idle)
>  event_add_idle: idle_evt(deadtime) 0x2ba68eb2c240
>[2009/10/26 17:01:26, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Added timed event "smbd_idle_event_handler": 0x2ba68eba6db0
>[2009/10/26 17:01:26, 10] smbd/process.c:699(event_add_idle)
>  event_add_idle: idle_evt(housekeeping) 0x2ba68eba6db0
>[2009/10/26 17:01:26, 10] lib/events.c:105(run_events)
>  Running timed event "smbd_idle_event_handler" 0x2ba68eb2c240
>[2009/10/26 17:01:26, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Destroying timer event 0x2ba68eb2c240 "smbd_idle_event_handler"
>[2009/10/26 17:01:26, 10] smbd/process.c:642(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) called
>[2009/10/26 17:01:26, 10] smbd/process.c:653(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(deadtime) (nil) rescheduled
>[2009/10/26 17:01:26, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Added timed event "smbd_idle_event_handler": 0x2ba68eb23810
>[2009/10/26 17:01:26, 10] lib/events.c:105(run_events)
>  Running timed event "smbd_idle_event_handler" 0x2ba68eba6db0
>[2009/10/26 17:01:26, 10] lib/events.c:287(s3_event_debug)
>  s3_event: Destroying timer event 0x2ba68eba6db0 "smbd_idle_event_handler"
>[2009/10/26 17:01:26, 10] smbd/process.c:642(smbd_idle_event_handler)
>  smbd_idle_event_handler: idle_evt(housekeeping) (nil) called
>[2009/10/26 17:01:26,  3] smbd/sec_ctx.c:310(set_sec_ctx)

Actions: View

Attachments on bug 6828: 4866 | 4872 | 4873 | 4889 | 4890 | 4891 | 4899 | 4900