PANIC: vfs_fruit for time machine backup causes PANIC

Anoop C S anoopcs at cryptolab.net
Tue Apr 14 08:03:00 UTC 2020


On Mon, 2020-04-13 at 14:04 +0200, Rouven WEILER via samba-technical
wrote:
> Okay here we go with „log level = 10“. 
> log level 10 output is included in the attached file.

We would be interested in log entries from the log file specific to the
client which reports smbd panic.

> As a proof, there the globals section:
> #======================= Global Settings
> =====================================
> [global]
> 
>     workgroup = mano-de.dios
>     netbios name = wutz
>     mdns name = mdns
>     multicast dns register = yes
>     server string = Filet Server (%v)
>     server role = standalone server
>     log file = /var/log/samba/smbd.log
>     max log size = 50
>     wins support = yes
>     dns proxy = no
>     guest account = nobody
>     fruit:aapl = yes
>     fruit:model = RackMac
>     log level = 10
> 
> #============================ Share Definitions
> ==============================
> 
> dmesg error:
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]   PANIC:
> assert failed at ../../lib/dbwrap/dbwrap.c(82): rec->value_valid
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error] [2020/04/13
> 13:55:57.164502,  0, pid=1517, effective(500, 500), real(0, 0)]
> ../../source3/lib/util.c:830(smb_panic_s3)
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]   PANIC
> (pid 1517): assert failed: rec->value_valid
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error] [2020/04/13
> 13:55:57.165311,  0, pid=1517, effective(500, 500), real(0, 0)]
> ../../lib/util/fault.c:265(log_stack_trace)
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911
> daemon.error]   BACKTRACE: 37 stack frames:
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #0
> /usr/lib/samba/amd64/libsamba-util.so.0.0.1'log_stack_trace+0x26
> [0xfffffd7fee51de66]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #1
> /usr/lib/samba/amd64/libsmbconf.so.0'smb_panic_s3+0x26
> [0xfffffd7fedf5a596]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #2
> /usr/lib/samba/amd64/libsamba-util.so.0.0.1'smb_panic+0x1f
> [0xfffffd7fee51df3f]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #3
> /usr/lib/samba/private/amd64/libdbwrap-
> samba4.so'dbwrap_record_get_value+0x2a [0xfffffd7fecf4627a]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #4
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'get_share_mode_lock+0x109 [0xfffffd7fee7195c9]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #5
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'smbd_contend_level2_oplocks_begin+0xa1 [0xfffffd7fee7f7761]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #6
> /usr/lib/samba/private/amd64/libsmbd-base-samba4.so'brl_lock+0x635
> [0xfffffd7fee710f45]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #7
> /usr/lib/samba/private/amd64/libsmbd-base-samba4.so'do_lock_fn+0xa4
> [0xfffffd7fee70d534]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #8
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'share_mode_do_locked_fn+0x86 [0xfffffd7fee7174b6]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #9
> /usr/lib/samba/amd64/libsmbconf.so.0'dbwrap_watched_do_locked_fn+0xfa
> [0xfffffd7fedf622ca]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #10
> /usr/lib/samba/private/amd64/libdbwrap-
> samba4.so'db_tdb_do_locked+0x12f [0xfffffd7fecf495cf]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #11
> /usr/lib/samba/private/amd64/libdbwrap-
> samba4.so'dbwrap_do_locked+0x48 [0xfffffd7fecf469a8]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #12
> /usr/lib/samba/amd64/libsmbconf.so.0'dbwrap_watched_do_locked+0x6f
> [0xfffffd7fedf60d7f]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #13
> /usr/lib/samba/private/amd64/libdbwrap-
> samba4.so'dbwrap_do_locked+0x48 [0xfffffd7fecf469a8]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #14
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'share_mode_do_locked+0xd2 [0xfffffd7fee719b82]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #15
> /usr/lib/samba/private/amd64/libsmbd-base-samba4.so'do_lock+0xf0
> [0xfffffd7fee70dfe0]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #16
> /usr/lib/samba/amd64/vfs/fruit.so'fruit_create_file+0x7ba
> [0xfffffd7fe88c55aa]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #17
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'smbd_smb2_request_process_create+0xa07 [0xfffffd7fee7d3237]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #18
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'smbd_smb2_request_dispatch+0xc8f [0xfffffd7fee7c985f]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #19
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'smbd_smb2_connection_handler+0x621 [0xfffffd7fee7ca7e1]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #20
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_invoke
> _fd_handler+0x80 [0xfffffd7fecfca580]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #21
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'epoll_event_loop_onc
> e+0x22c [0xfffffd7fecfd180c]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #22
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_once+
> 0x40 [0xfffffd7fecfcf8f0]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #23
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'_tevent_loop_once+0x
> 95 [0xfffffd7fecfc9bd5]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #24
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_loop_w
> ait+0x23 [0xfffffd7fecfc9e43]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #25
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_wait+
> 0x40 [0xfffffd7fecfcf870]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #26
> /usr/lib/samba/private/amd64/libsmbd-base-
> samba4.so'smbd_process+0x777 [0xfffffd7fee7b8677]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #27
> /usr/lib/samba/sbin/amd64/smbd'smbd_accept_connection+0x189
> [0x40d5b9]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #28
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_invoke
> _fd_handler+0x80 [0xfffffd7fecfca580]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #29
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'epoll_event_loop_onc
> e+0x22c [0xfffffd7fecfd180c]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #30
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_once+
> 0x40 [0xfffffd7fecfcf8f0]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #31
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'_tevent_loop_once+0x
> 95 [0xfffffd7fecfc9bd5]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #32
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_loop_w
> ait+0x23 [0xfffffd7fecfc9e43]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #33
> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_wait+
> 0x40 [0xfffffd7fecfcf870]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #34
> /usr/lib/samba/sbin/amd64/smbd'main+0x1a0f [0x40f9ff]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #35
> /usr/lib/samba/sbin/amd64/smbd'_start_crt+0x83 [0x408e73]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]    #36
> /usr/lib/samba/sbin/amd64/smbd'_start+0x18 [0x408dd8]
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error] [2020/04/13
> 13:55:57.167481,  0, pid=1517, effective(0, 0), real(0, 0)]
> ../../source3/lib/dumpcore.c:315(dump_core)
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error]   dumping
> core in /var/samba/log/cores/smbd
> Apr 13 13:55:57 wutz smbd[1517]: [ID 702911 daemon.error] 
> Apr 13 13:55:57 wutz smbd[1530]: [ID 702911 daemon.error] [2020/04/13
> 13:55:57.650723,  0, pid=1530, effective(0, 0), real(0, 0)]
> ../../lib/util/become_daemon.c:136(daemon_ready)
> 
> 
> 
> > Am 13.04.2020 um 13:31 schrieb Anoop C S via samba-technical <
> > samba-technical at lists.samba.org>:
> > 
> > On Sun, 2020-04-12 at 11:24 +0200, Rouven WEILER via samba-
> > technical
> > wrote:
> > > In a previous email I mentioned that I got issue with samba
> > > 4.12.1 on
> > > Illumos/OpenIndiana and MacOS 10.15.4.
> > > I digged a little bit more and found out that the PANIC is caused
> > > when trying to do a time machine backup.
> > > 
> > > Plain samba works a as always. There seems to be only issues with
> > > time machine backup.
> > > 
> > > Everything worked before samba-4.12.0 and MacOS 10.15.4. I cannot
> > > tell which change cause the issues...
> > > 
> > > Staring from a new time capsule share I got the following:
> > > Creating a new time machine backup shows, I get a
> > > „.com.apple.timemachine.supported“ file added in to the share.
> > > 
> > > MacOS tells in it’s logs: „Failed to read capabilities for
> > > 'file:///Volumes/FileVault/', error: Socket is not connected“.
> > > 
> > > The smbd.log with loglevel vfs:10 shows:
> > 
> > Is it possible for you to reproduce with log level 10 set in
> > general
> > rather than just vfs? It might gives us more context around the
> > assertion failure leading to smbd panic.
> > 
> > > [2020/04/12 11:15:21.534742,  5, pid=1707, effective(500, 500),
> > > real(0, 0), class=vfs]
> > > ../../source3/smbd/vfs.c:1395(check_reduced_name)
> > >  check_reduced_name: .com.apple.timemachine.supported reduced to
> > > /export/home/timecapsule/.com.apple.timemachine.supported
> > > [2020/04/12 11:15:21.534789, 10, pid=1707, effective(500, 500),
> > > real(0, 0), class=vfs]
> > > ../../source3/modules/vfs_streams_xattr.c:379(streams_xattr_open)
> > >  streams_xattr_open called for .com.apple.timemachine.supported
> > > with
> > > flags 0x20080
> > > [2020/04/12 11:15:21.535160,  0]
> > > ../../lib/dbwrap/dbwrap.c:82(dbwrap_record_get_value)
> > >  PANIC: assert failed at ../../lib/dbwrap/dbwrap.c(82): rec-
> > > > value_valid
> > 
> > Similar assertion failures were fixed sometime back and I could see
> > those present in v4-12-stable branch. May be we missed some other
> > place..
> > 
> > > [2020/04/12 11:15:21.535322,  0]
> > > ../../source3/lib/util.c:830(smb_panic_s3)
> > >  PANIC (pid 1707): assert failed: rec->value_valid
> > > [2020/04/12 11:15:21.536138,  0]
> > > ../../lib/util/fault.c:265(log_stack_trace)
> > >  BACKTRACE: 37 stack frames:
> > >   #0 /usr/lib/samba/amd64/libsamba-
> > > util.so.0.0.1'log_stack_trace+0x26 [0xfffffd7fee51de66]
> > >   #1 /usr/lib/samba/amd64/libsmbconf.so.0'smb_panic_s3+0x26
> > > [0xfffffd7fedf5a596]
> > >   #2 /usr/lib/samba/amd64/libsamba-util.so.0.0.1'smb_panic+0x1f
> > > [0xfffffd7fee51df3f]
> > >   #3 /usr/lib/samba/private/amd64/libdbwrap-
> > > samba4.so'dbwrap_record_get_value+0x2a [0xfffffd7fece2627a]
> > >   #4 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'get_share_mode_lock+0x109 [0xfffffd7fee7195c9]
> > >   #5 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'smbd_contend_level2_oplocks_begin+0xa1
> > > [0xfffffd7fee7f7761]
> > >   #6 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'brl_lock+0x635 [0xfffffd7fee710f45]
> > >   #7 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'do_lock_fn+0xa4 [0xfffffd7fee70d534]
> > >   #8 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'share_mode_do_locked_fn+0x86 [0xfffffd7fee7174b6]
> > >   #9
> > > /usr/lib/samba/amd64/libsmbconf.so.0'dbwrap_watched_do_locked_fn+
> > > 0xfa
> > > [0xfffffd7fedf622ca]
> > >   #10 /usr/lib/samba/private/amd64/libdbwrap-
> > > samba4.so'db_tdb_do_locked+0x12f [0xfffffd7fece295cf]
> > >   #11 /usr/lib/samba/private/amd64/libdbwrap-
> > > samba4.so'dbwrap_do_locked+0x48 [0xfffffd7fece269a8]
> > >   #12
> > > /usr/lib/samba/amd64/libsmbconf.so.0'dbwrap_watched_do_locked+0x6
> > > f
> > > [0xfffffd7fedf60d7f]
> > >   #13 /usr/lib/samba/private/amd64/libdbwrap-
> > > samba4.so'dbwrap_do_locked+0x48 [0xfffffd7fece269a8]
> > >   #14 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'share_mode_do_locked+0xd2 [0xfffffd7fee719b82]
> > >   #15 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'do_lock+0xf0 [0xfffffd7fee70dfe0]
> > >   #16 /usr/lib/samba/amd64/vfs/fruit.so'fruit_create_file+0x7ba
> > > [0xfffffd7fe88a55aa]
> > >   #17 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'smbd_smb2_request_process_create+0xa07
> > > [0xfffffd7fee7d3237]
> > >   #18 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'smbd_smb2_request_dispatch+0xc8f [0xfffffd7fee7c985f]
> > >   #19 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'smbd_smb2_connection_handler+0x621 [0xfffffd7fee7ca7e1]
> > >   #20
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_in
> > > voke
> > > _fd_handler+0x80 [0xfffffd7fecfca580]
> > >   #21
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'epoll_event_loop
> > > _onc
> > > e+0x22c [0xfffffd7fecfd180c]
> > >   #22
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_o
> > > nce+
> > > 0x40 [0xfffffd7fecfcf8f0]
> > >   #23
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'_tevent_loop_onc
> > > e+0x
> > > 95 [0xfffffd7fecfc9bd5]
> > >   #24
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_lo
> > > op_w
> > > ait+0x23 [0xfffffd7fecfc9e43]
> > >   #25
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_w
> > > ait+
> > > 0x40 [0xfffffd7fecfcf870]
> > >   #26 /usr/lib/samba/private/amd64/libsmbd-base-
> > > samba4.so'smbd_process+0x777 [0xfffffd7fee7b8677]
> > >   #27 /usr/lib/samba/sbin/amd64/smbd'smbd_accept_connection+0x189
> > > [0x40d5b9]
> > >   #28
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_in
> > > voke
> > > _fd_handler+0x80 [0xfffffd7fecfca580]
> > >   #29
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'epoll_event_loop
> > > _onc
> > > e+0x22c [0xfffffd7fecfd180c]
> > >   #30
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_o
> > > nce+
> > > 0x40 [0xfffffd7fecfcf8f0]
> > >   #31
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'_tevent_loop_onc
> > > e+0x
> > > 95 [0xfffffd7fecfc9bd5]
> > >   #32
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_lo
> > > op_w
> > > ait+0x23 [0xfffffd7fecfc9e43]
> > >   #33
> > > /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_w
> > > ait+
> > > 0x40 [0xfffffd7fecfcf870]
> > >   #34 /usr/lib/samba/sbin/amd64/smbd'main+0x1a0f [0x40f9ff]
> > >   #35 /usr/lib/samba/sbin/amd64/smbd'_start_crt+0x83 [0x408e73]
> > >   #36 /usr/lib/samba/sbin/amd64/smbd'_start+0x18 [0x408dd8]
> > > [2020/04/12 11:15:21.538230,  0]
> > > ../../source3/lib/dumpcore.c:315(dump_core)
> > >  dumping core in /var/samba/log/cores/smbd
> > > [2020/04/12 11:15:21.949051,  0]
> > > ../../lib/util/become_daemon.c:136(daemon_ready)
> > >  daemon_ready: daemon 'smbd' finished starting up and ready to
> > > serve
> > > connections
> > > 
> > > 
> > > My smb.conf is:
> > > #======================= Global Settings
> > > =====================================
> > > [global]
> > > 
> > >    workgroup = mano-de.dios
> > >    netbios name = wutz
> > >    mdns name = mdns
> > >    multicast dns register = yes
> > >    server string = Filet Server (%v)
> > >    server role = standalone server
> > >    log file = /var/log/samba/smbd.log
> > >    max log size = 50
> > >    wins support = yes
> > >    dns proxy = no
> > >    guest account = nobody
> > >    fruit:aapl = yes
> > >    fruit:model = RackMac
> > >    log level = vfs:10
> > > 
> > > #============================ Share Definitions
> > > ==============================
> > > [homes]
> > >    comment = Home Share 
> > >    browseable = no
> > >    writable = yes
> > >    guest ok = no
> > >    vfs objects = catia fruit zfsacl streams_xattr 
> > >    fruit:resource = xattr
> > >    fruit:metadata = netatalk
> > >    fruit:locking = netatalk
> > >    fruit:encoding = native
> > > 
> > > [FileVault]
> > >    comment = Apple Time Capsule
> > >    path = /export/home/timecapsule
> > >    browseable = no
> > >    writeable = no
> > >    valid users = @admin
> > >    write list = @admin
> > >    guest ok = no
> > >    vfs objects = catia fruit zfsacl streams_xattr 
> > >    fruit:resource = xattr
> > >    fruit:metadata = netatalk
> > >    fruit:locking = netatalk
> > >    fruit:encoding = native
> > >    fruit:time machine = yes




More information about the samba-technical mailing list