PANIC: vfs_fruit for time machine backup causes PANIC

Rouven WEILER Rouven_Weiler at gmx.net
Mon Apr 13 12:04:49 UTC 2020 

Okay here we go with „log level = 10“. 
log level 10 output is included in the attached file.
-------------- next part --------------
A non-text attachment was scrubbed...
Name: smbd.log
Type: application/octet-stream
Size: 57993 bytes
Desc: not available
URL: <http://lists.samba.org/pipermail/samba-technical/attachments/20200413/de983fa8/smbd.obj>
-------------- next part --------------


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_once+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+0x95 [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_wait+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_once+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+0x95 [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_wait+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+0x6f
>> [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_invoke
>> _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_once+
>> 0x40 [0xfffffd7fecfcf8f0]
>>   #23
>> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'_tevent_loop_once+0x
>> 95 [0xfffffd7fecfc9bd5]
>>   #24
>> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_loop_w
>> ait+0x23 [0xfffffd7fecfc9e43]
>>   #25
>> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_wait+
>> 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_invoke
>> _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_once+
>> 0x40 [0xfffffd7fecfcf8f0]
>>   #31
>> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'_tevent_loop_once+0x
>> 95 [0xfffffd7fecfc9bd5]
>>   #32
>> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'tevent_common_loop_w
>> ait+0x23 [0xfffffd7fecfc9e43]
>>   #33
>> /usr/lib/samba/private/amd64/libtevent.so.0.10.2'std_event_loop_wait+
>> 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