HiKey: xtest 7671 fails (1.0.0-rc2)

[jforissier]

Using https://github.com/OP-TEE/manifest (tag: 1.0.0-rc2) to run the full xtest on HiKey. All tests pass except 7671. And running xtest 7671 again always fails at this point.

root@HiKey:/ xtest 7671
Test ID: 7671
Run test suite with level=0

TEE test application started with device [opteearmtz00]
######################################################
#
# XTEST_TEE_TEST
#
######################################################

* XTEST_TEE_7671 9d-69-f3
DBG TEE-CORE:tee_ta_init_static_ta_session:1137:    Lookup for Static TA 534d4152-5443-534c
DBG TEE-CORE:tee_ta_init_session_with_signed_ta:1180:    Load dynamic TA
DBG TEE-CORE:tee_ta_load:619: Loaded TA at 0x3f200000
DBG TEE-CORE:tee_ta_load:620: ELF load address 0x40200000
DBG TEE-CORE:tee_ta_init_session_with_signed_ta:1188:       dyn TA : 534d4152-5443-534c
ERR TEE-CORE:tee_fs_common_open:1221: tee file not exists
ERR TEE-CORE:tee_fs_common_open:1221: tee file not exists
ERR TEE-CORE:tee_fs_common_open:1221: tee file not exists
ERR TEE-CORE:tee_fs_common_open:1221: tee file not exists
ERR TEE-CORE:tee_fs_common_open:1221: tee file not exists
ERR TEE-CORE:tee_fs_common_open:1221: tee file not exists
ERR TEE-CORE:tee_fs_common_open:1221: tee file not exists
DBG TEE-CORE:open_tee_file:1162: Opening TEE file=52414D5343544C535444415441535431/.6F626A65637450657273697374656E7431
DBG TEE-CORE:tee_fs_common_ftruncate:1389: Ignore due to file length does not changed
DBG TEE-CORE:tee_fs_common_write:1593: data len=16
DBG TEE-CORE:tee_fs_do_multi_blocks_transfer:1028: start_block:0, end_block:0, len:16
DBG TEE-CORE:write_one_block:806: offset=0, length=16
ERR TEE-CORE:write_one_block:818: Failed to allocate plaintext buffer, size=4096
DBG TEE-CORE:tee_ta_invoke_command:1408:   => Error: f00d of 4
ERR [968] TEEC:TEEC_InvokeCommand:396: Function returns with [61453]
./xml/include/xml_datastorage_api.h:1321: Expression "res == expectedReturn" (61453 == 0) is false
DBG TEE-CORE:tee_ta_close_session:998: tee_ta_close_session(0x3f053690)
DBG TEE-CORE:tee_ta_close_session:1017:    ... Destroy session
DBG TEE-CORE:tee_ta_destroy_context:933:    ... Destroy TA ctx
  XTEST_TEE_7671 FAILED
+-----------------------------------------------------
Result of testsuite XTEST_TEE_TEST filtered by "7671":
XTEST_TEE_7671 FAILED first error at ./xml/include/xml_datastorage_api.h:1321
+-----------------------------------------------------
13 subtests of which 1 failed
1 test case of which 1 failed
700 test cases was skipped
TEE test application done!

Sometimes, the first invocation of xtest is successfull (all tests pass) and you have to run xtest again to get multiple failures. AFAICT all failed tests report some memory allocation error similar to the above trace.

Running xtest 7671 on a freshly booted board shows no error.

[jforissier]

To reproduce the issue faster:

root@HiKey:/ xtest _76 ; xtest _76

[jforissier]

Looks like a regression introduced by a2e9a83

• Test case: while [ $? -eq 0 ]; do xtest _76 ; done
• Commit 8a7ee79 (1.0.0-rc2) fails this test
• Same commit with a2e9a83 reverted passes the test