Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Verilator test failure: caliptra-runtime::ecdsa ecdsa_cmd_run_wycheproof #852

Closed
korran opened this issue Sep 20, 2023 · 3 comments
Closed

Verilator test failure: caliptra-runtime::ecdsa ecdsa_cmd_run_wycheproof #852

korran opened this issue Sep 20, 2023 · 3 comments
Assignees
@zhalvorsen

Comments

@korran
Copy link
Collaborator

korran commented Sep 20, 2023

Looks to be an unexpected NMI error; maybe the watchdog firing?

The runtime should add additional instrumentation to its trap handler to make it easier to diagnose, similar to what the ROM does:

caliptra-sw/rom/dev/src/main.rs

Line 248 in 4bacc8f

"NMI mcause=0x{:08X} mscause=0x{:08X} mepc=0x{:08X} ra=0x{:08X} error_internal_intr_r={:08X}", 

2023-09-20T06:21:48.3612914Z         FAIL [47284.303s] caliptra-runtime::ecdsa ecdsa_cmd_run_wycheproof
2023-09-20T06:21:48.3613428Z 
2023-09-20T06:21:48.3614123Z --- STDOUT:              caliptra-runtime::ecdsa ecdsa_cmd_run_wycheproof ---
2023-09-20T06:21:48.3614583Z 
2023-09-20T06:21:48.3619285Z running 1 test
2023-09-20T06:21:48.3620190Z       6,155 ready_for_fuses is high
2023-09-20T06:21:48.3620920Z       6,333 writing to cptra_bootfsm_go
2023-09-20T06:21:48.3625193Z test ecdsa_cmd_run_wycheproof has been running for over 60 seconds
2023-09-20T06:21:48.3625901Z     195,201 UART: 
2023-09-20T06:21:48.3627019Z     195,256 UART: Running Caliptra ROM ...
2023-09-20T06:21:48.3627755Z     196,662 UART: 
2023-09-20T06:21:48.3628458Z     202,819 UART: [state] CFI Enabled
2023-09-20T06:21:48.3629219Z     205,066 UART: [state] LifecycleState = Unprovisioned
2023-09-20T06:21:48.3639438Z     207,326 UART: [state] DebugLocked = No
2023-09-20T06:21:48.3640557Z     209,534 UART: [state] Watchdog Timer is not started because the device is not locked for debugging
2023-09-20T06:21:48.3642225Z     356,026 UART: ROM Digest: BBB6BA0BFBA2F1E2103A1060EB333FF6613A1F43E352AA823F62EF22650FF80F
2023-09-20T06:21:48.3643161Z     367,266 UART: [kat] ++
2023-09-20T06:21:48.3643755Z     367,778 UART: [kat] sha1
2023-09-20T06:21:48.3644622Z     376,938 UART: [kat] SHA2-256
2023-09-20T06:21:48.3645467Z     379,810 UART: [kat] SHA2-384
2023-09-20T06:21:48.3646078Z     384,075 UART: [kat] SHA2-384-ACC
2023-09-20T06:21:48.3646694Z     386,372 UART: [kat] ECC-384
2023-09-20T06:21:48.3647378Z   3,956,726 UART: [kat] HMAC-384
2023-09-20T06:21:48.3647920Z   3,967,778 UART: [kat] LMS
2023-09-20T06:21:48.3648645Z   5,069,580 UART: [kat] --
2023-09-20T06:21:48.3649427Z   5,072,497 UART: [cold-reset] ++
2023-09-20T06:21:48.3650074Z   5,075,119 UART: [idev] ++
2023-09-20T06:21:48.3650905Z   5,075,698 UART: [idev] CDI.KEYID = 6
2023-09-20T06:21:48.3651663Z   5,076,948 UART: [idev] SUBJECT.KEYID = 7
2023-09-20T06:21:48.3654781Z   5,078,420 UART: [idev] UDS.KEYID = 0
2023-09-20T06:21:48.3656179Z   5,095,715 UART: [idev] Erasing UDS.KEYID = 0
2023-09-20T06:21:48.3657037Z   8,365,879 UART: [idev] Using Sha1 for KeyId Algorithm
2023-09-20T06:21:48.3657549Z   8,387,957 UART: [idev] --
2023-09-20T06:21:48.3657811Z   8,391,516 UART: [ldev] ++
2023-09-20T06:21:48.3658110Z   8,392,109 UART: [ldev] CDI.KEYID = 6
2023-09-20T06:21:48.3658452Z   8,393,351 UART: [ldev] SUBJECT.KEYID = 5
2023-09-20T06:21:48.3658781Z   8,394,823 UART: [ldev] AUTHORITY.KEYID = 7
2023-09-20T06:21:48.3659279Z   8,396,405 UART: [ldev] FE.KEYID = 1
2023-09-20T06:21:48.3659695Z   8,410,196 UART: [ldev] Erasing FE.KEYID = 1
2023-09-20T06:21:48.3660111Z  11,696,727 UART: [ldev] Signing Cert with AUTHORITY.KEYID = 7
2023-09-20T06:21:48.3660707Z  15,292,162 UART: [ldev] PUB.X = 504D38CA45D997901F48BA333A149A2FB2668B973AAC64D3B79ECC09A663F02ED2FABA133F5FA499677AEB7687C99B25
2023-09-20T06:21:48.3661682Z  15,307,860 UART: [ldev] PUB.Y = A5CD31125698B3322C086E4398A591946BF20A3ECEA8EB7D7C23410D49FA877E7ECFBE47BD883BD53D7DA865AC217F1D
2023-09-20T06:21:48.3662439Z  15,323,930 UART: [ldev] SIG.R = 14B5D494DF875AC7CEE9FE3E9A6168A6B64BFB01171F5ECC5216C8BB945FAF957CED1BE6C7CFBB1DDB2B7F4BFD76A492
2023-09-20T06:21:48.3663160Z  15,339,842 UART: [ldev] SIG.S = 821D8CC92F40AFF934771D7652C9BC3C59D47E5635E110A8486B76F7433B02B3C6B0FD8B1B6548B5994C481547111FC5
2023-09-20T06:21:48.3663688Z  15,361,245 UART: [ldev] --
2023-09-20T06:21:48.3663972Z  15,368,037 ready_for_fw is high
2023-09-20T06:21:48.3664331Z  15,368,039 <<< Executing mbox cmd 0x46574c44 (63908 bytes) from SoC
2023-09-20T06:21:48.3664705Z  15,368,098 UART: [fwproc] Waiting for Commands...
2023-09-20T06:21:48.3665089Z  15,404,534 UART: [fwproc] Received command 0x46574c44
2023-09-20T06:21:48.3665481Z  15,407,148 UART: [fwproc] Received Image of size 63908 bytes
2023-09-20T06:21:48.3665881Z  18,126,406 UART: [fwproc] Image verified using Vendor ECC Key Index 0
2023-09-20T06:21:48.3666849Z  18,204,273 UART: [fwproc] Loading FMC at address 0x40000000 len 12964
2023-09-20T06:21:48.3667324Z  18,285,679 UART: [fwproc] Loading Runtime at address 0x40004000 len 45060
2023-09-20T06:21:48.3667684Z  18,560,324 >>> mbox cmd response: success
2023-09-20T06:21:48.3668000Z  18,564,071 UART: [afmc] ++
2023-09-20T06:21:48.3668289Z  18,564,650 UART: [afmc] CDI.KEYID = 6
2023-09-20T06:21:48.3668605Z  18,565,900 UART: [afmc] SUBJECT.KEYID = 7
2023-09-20T06:21:48.3668921Z  18,567,358 UART: [afmc] AUTHORITY.KEYID = 5
2023-09-20T06:21:48.3669301Z  21,880,120 UART: [afmc] Signing Cert with AUTHORITY.KEYID = 5
2023-09-20T06:21:48.3669700Z  24,174,793 UART: [afmc] Erasing AUTHORITY.KEYID = 5
2023-09-20T06:21:48.3670300Z  25,483,075 UART: [afmc] PUB.X = 74A2DA8F43E3CF7B034EB91F9FEBBBEBD3F2BB8B9B15CCF6B2944AE9C548D4ACDD20742598EA46075F5EE3D570A3C884
2023-09-20T06:21:48.3671081Z  25,498,863 UART: [afmc] PUB.Y = F0B6D79EFAF624F13C60ACE8287337FA10B411125D5F76A85844E286F5E554EBD2D1B41B1DFB30A078203A651EFE8C34
2023-09-20T06:21:48.3671785Z  25,514,771 UART: [afmc] SIG.R = 8C94183EECD46495965C1F58565A801C567721535D1E46E8FBD8FE28C4361A17C85DDA0427F22D62A34CAFBA60DE91CF
2023-09-20T06:21:48.3672489Z  25,530,436 UART: [afmc] SIG.S = 57083EFAA8C09D86B408ABDEB8120F3A0A87C9C1DAD6EA4940C67CDFEED457B052E07185C310A798191C6F16BBB24B74
2023-09-20T06:21:48.3673071Z  25,558,650 UART: [afmc] --
2023-09-20T06:21:48.3673443Z  25,562,645 UART: [cold-reset] --
2023-09-20T06:21:48.3673761Z  25,577,676 UART: [fht] Storing FHT @ 0x50003400
2023-09-20T06:21:48.3674223Z  25,593,564 UART: [state] Watchdog Timer is not stopped because the device is not locked for debugging
2023-09-20T06:21:48.3674646Z  25,599,650 UART: [state] Locking Datavault
2023-09-20T06:21:48.3675006Z  25,604,836 UART: [state] Locking PCR0, PCR1 and PCR31
2023-09-20T06:21:48.3675351Z  25,606,912 UART: [state] Locking ICCM
2023-09-20T06:21:48.3675667Z  25,609,168 UART: [exit] Launching FMC @ 0x40000134
2023-09-20T06:21:48.3675974Z  25,617,978 UART: 
2023-09-20T06:21:48.3676310Z  25,618,012 UART: Running Caliptra FMC ...
2023-09-20T06:21:48.3676621Z  25,618,855 UART: 
2023-09-20T06:21:48.3677085Z  25,621,847 UART: [alias rt] Extend RT PCRs
2023-09-20T06:21:48.3677640Z  25,654,931 UART: [alias rt] Extend RT PCRs Done
2023-09-20T06:21:48.3678302Z  25,655,972 UART: [alias rt] Lock RT PCRs
2023-09-20T06:21:48.3678638Z  25,656,782 UART: [alias rt] Lock RT PCRs Done
2023-09-20T06:21:48.3678966Z  25,657,761 UART: [alias rt] Populate DV
2023-09-20T06:21:48.3679289Z  25,658,660 UART: [alias rt] Populate DV Done
2023-09-20T06:21:48.3679620Z  25,659,641 UART: Handoff : FMC CDI: 6
2023-09-20T06:21:48.3680104Z  25,660,409 UART: FMC Alias Private Key: 7
2023-09-20T06:21:48.3680465Z  25,662,319 UART: [alias rt] Derive CDI
2023-09-20T06:21:48.3680787Z  25,663,061 UART: [alias rt] Store in in slot 0x4
2023-09-20T06:21:48.3681129Z  25,696,801 UART: [alias rt] Derive Key Pair
2023-09-20T06:21:48.3681487Z  25,697,709 UART: [alias rt] Store priv key in slot 0x5
2023-09-20T06:21:48.3682161Z  28,936,909 UART: [alias rt] Derive Key Pair - Done
2023-09-20T06:21:48.3682528Z  28,947,920 UART: [alias rt] Signing Cert with AUTHO
2023-09-20T06:21:48.3682867Z  28,949,087 UART:             RITY.KEYID = 7
2023-09-20T06:21:48.3683220Z  31,227,209 UART: [alias rt] Erasing AUTHORITY.KEYID = 7
2023-09-20T06:21:48.3683748Z  31,228,719 UART: [alias rt] PUB.X = DF3393787B3E9A4B786B518FCAEE494488A8356DC92A6862B160D8F9D106575593235419CF5C9E3B323F88893D7E3209
2023-09-20T06:21:48.3684486Z  31,236,122 UART: [alias rt] PUB.Y = A1F7CEA89F6EE3D4F0F87084DDD6EF4FC843E7C495A4A62388ACF801E950FE547CBB3E130E05DC9D16DE0982E90C3BB3
2023-09-20T06:21:48.3685258Z  31,243,728 UART: [alias rt] SIG.R = 5CA2D5E23848047EEA0B9C4E8BB05E80C6BED7A94841E3F9D36168EF631A04E148BA02FF24FF353BC0166F7998F01D40
2023-09-20T06:21:48.3685988Z  31,251,127 UART: [alias rt] SIG.S = 894CA02731DBF435F5DA90EF908BDD2F88DB800F1BD014CB6D16C07E0C263CDE523D4B88B58CD1207981A8277BECBEB9
2023-09-20T06:21:48.3686532Z  32,554,681 UART: 
2023-09-20T06:21:48.3686837Z  32,554,714 UART:   ____      _ _       _               ____ _____
2023-09-20T06:21:48.3687194Z  32,556,347 UART:  / ___|__ _| (_)_ __ | |_ _ __ __ _  |  _ \_   _|
2023-09-20T06:21:48.3687637Z  32,558,013 UART: | |   / _` | | | '_ \| __| '__/ _` | | |_) || |
2023-09-20T06:21:48.3687991Z  32,559,612 UART: | |__| (_| | | | |_) | |_| | | (_| | |  _ < | |
2023-09-20T06:21:48.3688340Z  32,561,211 UART:  \____\__,_|_|_| .__/ \__|_|  \__,_| |_| \_\|_|
2023-09-20T06:21:48.3688633Z  32,562,810 UART:                |_|
2023-09-20T06:21:48.3688892Z  32,563,452 UART: 
2023-09-20T06:21:48.3689227Z  32,660,050 UART: Caliptra RT listening for mailbox commands...
2023-09-20T06:21:48.3689613Z  32,661,751 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3689990Z  32,667,725 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3690349Z  33,961,854 >>> mbox cmd response data (8 bytes)
2023-09-20T06:21:48.3690718Z  33,962,007 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3691078Z  33,967,953 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3691434Z  35,261,927 >>> mbox cmd response data (8 bytes)
2023-09-20T06:21:48.3691800Z  35,262,081 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3692146Z  35,268,028 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3692488Z  35,271,563 >>> mbox cmd response: failed
2023-09-20T06:21:48.3692839Z  35,271,713 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3693191Z  35,277,651 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3693532Z  36,568,353 >>> mbox cmd response: failed
2023-09-20T06:21:48.3693885Z  36,568,505 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3694253Z  36,574,452 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3694570Z  37,865,150 >>> mbox cmd response: failed
2023-09-20T06:21:48.3694923Z  37,865,300 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3695293Z  37,871,245 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3695609Z  39,161,937 >>> mbox cmd response: failed
2023-09-20T06:21:48.3696074Z  39,162,084 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3696500Z  39,168,024 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3696814Z  40,458,717 >>> mbox cmd response: failed
2023-09-20T06:21:48.3697164Z  40,458,866 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3697531Z  40,464,813 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3697944Z  40,468,342 >>> mbox cmd response: failed
2023-09-20T06:21:48.3698281Z  40,468,493 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3698646Z  40,474,439 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3698981Z  40,478,113 >>> mbox cmd response: failed
2023-09-20T06:21:48.3699440Z  40,478,264 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3699915Z  40,484,211 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3702349Z  41,774,905 >>> mbox cmd response: failed
2023-09-20T06:21:48.3702701Z  41,775,056 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3703079Z  41,781,004 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3703434Z  43,071,699 >>> mbox cmd response: failed
2023-09-20T06:21:48.3703766Z  43,071,848 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3704135Z  43,077,795 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3704475Z  43,081,467 >>> mbox cmd response: failed
2023-09-20T06:21:48.3704825Z  43,081,614 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3705191Z  43,087,555 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3705536Z  44,378,249 >>> mbox cmd response: failed
2023-09-20T06:21:48.3705896Z  44,378,400 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3706251Z  44,384,347 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3706679Z  45,675,040 >>> mbox cmd response: failed
2023-09-20T06:21:48.3707035Z  45,675,191 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3707386Z  45,681,137 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3707720Z  46,971,828 >>> mbox cmd response: failed
2023-09-20T06:21:48.3708071Z  46,971,977 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3708423Z  46,977,915 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3708762Z  48,268,609 >>> mbox cmd response: failed
2023-09-20T06:21:48.3709119Z  48,268,756 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3709488Z  48,274,693 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3709817Z  48,278,220 >>> mbox cmd response: failed
2023-09-20T06:21:48.3710173Z  48,278,369 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3710548Z  48,284,316 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3710870Z  48,287,840 >>> mbox cmd response: failed
2023-09-20T06:21:48.3711224Z  48,287,990 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3711595Z  48,293,927 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3711913Z  48,297,450 >>> mbox cmd response: failed
2023-09-20T06:21:48.3712264Z  48,297,598 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3712634Z  48,303,538 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3712970Z  48,307,062 >>> mbox cmd response: failed
2023-09-20T06:21:48.3713308Z  48,307,209 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3713684Z  48,313,149 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3714026Z  48,316,674 >>> mbox cmd response: failed
2023-09-20T06:21:48.3714363Z  48,316,823 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3714738Z  48,322,770 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3715085Z  48,326,293 >>> mbox cmd response: failed
2023-09-20T06:21:48.3715582Z  48,326,444 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3715965Z  48,332,391 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3716313Z  48,335,915 >>> mbox cmd response: failed
2023-09-20T06:21:48.3716708Z  48,336,062 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3717061Z  48,342,003 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3717886Z  48,345,527 >>> mbox cmd response: failed
2023-09-20T06:21:48.3718553Z  48,345,676 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3718910Z  48,351,624 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3721849Z  48,355,363 >>> mbox cmd response: failed
2023-09-20T06:21:48.3722430Z  48,355,511 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3723054Z  48,361,448 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3723737Z  49,652,280 >>> mbox cmd response: failed
2023-09-20T06:21:48.3724379Z  49,652,429 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3725218Z  49,658,376 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3726021Z  50,949,205 >>> mbox cmd response: failed
2023-09-20T06:21:48.3726851Z  50,949,353 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3727206Z  50,955,293 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3727554Z  50,959,134 >>> mbox cmd response: failed
2023-09-20T06:21:48.3727910Z  50,959,284 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3728264Z  50,965,230 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3728605Z  52,256,262 >>> mbox cmd response: failed
2023-09-20T06:21:48.3728961Z  52,256,410 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3729317Z  52,262,347 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3730576Z  52,266,190 >>> mbox cmd response: failed
2023-09-20T06:21:48.3731013Z  52,266,341 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3731414Z  52,272,289 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3731753Z  52,276,008 >>> mbox cmd response: failed
2023-09-20T06:21:48.3732125Z  52,276,156 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3732522Z  52,282,094 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3732866Z  52,285,809 >>> mbox cmd response: failed
2023-09-20T06:21:48.3733246Z  52,285,959 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3733633Z  52,291,897 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3733967Z  52,295,639 >>> mbox cmd response: failed
2023-09-20T06:21:48.3734341Z  52,295,790 <<< Executing mbox cmd 0x53494756 (196 bytes) from SoC
2023-09-20T06:21:48.3734734Z  52,301,737 UART: [rt] Received command=0x53494756, len=196
2023-09-20T06:21:48.3735141Z  52,671,693 UART: RT NMI mcause=0x00000000 mscause=0x00000000 mepc=0x40005098
2023-09-20T06:21:48.3735515Z  52,673,782 UART: RT Fatal Error: 0x000E000B
2023-09-20T06:21:48.3736192Z  52,675,766 >>> mbox cmd response: failed
2023-09-20T06:21:48.3736536Z test ecdsa_cmd_run_wycheproof ... FAILED
2023-09-20T06:21:48.3736732Z 
2023-09-20T06:21:48.3736828Z failures:
2023-09-20T06:21:48.3736975Z 
2023-09-20T06:21:48.3737067Z failures:
2023-09-20T06:21:48.3737348Z     ecdsa_cmd_run_wycheproof
2023-09-20T06:21:48.3737578Z 
2023-09-20T06:21:48.3737840Z test result: FAILED. 0 passed; 1 failed; 0 ignored; 0 measured; 0 filtered out; finished in 47284.28s
2023-09-20T06:21:48.3738150Z 
2023-09-20T06:21:48.3738156Z 
2023-09-20T06:21:48.3738820Z --- STDERR:              caliptra-runtime::ecdsa ecdsa_cmd_run_wycheproof ---
2023-09-20T06:21:48.3745029Z thread 'ecdsa_cmd_run_wycheproof' panicked at 'called `Result::unwrap()` on an `Err` value: UnableToLockSha512Acc', runtime/tests/ecdsa.rs:80:18
2023-09-20T06:21:48.3745922Z note: run with `RUST_BACKTRACE=1` environment variable to display a backtrace
@jhand2 jhand2 assigned jhand2 and zhalvorsen and unassigned jhand2 Sep 22, 2023
@korran
Copy link
Collaborator Author

korran commented Oct 13, 2023

Based on what I'm seeing on the FPGA, this appears to have been fixed by #938

@FerralCoder
Copy link
Contributor

can we close this?

@zhalvorsen
Copy link
Contributor

I agree with @korran

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@zhalvorsen zhalvorsen

Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@korran @jhand2 @zhalvorsen @FerralCoder