Message-ID: <9a725b81-9fed-4713-b56f-5b182f95e97a@microchip.com>
Date: Wed, 7 May 2025 08:27:18 -0700
From: Ryan Wanner <ryan.wanner@...rochip.com>
To: Herbert Xu <herbert@...dor.apana.org.au>
CC: <linux-crypto@...r.kernel.org>, open list <linux-kernel@...r.kernel.org>
Subject: Re: crypto self-test errors kernel 6.12.4

On 5/6/25 17:53, Herbert Xu wrote:
> EXTERNAL EMAIL: Do not click links or open attachments unless you know the content is safe
> 
> On Tue, May 06, 2025 at 01:12:31PM -0700, Ryan Wanner wrote:
>> Hello,
>>
>> I upgraded my ARM kernel from v6.6.9 to v6.12.4 and I am seeing some
>> crypto self test errors that I did not see before. Shown below. These
>> errors are different every boot and I did not see them on 6.6.9 kernel.
>> I did notice that when I compile the crypto HW acceleration drivers as
>> modules like this:
>> CONFIG_CRYPTO_DEV_ATMEL_AES [=m]
>> CONFIG_CRYPTO_DEV_ATMEL_TDES [=m]
>> CONFIG_CRYPTO_DEV_ATMEL_SHA [=m]
>>
>> and have them loaded by systemd at boot I do not get errors and all the
>> self tests pass.
>>
>> Is there a race condition I am not aware of? Or is the new asynchronous
>> crypto tests creating bugs in the HW acceleration drivers.
> 
> Yes that's entirely possible as I think it was around that time
> when the self-tests became concurrent.
> 
>> DRBG: could not allocate digest TFM handle: hmac(sha512)
>> alg: drbg: Failed to reset rng
>> alg: drbg: Test 0 failed for drbg_nopr_hmac_sha512
>> alg: self-tests for stdrng using drbg_nopr_hmac_sha512 failed (rc=-22)
>> ------------[ cut here ]------------
>> WARNING: CPU: 0 PID: 90 at crypto/testmgr.c:5928 0xc0397fd0
>> alg: self-tests for stdrng using drbg_nopr_hmac_sha512 failed (rc=-22)
>>
>> alg: hash: error allocating hmac(sha512-generic) (generic impl of
>> hmac(sha512)): -17
>> alg: self-tests for hmac(sha512) using atmel-hmac-sha512 failed (rc=-17)
>> ------------[ cut here ]------------
>> WARNING: CPU: 0 PID: 53 at crypto/testmgr.c:5928 0xc0397fd0
>> alg: self-tests for hmac(sha512) using atmel-hmac-sha512 failed (rc=-17)
> 
> 17 is EEXIST, that may actually point to a bug in the Crypto API
> itself.
> 
> Could you please test the current mainline kernel and send me the
> complete bootup messages and kconfig file?
Yes the config file is attached and here is the boot log, looks like it
is fixed on v6.15-rc5 and I do not see the error. Is there multiple
commits that fix this or where the async tests have been disabled?

[    0.031506] VFP support v0.3: implementor 41 architecture 2 part 30
variant 5 rev 1
[    0.032147] clocksource: jiffies: mask: 0xffffffff max_cycles:
0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.032204] posixtimers hash table entries: 512 (order: -1, 2048
bytes, linear)
[    0.032241] futex hash table entries: 256 (order: -1, 3072 bytes, linear)
[    0.032533] pinctrl core: initialized pinctrl subsystem
[    0.036040] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.037840] DMA: preallocated 256 KiB pool for atomic coherent
allocations
[    0.079843] AT91: PM: standby: standby, suspend: ulp1
[    0.090557] at_xdmac f0010000.dma-controller: 16 channels, mapped at
0x(ptrval)
[    0.094459] at_xdmac f0004000.dma-controller: 16 channels, mapped at
0x(ptrval)
[    0.096591] AT91: Detected SoC family: sama5d2
[    0.096620] AT91: Detected SoC: sama5d27c 128MiB SiP, revision 2
[    0.098172] SCSI subsystem initialized
[    0.098831] usbcore: registered new interface driver usbfs
[    0.098981] usbcore: registered new interface driver hub
[    0.099136] usbcore: registered new device driver usb
[    0.100549] mc: Linux media interface: v0.10
[    0.100732] videodev: Linux video capture interface: v2.00
[    0.100885] pps_core: LinuxPPS API ver. 1 registered
[    0.100901] pps_core: Software ver. 5.3.6 - Copyright 2005-2007
Rodolfo Giometti <giometti@...ux.it>
[    0.100958] PTP clock support registered
[    0.101721] Advanced Linux Sound Architecture Driver Initialized.
[    0.108737] clocksource: Switched to clocksource timer@...0c000
[    0.144332] NET: Registered PF_INET protocol family
[    0.144895] IP idents hash table entries: 2048 (order: 2, 16384
bytes, linear)
[    0.146341] tcp_listen_portaddr_hash hash table entries: 1024 (order:
0, 4096 bytes, linear)
[    0.146430] Table-perturb hash table entries: 65536 (order: 6, 262144
bytes, linear)
[    0.146465] TCP established hash table entries: 1024 (order: 0, 4096
bytes, linear)
[    0.146512] TCP bind hash table entries: 1024 (order: 1, 8192 bytes,
linear)
[    0.146586] TCP: Hash tables configured (established 1024 bind 1024)
[    0.146873] UDP hash table entries: 256 (order: 1, 8192 bytes, linear)
[    0.146966] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes,
linear)
[    0.147424] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.149199] RPC: Registered named UNIX socket transport module.
[    0.149242] RPC: Registered udp transport module.
[    0.149253] RPC: Registered tcp transport module.
[    0.149262] RPC: Registered tcp-with-tls transport module.
[    0.149271] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.154450] Initialise system trusted keyrings
[    0.155880] workingset: timestamp_bits=30 max_order=15 bucket_order=0
[    0.158372] Key type asymmetric registered
[    0.158416] Asymmetric key parser 'x509' registered
[    0.158555] io scheduler mq-deadline registered
[    0.158576] io scheduler kyber registered
[    0.158641] io scheduler bfq registered
[    0.187923] gpio gpiochip0: Static allocation of GPIO base is
deprecated, use dynamic allocation.
[    0.190718] pinctrl-at91-pio4 fc038000.pinctrl: atmel pinctrl initialized
[    0.214761] random: crng init done
[    0.222400] brd: module loaded
[    0.239383] loop: module loaded
[    0.242092] atmel_usart_serial.0.auto: ttyS0 at MMIO 0xf8020000 (irq
= 151, base_baud = 5125000) is a ATMEL_SERIAL
[    0.242218] printk: legacy console [ttyS0] enabled
[    0.687597] atmel_usart_serial.1.auto: ttyS2 at MMIO 0xf8024000 (irq
= 152, base_baud = 5125000) is a ATMEL_SERIAL
[    0.700150] atmel_usart_serial.2.auto: ttyS1 at MMIO 0xfc00c000 (irq
= 153, base_baud = 5125000) is a ATMEL_SERIAL
[    0.712898] at91_i2c f8038600.i2c: can't get DMA channel, continue
without DMA support
[    0.719611] at91_i2c f8038600.i2c: Using FIFO (16 data)
[    0.723887] i2c i2c-2: using pinctrl states for GPIO recovery
[    0.728377] i2c i2c-2: using generic GPIOs for recovery
[    0.732425] at91_i2c f8038600.i2c: AT91 i2c bus driver (hw version:
0x704).
[    0.747571] atmel_spi f8000000.spi: Using dma0chan0 (tx) and
dma0chan1 (rx) for DMA transfers
[    0.754933] atmel_spi f8000000.spi: Using FIFO (16 data)
[    0.759909] atmel_spi f8000000.spi: Atmel SPI Controller version
0x311 at 0xf8000000 (irq 155)
[    0.768335] atmel_spi fc018400.spi: Atmel SPI Controller using PIO only
[    0.773811] atmel_spi fc018400.spi: Using FIFO (16 data)
[    0.778822] atmel_spi fc018400.spi: Atmel SPI Controller version
0x311 at 0xfc018400 (irq 156)
[    0.789489] spi-nor spi2.0: supply vcc not found, using dummy regulator
[    0.795347] spi-nor spi2.0: unrecognized JEDEC id bytes: ff ff ff ff
ff ff
[    0.804591] CAN device driver interface
[    0.810025] m_can_platform fc050000.can: m_can device registered
(irq=158, version=31)
[    0.825259] macb f8008000.ethernet eth0: Cadence GEM rev 0x00020203
at 0xf8008000 irq 159 (54:10:ec:2e:bc:36)
[    0.839339] atmel-ehci 500000.usb: EHCI Host Controller
[    0.843259] atmel-ehci 500000.usb: new USB bus registered, assigned
bus number 1
[    0.849607] atmel-ehci 500000.usb: irq 162, io mem 0x00500000
[    0.868840] atmel-ehci 500000.usb: USB 2.0 started, EHCI 1.00
[    0.873765] usb usb1: New USB device found, idVendor=1d6b,
idProduct=0002, bcdDevice= 6.15
[    0.880681] usb usb1: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[    0.886487] usb usb1: Product: EHCI Host Controller
[    0.890051] usb usb1: Manufacturer: Linux 6.15.0-rc5+ ehci_hcd
[    0.894480] usb usb1: SerialNumber: 500000.usb
[    0.899558] hub 1-0:1.0: USB hub found
[    0.902077] hub 1-0:1.0: 3 ports detected
[    0.908309] at91_ohci 400000.usb: USB Host Controller
[    0.912179] at91_ohci 400000.usb: new USB bus registered, assigned
bus number 2
[    0.918338] at91_ohci 400000.usb: irq 162, io mem 0x00400000
[    0.983346] usb usb2: New USB device found, idVendor=1d6b,
idProduct=0001, bcdDevice= 6.15
[    0.990283] usb usb2: New USB device strings: Mfr=3, Product=2,
SerialNumber=1
[    0.996097] usb usb2: Product: USB Host Controller
[    0.999565] usb usb2: Manufacturer: Linux 6.15.0-rc5+ ohci_hcd
[    1.003995] usb usb2: SerialNumber: at91
[    1.008217] hub 2-0:1.0: USB hub found
[    1.010848] hub 2-0:1.0: 3 ports detected
[    1.016157] usbcore: registered new interface driver cdc_acm
[    1.020536] cdc_acm: USB Abstract Control Model driver for USB modems
and ISDN adapters
[    1.027342] usbcore: registered new interface driver usb-storage
[    1.032393] usbcore: registered new interface driver usbserial_generic
[    1.037599] usbserial: USB Serial support registered for generic
[    1.042387] usbcore: registered new interface driver ftdi_sio
[    1.046799] usbserial: USB Serial support registered for FTDI USB
Serial Device
[    1.053087] usbcore: registered new interface driver pl2303
[    1.057336] usbserial: USB Serial support registered for pl2303
[    1.062811] atmel_usba_udc 300000.gadget: MMIO registers at [mem
0xfc02c000-0xfc02c3ff] mapped at ee15e529
[    1.071301] atmel_usba_udc 300000.gadget: FIFO at [mem
0x00300000-0x003fffff] mapped at 9669f7ea
[    1.082632] g_serial gadget.0: Gadget Serial v2.4
[    1.085981] g_serial gadget.0: g_serial ready
[    1.092454] at91_rtc f80480b0.rtc: registered as rtc0
[    1.096226] at91_rtc f80480b0.rtc: setting system clock to
2012-01-01T00:00:06 UTC (1325376006)
[    1.103667] at91_rtc f80480b0.rtc: AT91 Real Time Clock driver.
[    1.108597] i2c_dev: i2c /dev entries driver
[    1.113722] at91-reset f8048000.reset-controller: Starting after
regular power-up
[    1.123041] sama5d4_wdt f8048040.watchdog: initialized (timeout = 16
sec, nowayout = 0)
[    1.130765] sdhci: Secure Digital Host Controller Interface driver
[    1.135544] sdhci: Copyright(c) Pierre Ossman
[    1.138907] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.144154] atmel_aes f002c000.crypto: version: 0x500
[    1.158056] atmel_sha f0028000.crypto: version: 0x510
[    1.162186] atmel_sha f0028000.crypto: using dma0chan2 for DMA transfers
[    1.167707] atmel_sha f0028000.crypto: Atmel
SHA1/SHA256/SHA224/SHA384/SHA512
[    1.174659] atmel_tdes fc044000.crypto: version: 0x703
[    1.179082] atmel_tdes fc044000.crypto: using dma0chan3, dma0chan4
for DMA transfers
[    1.185520] atmel_tdes fc044000.crypto: Atmel DES/TDES
[    1.190059] usbcore: registered new interface driver usbhid
[    1.194235] usbhid: USB HID core driver
[    1.204308] at91-sama5d2_adc fc030000.adc: setting up trigger as
external_rising
[    1.210433] at91-sama5d2_adc fc030000.adc: version: 800
[    1.217979] NET: Registered PF_INET6 protocol family
[    1.225262] Segment Routing with IPv6
[    1.227667] In-situ OAM (IOAM) with IPv6
[    1.230535] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[    1.236773] NET: Registered PF_PACKET protocol family
[    1.240556] can: controller area network core
[    1.243688] NET: Registered PF_CAN protocol family
[    1.247092] can: raw protocol
[    1.248837] can: broadcast manager protocol
[    1.251664] can: netlink gateway - max_hops=1
[    1.266988] Loading compiled-in X.509 certificates
[    1.284032] mmc0: SDHCI controller on a0000000.sdio-host
[a0000000.sdio-host] using ADMA
[    1.291764] mmc1: SDHCI controller on b0000000.sdio-host
[b0000000.sdio-host] using ADMA
[    1.300028] alg: extra crypto tests enabled.  This is intended for
developer use only.
[    1.429094] mmc0: new UHS-I speed DDR50 SDHC card at address aaaa
[    1.461653] mmcblk0: mmc0:aaaa SS08G 7.40 GiB
[    1.704472] at91_i2c f8028000.i2c: can't get DMA channel, continue
without DMA support
[    1.711159] at91_i2c f8028000.i2c: Using FIFO (16 data)
[    1.816319]  mmcblk0: p1 p2
[    1.969404] i2c i2c-0: using pinctrl states for GPIO recovery
[    1.973946] i2c i2c-0: using generic GPIOs for recovery
[    1.978599] at24 0-0050: supply vcc not found, using dummy regulator
[    2.288634] at24 0-0050: 256 byte 24c02 EEPROM, writable, 8 bytes/write
[    2.294188] at91_i2c f8028000.i2c: AT91 i2c bus driver (hw version:
0x704).
[    2.301058] at91_i2c fc028000.i2c: can't get DMA channel, continue
without DMA support
[    2.307588] at91_i2c fc028000.i2c: Using FIFO (16 data)
[    2.751622] i2c i2c-1: using pinctrl states for GPIO recovery
[    2.756163] i2c i2c-1: using generic GPIOs for recovery
[    2.760236] at91_i2c fc028000.i2c: AT91 i2c bus driver (hw version:
0x704).
[    2.766955] atmel_aes f002c000.crypto: version: 0x500
[    3.300037] alg: No test for authenc(hmac(sha224),cbc(aes))
(atmel-authenc-hmac-sha224-cbc-aes)
[    3.390806] alg: No test for authenc(hmac(sha384),cbc(aes))
(atmel-authenc-hmac-sha384-cbc-aes)
[    3.403220] atmel_aes f002c000.crypto: Atmel AES - Using dma0chan5,
dma0chan6 for DMA transfers
[    3.603869] input: gpio-keys as /devices/platform/gpio-keys/input/input0
[    3.721661] cfg80211: Loading compiled-in X.509 certificates for
regulatory database
[   19.265485] Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[   19.283255] Loaded X.509 cert 'wens:
61c038651aabdcf94bd0ac7ff06c7248db18c600'
[   19.289233] clk: Disabling unused clocks
[   19.292079] ALSA device list:
[   19.293674]   No soundcards found.
[   19.296309] platform regulatory.0: Direct firmware load for
regulatory.db failed with error -2
[   19.303617] cfg80211: failed to load regulatory.db
[   19.311497] atmel_usart_serial atmel_usart_serial.0.auto: using
dma0chan7 for rx DMA transfers
[   19.319039] atmel_usart_serial atmel_usart_serial.0.auto: using
dma0chan8 for tx DMA transfers
[   19.457448] EXT4-fs (mmcblk0p2): mounted filesystem
5df2fad9-8036-4769-97fa-b516397d778d r/w with ordered data mode. Quota
mode: disabled.
[   19.468755] VFS: Mounted root (ext4 filesystem) on device 179:2.
[   19.478947] devtmpfs: mounted
[   19.484235] Freeing unused kernel image (initmem) memory: 1024K
[   19.489031] Run /sbin/init as init process
[   19.491742]   with arguments:
[   19.491757]     /sbin/init
[   19.491772]   with environment:
[   19.491785]     HOME=/
[   19.491800]     TERM=linux
[   19.898247] systemd[1]: System time before build time, advancing clock.
[   19.960333] systemd[1]: Inserted module 'autofs4'
[   20.009317] systemd[1]: systemd 256.7 running in system mode (-PAM
-AUDIT -SELINUX -APPARMOR -IMA -SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL
+ACL +BLKID -CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD -LIBCRYPTSETUP
-LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY +P11KIT -QRENCODE
-TPM2 -BZIP2 -LZ4 -XZ +ZLIB -ZSTD -BPF_FRAMEWORK -XKBCOMMON -UTMP
-SYSVINIT -LIBARCHIVE)
[   20.040431] systemd[1]: Detected architecture arm.
[   20.086534] systemd[1]: Hostname set to <sama5>.
[   20.734422] systemd-ssh-generator[231]: Binding SSH to AF_UNIX socket
/run/ssh-unix-local/socket.
[   20.748622] systemd-ssh-generator[231]: \xe2\x86\x92 connect via 'ssh
.host' locally
[   22.262610] systemd[1]: Queued start job for default target
Multi-User System.
[   22.324413] systemd[1]: Created slice Slice /system/modprobe.
[   22.353431] systemd[1]: Created slice Slice /system/serial-getty.
[   22.380399] systemd[1]: Started Dispatch Password Requests to Console
Directory Watch.
[   22.410241] systemd[1]: Started Forward Password Requests to Wall
Directory Watch.
[   22.439663] systemd[1]: Expecting device /dev/ttyS0...
[   22.460081] systemd[1]: Reached target NFS client services.
[   22.489982] systemd[1]: Reached target Path Units.
[   22.519619] systemd[1]: Reached target Preparation for Remote File
Systems.
[   22.549537] systemd[1]: Reached target Remote File Systems.
[   22.579525] systemd[1]: Reached target Slice Units.
[   22.609801] systemd[1]: Reached target Swaps.
[   22.713530] systemd[1]: Listening on Credential Encryption/Decryption.
[   22.763716] systemd[1]: Journal Audit Socket was skipped because of
an unmet condition check (ConditionSecurity=audit).
[   22.775403] systemd[1]: Listening on Journal Socket (/dev/log).
[   22.801220] systemd[1]: Listening on Journal Sockets.
[   22.831682] systemd[1]: Listening on Network Service Netlink Socket.
[   22.861217] systemd[1]: Listening on udev Control Socket.
[   22.890753] systemd[1]: Listening on udev Kernel Socket.
[   22.921087] systemd[1]: Huge Pages File System was skipped because of
an unmet condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[   22.934598] systemd[1]: POSIX Message Queue File System was skipped
because of an unmet condition check
(ConditionPathExists=/proc/sys/fs/mqueue).
[   23.015204] systemd[1]: Mounting Kernel Debug File System...
[   23.050831] systemd[1]: Kernel Trace File System was skipped because
of an unmet condition check (ConditionPathExists=/sys/kernel/tracing).
[   23.170019] systemd[1]: Mounting Temporary Directory /tmp...
[   23.220713] systemd[1]: Starting Create List of Static Device Nodes...
[   23.294964] systemd[1]: Starting Load Kernel Module configfs...
[   23.390674] systemd[1]: Starting Load Kernel Module efi_pstore...
[   23.480741] systemd[1]: Starting Load Kernel Module fuse...
[   23.540826] systemd[1]: File System Check on Root Device was skipped
because of an unmet condition check (ConditionPathIsReadWrite=!/).
[   23.602242] systemd[1]: systemd-journald.service: unit configures an
IP firewall, but the local system does not support BPF/cgroup firewalling.
[   23.669083] systemd[1]: systemd-journald.service: (This warning is
only shown for the first unit using IP firewalling.)
[   23.750775] systemd[1]: Starting Journal Service...
[   23.798457] systemd[1]: Load Kernel Modules was skipped because no
trigger condition checks were met.
[   23.850908] systemd[1]: Starting Generate network units from Kernel
command line...
[   23.900845] systemd[1]: Starting Remount Root and Kernel File Systems...
[   23.990680] systemd[1]: Starting Apply Kernel Variables...
[   24.070729] systemd[1]: Starting Load udev Rules from Credentials...
[   24.209922] systemd[1]: Starting Coldplug All udev Devices...
[   24.598383] systemd[1]: Mounted Kernel Debug File System.
[   24.713420] systemd[1]: Mounted Temporary Directory /tmp.
[   24.789187] systemd[1]: Finished Create List of Static Device Nodes.
[   24.843604] systemd[1]: modprobe@...figfs.service: Deactivated
successfully.
[   24.859018] EXT4-fs (mmcblk0p2): re-mounted
5df2fad9-8036-4769-97fa-b516397d778d.
[   24.899320] systemd[1]: Finished Load Kernel Module configfs.
[   24.988086] systemd[1]: modprobe@..._pstore.service: Deactivated
successfully.
[   25.019401] systemd[1]: Finished Load Kernel Module efi_pstore.
[   25.055160] systemd[1]: modprobe@...e.service: Deactivated successfully.
[   25.081434] systemd[1]: Finished Load Kernel Module fuse.
[   25.140570] systemd[1]: Finished Generate network units from Kernel
command line.
[   25.183321] systemd[1]: Finished Remount Root and Kernel File Systems.
[   25.239163] systemd[1]: Finished Apply Kernel Variables.
[   25.259192] systemd[1]: Finished Load udev Rules from Credentials.
[   25.293061] systemd[1]: Reached target Preparation for Network.
[   25.311373] systemd[1]: FUSE Control File System was skipped because
of an unmet condition check (ConditionPathExists=/sys/fs/fuse/connections).
[   25.332906] systemd-journald[243]: Collecting audit messages is disabled.
[   25.389989] systemd[1]: Mounting Kernel Configuration File System...
[   25.419954] systemd[1]: Platform Persistent Storage Archival was
skipped because of an unmet condition check
(ConditionDirectoryNotEmpty=/sys/fs/pstore).
[   25.520662] systemd[1]: Starting Create Static Device Nodes in /dev
gracefully...
[   25.773080] systemd[1]: Mounted Kernel Configuration File System.
[   26.590013] systemd[1]: Started Journal Service.
[   27.260839] systemd-journald[243]: Received client request to flush
runtime journal.
[   27.337593] systemd-journald[243]:
/var/log/journal/0a50ee270f31407485fd48f832e4b18c/system.journal:
Realtime clock jumped backwards relative to last journal entry, rotating.
[   27.389171] systemd-journald[243]: Rotating system journal.
[   39.299015] macb f8008000.ethernet eth0: PHY
[f8008000.ethernet-ffffffff:07] driver [Micrel KSZ8081 or KSZ8091] (irq=150)
[   39.468997] macb f8008000.ethernet eth0: configuring for phy/rmii
link mode

> 
> Thanks,
> --
> Email: Herbert Xu <herbert@...dor.apana.org.au>
> Home Page: http://gondor.apana.org.au/~herbert/
> PGP Key: http://gondor.apana.org.au/~herbert/pubkey.txt
Thank you,
Ryan

View attachment "config.txt" of type "text/plain" (155897 bytes)

Powered by blists - more mailing lists