[16:15:04:987]U-Boot SPL 2021.01-g62a9e51344 (Mar 13 2023 - 15:43:18 +0000) [16:15:05:061]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:062]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:063]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:074]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:074]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:075]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:093]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:094]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:096]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:118]ti_i2c_eeprom_am6_get: Ignoring record id 255 [16:15:05:119]SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.3--v08.06.03 (Chill Capybar') [16:15:05:276]SPL initial stack usage: 13472 bytes [16:15:05:277]Trying to boot from MMC2 [16:15:05:497]Starting ATF on ARM64 core... [16:15:05:497] [16:15:05:512]NOTICE: BL31: v2.8(release):v2.8-226-g2fcd408bb3-dirty [16:15:05:513]NOTICE: BL31: Built : 15:42:56, Mar 13 2023 [16:15:05:524]I/TC: [16:15:05:545]I/TC: OP-TEE version: 3.20.0 (gcc version 9.2.1 20191025 (GNU Toolchain for the A-profile Architecture 9.2-2019.12 (arm-9.10))) #1 Mon Mar 13 15:43:08 UTC 2023 aarch64 [16:15:05:546]I/TC: WARNING: This OP-TEE configuration might be insecure! [16:15:05:559]I/TC: WARNING: Please check https://optee.readthedocs.io/en/latest/architecture/porting_guidelines.html [16:15:05:560]I/TC: Primary CPU initializing [16:15:05:574]I/TC: SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.3--v08.06.03 (Chill Capybar') [16:15:05:574]I/TC: HUK Initialized [16:15:05:575]I/TC: Activated SA2UL device [16:15:05:585]I/TC: Fixing SA2UL firewall owner for GP device [16:15:05:595]I/TC: Enabled firewalls for SA2UL TRNG device [16:15:05:595]I/TC: SA2UL TRNG initialized [16:15:05:595]I/TC: SA2UL Drivers initialized [16:15:05:596]I/TC: Primary CPU switching to normal world boot [16:15:05:687] [16:15:05:688]U-Boot SPL 2021.01-g62a9e51344 (Mar 13 2023 - 15:43:26 +0000) [16:15:05:716]SYSFW ABI: 3.1 (firmware rev 0x0008 '8.6.3--v08.06.03 (Chill Capybar') [16:15:05:749]Trying to boot from MMC2 [16:15:06:979] [16:15:06:979] [16:15:06:979]U-Boot 2021.01-g62a9e51344 (Mar 13 2023 - 15:43:26 +0000) [16:15:06:979] [16:15:06:980]SoC: J784S4 SR1.0 GP [16:15:06:980]Model: Texas Instruments J784S4 EVM [16:15:06:994]Board: J784S4X-EVM rev E2 [16:15:06:995]DRAM: 32 GiB [16:15:07:027]idle-statesFlash: 0 Bytes [16:15:07:044]MMC: mmc@4f80000: 0, mmc@4fb0000: 1 [16:15:07:135]Loading Environment from MMC... OK [16:15:07:135]In: serial@2880000 [16:15:07:135]Out: serial@2880000 [16:15:07:136]Err: serial@2880000 [16:15:07:147]am65_cpsw_nuss ethernet@46000000: K3 CPSW: nuss_ver: 0x6BA02102 cpsw_ver: 0x6BA82102 ale_ver: 0x00293904 Ports:1 mdio_freq:1000000 [16:15:07:160]Unidentified board claims J784S4X-EVM in eeprom header [16:15:07:161]Net: eth0: ethernet@46000000port@1 [16:15:07:162]Hit any key to stop autoboot: 0 [16:15:07:371]switch to partitions #0, OK [16:15:07:372]mmc1 is current device [16:15:08:019]SD/MMC found on device 1 [16:15:08:029]Failed to load 'boot.scr' [16:15:08:029]1331 bytes read in 14 ms (92.8 KiB/s) [16:15:08:030]Loaded env from uEnv.txt [16:15:08:030]Importing environment from mmc1 ... [16:15:08:030]Running uenvcmd ... [16:15:08:030]1 bytes read in 14 ms (0 Bytes/s) [16:15:08:030]Already setup. [16:15:08:030]gpio: pin gpio@22_17 (gpio 188) value is 1 [16:15:08:030]gpio: pin gpio@22_16 (gpio 187) value is 0 [16:15:08:030]gpio: pin gpio@22_13 (gpio 184) value is 1 [16:15:08:030]gpio: pin gpio@22_14 (gpio 185) value is 1 [16:15:08:030]gpio: pin gpio@22_15 (gpio 186) value is 1 [16:15:08:031]k3_r5f_rproc r5f@41000000: Core 1 is already in use. No rproc commands work [16:15:08:031]k3_r5f_rproc r5f@41400000: Core 2 is already in use. No rproc commands work [16:15:08:031]597188 bytes read in 50 ms (11.4 MiB/s) [16:15:08:031]Load Remote Processor 2 with data@addr=0x82000000 597188 bytes: Success! [16:15:08:031]301996 bytes read in 38 ms (7.6 MiB/s) [16:15:08:031]Load Remote Processor 3 with data@addr=0x82000000 301996 bytes: Success! [16:15:08:031]179128 bytes read in 33 ms (5.2 MiB/s) [16:15:08:032]Load Remote Processor 4 with data@addr=0x82000000 179128 bytes: Success! [16:15:08:032]179128 bytes read in 32 ms (5.3 MiB/s) [16:15:08:032]Load Remote Processor 5 with data@addr=0x82000000 179128 bytes: Success! [16:15:08:032]596856 bytes read in 50 ms (11.4 MiB/s) [16:15:08:033]Load Remote Processor 6 with data@addr=0x82000000 596856 bytes: Success! [16:15:08:034]179128 bytes read in 32 ms (5.3 MiB/s) [16:15:08:041]Load Remote Processor 7 with data@addr=0x82000000 179128 bytes: Success! [16:15:09:066]14684456 bytes read in 634 ms (22.1 MiB/s) [16:15:09:076]Load Remote Processor 8 with data@addr=0x82000000 14684456 bytes: Success! [16:15:09:353]14946600 bytes read in 646 ms (22.1 MiB/s) [16:15:09:354]Load Remote Processor 9 with data@addr=0x82000000 14946600 bytes: Success! [16:15:10:100]14553384 bytes read in 629 ms (22.1 MiB/s) [16:15:10:106]Load Remote Processor 10 with data@addr=0x82000000 14553384 bytes: Success! [16:15:11:133]14553384 bytes read in 630 ms (22 MiB/s) [16:15:11:147]Load Remote Processor 11 with data@addr=0x82000000 14553384 bytes: Success! [16:15:11:479]19079680 bytes read in 808 ms (22.5 MiB/s) [16:15:11:507]99445 bytes read in 19 ms (5 MiB/s) [16:15:11:528]13306 bytes read in 14 ms (927.7 KiB/s) [16:15:11:653]## Flattened Device Tree blob at 88000000 [16:15:11:667] Booting using the fdt blob at 0x88000000 [16:15:11:703] Loading Device Tree to 000000008fee4000, end 000000008fffffff ... OK [16:15:11:703] [16:15:11:704]Starting kernel ... [16:15:11:705] [16:15:12:882]I/TC: Secondary CPU 1 initializing [16:15:12:892]I/TC: Secondary CPU 1 switching to normal world boot [16:15:12:892]I/TC: Secondary CPU 2 initializing [16:15:12:893]I/TC: Secondary CPU 2 switching to normal world boot [16:15:12:893]I/TC: Secondary CPU 3 initializing [16:15:12:905]I/TC: Secondary CPU 3 switching to normal world boot [16:15:12:905]I/TC: Secondary CPU 2 initializing [16:15:12:906]I/TC: Secondary CPU 2 switching to normal world boot [16:15:12:916]I/TC: Secondary CPU 3 initializing [16:15:12:916]I/TC: Secondary CPU 3 switching to normal world boot [16:15:12:917]I/TC: Secondary CPU 4 initializing [16:15:12:923]I/TC: Secondary CPU 4 switching to normal world boot [16:15:12:938]I/TC: Secondary CPU 5 initializing [16:15:12:939]I/TC: Secondary CPU 5 switching to normal world boot [16:15:13:043]I/TC: Reserved shared memory is enabled [16:15:13:064]I/TC: Dynamic shared memory is enabled [16:15:13:064]I/TC: Normal World virtualization support is disabled [16:15:13:065]I/TC: Asynchronous notifications are disabled [16:15:14:199][ 1.314382] j721e-pcie 2910000.pcie: Failed to init phy [16:15:14:265][ 1.393004] cadence-qspi 47040000.spi: error -ENODEV: No Rx DMA available [16:15:14:349][ 1.473283] cadence-qspi 47050000.spi: error -ENODEV: No Rx DMA available [16:15:14:444][ 1.563068] am65-cpsw-nuss c200000.ethernet: Use random MAC address [16:15:16:344][ 3.453275] [ZJDBG] am64-cpsw-nuss ------ [16:15:16:375][ 3.499048] am65-cpsw-nuss c200000.ethernet: Use random MAC address [16:15:16:376][ 3.507331] [ZJDBG] am64-cpsw-nuss ------ [16:15:16:387][ 3.513797] debugfs: Directory 'pd:74' with parent 'pm_genpd' already present! [16:15:16:395][ 3.521034] debugfs: Directory 'pd:73' with parent 'pm_genpd' already present! [16:15:16:403][ 3.528257] debugfs: Directory 'pd:72' with parent 'pm_genpd' already present! [16:15:16:414][ 3.535935] debugfs: Directory 'pd:335' with parent 'pm_genpd' already present! [16:15:16:428][ 3.543243] debugfs: Directory 'pd:333' with parent 'pm_genpd' already present! [16:15:16:430][ 3.550561] debugfs: Directory 'pd:332' with parent 'pm_genpd' already present! [16:15:16:684]INIT: version 2.96 booting [16:15:16:813]hwclock: can't open '/dev/misc/rtc': No such file or directory [16:15:16:814]Mon Mar 13 15:52:52 UTC 2023 [16:15:16:827]hwclock: can't open '/dev/misc/rtc': No such file or directory [16:15:16:896]INIT: Entering runlevel: 5 [16:15:16:905]Configuring network interfaces... done. [16:15:16:948][ 4.076233] k3-dsp-rproc 64800000.dsp: configured DSP for IPC-only mode [16:15:16:958][ 4.083026] k3-dsp-rproc 64800000.dsp: DSP initialized in IPC-only mode [16:15:16:965][ 4.091735] k3-dsp-rproc 65800000.dsp: configured DSP for IPC-only mode [16:15:16:975][ 4.098455] k3-dsp-rproc 65800000.dsp: DSP initialized in IPC-only mode [16:15:16:980][ 4.107347] k3-dsp-rproc 66800000.dsp: configured DSP for IPC-only mode [16:15:16:992][ 4.114095] k3-dsp-rproc 66800000.dsp: DSP initialized in IPC-only mode [16:15:16:998][ 4.122946] k3-dsp-rproc 67800000.dsp: configured DSP for IPC-only mode [16:15:17:007][ 4.129676] k3-dsp-rproc 67800000.dsp: DSP initialized in IPC-only mode [16:15:17:022][ 4.147486] platform 41000000.r5f: configured R5F for IPC-only mode [16:15:17:033][ 4.154275] platform 41000000.r5f: R5F core initialized in IPC-only mode [16:15:17:034][ 4.163092] platform 5c00000.r5f: configured R5F for IPC-only mode [16:15:17:049][ 4.170021] platform 5c00000.r5f: R5F core initialized in IPC-only mode [16:15:17:050][ 4.177383] platform 5d00000.r5f: configured R5F for IPC-only mode [16:15:17:064][ 4.184234] platform 5d00000.r5f: R5F core initialized in IPC-only mode [16:15:17:065][ 4.193177] platform 5e00000.r5f: configured R5F for IPC-only mode [16:15:17:080][ 4.199883] platform 5e00000.r5f: R5F core initialized in IPC-only mode [16:15:17:081][ 4.207115] platform 5f00000.r5f: configured R5F for IPC-only mode [16:15:17:090][ 4.213808] platform 5f00000.r5f: R5F core initialized in IPC-only mode [16:15:17:103][ 4.222277] platform 5900000.r5f: configured R5F for IPC-only mode [16:15:17:103][ 4.229277] platform 5900000.r5f: R5F core initialized in IPC-only mode [16:15:17:109][ 4.236495] platform 5a00000.r5f: configured R5F for IPC-only mode [16:15:17:117][ 4.243786] platform 5a00000.r5f: R5F core initialized in IPC-only mode [16:15:17:136]Module Size Used by [16:15:17:136]ti_k3_r5_remoteproc 28672 0 [16:15:17:136]ti_k3_m4_remoteproc 20480 0 [16:15:17:137]ti_k3_dsp_remoteproc 20480 0 [16:15:17:146]virtio_rpmsg_bus 24576 0 [16:15:17:146]rpmsg_char 20480 0 [16:15:17:146]mount nfs start---------- [16:15:17:147]mount result:---------------------1 [16:15:17:154]Filesystem 1K-blocks Used Available Use% Mounted on [16:15:17:166]/dev/root 7390912 420356 6573708 6% / [16:15:17:166]devtmpfs 13849024 0 13849024 0% /dev [16:15:17:167]tmpfs 14767456 8 14767448 0% /run [16:15:17:177]tmpfs 51200 40 51160 0% /var/volatile [16:15:17:180]10.1.14.33:/data1/zhangjing24/nfs [16:15:17:186] 20971520 10970112 10001408 52% /mnt/nfs0 [16:15:17:197]mount result:---------------------2 [16:15:17:198]#!/bin/sh [16:15:17:198] [16:15:17:198]echo "nfs data write:####################" [16:15:17:198]rm -rf /mnt/nfs0/tievm/random.bin [16:15:17:198]dd if=/dev/zero of=/mnt/nfs0/tievm/random.bin bs=1024 count=10240 [16:15:17:209]echo "nfs data write end#################" [16:15:17:209] [16:15:17:211] [16:15:17:227]echo "nfs data read:*******************[ 4.338108] SError Interrupt on CPU0, code 0xbf000002 -- SError [16:15:17:227][ 4.338110] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.10.162-g76b3e88d56 #1 [16:15:17:228][ 4.338112] Hardware name: Texas Instruments J784S4 EVM (DT) [16:15:17:240][ 4.338113] pstate: 00000085 (nzcv daIf -PAN -UAO -TCO BTYPE=--) [16:15:17:240][ 4.338114] pc : _raw_spin_unlock+0x38/0x48 [16:15:17:242][ 4.338115] lr : handle_irq_event+0x40/0xe0 [16:15:17:249][ 4.338116] sp : ffff8000112bbed0 [16:15:17:263][ 4.338117] x29: ffff8000112bbed0 x28: ffff8000111219c0 [16:15:17:263][ 4.338120] x27: 00000000ffee3504 x26: ffff8000112bc000 [16:15:17:264][ 4.338123] x25: ffff0008f20f2680 x24: ffff0008f36f0060 [16:15:17:280][ 4.338125] x23: ffff8000111219c0 x22: ffff0008f36f0000 [16:15:17:280][ 4.338127] x21: ffff0008f211f8dc x20: ffff0008f211f8dc [16:15:17:282][ 4.338129] x19: ffff0008f211f800 x18: 0000000000000002 [16:15:17:293][ 4.338131] x17: 000000008a14937f x16: 00000000af384a83 [16:15:17:293][ 4.338133] x15: 0000000000000000 x14: 0000000000000161 [16:15:17:294][ 4.338135] x13: 0000000000000000 x12: 0000000000000001 [16:15:17:311][ 4.338138] x11: 0000000000000040 x10: ffff8000111a0750 [16:15:17:311][ 4.338140] x9 : ffff8000111a0748 x8 : ffff0008f2401940 [16:15:17:313][ 4.338142] x7 : 0000000000000000 x6 : 0000000000000000 [16:15:17:324][ 4.338144] x5 : ffff800f6b443000 x4 : ffff8000112bbf00 [16:15:17:325][ 4.338147] x3 : ffff800f6b443000 x2 : ffff0008f211f800 [16:15:17:325][ 4.338149] x1 : ffff8000111219c0 x0 : 0000000100010001 [16:15:17:339][ 4.338152] Kernel panic - not syncing: Asynchronous SError Interrupt [16:15:17:340][ 4.338153] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 5.10.162-g76b3e88d56 #1 [16:15:17:353][ 4.338154] Hardware name: Texas Instruments J784S4 EVM (DT) [16:15:17:353][ 4.338155] Call trace: [16:15:17:353][ 4.338156] dump_backtrace+0x0/0x1a0 [16:15:17:354][ 4.338157] show_stack+0x18/0x68 [16:15:17:370][ 4.338158] dump_stack+0xd0/0x12c [16:15:17:370][ 4.338159] panic+0x16c/0x334 [16:15:17:370][ 4.338160] nmi_panic+0x8c/0x90 [16:15:17:370][ 4.338161] arm64_serror_panic+0x78/0x84 [16:15:17:371][ 4.338162] do_serror+0x38/0x98 [16:15:17:386][ 4.338163] el1_error+0x90/0x110 [16:15:17:386][ 4.338164] _raw_spin_unlock+0x38/0x48 [16:15:17:387][ 4.338165] handle_level_irq+0xb8/0x140 [16:15:17:387][ 4.338166] generic_handle_irq+0x30/0x48 [16:15:17:401][ 4.338167] ti_sci_inta_irq_handler+0xc4/0x160 [16:15:17:401][ 4.338168] generic_handle_irq+0x30/0x48 [16:15:17:401][ 4.338169] __handle_domain_irq+0x64/0xc0 [16:15:17:401][ 4.338170] gic_handle_irq+0x58/0x128 [16:15:17:402][ 4.338171] el1_irq+0xcc/0x180 [16:15:17:421][ 4.338172] arch_cpu_idle+0x18/0x28 [16:15:17:421][ 4.338173] default_idle_call+0x20/0x68 [16:15:17:421][ 4.338174] do_idle+0xc0/0x128 [16:15:17:421][ 4.338175] cpu_startup_entry+0x24/0x60 [16:15:17:422][ 4.338176] rest_init+0xd4/0xe4 [16:15:17:434][ 4.338177] arch_call_rest_init+0x10/0x1c [16:15:17:434][ 4.338178] start_kernel+0x478/0x4b0 [16:15:17:434][ 4.338202] SMP: stopping secondary CPUs [16:15:17:451][ 4.338203] Kernel Offset: disabled [16:15:17:451][ 4.338205] CPU features: 0x28040022,20006008 [16:15:17:451][ 4.338205] Memory Limit: none [16:15:17:451]**" [16:15:17:451]rm -rf /run/test.bin [16:15:17:452]dd if=/mnt/nfs0/tievm/Image of=/run/test.bin bs=1024 [16:15:17:462]echo "nfs data read end******************" [16:15:17:462] [16:15:17:462]sync [16:15:17:463] [16:15:17:479]df