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