Problems samba4 fuse fs.

[Stef Bon]

Hi,

I've build a fuse fs using samba4 calls (software found in source4).

The source of the smb_lookup and smb_getattr calls is attached.

These functions are called by the callbacks of the fuse fs, the lookup
and getattr functions resp, after inode, entry and path are
determined.

Below you see the log:


Mar 29 16:18:34 localhost samba4fs[5636]: create_root: created
rootentry . with ino 1
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: initialize
samba4 async fuse fs
Mar 29 16:18:34 localhost samba4fs[5637]: eventloop_samba4: start
eventloop in a special thread
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: global memory
context created
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: fuse smb cli created
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: initialize gensec
Mar 29 16:18:34 localhost samba4fs[5637]: eventloop_samba4: samba
eventloop created
Mar 29 16:18:34 localhost samba4fs[5637]: tevent_common_loop_wait()
out of events
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: initialize loadparm
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: load options
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: load session options
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: load resolve context
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: load socket options
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: load gensec options
Mar 29 16:18:34 localhost samba4fs[5637]: samba4_init: load smb ports
Mar 29 16:18:34 localhost samba4fs[5637]: Samba4 initialized.
Mar 29 16:18:34 localhost samba4fs[5637]: connect_smb_share: server
192.168.1.2 share public
Mar 29 16:18:34 localhost samba4fs[5637]: connect_smb_share: test credentials
Mar 29 16:18:34 localhost samba4fs[5637]: connect_smb_share: set conf
Mar 29 16:18:34 localhost samba4fs[5637]: connect_smb_share: set kerberos
Mar 29 16:18:34 localhost samba4fs[5637]: connect_smb_share: get authmethod
Mar 29 16:18:34 localhost samba4fs[5637]: get_authmethod: got guest
Mar 29 16:18:34 localhost samba4fs[5637]: create_connection_smb_share:
call smbcli_full_connection to server 192.168.1.2, share public
Mar 29 16:18:34 localhost samba4fs[5637]: Added timed event
"¨Hÿ¶Rh7¿`7¿Fþ¶À·": 0xbf9c373c
Mar 29 16:18:34 localhost samba4fs[5637]: Added timed event "¨Hÿ¶øR¨7¿
7¿Fþ¶À·": 0xbf9c377c
Mar 29 16:18:34 localhost samba4fs[5637]: Running timer event
0xbf9c383c "R¨Hÿ¶Ä8g·x8¿¦áf·(µR_ºg·ÐÁ"
Mar 29 16:18:34 localhost samba4fs[5637]: Destroying timer event
0xbf9c365c "øR¨Hÿ¶"
Mar 29 16:18:34 localhost samba4fs[5637]: Added timed event
"æPÿ¶8TRPv·¤f·3©µ": 0xbf9c368c
Mar 29 16:18:34 localhost samba4fs[5637]: Ending timer event
0xbf9c383c "R¨Hÿ¶Ä8g·x8¿¦áf·(µR_ºg·"
Mar 29 16:18:34 localhost samba4fs[5637]: Schedule immediate event
"jçg·ÈR": 0xbf9c35ec
Mar 29 16:18:34 localhost samba4fs[5637]: Run immediate event
"jçg·ÈRh·õÁÐÁÈR³v·@îg·Umg·": 0xbf9c384c
Mar 29 16:18:34 localhost samba4fs[5637]: Destroying timer event
0xbf9c366c "8TæPÿ¶RPv·8TõÁx.g·Ø6¿EÕf·8TÈRØQÿ¶åã!·Üó÷¶ó÷¶Hü¶À©T8g·¹g·ë!·`Tø¶
45·ÐÁ▒7¿x.g7¿äf·8T0Rÿ¶▒7¿Ìü÷¶"
Mar 29 16:18:34 localhost samba4fs[5637]: Added timed event "
èg·hX": 0xbf9c32dc
Mar 29 16:18:34 localhost samba4fs[5637]: Schedule immediate event
"Üåg·Mfæg·Y¤Êf·Ôæ": 0xbf9c324c
Mar 29 16:18:34 localhost samba4fs[5637]: Run immediate event
"Üåg·Mh·õÁÐÁM«yg·XR@îg·": 0xbf9c384c
Mar 29 16:18:34 localhost samba4fs[5637]: Destroying timer event
0xbf9c32ec "hX¼èg·"
Mar 29 16:18:34 localhost samba4fs[5637]: Added timed event "   èg·": 0xbf9c2e9c
Mar 29 16:18:34 localhost samba4fs[5637]: Schedule immediate event
"Üåg·Mfæg·0d¤Êf· 45·": 0xbf9c2ebc
Mar 29 16:18:34 localhost samba4fs[5637]: Run immediate event "Üåg·M":
0xbf9c384c
Mar 29 16:18:34 localhost samba4fs[5637]: Destroying timer event 0xbf9c33cc ""
Mar 29 16:18:34 localhost samba4fs[5637]: Added timed event "
èg·▒ix.g·hgg"·x.g·ÈW": 0xbf9c313c
Mar 29 16:18:34 localhost samba4fs[5637]: Schedule immediate event
"Üåg·Mfæg·xi¤Êf· 45·": 0xbf9c315c
Mar 29 16:18:34 localhost samba4fs[5637]: Run immediate event "Üåg·M":
0xbf9c384c
Mar 29 16:18:34 localhost samba4fs[5637]: Destroying timer event
0xbf9c33cc "▒i¼èg·¨ñU·▒ièh°^x.g·84¿EÕf·▒i¨ñU·°^^°^x.g·84¿¡Úf·èh¼ÿ¶¹g·üî!·"
Mar 29 16:18:34 localhost samba4fs[5637]: Added timed event "
èg·0`x.g·0^^"·x.g·à_": 0xbf9c305c
Mar 29 16:18:34 localhost samba4fs[5637]: Schedule immediate event
"Üåg·Mfæg·`a¤Êf· 45·": 0xbf9c307c
Mar 29 16:18:34 localhost samba4fs[5637]: Run immediate event "Üåg·M":
0xbf9c384c
Mar 29 16:18:34 localhost samba4fs[5637]: Destroying timer event
0xbf9c33cc "0`¼èg·oU·0`"
Mar 29 16:18:35 localhost samba4fs[5637]: initialize_fuse: mountpoint
/home/sbon/Mount, name overlayfs
Mar 29 16:18:35 localhost samba4fs[5637]: initialize_fuse: added fuse
channel to mountpoint /home/sbon/Mount
Mar 29 16:18:35 localhost samba4fs[5637]: initialize_fuse: added fuse
session to mountpoint /home/sbon/Mount
Mar 29 16:18:35 localhost samba4fs[5637]: INIT
Mar 29 16:18:49 localhost samba4fs[5637]: GETATTR
Mar 29 16:18:49 localhost samba4fs[5637]: smb_getattr
Mar 29 16:18:49 localhost samba4fs[5637]: convert_path_smb: converted / to \
Mar 29 16:18:49 localhost samba4fs[5637]: Added timed event "
èg·Ðµx.g·Ðµµ"·x.g·µ": 0xb5a69cdc
Mar 29 16:18:49 localhost samba4fs[5637]: Schedule immediate event
"Üåg·Mfæg·8µ¤Êf·": 0xb5a69c4c
Mar 29 16:23:31 localhost samba4fs[5637]: DESTROY

As you can see (I've added a lot of log messages) the getattr call at
16:18:49 causes two events: a timed event and an immediate event.
Futher the callback smb_asyncgetattr_cb is never called. Can you see
what happens?

Stef