root@mmt2020-a880:~# cat /sys/kernel/debug/remoteproc/remoteproc0/trace0 [0][ 0.000] 18 Resource entries at 0x3000 [0][ 0.000] 15.091685 s: SYSTEM: System Common Init in progress !!! [0][ 0.010] 15.101415 s: SYSTEM: IPC init in progress !!! [0][ 0.010] 15.101689 s: SYSTEM: Notify init done !!! [0][ 0.012] 15.103550 s: SYSTEM: MsgQ init done !!! [0][ 0.013] 15.104617 s: RpmsgInit in progress... [0][ 0.014] 15.104861 s: Connection established with HLOS, calling rpmsgInit [0][ 0.014] [t=0x000db469] ti.ipc.family.vayu.VirtQueue: vring: 0 0x60000000 (0x3000) [0][ 0.014] [0][ 0.014] [t=0x000dcd0e] ti.ipc.family.vayu.VirtQueue: vring: 1 0x60004000 (0x3000) [0][ 0.014] [0][ 0.015] [t=0x000de112] ti.ipc.family.vayu.VirtQueue: VirtQueue_startup: VDEV status: 0x7 [0][ 0.015] [0][ 0.015] [t=0x000df7c8] ti.ipc.family.vayu.VirtQueue: VirtQueue_startup: Polling VDEV status... [0][ 0.015] [0][ 0.015] [t=0x000e0cf8] ti.ipc.family.vayu.VirtQueue: VirtQueue_startup: VDEV status: 0x7 [0][ 0.015] [0][ 0.015] [t=0x000e28b5] ti.ipc.family.vayu.VirtQueue: VirtQueue_isr received msg = 0x0 [0][ 0.015] [0][ 0.015] [t=0x000e3d9d] ti.ipc.rpmsg.RPMessage: callback_availBufReady: virtQueue_toHost kicked [0][ 0.016] [t=0x000e5336] ti.ipc.family.vayu.VirtQueue: Passed VirtQueue_startup [0][ 0.016] [0][ 0.016] [t=0x000e68b7] ti.ipc.rpmsg.RPMessage: RPMessage_create: endPt created: 80 [0][ 0.016] registering rpmsg-proto:rpmsg-proto service on 80 with HOST [0][ 0.017] [t=0x000e8765] xdc.runtime.Main: NameMap_sendMessage: HOST 53, port=80 [0][ 0.017] [t=0x000e9b18] ti.ipc.family.vayu.VirtQueue: getAvailBuf vq: 0x9ec51d00 0 256 256 0x9ec51d10 0x60001000 [0][ 0.017] [0][ 0.017] [t=0x000eb768] ti.ipc.family.vayu.VirtQueue: VirtQueue_kick: Sending interrupt to proc 0 with payload 0x0 [0][ 0.017] [0][ 0.018] 15.108979 s: SYSTEM: IPC init DONE !!! [0][ 0.025] 15.116116 s: SYSTEM: System Common Init Done !!! [0][ 0.025] 15.116391 s: SYSTEM: System Init in progress !!! [0][ 0.025] 15.116604 s: SYSTEM: BSP Common Init in progress !!! [0][ 0.025] 15.116818 s: SYSTEM: BSP Common Init Done !!! [0][ 0.026] 15.117062 s: SYSTEM: BSP Platform Init in progress !!! [0][ 0.026] 15.117306 s: SYSTEM: BSP Platform Init Done !!! [0][ 0.026] 15.117519 s: SYSTEM: FVID2 Init in progress !!! [0][ 0.026] 15.117824 s: SYSTEM: FVID2 Init Done !!! [0][ 0.027] 15.118068 s: SYSTEM: VPS Init in progress !!! [0][ 0.027] 15.118282 s: SYSTEM: VPDMA Descriptor Memory Address translation ENABLED [0x80000000 -> 0x80000000] [0][ 0.030] 15.121667 s: *** VPDMA Firmware Loading... *** [0][ 0.031] 15.121972 s: VPDMA Firmware Address = 0xa035ba40 [0][ 0.031] 15.122247 s: VPDMA Load Address = 0x4897d004 [0][ 0.031] 15.122491 s: VPDMA Firmware Version = 0x4d0001b8 [0][ 0.031] 15.122735 s: VPDMA List Busy Status = 0x00000000 [0][ 0.032] 15.122979 s: *** VPDMA Firmware Load Success *** [0][ 0.032] [0][ 0.032] 15.123497 s: *** VPDMA Firmware Loading... *** [0][ 0.032] 15.123772 s: VPDMA Firmware Address = 0xa035ba40 [0][ 0.033] 15.123985 s: VPDMA Load Address = 0x4899d004 [0][ 0.033] 15.124290 s: VPDMA Firmware Version = 0x4d0001b8 [0][ 0.033] 15.124504 s: VPDMA List Busy Status = 0x00000000 [0][ 0.033] 15.124748 s: *** VPDMA Firmware Load Success *** [0][ 0.033] [0][ 0.034] 15.125266 s: *** VPDMA Firmware Loading... *** [0][ 0.034] 15.125510 s: VPDMA Firmware Address = 0xa035ba40 [0][ 0.034] 15.125754 s: VPDMA Load Address = 0x489bd004 [0][ 0.035] 15.125998 s: VPDMA Firmware Version = 0x4d0001b8 [0][ 0.035] 15.126273 s: VPDMA List Busy Status = 0x00000000 [0][ 0.035] 15.126517 s: *** VPDMA Firmware Load Success *** [0][ 0.035] [0][ 0.036] 15.127066 s: *** VPDMA Firmware Loading... *** [0][ 0.036] 15.127310 s: VPDMA Firmware Address = 0xa035ba40 [0][ 0.036] 15.127523 s: VPDMA Load Address = 0x489dd004 [0][ 0.036] 15.127798 s: VPDMA Firmware Version = 0x4d0001b8 [0][ 0.037] 15.128042 s: VPDMA List Busy Status = 0x00000000 [0][ 0.037] 15.128286 s: *** VPDMA Firmware Load Success *** [0][ 0.037] [0][ 0.093] 15.184285 s: SYSTEM: VPS Init Done !!! [0][ 0.095] 15.186177 s: UTILS: DMA: HWI Create for INT34 !!! [0][ 0.095] 15.186604 s: SYSTEM: SW Message Box Msg Pool, Free Msg Count = 1024 [0][ 0.096] 15.186909 s: SYSTEM: Heap = LOCAL_DDR @ 0x00000000, Total size = 262144 B (256 KB), Free size = 260664 B (254 KB) [0][ 0.096] 15.187427 s: SYSTEM: Heap = SR_OCMC @ 0x00000000, Total size = 0 B (0 KB), Free size = 0 B (0 KB) [0][ 0.097] 15.187885 s: SYSTEM: Heap = SR_DDR_CACHED @ 0x84203000, Total size = 16777216 B (16 MB), Free size = 16777216 B (16 MB) [0][ 0.097] 15.188434 s: SYSTEM: Heap = SR_DDR_NON_CACHED @ 0xa0100000, Total size = 1047168 B (0 MB), Free size = 1047168 B (0 MB) [0][ 0.098] 15.188922 s: SYSTEM: Initializing Links !!! [0][ 0.371] 15.462209 s: SYSTEM: Initializing Links ... DONE !!! [0][ 0.371] 15.462514 s: BOARD: Board Init in progress !!! [0][ 0.372] 15.463155 s: BOARD: Board Init Done !!! [0][ 0.379] 15.470841 s: [0][ 0.380] 15.470993 s: CHAINS: Sensor create in progress [0][ 0.380] 15.471237 s: VIDEO_DECODER: ********** CREATE ********** [0][ 0.390] 15.481058 s: gpiopin power on : [10] [0][ 0.390] 15.481241 s: gpiopin power on : [1] [0][ 0.390] 15.481424 s: gpiopin reset : [40000] [0][ 0.390] 15.481608 s: gpiopin reset : [1] [0][ 5.495] [0][ 5.495] i2cMdSubmitChan: i2c transfer Timeout IRQ not received [0][ 5.496] 20.587323 s: src/bsp_deviceI2c.c @ Line 580: 20.587537 s: I2C1: DEV 0x20: WR 0x0f = 0x80 ... ERROR !!! [0][ 5.497] 20.587842 s: src/bsp_deviceI2c.c @ Line 602: 20.588025 s: I2C1: Error timeout 5002 ms!!! [0][ 5.497] 20.588299 s: I2C write failed [0][ 5.507] 20.598334 s: I2C write Passed [0][ 5.512] 20.603428 s: read PASSfor Address 0x10, REG VALUE IS 0x4a [0][ 5.512] 20.603946 s: read PASSfor Address 0x12, REG VALUE IS 0x0 [0][ 5.513] 20.604465 s: read PASSfor Address 0x13, REG VALUE IS 0x2c [0][ 5.513] 20.604953 s: read PASSfor Address 0xc, REG VALUE IS 0x36 [0][ 5.514] 20.605380 s: I2C write Passed [0][ 5.514] 20.605776 s: read PASSfor Address 0x14, REG VALUE IS 0x11 [0][ 5.515] 20.606203 s: I2C write Passed [0][ 5.515] 20.606508 s: I2C write Passed [0][ 5.515] 20.606844 s: I2C write Passed [0][ 5.516] 20.607149 s: I2C write Passed [0][ 5.516] 20.607484 s: I2C write Passed [0][ 5.516] 20.607881 s: read PASSfor Address 0x1d, REG VALUE IS 0x40 [0][ 5.517] 20.608308 s: I2C write Passed [0][ 5.517] 20.608704 s: read PASSfor Address 0xf4, REG VALUE IS 0x4 [0][ 5.518] 20.609101 s: I2C write Passed [0][ 5.518] 20.609528 s: read PASSfor Address 0x18, REG VALUE IS 0x93 [0][ 5.518] 20.609955 s: I2C write Passed [0][ 5.519] 20.610321 s: read PASSfor Address 0x19, REG VALUE IS 0xf1 [0][ 5.519] 20.610748 s: I2C write Passed [0][ 5.520] 20.611145 s: read PASSfor Address 0x2d, REG VALUE IS 0xf2 [0][ 5.530] 20.621179 s: adv2 gpiopin power on : [2] [0][ 5.530] 20.621393 s: adv2 gpiopin power on : [1] [0][ 5.530] 20.621606 s: adv2 gpiopin reset : [10000000] [0][ 5.530] 20.621789 s: Adv2 gpiopin reset : [0] [0][ 5.635] 20.726194 s: do probe [0][ 5.645] 20.736442 s: I2C1: Passed for address 0xc !!! [0][ 9.034] [t=0x0ace964f] ti.ipc.family.vayu.VirtQueue: VirtQueue_isr received msg = 0x1 [0][ 9.034] [0][ 9.034] [t=0x0acead05] ti.ipc.rpmsg.RPMessage: callback_availBufReady: virtQueue_fromHost kicked [0][ 9.035] [t=0x0acec2f2] ti.ipc.family.vayu.VirtQueue: getAvailBuf vq: 0x9ec51d40 0 1 256 0x9ec51d50 0x60005000 [0][ 9.035] [0][ 9.036] [t=0x0acede9d] ti.ipc.rpmsg.RPMessage: RPMessage_swiFxn: Received msg from: 0x400, to: 0x50, dataLen: 4 [0][ 9.036] [t=0x0acef61f] ti.ipc.rpmsg.RPMessage: RPMessage_send: calling callback with data len: 4, from: 1024 [0][ 9.036] [t=0x0acf0d5f] ti.ipc.family.vayu.VirtQueue: getAvailBuf vq: 0x9ec51d40 1 1 256 0x9ec51d50 0x60005000 [0][ 9.036] [0][ 9.037] [t=0x0acf2874] ti.ipc.family.vayu.VirtQueue: VirtQueue_kick: no kick because of VRING_AVAIL_F_NO_INTERRUPT [0][ 9.037] [0][ 9.037] [t=0x0acf4fd7] ti.ipc.family.vayu.VirtQueue: getAvailBuf vq: 0x9ec51d00 1 257 256 0x9ec51d10 0x60001000 [0][ 9.037] [0][ 9.037] [t=0x0acf6dbc] ti.ipc.family.vayu.VirtQueue: VirtQueue_kick: Sending interrupt to proc 0 with payload 0x0 [0][ 9.037] [0][ 9.038] [t=0x0acfaeba] ti.ipc.family.vayu.VirtQueue: VirtQueue_isr received msg = 0x1 [0][ 9.038] [0][ 9.038] [t=0x0acfc35a] ti.ipc.rpmsg.RPMessage: callback_availBufReady: virtQueue_fromHost kicked [0][ 9.039] [t=0x0acfd8c6] ti.ipc.family.vayu.VirtQueue: getAvailBuf vq: 0x9ec51d40 1 2 256 0x9ec51d50 0x60005000 [0][ 9.039] [0][ 9.039] [t=0x0acff453] ti.ipc.rpmsg.RPMessage: RPMessage_swiFxn: Received msg from: 0x400, to: 0x50, dataLen: 4 [0][ 9.039] [t=0x0ad00afd] ti.ipc.rpmsg.RPMessage: RPMessage_send: calling callback with data len: 4, from: 1024 [0][ 9.039] [t=0x0ad0218c] ti.ipc.family.vayu.VirtQueue: getAvailBuf vq: 0x9ec51d40 2 2 256 0x9ec51d50 0x60005000 [0][ 9.039] [0][ 9.040] [t=0x0ad03d4c] ti.ipc.family.vayu.VirtQueue: VirtQueue_kick: no kick because of VRING_AVAIL_F_NO_INTERRUPT [0][ 9.040] [0][ 9.040] 24.131977 s: SYSTEM: ERROR: No link registered at link ID [84] !!! [0][ 9.041] [t=0x0ad074c6] ti.ipc.family.vayu.VirtQueue: getAvailBuf vq: 0x9ec51d00 2 258 256 0x9ec51d10 0x60001000 [0][ 9.041] [0][ 9.041] [t=0x0ad09206] ti.ipc.family.vayu.VirtQueue: VirtQueue_kick: Sending interrupt to proc 0 with payload 0x0 [0][ 9.041] [0][ 10.741] [0][ 10.741] i2cMdSubmitChan: i2c transfer Timeout IRQ not received [0][ 10.742] 25.833496 s: src/bsp_deviceI2c.c @ Line 580: 25.833709 s: I2C1: DEV 0x21: WR 0x0f = 0x80 ... ERROR !!! [0][ 10.743] 25.834014 s: src/bsp_deviceI2c.c @ Line 602: 25.834167 s: I2C1: Error timeout 5001 ms!!! [0][ 10.743] 25.834441 s: I2C write failed [0][ 10.753] 25.844476 s: I2C write Passed [0][ 10.758] 25.849325 s: Adv2 do read [0][ 10.758] 25.849722 s: read PASSfor Address 0x10, REG VALUE IS 0x4a [0][ 10.758] 25.849996 s: Adv2 do read [0][ 10.759] 25.850362 s: read PASSfor Address 0x12, REG VALUE IS 0x0 [0][ 10.759] 25.850637 s: Adv2 do read [0][ 10.759] 25.851003 s: read PASSfor Address 0x13, REG VALUE IS 0x2c [0][ 10.760] 25.851277 s: Adv2 do read [0][ 10.760] 25.851674 s: read PASSfor Address 0xc, REG VALUE IS 0x36 [0][ 10.760] 25.852071 s: I2C write Passed [0][ 10.761] 25.852467 s: read PASSfor Address 0x14, REG VALUE IS 0x11 [0][ 10.761] 25.852894 s: I2C write Passed [0][ 10.762] 25.853199 s: I2C write Passed [0][ 10.762] 25.853565 s: I2C write Passed [0][ 10.762] 25.853870 s: I2C write Passed [0][ 10.762] 25.854175 s: I2C write Passed [0][ 10.763] 25.854602 s: read PASSfor Address 0x1d, REG VALUE IS 0x40 [0][ 10.763] 25.855029 s: I2C write Passed [0][ 10.764] 25.855426 s: read PASSfor Address 0xf4, REG VALUE IS 0x4 [0][ 10.764] 25.855853 s: I2C write Passed [0][ 10.765] 25.856249 s: read PASSfor Address 0x18, REG VALUE IS 0x93 [0][ 10.765] 25.856676 s: I2C write Passed [0][ 10.765] 25.857073 s: read PASSfor Address 0x19, REG VALUE IS 0xf1 [0][ 10.766] 25.857530 s: I2C write Passed [0][ 10.766] 25.857896 s: read PASSfor Address 0x2d, REG VALUE IS 0xf2 [0][ 10.767] 25.858415 s: read PASSfor Address 0x0, REG VALUE IS 0x18 [0][ 10.767] 25.858811 s: I2C write Passed [0][ 10.817] 25.908497 s: I2C write Passed [0][ 10.818] 25.909504 s: I2C write Passed [0][ 10.819] 25.910480 s: I2C write Passed [0][ 10.820] 25.911578 s: read PASSfor Address 0xc, REG VALUE IS 0x1 [0][ 10.831] 25.922436 s: I2C1: Passed for address 0xc !!! [0][ 10.865] 25.957024 s: I2C1: Passed for address 0x35 !!! [0][ 10.928] 26.019490 s: read PASSfor Address 0x0, REG VALUE IS 0x6a [0][ 10.929] 26.020496 s: I2C write Passed [0][ 10.979] 26.070487 s: I2C write Passed [0][ 10.980] 26.071493 s: I2C write Passed [0][ 10.981] 26.072500 s: I2C write Passed [0][ 10.986] 26.077563 s: read PASSfor Address 0x1c, REG VALUE IS 0x3 [0][ 10.989] 26.080583 s: read PASSfor Address 0x22, REG VALUE IS 0x4a [0][ 10.990] 26.081559 s: read PASSfor Address 0xc, REG VALUE IS 0x3 [0][ 10.991] 26.082352 s: CHAINS: Sensor create ... DONE !!!