AGL with OPTEE on Raspberry Pi 3 #help


danojib@...
 

Hi everyone. I'm totally new to this mailing list and not even sure if this is the right way to ask question. I'm currently trying to put OPTEE and AGL together on Raspberry Pi 3. There's a github repo that combined OPTEE with Raspbian (https://github.com/benhaz1024/raspbian-tee). I've been following the similar procedures. It looks like I can run the optee_example_hello_world, but now the issue is that I don't see the homescreen anymore. I can only access its shell via USB serial, and the screen hangs in the middle of booting log (always at the same spot, shown below).

[ 21.773144] Bluetooth: HCI UART driver ver 2.3
[ 21.777909] Bluetooth: HCI UART protocol H4 registered
[ 21.783272] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 21.814435] Bluetooth: HCI UART protocol Broadcom registered
[ 21.971751] Bluetooth: RFCOMM TTY layer initialized
[ 21.986261] Bluetooth: RFCOMM socket layer initialized
[ 21.991673] Bluetooth: RFCOMM ver 1.11
(Blinking cursor here)


FYI, these are newly added files (which are outcome of build) to the SD card (besides tee-related files):

/boot/armstub8.bin
/boot/uboot.env
/root/lib/modules/4.14.98-v7/*

and these are overwritten files:

/boot/bcm2710-rpi-3-b.dtb
/boot/bcm2710-rpi-3-b-plus.dtb
/boot/config.txt
/boot/uImage

In case anyone's wondering, I've also copied the full boot log of both pure AGL and AGL with OPTEE.
AGL: https://pastebin.com/a6YzqKJk

AGL+OPTEE: https://pastebin.com/hNUv63aD

What I can guess is after OPTEE stuff is copied over to the SD card, the modules that were used in the pure AGL is not used anymore. The pure AGL module version is 4.9.80, while the newly added one says 4.14.98-v7. It looks like Weston is what GUI is related, and it isn't included in the new directory.

I'm using pre-built image (6.99.5-agl-demo-platform-crosssdk-raspberrypi3.wic). Can anyone help me walk through?



Leon Anavi
 

Hi,

Please keep in mind that AGL is significantly different from Raspbian. A walk-through for building AGL from scratch for Raspberry Pi 3 and 4 is available at the following links:
https://wiki.automotivelinux.org/agl-distro/agl-raspberrypi
https://docs.automotivelinux.org/docs/en/master/getting_started/reference/getting-started/machines/raspberrypi.html

Best regards,
Leon

On 2.03.20 г. 21:12 ч., danojib@... wrote:

Hi everyone. I'm totally new to this mailing list and not even sure if this is the right way to ask question. I'm currently trying to put OPTEE and AGL together on Raspberry Pi 3. There's a github repo that combined OPTEE with Raspbian (https://github.com/benhaz1024/raspbian-tee). I've been following the similar procedures. It looks like I can run the optee_example_hello_world, but now the issue is that I don't see the homescreen anymore. I can only access its shell via USB serial, and the screen hangs in the middle of booting log (always at the same spot, shown below).

[ 21.773144] Bluetooth: HCI UART driver ver 2.3
[ 21.777909] Bluetooth: HCI UART protocol H4 registered
[ 21.783272] Bluetooth: HCI UART protocol Three-wire (H5) registered
[ 21.814435] Bluetooth: HCI UART protocol Broadcom registered
[ 21.971751] Bluetooth: RFCOMM TTY layer initialized
[ 21.986261] Bluetooth: RFCOMM socket layer initialized
[ 21.991673] Bluetooth: RFCOMM ver 1.11
(Blinking cursor here)


FYI, these are newly added files (which are outcome of build) to the SD card (besides tee-related files):

/boot/armstub8.bin
/boot/uboot.env
/root/lib/modules/4.14.98-v7/*

and these are overwritten files:

/boot/bcm2710-rpi-3-b.dtb
/boot/bcm2710-rpi-3-b-plus.dtb
/boot/config.txt
/boot/uImage

In case anyone's wondering, I've also copied the full boot log of both pure AGL and AGL with OPTEE.
AGL: https://pastebin.com/a6YzqKJk

AGL+OPTEE: https://pastebin.com/hNUv63aD

What I can guess is after OPTEE stuff is copied over to the SD card, the modules that were used in the pure AGL is not used anymore. The pure AGL module version is 4.9.80, while the newly added one says 4.14.98-v7. It looks like Weston is what GUI is related, and it isn't included in the new directory.

I'm using pre-built image (6.99.5-agl-demo-platform-crosssdk-raspberrypi3.wic). Can anyone help me walk through?


-- 
Leon Anavi
Software Engineer
konsulko.com


danojib@...
 

Hi again,

Thank you for your reply. I actually took a look at the wiki before, and I managed to build the pure AGL (7.99.3) for rpi3 B+. In fact, I'm still stuck at the similar step. As I mentioned before, I followed https://github.com/benhaz1024/raspbian-tee to build OPTEE for AGL. While the build was successful, many of the modules fail to be loaded during the boot time, and I can't even log in. I'm guessing this is because now there are two module directories at /root/lib/modules/ (There are currently 4.14.98 dir from pure AGL build and 4.14.98-v7 from OPTEE build), and all the modified u-boot and uImage files are looking at 4.14.98-v7 directory. If this is the case, what would be a possible approach? Should I find a way to build OPTEE stuff along with AGL instead of building separately and combining? FYI I've attached the current boot log.

NOTICE:  Booting Trusted Firmware
NOTICE:  BL1: v2.2(debug):v2.2-dirty
NOTICE:  BL1: Built : 14:57:44, Mar 11 2020
INFO:    BL1: RAM 0x100ee000 - 0x100f7000
INFO:    BL1: cortex_a53: CPU workaround for 843419 was applied
INFO:    BL1: cortex_a53: CPU workaround for 855873 was applied
NOTICE:  rpi3: Detected: Raspberry Pi 3 Model B+ (1GB, Sony, UK) [0x00a020d3]
INFO:    BL1: Loading BL2
INFO:    Loading image id=1 at address 0x100b4000
INFO:    Image id=1 loaded: 0x100b4000 - 0x100bc410
NOTICE:  BL1: Booting BL2
INFO:    Entry point address = 0x100b4000
INFO:    SPSR = 0x3c5
NOTICE:  BL2: v2.2(debug):v2.2-dirty
NOTICE:  BL2: Built : 14:57:46, Mar 11 2020
INFO:    BL2: Doing platform setup
INFO:    BL2: Loading image id 3
INFO:    Loading image id=3 at address 0x100e0000
INFO:    Image id=3 loaded: 0x100e0000 - 0x100e9078
INFO:    BL2: Loading image id 4
INFO:    Loading image id=4 at address 0x10100000
INFO:    Image id=4 loaded: 0x10100000 - 0x1010001c
INFO:    OPTEE ep=0x10100000
INFO:    OPTEE header info:
INFO:          magic=0x4554504f
INFO:          version=0x2
INFO:          arch=0x0
INFO:          flags=0x0
INFO:          nb_images=0x1
INFO:    BL2: Loading image id 21
INFO:    Loading image id=21 at address 0x10100000
INFO:    Image id=21 loaded: 0x10100000 - 0x101436b8
INFO:    BL2: Skip loading image id 22
INFO:    BL2: Loading image id 5
INFO:    Loading image id=5 at address 0x11000000
INFO:    Image id=5 loaded: 0x11000000 - 0x11068bf8
INFO:    BL33 will boot in Non-secure AArch32 Hypervisor mode
NOTICE:  BL1: Booting BL31
INFO:    Entry point address = 0x100e0000
INFO:    SPSR = 0x3cd
NOTICE:  BL31: v2.2(debug):v2.2-dirty
NOTICE:  BL31: Built : 14:57:48, Mar 11 2020
INFO:    BL31: Initializing runtime services
INFO:    BL31: cortex_a53: CPU workaround for 843419 was applied
INFO:    BL31: cortex_a53: CPU workaround for 855873 was applied
INFO:    BL31: Initializing BL32
D/TC:0 0 get_aslr_seed:1313 Warning: no ASLR seed
D/TC:0 0 add_phys_mem:583 TEE_SHMEM_START type NSEC_SHM 0x08000000 size 0x00400000
D/TC:0 0 add_phys_mem:583 TA_RAM_START type TA_RAM 0x10800000 size 0x00800000
D/TC:0 0 add_phys_mem:583 VCORE_UNPG_RW_PA type TEE_RAM_RW 0x10141000 size 0x006bf000
D/TC:0 0 add_phys_mem:583 VCORE_UNPG_RX_PA type TEE_RAM_RX 0x10100000 size 0x00041000
D/TC:0 0 add_phys_mem:583 ROUNDDOWN(0x3f215040, CORE_MMU_PGDIR_SIZE) type IO_NSEC 0x3f200000 size 0x00100000
D/TC:0 0 verify_special_mem_areas:521 No NSEC DDR memory area defined
D/TC:0 0 add_va_space:622 type RES_VASPACE size 0x00a00000
D/TC:0 0 add_va_space:622 type SHM_VASPACE size 0x02000000
D/TC:0 0 dump_mmap_table:734 type TEE_RAM_RX   va 0x10100000..0x10140fff pa 0x10100000..0x10140fff size 0x00041000 (smallpg)
D/TC:0 0 dump_mmap_table:734 type TEE_RAM_RW   va 0x10141000..0x107fffff pa 0x10141000..0x107fffff size 0x006bf000 (smallpg)
D/TC:0 0 dump_mmap_table:734 type RES_VASPACE  va 0x10800000..0x111fffff pa 0x00000000..0x009fffff size 0x00a00000 (pgdir)
D/TC:0 0 dump_mmap_table:734 type SHM_VASPACE  va 0x11200000..0x131fffff pa 0x00000000..0x01ffffff size 0x02000000 (pgdir)
D/TC:0 0 dump_mmap_table:734 type NSEC_SHM     va 0x13200000..0x135fffff pa 0x08000000..0x083fffff size 0x00400000 (pgdir)
D/TC:0 0 dump_mmap_table:734 type TA_RAM       va 0x13600000..0x13dfffff pa 0x10800000..0x10ffffff size 0x00800000 (pgdir)
D/TC:0 0 dump_mmap_table:734 type IO_NSEC      va 0x13e00000..0x13efffff pa 0x3f200000..0x3f2fffff size 0x00100000 (pgdir)
D/TC:0 0 core_mmu_alloc_l2:278 L2 table used: 1/5
I/TC:
D/TC:0 0 init_canaries:164 #Stack canaries for stack_tmp[0] with top at 0x10176038
D/TC:0 0 init_canaries:164 watch *0x1017603c
D/TC:0 0 init_canaries:164 #Stack canaries for stack_tmp[1] with top at 0x10176878
D/TC:0 0 init_canaries:164 watch *0x1017687c
D/TC:0 0 init_canaries:164 #Stack canaries for stack_tmp[2] with top at 0x101770b8
D/TC:0 0 init_canaries:164 watch *0x101770bc
D/TC:0 0 init_canaries:164 #Stack canaries for stack_tmp[3] with top at 0x101778f8
D/TC:0 0 init_canaries:164 watch *0x101778fc
D/TC:0 0 init_canaries:165 #Stack canaries for stack_abt[0] with top at 0x1016be38
D/TC:0 0 init_canaries:165 watch *0x1016be3c
D/TC:0 0 init_canaries:165 #Stack canaries for stack_abt[1] with top at 0x1016c678
D/TC:0 0 init_canaries:165 watch *0x1016c67c
D/TC:0 0 init_canaries:165 #Stack canaries for stack_abt[2] with top at 0x1016ceb8
D/TC:0 0 init_canaries:165 watch *0x1016cebc
D/TC:0 0 init_canaries:165 #Stack canaries for stack_abt[3] with top at 0x1016d6f8
D/TC:0 0 init_canaries:165 watch *0x1016d6fc
D/TC:0 0 init_canaries:167 #Stack canaries for stack_thread[0] with top at 0x1016f738
D/TC:0 0 init_canaries:167 watch *0x1016f73c
D/TC:0 0 init_canaries:167 #Stack canaries for stack_thread[1] with top at 0x10171778
D/TC:0 0 init_canaries:167 watch *0x1017177c
D/TC:0 0 init_canaries:167 #Stack canaries for stack_thread[2] with top at 0x101737b8
D/TC:0 0 init_canaries:167 watch *0x101737bc
D/TC:0 0 init_canaries:167 #Stack canaries for stack_thread[3] with top at 0x101757f8
D/TC:0 0 init_canaries:167 watch *0x101757fc
I/TC: OP-TEE version: 3.8.0-98-g97c7050 (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 Wed Mar 11 19:56:54 UTC 2020 arm
D/TC:0 0 init_primary_helper:1171 Executing at offset 0 with virtual load address 0x10100000
D/TC:0 0 check_ta_store:636 TA store: "Secure Storage TA"
D/TC:0 0 check_ta_store:636 TA store: "REE"
D/TC:0 0 mobj_mapped_shm_init:447 Shared memory address range: 11200000, 13200000
I/TC: Initialized
D/TC:0 0 init_primary_helper:1184 Primary CPU switching to normal world boot
INFO:    BL31: Preparing for EL3 exit to normal world
INFO:    Entry point address = 0x11000000
INFO:    SPSR = 0x1da


U-Boot 2018.07 (Mar 11 2020 - 14:57:13 -0500)

DRAM:  948 MiB
RPI 3 Model B+ (0xa020d3)
MMC:   mmc@7e202000: 0, sdhci@7e300000: 1
Loading Environment from FAT... OK
In:    serial
Out:   serial
Err:   serial
Net:   No ethernet found.
starting USB...
USB0:   scanning bus 0 for devices... 4 USB Device(s) found
       scanning usb for storage devices... 0 Storage Device(s) found
Hit any key to stop autoboot:  0
4944720 bytes read in 206 ms (22.9 MiB/s)
26041 bytes read in 4 ms (6.2 MiB/s)
## Booting kernel from Legacy Image at 02000000 ...
   Image Name:   linux kernel image
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    4944656 Bytes = 4.7 MiB
   Load Address: 02000000
   Entry Point:  02000000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 01000000
   Booting using the fdt blob at 0x1000000
   Loading Kernel Image ... OK
   reserving fdt memory region: addr=0 size=1000
   Loading Device Tree to 3af47000, end 3af505b8 ... OK

Starting kernel ...

D/TC:0   tee_entry_exchange_capabilities:101 Dynamic shared memory is disabled
[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.14.98-v7 (hakkyung@ryanxu-Aspire-TC-865) (gcc version 8.3.0 (GNU Toolchain for the A-profile Architecture 8.3-2019.03 (arm-rel-8.36))) #1 SMP Wed Mar 11 15:27:56 CDT 2020
[    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
[    0.000000] CPU: div instructions available: patching division code
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B+
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] cma: Reserved 8 MiB at 0x3a400000
[    0.000000] On node 0 totalpages: 242688
[    0.000000] free_area_init_node: node 0, pgdat 80c85700, node_mem_map b9bab000
[    0.000000]   Normal zone: 2133 pages used for memmap
[    0.000000]   Normal zone: 0 pages reserved
[    0.000000]   Normal zone: 242688 pages, LIFO batch:31
[    0.000000] percpu: Embedded 17 pages/cpu @bb39d000 s38668 r8192 d22772 u69632
[    0.000000] pcpu-alloc: s38668 r8192 d22772 u69632 alloc=17*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
[    0.000000] Kernel command line: console=tty0 console=ttyS0,115200 root=/dev/mmcblk0p2 rw rootfs=ext4 smsc95xx.macaddr=b8:27:eb:d7:db:a7 ignore_loglevel dma.dmachans=0x7f35 memmap=16M$256M rootwait 8250.nr_uarts=1 elevator=deadline fsck.repair=yes bcm2708_fb.fbwidth=1920 bcm2708_fb.fbheight=1080 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.fiq_enable=0 dwc_otg.fiq_fsm_enable=0 dwc_otg.nak_holdoff=0
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] Memory: 940232K/970752K available (7168K kernel code, 577K rwdata, 2084K rodata, 1024K init, 696K bss, 22328K reserved, 8192K cma-reserved)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xbb800000 - 0xff800000   (1088 MB)
[    0.000000]     lowmem  : 0x80000000 - 0xbb400000   ( 948 MB)
[    0.000000]     modules : 0x7f000000 - 0x80000000   (  16 MB)
[    0.000000]       .text : 0x80008000 - 0x80800000   (8160 kB)
[    0.000000]       .init : 0x80b00000 - 0x80c00000   (1024 kB)
[    0.000000]       .data : 0x80c00000 - 0x80c9076c   ( 578 kB)
[    0.000000]        .bss : 0x80c98030 - 0x80d46420   ( 697 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 25311 entries in 75 pages
[    0.000000] Hierarchical RCU implementation.
[    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
[    0.000008] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
[    0.000023] Switching to timer-based delay loop, resolution 52ns
[    0.000278] Console: colour dummy device 80x30
[    0.000953] console [tty0] enabled
[    0.000993] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
[    0.001033] pid_max: default: 32768 minimum: 301
[    0.001358] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.001393] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
[    0.002335] Disabling memory control group subsystem
[    0.002428] CPU: Testing write buffer coherency: ok
[    0.002857] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.003266] Setting up static identity map for 0x100000 - 0x10003c
[    0.003404] Hierarchical SRCU implementation.
[    0.004078] smp: Bringing up secondary CPUs ...
[    1.040635] CPU1: failed to come online
[    2.081365] CPU2: failed to come online
[    3.122099] CPU3: failed to come online
[    3.122152] smp: Brought up 1 node, 1 CPU
[    3.122174] SMP: Total of 1 processors activated (38.40 BogoMIPS).
[    3.122196] CPU: All CPU(s) started in HYP mode.
[    3.122214] CPU: Virtualization extensions available.
[    3.122988] devtmpfs: initialized
[    3.133217] random: get_random_u32 called from bucket_table_alloc+0x108/0x238 with crng_init=0
[    3.134041] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
[    3.134353] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    3.134398] futex hash table entries: 1024 (order: 4, 65536 bytes)
[    3.135045] pinctrl core: initialized pinctrl subsystem
[    3.135819] NET: Registered protocol family 16
[    3.138180] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[    3.142913] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
[    3.142945] hw-breakpoint: maximum watchpoint size is 8 bytes.
[    3.143143] Serial: AMBA PL011 UART driver
[    3.144898] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
[    3.145359] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
[    3.175861] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb813000, dmachans=0x1
[    3.177299] SCSI subsystem initialized
[    3.177532] usbcore: registered new interface driver usbfs
[    3.177606] usbcore: registered new interface driver hub
[    3.177760] usbcore: registered new device driver usb
[    3.182476] raspberrypi-firmware soc:firmware: Attached to firmware from 2019-02-12 19:42
[    3.184053] clocksource: Switched to clocksource arch_sys_counter
[    3.264838] VFS: Disk quotas dquot_6.6.0
[    3.264964] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    3.265186] FS-Cache: Loaded
[    3.265409] CacheFiles: Loaded
[    3.273157] NET: Registered protocol family 2
[    3.273888] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
[    3.274017] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
[    3.274282] TCP: Hash tables configured (established 8192 bind 8192)
[    3.274442] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    3.274505] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    3.274757] NET: Registered protocol family 1
[    3.275298] RPC: Registered named UNIX socket transport module.
[    3.275322] RPC: Registered udp transport module.
[    3.275341] RPC: Registered tcp transport module.
[    3.275359] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    3.277088] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
[    3.279850] workingset: timestamp_bits=14 max_order=18 bucket_order=4
[    3.287646] FS-Cache: Netfs 'nfs' registered for caching
[    3.288285] NFS: Registering the id_resolver key type
[    3.288330] Key type id_resolver registered
[    3.288349] Key type id_legacy registered
[    3.288380] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    3.290467] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[    3.290684] io scheduler noop registered
[    3.290706] io scheduler deadline registered (default)
[    3.290894] io scheduler cfq registered
[    3.290915] io scheduler mq-deadline registered
[    3.290934] io scheduler kyber registered
[    3.293250] BCM2708FB: allocated DMA memory fa500000
[    3.293296] BCM2708FB: allocated DMA channel 0 @ bb813000
[    3.354690] Console: switching to colour frame buffer device 240x67
[    3.388609] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[    3.390141] bcm2835-rng 3f104000.rng: hwrng registered
[    3.390420] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
[    3.391084] vc-sm: Videocore shared memory driver
[    3.391510] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
[    3.401107] brd: module loaded
[    3.410187] loop: module loaded
[    3.410326] Loading iSCSI transport class v2.0-870.
[    3.411172] libphy: Fixed MDIO Bus: probed
[    3.411403] usbcore: registered new interface driver lan78xx
[    3.411617] usbcore: registered new interface driver smsc95xx
[    3.411795] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    3.412261] dwc_otg 3f980000.usb: base=0xf0980000
[    3.612641] Core Release: 2.80a
[    3.612749] Setting default values for core params
[    3.612913] Finished setting default values for core params
[    3.813313] Using Buffer DMA mode
[    3.813420] Periodic Transfer Interrupt Enhancement - disabled
[    3.813588] Multiprocessor Interrupt Enhancement - disabled
[    3.813751] OTG VER PARAM: 0, OTG VER FLAG: 0
[    3.813959] Dedicated Tx FIFOs mode
[    3.814342] dwc_otg: Microframe scheduler enabled
[    3.814542] dwc_otg 3f980000.usb: DWC OTG Controller
[    3.814714] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
[    3.814948] dwc_otg 3f980000.usb: irq 39, io mem 0x00000000
[    3.815155] Init: Port Power? op_state=1
[    3.815274] Init: Power Port (0)
[    3.815574] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    3.815774] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    3.815983] usb usb1: Product: DWC OTG Controller
[    3.816124] usb usb1: Manufacturer: Linux 4.14.98-v7 dwc_otg_hcd
[    3.816301] usb usb1: SerialNumber: 3f980000.usb
[    3.824454] hub 1-0:1.0: USB hub found
[    3.831999] hub 1-0:1.0: 1 port detected
[    3.839958] dwc_otg: FIQ disabled
[    3.847323] dwc_otg: NAK holdoff disabled
[    3.854599] dwc_otg: FIQ split-transaction FSM disabled
[    3.861879] Module dwc_common_port init
[    3.869355] usbcore: registered new interface driver usb-storage
[    3.876796] mousedev: PS/2 mouse device common for all mice
[    3.884190] IR NEC protocol handler initialized
[    3.891458] IR RC5(x/sz) protocol handler initialized
[    3.898868] IR RC6 protocol handler initialized
[    3.906245] IR JVC protocol handler initialized
[    3.913478] IR Sony protocol handler initialized
[    3.920662] IR SANYO protocol handler initialized
[    3.927734] IR Sharp protocol handler initialized
[    3.934737] IR MCE Keyboard/mouse protocol handler initialized
[    3.941728] IR XMP protocol handler initialized
[    3.949548] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
[    3.956980] bcm2835-cpufreq: min=600000 max=1400000
[    3.964461] sdhci: Secure Digital Host Controller Interface driver
[    3.971537] sdhci: Copyright(c) Pierre Ossman
[    3.978941] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
[    3.986406] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
[    3.993690] sdhci-pltfm: SDHCI platform and OF driver helper
[    4.002286] ledtrig-cpu: registered to indicate activity on CPUs
[    4.009750] hidraw: raw HID events driver (C) Jiri Kosina
[    4.017312] usbcore: registered new interface driver usbhid
[    4.024695] usbhid: USB HID core driver
[    4.032707] vchiq: vchiq_init_state: slot_zero = ba580000, is_master = 0
[    4.041754] [vc_sm_connected_init]: start
[    4.051976] [vc_sm_connected_init]: end - returning 0
[    4.059474] Indeed it is in host mode hprt0 = 00021501
[    4.127220] optee: probing for conduit method from DT.
[    4.141834] optee: initialized driver
[    4.149328] Initializing XFRM netlink socket
[    4.156391] NET: Registered protocol family 17
[    4.163565] Key type dns_resolver registered
[    4.171234] Registering SWP/SWPB emulation handler
[    4.178900] registered taskstats version 1
[    4.192021] uart-pl011 3f201000.serial: cts_event_workaround enabled
[    4.199469] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
[    4.208428] console [ttyS0] disabled
[    4.215814] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 166, base_baud = 31250000) is a 16550
[    4.337202] usb 1-1: new high-speed USB device number 2 using dwc_otg
[    4.337331] Indeed it is in host mode hprt0 = 00001101
[    4.637877] usb 1-1: New USB device found, idVendor=0424, idProduct=2514
[    4.637886] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    4.638381] hub 1-1:1.0: USB hub found
[    4.638450] hub 1-1:1.0: 4 ports detected
[    5.400220] console [ttyS0] enabled
[    5.412193] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
[    5.425659] mmc-bcm2835 3f300000.mmc: DMA channel allocated
[    5.494644] sdhost: log_buf @ ba513000 (fa513000)
[    5.545387] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[    5.560005] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    5.574651] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[    5.590357] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[    5.603214] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[    5.617244] of_cfs_init
[    5.647140] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
[    5.661328] of_cfs_init: OK
[    5.672347] Waiting for root device /dev/mmcblk0p2...
[    5.685286] random: fast init done
[    5.741443] mmc0: host does not support reading read-only switch, assuming write-enable
[    5.760146] mmc0: new high speed SDHC card at address 59b4
[    5.773783] mmcblk0: mmc0:59b4 SD    14.7 GiB
[    5.787489]  mmcblk0: p1 p2
[    5.811172] mmc1: new high speed SDIO card at address 0001
[    5.825143] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
[    5.839761] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    5.856629] hub 1-1.1:1.0: USB hub found
[    5.868630] hub 1-1.1:1.0: 3 ports detected
[    5.941508] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    5.957614] VFS: Mounted root (ext4 filesystem) on device 179:2.
[    5.979118] devtmpfs: mounted
[    5.993208] Freeing unused kernel memory: 1024K
[    6.234587] dwc_otg_handle_wakeup_detected_intr lxstate = 2
[    6.261807] systemd[1]: System time before build time, advancing clock.
[    6.376798] NET: Registered protocol family 10
[    6.390402] Segment Routing with IPv6
[    6.434283] systemd[1]: systemd 239 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYPT -GNUTLS +ACL +XZ -LZ4 -SECCOMP +BLKID -ELFUTILS +KMOD -IDN2 -IDN -PCRE2 default-hierarchy=hybrid)
[    6.473838] systemd[1]: Detected architecture arm.

Welcome to Automotive Grade Linux 7.99.3 (halibut)!

[    6.524992] systemd[1]: Set hostname to <raspberrypi3>.
[    6.815230] systemd[1]: File /lib/systemd/system/systemd-journald.service:36 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling.
[    6.841978] usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg
[    6.858180] systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.)
[    6.902803] systemd[1]: Configuration file /lib/systemd/system/systemd-udevd.service.d/udev-shared.conf is marked executable. Please remove executable permission bits. Proceeding anyway.
[    7.024780] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
[    7.041963] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    7.331661] libphy: lan78xx-mdiobus: probed
[    7.381180] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
[    7.749830] random: systemd: uninitialized urandom read (16 bytes read)
[    7.769898] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-[    7.789111] random: crng init done
getty.slice.
[    7.830158] systemd[1]: Created slice system-afm\x2duser\x2dsession.slice.
[  OK  ] Created slice system-afm\x2duser\x2dsession.slice.
[    7.875107] systemd[1]: Created slice User and Session Slice.
[  OK  ] Created slice User and Session Slice.
[    7.924978] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[  OK  ] Listening on Journal Socket (/dev/log).
[  OK  ] Listening on udev Kernel Socket.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[  OK  ] Reached target Swap.
[  OK  ] Created slice platform.slice.
[  OK  ] Reached target Remote File Systems.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Reached target Paths.
[  OK  ] Reached target Slices.
[  OK  ] Listening on Journal Socket.
         Starting udev Coldplug all Devices...
         Starting Load Kernel Modules...
         Starting Create list of required st…ce nodes for the current kernel...
         Mounting Kernel Debug File System...
[  OK  ] Started Hardware RNG Entropy [    8.460147] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Gatherer Daemon.
         Starting Journal Service...
         Mounting Temporary Directory (/tmp)...
         Mounting POSIX Message Queue File System...
         Starting Remount Root and Kernel File Systems...
[  OK  ] Listening on initctl Compatibility Named Pipe.
[  OK  ] Reached target Host and Network Name Lookups.
[  OK  ] Listening on Network Service Netlink Socket.
[    8.701350] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
[DEPEND] Dependency failed for Driver configuration for Unicens2.
[  OK  ] Started Journal Service.
[  OK  ] Started Create list of required sta…vice nodes for the current kernel.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Temporary Directory (/tmp).
[  OK  ] Mounted POSIX Message Queue File System.
[  OK  ] Started Remount Root and Kernel File Systems.
[  OK  ] Started udev Coldplug all Devices.
         Starting Create Static Device Nodes in /dev...
         Starting Flush Journal to Persistent Storage...
         Mounting Kernel Configuration File System...
         Starting Apply Kernel Variables...
[  OK  ] Started Create Static Device Nodes in /dev.
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Started Apply Kernel Variables.
[    9.303612] systemd-journald[86]: Received request to flush runtime journal from PID 1
         Starting udev Kernel Device Manager...
[  OK  ] Reached target Local File Systems (Pre).
[  OK  ] Reached target Containers.
         Mounting /var/volatile...
[  OK  ] Started Flush Journal to Persistent Storage.
[  OK  ] Mounted /var/volatile.
         Starting Load/Save Random Seed...
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Found device /dev/ttyS0.
[  OK  ] Found device /dev/serial1.
[   10.583181] brcmfmac: F1 signature read @0x18000000=0x15264345
[   10.662492] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006
[   10.716510] usbcore: registered new interface driver brcmfmac
[   11.087730] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
[   11.137561] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28
[  OK  ] Found device /dev/mmcblk0p1.
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
         Starting Load Kernel Modules...
         Mounting /boot...
[   12.809329] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
[FAILED] Failed to start Load Kernel Modules.
See 'systemctl status systemd-modules-load.service' for details.
[  OK  ] Mounted /boot.
         Starting Load/Save RF Kill Switch Status...
[  OK  ] Reached target Local File Systems.
         Starting Create Volatile Files and Directories...
[  OK  ] Started Load/Save RF Kill Switch Status.
[  OK  ] Started Create Volatile Files and Directories.
         Starting Update UTMP about System Boot/Shutdown...
         Starting Network Service...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Service.
[  OK  ] Reached target System Initialization.
         Starting cynara-agent.socket.
[  OK  ] Listening on afm-system-daemon.socket.
         Starting sshd.socket.
[  OK  ] Listening on security-manager.socket.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on cynara-admin.socket.
         Starting cynara-monitor-get.socket.
[  OK  ] Listening on GPS (Global Positioning System) Daemon Sockets.
[  OK  ] Listening on cynara.socket.
[  OK  ] Listening on cynara-agent.socket.
[  OK  ] Listening on sshd.socket.
[  OK  ] Listening on cynara-monitor-get.socket.
[  OK  ] Reached target Sockets.
[  OK  ] Reached target Basic System.
         Starting Install Security Manager database...
         Starting Telephony service...
         Starting Disk Manager (legacy version)...
         Starting Configure Bluetooth Modems connected by UART...
         Starting Initiate afm-system-setup...
[  OK  ] Started D-Bus System Message Bus.
         Starting Connection service...
         Starting neard service...
         Starting Login Service...
         Starting Cynara service...
[  OK  ] Reached target cynara sockets.
[  OK  ] Started Install Security Manager database.
[  OK  ] Started Initiate afm-system-s[   14.346110] uart-pl011 3f201000.serial: no DMA platform data
etup.
[  OK  ] Listening on Provides websocket api geoclue for platform.
[  OK  ] Started Application Framework Master, system side.
[  OK  ] Listening on Provides websocket api Bluetooth-Manager for platform.
         Starting Provides api Bluetooth-Manager for platform...
         Starting Weston Wayland Compositor...
[  OK  ] Listening on Provides websocket api low-can for platform.
[  OK  ] Listening on Provides websocket api unicens for platform.
[  OK  ] Listening on Provides websocket api persistence for platform.
[  OK  ] Listening on Provides websocket api platform-info for platform.
[  OK  ] Listening on Provides websocket api bluetooth-pbap for platform.
         Starting Provides api persistence for platform...
[  OK  ] Listening on Provides websocket api bluetooth-map for platform.
[  OK  ] Listening on Provides websocket api network-manager for platform.
         Starting Provides api network-manager for platform...
         Starting Start the security manager...
[  OK  ] Started Cynara service.
[  OK  ] Started Provides api Bluetooth-Manager for platform.
[  OK  ] Started Start the security manager.
[  OK  ] Started Provides api persistence for platform.
[  OK  ] Started Provides api network-manager for platform.
[   21.335405] Bluetooth: Core ver 2.22
[   21.339148] NET: Registered protocol family 31
[   21.343665] Bluetooth: HCI device and connection manager initialized
[   21.354583] Bluetooth: HCI socket layer initialized
[   21.359567] Bluetooth: L2CAP socket layer initialized
[   21.365570] Bluetooth: SCO socket layer initialized
[   21.387637] Bluetooth: HCI UART driver ver 2.3
[   21.392171] Bluetooth: HCI UART protocol H4 registered
[   21.397502] Bluetooth: HCI UART protocol Three-wire (H5) registered
[   21.415173] Bluetooth: HCI UART protocol Broadcom registered
[  OK  ] Started Telephony service.
[FAILED] Failed to start Disk Manager (legacy version).
See 'systemctl status udisks.service' for details.
[  OK  ] Started Configure Bluetooth Modems connected by UART.
[FAILED] Failed to start neard service.
See 'systemctl status neard.service' for details.
[FAILED] Failed to start Connection service.
See 'systemctl status connman.service' for details.
[FAILED] Failed to start Weston Wayland Compositor.
See 'systemctl status weston.service' for details.
[  OK  ] Stopped Connection service.
         Starting Connection service...
         Starting Load/Save RF Kill Switch Status...
[  OK  ] Created slice system-bthelper.slice.
         Starting Bluetooth service...
[  OK  ] Started Automount Disk Manager.
[  OK  ] Started Load/Save RF Kill Switch Status.
[FAILED] Failed to start Bluetooth service.
See 'systemctl status bluetooth.service' for details.
[DEPEND] Dependency failed for Raspberry Pi bluetooth helper.
[  OK  ] Reached target Bluetooth.
[FAILED] Failed to start Connection service.
See 'systemctl status connman.service' for details.
[  OK  ] Reached target Network.
         Starting Permit User Sessions...
[  OK  ] Started Permit User Sessions.
[FAILED] Failed to start Login Service.
See 'systemctl status systemd-logind.service' for details.
[  OK  ] Stopped Login Service.
         Starting Login Service...
[  OK  ] Stopped Connection service.
         Starting Connection service...
[  OK  ] Started Initiate afm-user-session 1001.
[  OK  ] Started Serial Getty on ttyS0.
[  OK  ] Reached target Login Prompts.

Automotive Grade Linux 7.99.3 raspberrypi3 ttyS0

raspberrypi3 login: root


From here, it hangs forever, so I need to Ctrl + C, then it shows the following.

[  OK  ] Stopped Serial Getty on ttyS0.
[  OK  ] Started Serial Getty on ttyS0.
[  OK  ] Stopped Connection service.
         Starting Connection service...

Automotive Grade Linux 7.99.3 raspberrypi3 ttyS0

raspberrypi3 login: root
Last login: Wed Mar  4 21:11:04 UTC 2020 on ttyS0

Again, the same applies here as well. When it hangs, I can do Ctrl + C, and it keeps repeating.

[FAILED] Failed to start Connection service.
[  OK  ] Stopped Serial Getty on ttyS0.
[  OK  ] Started Serial Getty on ttyS0.

Automotive Grade Linux 7.99.3 raspberrypi3 ttyS0

raspberrypi3 login:

I'd really appreciate if you can help me a bit.. I've been working on putting OPTEE on AGL for RPI3 B+ over the last two weeks. Please let me know if there's any unclear explanation.