xtest regression 1033 failed

[p870613]

Hi, when I use xtest, I met some trouble.
The test regression 1033 failed.
The error log

* regression_1033 Test the supplicant plugin framework
D/TC:? 0 tee_ta_init_pseudo_ta_session:296 Lookup pseudo TA 380231ac-fb99-47ad-a689-9e017eb6e78a
D/TC:? 0 ldelf_load_ldelf:96 ldelf load address 0x80006000
D/LD:  ldelf:135 Loading TS 380231ac-fb99-47ad-a689-9e017eb6e78a
D/TC:? 0 ldelf_syscall_open_bin:143 Lookup user TA ELF 380231ac-fb99-47ad-a689-9e017eb6e78a (Secure Storage TA)
D/TC:? 0 secstor_ta_open:18 secstor_ta_open
D/TC:? 0 ldelf_syscall_open_bin:146 res=0xffff0008
D/TC:? 0 ldelf_syscall_open_bin:143 Lookup user TA ELF 380231ac-fb99-47ad-a689-9e017eb6e78a (REE)
D/TC:? 0 ldelf_syscall_open_bin:146 res=0
D/LD:  ldelf:170 ELF (380231ac-fb99-47ad-a689-9e017eb6e78a) at 0x80073000
D/TC:? 0 tee_ta_init_session_with_context:624 Re-open TA 3a2f8978-5dc0-11e8-9c2d-fa7ae01bbebc
D/TC:? 0 tee_ta_invoke_command:840 Error: ffff0008 of 4
E/TA:  tee_invoke_supp_plugin:105 Invoke tee-supplicant's plugin failed: 0xffff0008
D/TC:? 0 tee_ta_close_session:530 csess 0x430c5480 id 1
D/TC:? 0 tee_ta_close_session:548 Destroy session
D/TC:? 0 destroy_context:326 Destroy TA ctx (0x430c5420)
D/TC:? 0 tee_ta_close_session:530 csess 0x430c3e00 id 1
D/TC:? 0 tee_ta_close_session:548 Destroy session
E/TC:? 0 tee_ta_open_session:785 Failed. Return error 0xffff0008
/mfs/mtkslt1261/mtk30464/mt7896/openwrt/lede/build_dir/target-aarch64_cortex-a53_musl/optee-mediatek-3.9.0/optee_test/host/xtest/regression_1000.c:2450: xtest_teec_open_session(&session, &supp_plugin_test_ta_uuid, ((void*)0), &ret_orig) has an unexpected value: 0xffff0008 = TEEC_ERROR_ITEM_NOT_FOUND, expected 0x0 = TEEC_SUCCESS
  regression_1033 FAILED
+-----------------------------------------------------
Result of testsuite regression filtered by "1033":
regression_1033 FAILED first error at /mfs/xxxx/xxxx/mt7896/openwrt/lede/build_dir/target-aarch64_cortex-a53_musl/optee-mediatek-3.9.0/optee_test/host/xtest/regression_1000.c:2450
+-----------------------------------------------------
1 subtest of which 1 failed
1 test case of which 1 failed
103 test cases were skipped
TEE test application done!

Do you have any idea ??

[p870613]

more detail log

root@OpenWrt:/# xtest -x regression 1033
Test ID: -x regression
Test ID: 1033
Run test suite with level=0

TEE test application started over default TEE instance
######################################################
#
# regression
#
######################################################

* regression_1033 Test the supplicant plugin framework
D/TC:? 0 tee_ta_init_pseudo_ta_session:296 Lookup pseudo TA 380231ac-fb99-47ad-a689-9e017eb6e78a
D/TC:? 0 ldelf_load_ldelf:96 ldelf load address 0x80006000
D/LD:  ldelf:135 Loading TS 380231ac-fb99-47ad-a689-9e017eb6e78a
F/TC:? 0 trace_syscall:149 syscall #3 (syscall_get_property)
F/TC:? 0 trace_syscall:149 syscall #5 (syscall_open_ta_session)
D/TC:? 0 ldelf_syscall_open_bin:143 Lookup user TA ELF 380231ac-fb99-47ad-a689-9e017eb6e78a (Secure Storage TA)
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x8A
F/TC:? 0 plat_prng_add_jitter_entropy:72 0xCE
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x86
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x67
D/TC:? 0 ldelf_syscall_open_bin:146 res=0xffff0008
D/TC:? 0 ldelf_syscall_open_bin:143 Lookup user TA ELF 380231ac-fb99-47ad-a689-9e017eb6e78a (REE)
F/TC:? 0 plat_prng_add_jitter_entropy:72 0xBA
D/TC:? 0 ldelf_syscall_open_bin:146 res=0
F/TC:? 0 trace_syscall:149 syscall #7 (syscall_invoke_ta_command)
F/TC:? 0 trace_syscall:149 syscall #11 (syscall_mask_cancellation)
F/TC:? 0 trace_syscall:149 syscall #7 (syscall_invoke_ta_command)
F/TC:? 0 trace_syscall:149 syscall #3 (syscall_get_property)
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x2D
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x51
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x04
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x2F
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x49
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x0E
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x10
F/TC:? 0 trace_syscall:149 syscall #6 (syscall_close_ta_session)
F/TC:? 0 trace_syscall:149 syscall #3 (syscall_get_property)
D/LD:  ldelf:170 ELF (380231ac-fb99-47ad-a689-9e017eb6e78a) at 0x80071000
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #4 (syscall_get_property_name_to_index)
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #5 (syscall_open_ta_session)
D/TC:? 0 tee_ta_init_pseudo_ta_session:296 Lookup pseudo TA 3a2f8978-5dc0-11e8-9c2d-fa7ae01bbebc
D/TC:? 0 tee_ta_init_pseudo_ta_session:309 Open system.pta
D/TC:? 0 tee_ta_init_pseudo_ta_session:326 system.pta : 3a2f8978-5dc0-11e8-9c2d-fa7ae01bbebc
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #7 (syscall_invoke_ta_command)
D/TC:? 0 invoke_command:335 2
F/TC:? 0 trace_syscall:149 syscall #7 (syscall_invoke_ta_command)
D/TC:? 0 invoke_command:335 13
D/TC:? 0 tee_ta_invoke_command:840 Error: ffff0008 of 4
F/TC:? 0 trace_syscall:149 syscall #8 (syscall_check_access_rights)
F/TC:? 0 trace_syscall:149 syscall #7 (syscall_invoke_ta_command)
D/TC:? 0 invoke_command:335 3
E/TA:  tee_invoke_supp_plugin:105 Invoke tee-supplicant's plugin failed: 0xffff0008
D/TC:? 0 tee_ta_close_session:530 csess 0x430c8d90 id 1
D/TC:? 0 tee_ta_close_session:548 Destroy session
D/TC:? 0 destroy_context:326 Destroy TA ctx (0x430c8d30)
D/TC:? 0 tee_ta_close_session:530 csess 0x430c7680 id 1
D/TC:? 0 tee_ta_close_session:548 Destroy session
E/TC:? 0 tee_ta_open_session:785 Failed. Return error 0xffff0008
F/TC:? 0 plat_prng_add_jitter_entropy:72 0x87
/mfs/mtkslt1261/mtk30464/mt7896/openwrt/lede/build_dir/target-aarch64_cortex-a53_musl/optee-mediatek-3.9.0/optee_test/host/xtest/regression_1000.c:2450: xtest_teec_open_session(&session, &supp_plugin_test_ta_uuid, ((void*)0), &ret_orig) has an unexpected value: 0xffff0008 = TEEC_ERROR_ITEM_NOT_FOUND, expected 0x0 = TEEC_SUCCESS
  regression_1033 FAILED
+-----------------------------------------------------
Result of testsuite regression filtered by "-x regression":
Result of testsuite regression filtered by "1033":
regression_1033 FAILED first error at /mfs/mtkslt1261/mtk30464/mt7896/openwrt/lede/build_dir/target-aarch64_cortex-a53_musl/optee-mediatek-3.9.0/optee_test/host/xtest/regression_1000.c:2450
+-----------------------------------------------------
1 subtest of which 1 failed
1 test case of which 1 failed
103 test cases were skipped
TEE test application done!

thanks!!

[jforissier]

root@OpenWrt:/# xtest -x regression 1033

xtest 1033 is simpler (what -x regression 1033 does is: first remove all tests having regression in their name from the whole list of tests, then add all tests having 1033 in their name which is only regression_1033).

E/TA: tee_invoke_supp_plugin:105 Invoke tee-supplicant's plugin failed: 0xffff0008

Could it be that the supplicant plugin for this test is not installed? In the QEMU build it is copied to /usr/lib/tee-supplicant/plugins/f07bfc66-958c-4a15-99c0-260e4e7375dd.plugin.so.
The plugin path is configured via CFG_TEE_PLUGIN_LOAD_PATH when building tee-supplicant (in optee_client) so it may be different on your platform.
The test plugin source code is in optee_test/host/supp_plugin.

[p870613]

Thanks for your reply.

I find the CFG_TEE_PLUGIN_LOAD_PATH and CFG_TEE_PLUGIN_LOAD_PATH is set to
/usr/lib/tee-supplicant/plugins/.
And, I find f07bfc66-958c-4a15-99c0-260e4e7375dd.plugin in /usr/lib/tee- supplicant/plugins/ in my device.

Thanks !!

[p870613]

Thank you for help !!

I find the CFG_TEE_PLUGIN_LOAD_PATH and move to this path.
After rebooting, this fail don;'t happend.

Thanks !!