debugfs message during creation of the NVMeOf controller

Sun Mar 26 16:48:35 PDT 2023

On 3/26/23 16:44, Chaitanya Kulkarni wrote:
> On 3/24/23 15:34, alan.adamson at oracle.com wrote:
>>
>> On 3/23/23 1:18 AM, Chaitanya Kulkarni wrote:
>>> Hi everyone,
>>>
>>> Whenever I create NVMeOF target controller using nvme-loop
>>> transport I'm getting following message:-
>>>
>>> [18318.052193] debugfs: Directory 'nvme1' with parent '/' already 
>>> present!
>>> [18318.053164] nvme1: failed to create debugfs attr
>>> [18318.053239] nvmet: creating nvm controller 1 for subsystem testnqn
>>> for NQN
>>> nqn.2014-08.org.nvmexpress:uuid:bb0a23ee-0be3-4285-8d8a-b47fcad11fe1.
>>> [18318.053369] nvme nvme1: creating 48 I/O queues.
>>> [18318.058812] nvme nvme1: new ctrl: "testnqn"
>>>
>>> It is certainly coming from
>>> host/fault_inject.c:nvme_fault_inject_init().
>>>
>>> any idea about this ?
>>>
>> I may have seen this before, but I can't seem to reproduce it. Can you
>>
>> provide the script the creates the targets?
>>
>> Alan
>>
>
> with latest pull on the nvme-6.4 the message is gone.
> It was coming from blktests.
>
> -ck
>
>
sorry I take that back, the message is still coming but now only for
testcase 045 in nvme category in blktest, and its 100% repro:-

blktests (master) # ./check nvme/045
nvme/045 (Test re-authentication) [passed]
     runtime  3.979s  ...  3.906s
blktests (master) # dmesg  -c
[  735.980552] run blktests nvme/045 at 2023-03-26 16:46:36
[  736.007927] nvmet: adding nsid 1 to subsystem blktests-subsystem-1
*[  736.021838] debugfs: Directory 'nvme1' with parent '/' already present!*
[  736.023130] nvme1: failed to create debugfs attr
[  736.025394] nvmet: creating nvm controller 1 for subsystem 
blktests-subsystem-1 for NQN 
nqn.2014-08.org.nvmexpress:uuid:aa415685-2775-415b-820f-192873a29f17 
with DH-HMAC-CHAP.
[  736.034222] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  736.034227] nvme nvme1: qid 0: controller authenticated
[  736.034275] nvme nvme1: qid 0: authenticated
[  736.034459] nvme nvme1: creating 48 I/O queues.
[  736.439534] nvme nvme1: new ctrl: "blktests-subsystem-1"
[  737.478680] nvme nvme1: re-authenticating controller
[  737.487865] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  737.487872] nvme nvme1: qid 0: controller authenticated
[  737.512037] nvme nvme1: re-authenticating controller
[  737.517684] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  737.517689] nvme nvme1: qid 0: controller authenticated
[  737.538995] nvme nvme1: re-authenticating controller
[  737.544676] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  737.544680] nvme nvme1: qid 0: controller authenticated
[  737.575276] nvme nvme1: re-authenticating controller
[  737.742283] nvme nvme1: qid 0: authenticated with hash hmac(sha512) 
dhgroup ffdhe8192
[  737.742290] nvme nvme1: qid 0: controller authenticated
[  738.058253] nvme nvme1: re-authenticating controller
[  738.198552] nvme nvme1: qid 0: authenticated with hash hmac(sha512) 
dhgroup ffdhe8192
[  738.198558] nvme nvme1: qid 0: controller authenticated
[  739.541920] nvme nvme1: Removing ctrl: NQN "blktests-subsystem-1"
blktests (master) # ./check nvme/045
nvme/045 (Test re-authentication) [passed]
     runtime  3.906s  ...  3.891s
blktests (master) # dmesg  -c
[  748.692757] run blktests nvme/045 at 2023-03-26 16:46:49
[  748.720701] nvmet: adding nsid 1 to subsystem blktests-subsystem-1
*[  748.734004] debugfs: Directory 'nvme1' with parent '/' already present!*
[  748.735247] nvme1: failed to create debugfs attr
[  748.737923] nvmet: creating nvm controller 1 for subsystem 
blktests-subsystem-1 for NQN 
nqn.2014-08.org.nvmexpress:uuid:f58ec6ca-076c-49a7-8644-344dc03164f6 
with DH-HMAC-CHAP.
[  748.747012] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  748.747018] nvme nvme1: qid 0: controller authenticated
[  748.747076] nvme nvme1: qid 0: authenticated
[  748.747328] nvme nvme1: creating 48 I/O queues.
[  749.157989] nvme nvme1: new ctrl: "blktests-subsystem-1"
[  750.194726] nvme nvme1: re-authenticating controller
[  750.205283] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  750.205291] nvme nvme1: qid 0: controller authenticated
[  750.226349] nvme nvme1: re-authenticating controller
[  750.232060] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  750.232071] nvme nvme1: qid 0: controller authenticated
[  750.253677] nvme nvme1: re-authenticating controller
[  750.259398] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  750.259404] nvme nvme1: qid 0: controller authenticated
[  750.280421] nvme nvme1: re-authenticating controller
[  750.451835] nvme nvme1: qid 0: authenticated with hash hmac(sha512) 
dhgroup ffdhe8192
[  750.451843] nvme nvme1: qid 0: controller authenticated
[  750.736873] nvme nvme1: re-authenticating controller
[  750.875202] nvme nvme1: qid 0: authenticated with hash hmac(sha512) 
dhgroup ffdhe8192
[  750.875208] nvme nvme1: qid 0: controller authenticated
[  752.233868] nvme nvme1: Removing ctrl: NQN "blktests-subsystem-1"
blktests (master) # ./check nvme/045
nvme/045 (Test re-authentication) [passed]
     runtime  3.891s  ...  3.927s
blktests (master) # dmesg  -c
[  756.082248] run blktests nvme/045 at 2023-03-26 16:46:56
[  756.107701] nvmet: adding nsid 1 to subsystem blktests-subsystem-1
*[  756.120529] debugfs: Directory 'nvme1' with parent '/' already present!*
[  756.121856] nvme1: failed to create debugfs attr
[  756.124149] nvmet: creating nvm controller 1 for subsystem 
blktests-subsystem-1 for NQN 
nqn.2014-08.org.nvmexpress:uuid:fbf72a00-890d-42a8-a2e9-fc1aa74b565d 
with DH-HMAC-CHAP.
[  756.132708] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  756.132715] nvme nvme1: qid 0: controller authenticated
[  756.132756] nvme nvme1: qid 0: authenticated
[  756.132976] nvme nvme1: creating 48 I/O queues.
[  756.541422] nvme nvme1: new ctrl: "blktests-subsystem-1"
[  757.578586] nvme nvme1: re-authenticating controller
[  757.588968] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  757.588975] nvme nvme1: qid 0: controller authenticated
[  757.613757] nvme nvme1: re-authenticating controller
[  757.619329] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  757.619332] nvme nvme1: qid 0: controller authenticated
[  757.641450] nvme nvme1: re-authenticating controller
[  757.647279] nvme nvme1: qid 0: authenticated with hash hmac(sha256) 
dhgroup ffdhe2048
[  757.647284] nvme nvme1: qid 0: controller authenticated
[  757.672671] nvme nvme1: re-authenticating controller
[  757.839209] nvme nvme1: qid 0: authenticated with hash hmac(sha512) 
dhgroup ffdhe8192
[  757.839217] nvme nvme1: qid 0: controller authenticated
[  758.138670] nvme nvme1: re-authenticating controller
[  758.274816] nvme nvme1: qid 0: authenticated with hash hmac(sha512) 
dhgroup ffdhe8192
[  758.274823] nvme nvme1: qid 0: controller authenticated
[  759.634987] nvme nvme1: Removing ctrl: NQN "blktests-subsystem-1"
blktests (master) #

-ck