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