Continous Samba crash (Lock order violation)
Posted: 28 Nov 2015 10:29
After installing a newer version of Nas4free (10.2.0.2.2003) I have some serious problems with my Samba shares. From some VM's (Debian and CentOS) the shares are not working correctly in the sense that I cannot delete files and moving seems an issue also.
In the log I can see that the SMBD process is crashing every 10 seconds with the crashlog as shown below.
When I downgrade to version 10.2.0.2.1868 the problems disappear. Does anyone have any suggestion to fix this problem?
Many thanks in advance,
Lex
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.529199, 0] ../lib/dbwrap/dbwrap.c:184(dbwrap_check_lock_order)
Nov 28 09:46:30 nas4free smbd[5702]: Lock order violation: Trying /var/db/samba4/locking.tdb at 1 while /var/db/samba4/brlock.tdb at 2 is locked
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.529235, 0] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
Nov 28 09:46:30 nas4free smbd[5702]: lock order: 1:<none> 2:/var/db/samba4/brlock.tdb 3:<none>
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.529249, 0] ../source3/lib/util.c:788(smb_panic_s3)
Nov 28 09:46:30 nas4free smbd[5702]: PANIC (pid 5702): invalid lock_order
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.530306, 0] ../source3/lib/util.c:899(log_stack_trace)
Nov 28 09:46:30 nas4free smbd[5702]: BACKTRACE: 26 stack frames:
Nov 28 09:46:30 nas4free smbd[5702]: #0 0x8035ba918 <log_stack_trace+0x28> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #1 0x8035ba802 <smb_panic_s3+0x52> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #2 0x80168eacd <smb_panic+0x13d> at /usr/local/lib/libsamba-util.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #3 0x8070108cf <dbwrap_fetch_locked+0x29f> at /usr/local/lib/samba/libdbwrap-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #4 0x801b8d229 <get_share_mode_lock+0x149> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #5 0x801b52c1f <remove_oplock+0x4f> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #6 0x801b048e9 <close_file+0xb89> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #7 0x813010f14 <samba_init_module+0x15f4> at /usr/local/lib/shared-modules/vfs/fruit.so
Nov 28 09:46:30 nas4free smbd[5702]: #8 0x801aebede <smbd_do_setfilepathinfo+0x2f0e> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #9 0x801aeecd5 <reply_trans2+0x1dc5> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #10 0x801aed47b <reply_trans2+0x56b> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #11 0x801b1be26 <smb_request_done+0xb16> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #12 0x801b1f2e9 <req_is_in_chain+0xc89> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #13 0x801b1e833 <req_is_in_chain+0x1d3> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #14 0x8035cfe3b <run_events_poll+0x28b> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #15 0x8035d0631 <event_add_idle+0x541> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #16 0x804d115b2 <_tevent_loop_once+0x72> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #17 0x804d117eb <tevent_common_loop_wait+0x3b> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #18 0x801b1df51 <smbd_process+0x8a1> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #19 0x102c08f <main+0x268f> at /usr/local/sbin/smbd
Nov 28 09:46:30 nas4free smbd[5702]: #20 0x8035cfe3b <run_events_poll+0x28b> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #21 0x8035d0631 <event_add_idle+0x541> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #22 0x804d115b2 <_tevent_loop_once+0x72> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #23 0x804d117eb <tevent_common_loop_wait+0x3b> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #24 0x102b3fa <main+0x19fa> at /usr/local/sbin/smbd
Nov 28 09:46:30 nas4free smbd[5702]: #25 0x1027381 <_start+0x1a1> at /usr/local/sbin/smbd
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.530529, 0] ../source3/lib/dumpcore.c:313(dump_core)
Nov 28 09:46:30 nas4free smbd[5702]: unable to change to %N.core
Nov 28 09:46:30 nas4free smbd[5702]: refusing to dump core
In the log I can see that the SMBD process is crashing every 10 seconds with the crashlog as shown below.
When I downgrade to version 10.2.0.2.1868 the problems disappear. Does anyone have any suggestion to fix this problem?
Many thanks in advance,
Lex
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.529199, 0] ../lib/dbwrap/dbwrap.c:184(dbwrap_check_lock_order)
Nov 28 09:46:30 nas4free smbd[5702]: Lock order violation: Trying /var/db/samba4/locking.tdb at 1 while /var/db/samba4/brlock.tdb at 2 is locked
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.529235, 0] ../lib/dbwrap/dbwrap.c:133(debug_lock_order)
Nov 28 09:46:30 nas4free smbd[5702]: lock order: 1:<none> 2:/var/db/samba4/brlock.tdb 3:<none>
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.529249, 0] ../source3/lib/util.c:788(smb_panic_s3)
Nov 28 09:46:30 nas4free smbd[5702]: PANIC (pid 5702): invalid lock_order
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.530306, 0] ../source3/lib/util.c:899(log_stack_trace)
Nov 28 09:46:30 nas4free smbd[5702]: BACKTRACE: 26 stack frames:
Nov 28 09:46:30 nas4free smbd[5702]: #0 0x8035ba918 <log_stack_trace+0x28> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #1 0x8035ba802 <smb_panic_s3+0x52> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #2 0x80168eacd <smb_panic+0x13d> at /usr/local/lib/libsamba-util.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #3 0x8070108cf <dbwrap_fetch_locked+0x29f> at /usr/local/lib/samba/libdbwrap-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #4 0x801b8d229 <get_share_mode_lock+0x149> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #5 0x801b52c1f <remove_oplock+0x4f> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #6 0x801b048e9 <close_file+0xb89> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #7 0x813010f14 <samba_init_module+0x15f4> at /usr/local/lib/shared-modules/vfs/fruit.so
Nov 28 09:46:30 nas4free smbd[5702]: #8 0x801aebede <smbd_do_setfilepathinfo+0x2f0e> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #9 0x801aeecd5 <reply_trans2+0x1dc5> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #10 0x801aed47b <reply_trans2+0x56b> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #11 0x801b1be26 <smb_request_done+0xb16> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #12 0x801b1f2e9 <req_is_in_chain+0xc89> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #13 0x801b1e833 <req_is_in_chain+0x1d3> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #14 0x8035cfe3b <run_events_poll+0x28b> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #15 0x8035d0631 <event_add_idle+0x541> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #16 0x804d115b2 <_tevent_loop_once+0x72> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #17 0x804d117eb <tevent_common_loop_wait+0x3b> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #18 0x801b1df51 <smbd_process+0x8a1> at /usr/local/lib/samba/libsmbd-base-samba4.so
Nov 28 09:46:30 nas4free smbd[5702]: #19 0x102c08f <main+0x268f> at /usr/local/sbin/smbd
Nov 28 09:46:30 nas4free smbd[5702]: #20 0x8035cfe3b <run_events_poll+0x28b> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #21 0x8035d0631 <event_add_idle+0x541> at /usr/local/lib/libsmbconf.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #22 0x804d115b2 <_tevent_loop_once+0x72> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #23 0x804d117eb <tevent_common_loop_wait+0x3b> at /usr/local/lib/libtevent.so.0
Nov 28 09:46:30 nas4free smbd[5702]: #24 0x102b3fa <main+0x19fa> at /usr/local/sbin/smbd
Nov 28 09:46:30 nas4free smbd[5702]: #25 0x1027381 <_start+0x1a1> at /usr/local/sbin/smbd
Nov 28 09:46:30 nas4free smbd[5702]: [2015/11/28 09:46:30.530529, 0] ../source3/lib/dumpcore.c:313(dump_core)
Nov 28 09:46:30 nas4free smbd[5702]: unable to change to %N.core
Nov 28 09:46:30 nas4free smbd[5702]: refusing to dump core