Looks like rtcpu driver is not loaded. Try the following command
modprobe rtcpu_debug
Then run the trace commands
Using “sudo modprobe rtcpu_debug”, I was able to capture the trace and it looks like this:
# tracer: nop
#
# entries-in-buffer/entries-written: 13172/13172 #P:8
#
# _-------=> irqs-off
# / _------=> need-resched
# | / _-----=> need-resched-lazy
# || / _----=> hardirq/softirq
# ||| / _---=> preempt-depth
# |||| / _--=> preempt-lazy-depth
# ||||| / _-=> migrate-disable
# |||||| / delay
# TASK-PID CPU# ||||||| TIMESTAMP FUNCTION
# | | | ||||||| | |
kworker/4:1-80 [004] ....... 257.219830: rtcpu_string: tstamp:8750865104 id:0x04010000 str:"VM0 deactivating."
v4l2-ctl-3607 [000] ....... 277.157213: tegra_channel_open: vi-output, imx415 9-001a
v4l2-ctl-3607 [000] ....... 277.170669: tegra_channel_set_power: imx415 9-001a : 0x1
v4l2-ctl-3607 [000] ....... 277.170678: camera_common_s_power: status : 0x1
v4l2-ctl-3607 [000] ....... 277.195493: tegra_channel_set_power: 13e00000.host1x:nvcsi@15a00000- : 0x1
v4l2-ctl-3607 [000] ....... 277.195496: csi_s_power: enable : 0x1
v4l2-ctl-3607 [000] ....... 277.195978: tegra_channel_capture_setup: vnc_id 0 W 3840 H 2160 fmt c4
vi-output, imx4-3608 [001] ....... 277.207784: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3608 [001] ....... 277.207791: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3608 [001] ....... 277.207793: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3608 [001] ....... 277.207793: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
v4l2-ctl-3607 [000] ....... 277.207943: tegra_channel_set_stream: enable : 0x1
v4l2-ctl-3607 [000] ....... 277.207951: tegra_channel_set_stream: 13e00000.host1x:nvcsi@15a00000- : 0x1
v4l2-ctl-3607 [000] ....... 277.207954: csi_s_stream: enable : 0x1
v4l2-ctl-3607 [000] ....... 277.208263: tegra_channel_set_stream: imx415 9-001a : 0x1
kworker/4:1-80 [004] ....... 277.219260: rtcpu_string: tstamp:9375796844 id:0x04010000 str:"VM0 activating."
kworker/4:1-80 [004] ....... 277.219265: rtcpu_vinotify_event: tstamp:9376394442 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300034263680 data:0x359d580010000000
kworker/4:1-80 [004] ....... 277.219266: rtcpu_vinotify_event: tstamp:9376394582 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300034270080 data:0x0000000031000001
kworker/4:1-80 [004] ....... 277.219266: rtcpu_vinotify_event: tstamp:9376394737 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:300034273120 data:0x0000000007020001
kworker/4:1-80 [004] ....... 277.219267: rtcpu_vinotify_event: tstamp:9376394872 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300034315360 data:0x359d550010000000
kworker/4:1-80 [004] ....... 277.219267: rtcpu_vinotify_event: tstamp:9376395025 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300034321856 data:0x0000000031000002
vi-output, imx4-3609 [005] ....... 277.270577: tegra_channel_capture_frame: sof:300.96494304
vi-output, imx4-3609 [005] ....... 277.270578: tegra_channel_capture_frame: eof:300.96505728
kworker/4:1-80 [004] ....... 277.279244: rtcpu_nvcsi_intr: tstamp:9377697915 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x01c00000
kworker/4:1-80 [004] ....... 277.279246: rtcpu_nvcsi_intr: tstamp:9377697915 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00c00000
kworker/4:1-80 [004] ....... 277.279248: rtcpu_vinotify_event: tstamp:9378018771 cch:0 vi:0 tag:FS channel:0x00 frame:1 vi_tstamp:300096306720 data:0x0000000100000010
kworker/4:1-80 [004] ....... 277.279248: rtcpu_vinotify_event: tstamp:9378018927 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:1 vi_tstamp:300096306752 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.279249: rtcpu_vinotify_event: tstamp:9378019062 cch:0 vi:0 tag:CHANSEL_EMBED_SOF channel:0x23 frame:1 vi_tstamp:300096311936 data:0x0000000000000004
kworker/4:1-80 [004] ....... 277.279249: rtcpu_vinotify_event: tstamp:9378019215 cch:0 vi:0 tag:CHANSEL_EMBED_EOF channel:0x23 frame:1 vi_tstamp:300096315968 data:0x0000000000000008
kworker/4:1-80 [004] ....... 277.279249: rtcpu_vinotify_event: tstamp:9378019348 cch:0 vi:0 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:1 vi_tstamp:300096316576 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.279250: rtcpu_vinotify_event: tstamp:9378019499 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:300096319104 data:0x0000000003020001
kworker/4:1-80 [004] ....... 277.279250: rtcpu_vinotify_event: tstamp:9378019632 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:1 vi_tstamp:300096316864 data:0x00000000000006e9
kworker/4:1-80 [004] ....... 277.279251: rtcpu_vinotify_event: tstamp:9378019781 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:1 vi_tstamp:300096494304 data:0x0000000000000001
kworker/4:1-80 [004] ....... 277.279251: rtcpu_vinotify_event: tstamp:9378019912 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:300096495680 data:0x0000000008020001
kworker/4:1-80 [004] ....... 277.279251: rtcpu_vinotify_event: tstamp:9378020063 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:300096498336 data:0x0000000000000100
kworker/4:1-80 [004] ....... 277.279252: rtcpu_vinotify_event: tstamp:9378020196 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:300096506016 data:0x0000000001020001
vi-output, imx4-3609 [005] ....... 277.281497: tegra_channel_capture_frame: sof:300.107584960
vi-output, imx4-3609 [005] ....... 277.281500: tegra_channel_capture_frame: eof:300.107598496
vi-output, imx4-3609 [005] ....... 277.292559: tegra_channel_capture_frame: sof:300.118675616
vi-output, imx4-3609 [005] ....... 277.292560: tegra_channel_capture_frame: eof:300.118687040
vi-output, imx4-3609 [005] ....... 277.303640: tegra_channel_capture_frame: sof:300.129766272
vi-output, imx4-3609 [005] ....... 277.303641: tegra_channel_capture_frame: eof:300.129777696
kworker/4:1-80 [004] ....... 277.335258: rtcpu_vinotify_event: tstamp:9378355047 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:300107140416 data:0x00000000086f0182
kworker/4:1-80 [004] ....... 277.335261: rtcpu_vinotify_event: tstamp:9378355179 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:1 vi_tstamp:300107140992 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.335262: rtcpu_vinotify_event: tstamp:9378355332 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:300107141344 data:0x00000000087001c0
kworker/4:1-80 [004] ....... 277.335263: rtcpu_vinotify_event: tstamp:9378355463 cch:0 vi:0 tag:FE channel:0x00 frame:1 vi_tstamp:300107298656 data:0x0000000100000020
kworker/4:1-80 [004] ....... 277.335263: rtcpu_vinotify_event: tstamp:9378355629 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:1 vi_tstamp:300107298656 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.335264: rtcpu_vinotify_event: tstamp:9378355762 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:300107303552 data:0x0000000007020002
kworker/4:1-80 [004] ....... 277.335264: rtcpu_vinotify_event: tstamp:9378355913 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300107314144 data:0x359d580010000000
kworker/4:1-80 [004] ....... 277.335265: rtcpu_vinotify_event: tstamp:9378356046 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300107320640 data:0x0000000031000003
kworker/4:1-80 [004] ....... 277.335267: rtcpu_vinotify_event: tstamp:9378365293 cch:0 vi:0 tag:FS channel:0x00 frame:2 vi_tstamp:300107397376 data:0x0000000200000010
kworker/4:1-80 [004] ....... 277.335268: rtcpu_vinotify_event: tstamp:9378365432 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:2 vi_tstamp:300107397408 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.335268: rtcpu_vinotify_event: tstamp:9378365589 cch:0 vi:0 tag:CHANSEL_EMBED_SOF channel:0x23 frame:2 vi_tstamp:300107402592 data:0x0000000000000004
kworker/4:1-80 [004] ....... 277.335269: rtcpu_vinotify_event: tstamp:9378365724 cch:0 vi:0 tag:CHANSEL_EMBED_EOF channel:0x23 frame:2 vi_tstamp:300107406624 data:0x0000000000000008
kworker/4:1-80 [004] ....... 277.335270: rtcpu_vinotify_event: tstamp:9378365877 cch:0 vi:0 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:2 vi_tstamp:300107407200 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.335270: rtcpu_vinotify_event: tstamp:9378366012 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:300107409760 data:0x0000000003020002
kworker/4:1-80 [004] ....... 277.335271: rtcpu_vinotify_event: tstamp:9378366165 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:2 vi_tstamp:300107407520 data:0x00000000000006e9
kworker/4:1-80 [004] ....... 277.335271: rtcpu_vinotify_event: tstamp:9378366298 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:2 vi_tstamp:300107584960 data:0x0000000000000001
kworker/4:1-80 [004] ....... 277.335273: rtcpu_vinotify_event: tstamp:9378693147 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:300107587520 data:0x0000000008020002
kworker/4:1-80 [004] ....... 277.335273: rtcpu_vinotify_event: tstamp:9378693282 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:300107588992 data:0x0000000000000100
kworker/4:1-80 [004] ....... 277.335274: rtcpu_vinotify_event: tstamp:9378693435 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:2 vi_tstamp:300107598752 data:0x0000000001020002
kworker/4:1-80 [004] ....... 277.335274: rtcpu_vinotify_event: tstamp:9378704846 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:300118231072 data:0x00000000086f0182
kworker/4:1-80 [004] ....... 277.335275: rtcpu_vinotify_event: tstamp:9378704999 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:2 vi_tstamp:300118231648 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.335276: rtcpu_vinotify_event: tstamp:9378705131 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:2 vi_tstamp:300118232000 data:0x00000000087001c0
kworker/4:1-80 [004] ....... 277.335276: rtcpu_vinotify_event: tstamp:9378705287 cch:0 vi:0 tag:FE channel:0x00 frame:2 vi_tstamp:300118389312 data:0x0000000200000020
kworker/4:1-80 [004] ....... 277.335277: rtcpu_vinotify_event: tstamp:9378705421 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:2 vi_tstamp:300118389312 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.335277: rtcpu_vinotify_event: tstamp:9378705575 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:300118394176 data:0x0000000007020003
kworker/4:1-80 [004] ....... 277.335277: rtcpu_vinotify_event: tstamp:9378705707 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300118404160 data:0x359d550010000000
kworker/4:1-80 [004] ....... 277.335278: rtcpu_vinotify_event: tstamp:9378705859 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:300118410624 data:0x0000000031000004
kworker/4:1-80 [004] ....... 277.335278: rtcpu_vinotify_event: tstamp:9378705991 cch:0 vi:0 tag:FS channel:0x00 frame:3 vi_tstamp:300118488032 data:0x0000000300000010
kworker/4:1-80 [004] ....... 277.335279: rtcpu_vinotify_event: tstamp:9378706143 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:3 vi_tstamp:300118488064 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.335279: rtcpu_vinotify_event: tstamp:9378706275 cch:0 vi:0 tag:CHANSEL_EMBED_SOF channel:0x23 frame:3 vi_tstamp:300118493248 data:0x0000000000000004
kworker/4:1-80 [004] ....... 277.335279: rtcpu_vinotify_event: tstamp:9378706425 cch:0 vi:0 tag:CHANSEL_EMBED_EOF channel:0x23 frame:3 vi_tstamp:300118497280 data:0x0000000000000008
kworker/4:1-80 [004] ....... 277.335280: rtcpu_vinotify_event: tstamp:9378706558 cch:0 vi:0 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:3 vi_tstamp:300118497888 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.335280: rtcpu_vinotify_event: tstamp:9379031236 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:300118501536 data:0x0000000003020003
kworker/4:1-80 [004] ....... 277.335281: rtcpu_vinotify_event: tstamp:9379031375 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:3 vi_tstamp:300118498144 data:0x00000000000006e9
kworker/4:1-80 [004] ....... 277.335281: rtcpu_vinotify_event: tstamp:9379031537 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:3 vi_tstamp:300118675616 data:0x0000000000000001
kworker/4:1-80 [004] ....... 277.335282: rtcpu_vinotify_event: tstamp:9379031673 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:300118676992 data:0x0000000008020003
kworker/4:1-80 [004] ....... 277.335282: rtcpu_vinotify_event: tstamp:9379031830 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:300118679616 data:0x0000000000000100
kworker/4:1-80 [004] ....... 277.335282: rtcpu_vinotify_event: tstamp:9379031965 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:3 vi_tstamp:300118687296 data:0x0000000001020003
kworker/4:1-80 [004] ....... 277.335283: rtcpu_vinotify_event: tstamp:9379051424 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:300129321728 data:0x00000000086f0182
kworker/4:1-80 [004] ....... 277.335283: rtcpu_vinotify_event: tstamp:9379051560 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:3 vi_tstamp:300129322336 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.335284: rtcpu_vinotify_event: tstamp:9379051716 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:3 vi_tstamp:300129322624 data:0x00000000087001c0
kworker/4:1-80 [004] ....... 277.335284: rtcpu_vinotify_event: tstamp:9379051852 cch:0 vi:0 tag:FE channel:0x00 frame:3 vi_tstamp:300129479968 data:0x0000000300000020
kworker/4:1-80 [004] ....... 277.335284: rtcpu_vinotify_event: tstamp:9379052012 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:3 vi_tstamp:300129479968 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.335285: rtcpu_vinotify_event: tstamp:9379052148 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:300129484864 data:0x0000000007020004
kworker/4:1-80 [004] ....... 277.335285: rtcpu_vinotify_event: tstamp:9379052303 cch:0 vi:0 tag:FS channel:0x00 frame:4 vi_tstamp:300129578688 data:0x0000000400000010
kworker/4:1-80 [004] ....... 277.335286: rtcpu_vinotify_event: tstamp:9379052442 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:4 vi_tstamp:300129578720 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.335286: rtcpu_vinotify_event: tstamp:9379052595 cch:0 vi:0 tag:CHANSEL_EMBED_SOF channel:0x23 frame:4 vi_tstamp:300129583904 data:0x0000000000000004
kworker/4:1-80 [004] ....... 277.335288: rtcpu_vinotify_event: tstamp:9379052727 cch:0 vi:0 tag:CHANSEL_EMBED_EOF channel:0x23 frame:4 vi_tstamp:300129587936 data:0x0000000000000008
kworker/4:1-80 [004] ....... 277.335289: rtcpu_vinotify_event: tstamp:9379369165 cch:0 vi:0 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:4 vi_tstamp:300129588512 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.335289: rtcpu_vinotify_event: tstamp:9379369301 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:300129592192 data:0x0000000003020004
kworker/4:1-80 [004] ....... 277.335290: rtcpu_vinotify_event: tstamp:9379369457 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:4 vi_tstamp:300129588800 data:0x00000000000006e9
kworker/4:1-80 [004] ....... 277.335290: rtcpu_vinotify_event: tstamp:9379369591 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:4 vi_tstamp:300129766272 data:0x0000000000000001
kworker/4:1-80 [004] ....... 277.335291: rtcpu_vinotify_event: tstamp:9379369745 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:300129767648 data:0x0000000008020004
kworker/4:1-80 [004] ....... 277.335291: rtcpu_vinotify_event: tstamp:9379369877 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:4 vi_tstamp:300129770272 data:0x0000000000000100
kworker/4:1-80 [004] ....... 277.335292: rtcpu_vinotify_event: tstamp:9379370029 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:4 vi_tstamp:300129777920 data:0x0000000001020004
kworker/4:1-80 [004] ....... 277.335294: rtcpu_vinotify_error: tstamp:9379396891 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:5 vi_tstamp:300140674528 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.335294: rtcpu_vinotify_event: tstamp:9379707185 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:4 vi_tstamp:300140412384 data:0x00000000086f0182
kworker/4:1-80 [004] ....... 277.335295: rtcpu_vinotify_event: tstamp:9379707340 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:4 vi_tstamp:300140412992 data:0x0000000000000000
kworker/4:1-80 [004] ....... 277.335295: rtcpu_vinotify_event: tstamp:9379707478 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:4 vi_tstamp:300140413280 data:0x00000000087001c0
kworker/4:1-80 [004] ....... 277.335295: rtcpu_vinotify_event: tstamp:9379707633 cch:0 vi:0 tag:FE channel:0x00 frame:4 vi_tstamp:300140570592 data:0x0000000400000020
kworker/4:1-80 [004] ....... 277.335296: rtcpu_vinotify_event: tstamp:9379707770 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:4 vi_tstamp:300140570592 data:0x0000000800000000
kworker/4:1-80 [004] ....... 277.335296: rtcpu_vinotify_event: tstamp:9379707923 cch:0 vi:0 tag:FS channel:0x00 frame:5 vi_tstamp:300140669344 data:0x0000000500000010
kworker/4:1-80 [004] ....... 277.335297: rtcpu_vinotify_event: tstamp:9379708058 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:5 vi_tstamp:300140674528 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.335297: rtcpu_vinotify_error: tstamp:9379743884 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:6 vi_tstamp:300151765184 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.335297: rtcpu_vinotify_event: tstamp:9379744339 cch:0 vi:0 tag:FE channel:0x00 frame:5 vi_tstamp:300151661248 data:0x0000000500000020
kworker/4:1-80 [004] ....... 277.335298: rtcpu_vinotify_event: tstamp:9379744474 cch:0 vi:0 tag:FS channel:0x00 frame:6 vi_tstamp:300151759968 data:0x0000000600000010
kworker/4:1-80 [004] ....... 277.391438: rtcpu_vinotify_event: tstamp:9380079063 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:6 vi_tstamp:300151765184 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391441: rtcpu_vinotify_error: tstamp:9380090056 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:7 vi_tstamp:300162855840 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391442: rtcpu_vinotify_event: tstamp:9380417067 cch:0 vi:0 tag:FE channel:0x00 frame:6 vi_tstamp:300162751872 data:0x0000000600000020
kworker/4:1-80 [004] ....... 277.391443: rtcpu_vinotify_event: tstamp:9380417220 cch:0 vi:0 tag:FS channel:0x00 frame:7 vi_tstamp:300162850624 data:0x0000000700000010
kworker/4:1-80 [004] ....... 277.391444: rtcpu_vinotify_event: tstamp:9380417356 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:7 vi_tstamp:300162855840 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391445: rtcpu_vinotify_error: tstamp:9380436647 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:8 vi_tstamp:300173946496 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391445: rtcpu_vinotify_event: tstamp:9380755231 cch:0 vi:0 tag:FE channel:0x00 frame:7 vi_tstamp:300173842528 data:0x0000000700000020
kworker/4:1-80 [004] ....... 277.391446: rtcpu_vinotify_event: tstamp:9380755367 cch:0 vi:0 tag:FS channel:0x00 frame:8 vi_tstamp:300173941280 data:0x0000000800000010
kworker/4:1-80 [004] ....... 277.391447: rtcpu_vinotify_event: tstamp:9380755522 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:8 vi_tstamp:300173946496 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391447: rtcpu_vinotify_error: tstamp:9380783219 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:9 vi_tstamp:300185037120 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391449: rtcpu_vinotify_event: tstamp:9381093246 cch:0 vi:0 tag:FE channel:0x00 frame:8 vi_tstamp:300184933184 data:0x0000000800000020
kworker/4:1-80 [004] ....... 277.391449: rtcpu_vinotify_event: tstamp:9381093398 cch:0 vi:0 tag:FS channel:0x00 frame:9 vi_tstamp:300185031936 data:0x0000000900000010
kworker/4:1-80 [004] ....... 277.391450: rtcpu_vinotify_event: tstamp:9381093533 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:9 vi_tstamp:300185037120 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391451: rtcpu_vinotify_error: tstamp:9381129802 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:10 vi_tstamp:300196127808 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391451: rtcpu_vinotify_event: tstamp:9381431254 cch:0 vi:0 tag:FE channel:0x00 frame:9 vi_tstamp:300196023840 data:0x0000000900000020
kworker/4:1-80 [004] ....... 277.391452: rtcpu_vinotify_event: tstamp:9381431388 cch:0 vi:0 tag:FS channel:0x00 frame:10 vi_tstamp:300196122592 data:0x0000000a00000010
kworker/4:1-80 [004] ....... 277.391452: rtcpu_vinotify_event: tstamp:9381431540 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:10 vi_tstamp:300196127808 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391453: rtcpu_vinotify_error: tstamp:9381476376 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:11 vi_tstamp:300207218432 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.391454: rtcpu_vinotify_event: tstamp:9381476865 cch:0 vi:0 tag:FE channel:0x00 frame:10 vi_tstamp:300207114496 data:0x0000000a00000020
kworker/4:1-80 [004] ....... 277.391454: rtcpu_vinotify_event: tstamp:9381477015 cch:0 vi:0 tag:FS channel:0x00 frame:11 vi_tstamp:300207213248 data:0x0000000b00000010
kworker/4:1-80 [004] ....... 277.391455: rtcpu_vinotify_event: tstamp:9381477149 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:11 vi_tstamp:300207218432 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447442: rtcpu_vinotify_error: tstamp:9381822967 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:12 vi_tstamp:300218309088 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447445: rtcpu_vinotify_event: tstamp:9381836955 cch:0 vi:0 tag:FE channel:0x00 frame:11 vi_tstamp:300218205152 data:0x0000000b00000020
kworker/4:1-80 [004] ....... 277.447446: rtcpu_vinotify_event: tstamp:9381837089 cch:0 vi:0 tag:FS channel:0x00 frame:12 vi_tstamp:300218303904 data:0x0000000c00000010
kworker/4:1-80 [004] ....... 277.447446: rtcpu_vinotify_event: tstamp:9381837244 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:12 vi_tstamp:300218309088 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447447: rtcpu_vinotify_error: tstamp:9382169576 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:13 vi_tstamp:300229399744 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447448: rtcpu_vinotify_event: tstamp:9382174963 cch:0 vi:0 tag:FE channel:0x00 frame:12 vi_tstamp:300229295808 data:0x0000000c00000020
kworker/4:1-80 [004] ....... 277.447449: rtcpu_vinotify_event: tstamp:9382175116 cch:0 vi:0 tag:FS channel:0x00 frame:13 vi_tstamp:300229394528 data:0x0000000d00000010
kworker/4:1-80 [004] ....... 277.447449: rtcpu_vinotify_event: tstamp:9382175249 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:13 vi_tstamp:300229399744 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447450: rtcpu_vinotify_event: tstamp:9382513058 cch:0 vi:0 tag:FE channel:0x00 frame:13 vi_tstamp:300240386464 data:0x0000000d00000020
kworker/4:1-80 [004] ....... 277.447451: rtcpu_vinotify_error: tstamp:9382516144 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:14 vi_tstamp:300240490400 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447452: rtcpu_vinotify_event: tstamp:9382850982 cch:0 vi:0 tag:FS channel:0x00 frame:14 vi_tstamp:300240485184 data:0x0000000e00000010
kworker/4:1-80 [004] ....... 277.447452: rtcpu_vinotify_event: tstamp:9382851134 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:14 vi_tstamp:300240490400 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447453: rtcpu_vinotify_error: tstamp:9382862717 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:15 vi_tstamp:300251581056 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447454: rtcpu_vinotify_event: tstamp:9383189143 cch:0 vi:0 tag:FE channel:0x00 frame:14 vi_tstamp:300251477120 data:0x0000000e00000020
kworker/4:1-80 [004] ....... 277.447454: rtcpu_vinotify_event: tstamp:9383189295 cch:0 vi:0 tag:FS channel:0x00 frame:15 vi_tstamp:300251575840 data:0x0000000f00000010
kworker/4:1-80 [004] ....... 277.447455: rtcpu_vinotify_event: tstamp:9383189429 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:15 vi_tstamp:300251581056 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447456: rtcpu_vinotify_error: tstamp:9383209303 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:16 vi_tstamp:300262671712 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447456: rtcpu_vinotify_event: tstamp:9383527504 cch:0 vi:0 tag:FE channel:0x00 frame:15 vi_tstamp:300262567776 data:0x0000000f00000020
kworker/4:1-80 [004] ....... 277.447457: rtcpu_vinotify_event: tstamp:9383527640 cch:0 vi:0 tag:FS channel:0x00 frame:16 vi_tstamp:300262666496 data:0x0000001000000010
kworker/4:1-80 [004] ....... 277.447457: rtcpu_vinotify_event: tstamp:9383527797 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:16 vi_tstamp:300262671712 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.447458: rtcpu_vinotify_event: tstamp:9383552577 cch:0 vi:0 tag:FE channel:0x00 frame:16 vi_tstamp:300273658432 data:0x0000001000000020
kworker/4:1-80 [004] ....... 277.447458: rtcpu_vinotify_error: tstamp:9383555890 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:17 vi_tstamp:300273762336 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503257: rtcpu_vinotify_event: tstamp:9383865212 cch:0 vi:0 tag:FS channel:0x00 frame:17 vi_tstamp:300273757152 data:0x0000001100000010
kworker/4:1-80 [004] ....... 277.503260: rtcpu_vinotify_event: tstamp:9383865358 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:17 vi_tstamp:300273762336 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503261: rtcpu_vinotify_error: tstamp:9383902464 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:18 vi_tstamp:300284852992 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503262: rtcpu_vinotify_event: tstamp:9384203224 cch:0 vi:0 tag:FE channel:0x00 frame:17 vi_tstamp:300284749056 data:0x0000001100000020
kworker/4:1-80 [004] ....... 277.503263: rtcpu_vinotify_event: tstamp:9384203360 cch:0 vi:0 tag:FS channel:0x00 frame:18 vi_tstamp:300284847808 data:0x0000001200000010
kworker/4:1-80 [004] ....... 277.503263: rtcpu_vinotify_event: tstamp:9384203513 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:18 vi_tstamp:300284852992 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503266: rtcpu_vinotify_error: tstamp:9384249051 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:19 vi_tstamp:300295943648 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503267: rtcpu_vinotify_event: tstamp:9384541237 cch:0 vi:0 tag:FE channel:0x00 frame:18 vi_tstamp:300295839712 data:0x0000001200000020
kworker/4:1-80 [004] ....... 277.503267: rtcpu_vinotify_event: tstamp:9384541394 cch:0 vi:0 tag:FS channel:0x00 frame:19 vi_tstamp:300295938432 data:0x0000001300000010
kworker/4:1-80 [004] ....... 277.503268: rtcpu_vinotify_event: tstamp:9384541532 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:19 vi_tstamp:300295943648 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503269: rtcpu_vinotify_error: tstamp:9384595638 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:20 vi_tstamp:300307034304 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503270: rtcpu_vinotify_event: tstamp:9384879252 cch:0 vi:0 tag:FE channel:0x00 frame:19 vi_tstamp:300306930368 data:0x0000001300000020
kworker/4:1-80 [004] ....... 277.503270: rtcpu_vinotify_event: tstamp:9384879387 cch:0 vi:0 tag:FS channel:0x00 frame:20 vi_tstamp:300307029088 data:0x0000001400000010
kworker/4:1-80 [004] ....... 277.503271: rtcpu_vinotify_event: tstamp:9384879541 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:20 vi_tstamp:300307034304 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503272: rtcpu_vinotify_error: tstamp:9384942222 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:21 vi_tstamp:300318124960 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503272: rtcpu_vinotify_event: tstamp:9385217416 cch:0 vi:0 tag:FE channel:0x00 frame:20 vi_tstamp:300318021024 data:0x0000001400000020
kworker/4:1-80 [004] ....... 277.503273: rtcpu_vinotify_event: tstamp:9385217569 cch:0 vi:0 tag:FS channel:0x00 frame:21 vi_tstamp:300318119744 data:0x0000001500000010
kworker/4:1-80 [004] ....... 277.503274: rtcpu_vinotify_event: tstamp:9385217703 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:21 vi_tstamp:300318124960 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503274: rtcpu_vinotify_error: tstamp:9385289204 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:22 vi_tstamp:300329215616 data:0x0000000000000249
kworker/4:1-80 [004] ....... 277.503275: rtcpu_vinotify_event: tstamp:9385289654 cch:0 vi:0 tag:FE channel:0x00 frame:21 vi_tstamp:300329111680 data:0x0000001500000020
kworker/4:1-80 [004] ....... 277.503275: rtcpu_vinotify_event: tstamp:9385289788 cch:0 vi:0 tag:FS channel:0x00 frame:22 vi_tstamp:300329210400 data:0x0000001600000010
vi-output, imx4-3608 [002] ....... 277.509774: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3608 [002] ....... 277.509790: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3608 [002] ....... 277.509793: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3608 [002] ....... 277.509794: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3609 [005] ....... 277.514602: tegra_channel_capture_frame: sof:300.340488640
vi-output, imx4-3609 [005] ....... 277.514603: tegra_channel_capture_frame: eof:300.340502144
vi-output, imx4-3608 [002] ....... 277.515150: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3609 [005] ....... 277.525276: tegra_channel_capture_frame: sof:300.351579264
vi-output, imx4-3609 [005] ....... 277.525277: tegra_channel_capture_frame: eof:300.351590720
vi-output, imx4-3608 [002] ....... 277.525326: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3609 [005] ....... 277.536379: tegra_channel_capture_frame: sof:300.362669920
vi-output, imx4-3609 [005] ....... 277.536380: tegra_channel_capture_frame: eof:300.362681376
vi-output, imx4-3608 [002] ....... 277.536444: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3609 [005] ....... 277.547463: tegra_channel_capture_frame: sof:300.373760576
vi-output, imx4-3609 [005] ....... 277.547464: tegra_channel_capture_frame: eof:300.373772032
vi-output, imx4-3608 [002] ....... 277.547502: vi_task_submit: class_id:48 ch:0 syncpt_id:36 syncpt_thresh:1389 pid:3608 tid:3608
vi-output, imx4-3609 [005] ....... 277.558554: tegra_channel_capture_frame: sof:300.384851232
vi-output, imx4-3609 [005] ....... 277.558554: tegra_channel_capture_frame: eof:300.384862656
...
...
...
kworker/4:1-80 [004] ....... 284.067089: rtcpu_nvcsi_intr: tstamp:9589788176 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x00000001
kworker/4:1-80 [004] ....... 284.067090: rtcpu_nvcsi_intr: tstamp:9589788176 class:GLOBAL type:PHY_INTR1 phy:0 cil:0 st:0 vc:0 status:0x00000125
kworker/4:1-80 [004] ....... 284.067090: rtcpu_nvcsi_intr: tstamp:9589788176 class:GLOBAL type:PHY_INTR1 phy:0 cil:1 st:0 vc:0 status:0x00000125
kworker/4:1-80 [004] ....... 284.067090: rtcpu_vinotify_event: tstamp:9590105977 cch:0 vi:0 tag:CHANSEL_EMBED_SOF channel:0x23 frame:104 vi_tstamp:306872704224 data:0x0000000000000004
kworker/4:1-80 [004] ....... 284.067090: rtcpu_vinotify_event: tstamp:9590106112 cch:0 vi:0 tag:CHANSEL_EMBED_EOF channel:0x23 frame:104 vi_tstamp:306872708256 data:0x0000000000000008
kworker/4:1-80 [004] ....... 284.067091: rtcpu_vinotify_event: tstamp:9590106267 cch:0 vi:0 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:104 vi_tstamp:306872708864 data:0x0000000000000000
kworker/4:1-80 [004] ....... 284.067091: rtcpu_vinotify_event: tstamp:9590106398 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:104 vi_tstamp:306872715168 data:0x0000000003020252
kworker/4:1-80 [004] ....... 284.067091: rtcpu_vinotify_event: tstamp:9590106578 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:104 vi_tstamp:306872709152 data:0x00000000000006e9
kworker/4:1-80 [004] ....... 284.067091: rtcpu_vinotify_event: tstamp:9590106712 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:104 vi_tstamp:306872886592 data:0x0000000000000001
kworker/4:1-80 [004] ....... 284.067092: rtcpu_vinotify_event: tstamp:9590106864 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:104 vi_tstamp:306872887968 data:0x0000000008020252
kworker/4:1-80 [004] ....... 284.067092: rtcpu_vinotify_event: tstamp:9590106995 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:104 vi_tstamp:306872890624 data:0x0000000000000100
kworker/4:1-80 [004] ....... 284.067092: rtcpu_vinotify_event: tstamp:9590107148 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:104 vi_tstamp:306872898304 data:0x0000000001020252
kworker/4:1-80 [004] ....... 284.067092: rtcpu_vinotify_event: tstamp:9590107280 cch:0 vi:0 tag:FE channel:0x00 frame:104 vi_tstamp:306873216448 data:0x0000006800000020
kworker/4:1-80 [004] ....... 284.067092: rtcpu_vinotify_event: tstamp:9590107433 cch:0 vi:0 tag:CHANSEL_SHORT_FRAME channel:0x01 frame:104 vi_tstamp:306873216448 data:0x0043200001000000
kworker/4:1-80 [004] ....... 284.067093: rtcpu_vinotify_event: tstamp:9590107567 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:104 vi_tstamp:306873216448 data:0x0000000800000000
kworker/4:1-80 [004] ....... 284.067093: rtcpu_vinotify_event: tstamp:9590107720 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:0 vi_tstamp:306873229120 data:0x0000000007020253
kworker/4:1-80 [004] ....... 284.067093: rtcpu_vinotify_event: tstamp:9590107853 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:306873241856 data:0x359d550010000000
kworker/4:1-80 [004] ....... 284.067093: rtcpu_vinotify_event: tstamp:9590108006 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:306873248384 data:0x0000000031000254
v4l2-ctl-3607 [002] ....... 284.099497: tegra_channel_set_stream: 13e00000.host1x:nvcsi@15a00000- : 0x0
v4l2-ctl-3607 [002] ....... 284.099501: csi_s_stream: enable : 0x0
v4l2-ctl-3607 [004] ....... 284.100077: tegra_channel_set_power: imx415 9-001a : 0x0
v4l2-ctl-3607 [004] ....... 284.100083: camera_common_s_power: status : 0x0
v4l2-ctl-3607 [004] ....... 284.100587: tegra_channel_set_power: 13e00000.host1x:nvcsi@15a00000- : 0x0
v4l2-ctl-3607 [004] ....... 284.100588: csi_s_power: enable : 0x0
kworker/4:1-80 [004] ....... 290.126948: rtcpu_string: tstamp:9778543785 id:0x04010000 str:"VM0 deactivating."
I do see a “CHANSEL_FAULT”, could that explain that all frames are discarded?
Suppose yes.
CHANSEL_FAULT with data=0x100 means:
/** A line has more pixels than expected width, pixels dropped */
#define CAPTURE_CHANNEL_ERROR_PIXEL_LONG_LINE MK_BIT32(8)
?
The same camera/sensor and driver (with associated tables) works when running on Jetpack5.
You wrote earlier that line_length shouldn’t be an issue.
It must hence be something that jetpack6 does different (programming of HW like clocks).
Any suggestion where to look?
Doesn’t matter with line_length but matter with the output size.
The line_length is different setting from the sensor.
The pixel long line could be the output width more than expected.
Thanks
how would output width differ between jetpack5 and jetpack6.
driver and associated register tables are the exact same.
Do you confirm the trace log in JP5.x again.
Please adjust the size from the driver and device tree to fix the problem on JP6.
Thanks
I’m assuming you mean width and height.
I ran on a jetpack5 HW (not orin devkit, but same camera and driver) and see
nvidia@sdj-rogue-06:~$ lsb_release -a
No LSB modules are available.
Distributor ID: Ubuntu
Description: Ubuntu 20.04.6 LTS
Release: 20.04
Codename: focal
~$ env | grep NVIDIA
nvidia@sdj-rogue-06:~$ env | grep L4T
JETSON_L4T=35.4.1
nvidia@sdj-rogue-06:~$ env | grep -i JETPACK
JETSON_JETPACK=5.1.2
and I see the same CHANSEL_FAULT:
# tracer: nop
#
# entries-in-buffer/entries-written: 2003/2003 #P:8
#
# _-----=> irqs-off
# / _----=> need-resched
# | / _---=> hardirq/softirq
# || / _--=> preempt-depth
# ||| / delay
# TASK-PID CPU# |||| TIMESTAMP FUNCTION
# | | | |||| | |
kworker/2:2-406908 [002] .... 346797.246879: rtcpu_string: tstamp:10838006297360 id:0x04010000 str:"VM0 deactivating."
v4l2-ctl-407343 [004] .... 346806.142283: tegra_channel_open: vi-output, imx415 30-001a
v4l2-ctl-407343 [004] .... 346806.142688: tegra_channel_close: vi-output, imx415 30-001a
v4l2-ctl-407364 [005] .... 346821.277420: tegra_channel_open: vi-output, imx415 30-001a
v4l2-ctl-407364 [005] .... 346821.294512: tegra_channel_set_power: imx415 30-001a : 0x1
v4l2-ctl-407364 [005] .... 346821.294525: camera_common_s_power: status : 0x1
v4l2-ctl-407364 [005] .... 346821.321071: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2-ctl-407364 [005] .... 346821.321075: csi_s_power: enable : 0x1
v4l2-ctl-407364 [005] .... 346821.322327: tegra_channel_capture_setup: vnc_id 0 W 3840 H 2160 fmt c4
v4l2-ctl-407364 [005] .... 346821.333829: tegra_channel_set_stream: enable : 0x1
kworker/2:2-406908 [002] .... 346821.346690: rtcpu_string: tstamp:10838759977920 id:0x04010000 str:"VM0 activating."
kworker/2:2-406908 [002] .... 346821.346694: rtcpu_vinotify_event: tstamp:10838760501027 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:346840323895712 data:0x359d580010000000
kworker/2:2-406908 [002] .... 346821.346694: rtcpu_vinotify_event: tstamp:10838760501219 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:346840323902208 data:0x0000000031000001
kworker/2:2-406908 [002] .... 346821.346694: rtcpu_vinotify_event: tstamp:10838760501410 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:346840323947776 data:0x359d550010000000
kworker/2:2-406908 [002] .... 346821.346695: rtcpu_vinotify_event: tstamp:10838760501573 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:346840323954368 data:0x0000000031000002
v4l2-ctl-407364 [005] .... 346821.347775: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1
v4l2-ctl-407364 [005] .... 346821.347778: csi_s_stream: enable : 0x1
v4l2-ctl-407364 [005] .... 346821.348101: tegra_channel_set_stream: imx415 30-001a : 0x1
kworker/2:2-406908 [002] .... 346821.402847: rtcpu_nvcsi_intr: tstamp:10838762149153 class:GLOBAL type:PHY_INTR0 phy:0 cil:0 st:0 vc:0 status:0x01c00000
kworker/2:2-406908 [002] .... 346821.402849: rtcpu_nvcsi_intr: tstamp:10838762149153 class:GLOBAL type:PHY_INTR0 phy:0 cil:1 st:0 vc:0 status:0x00c00000
vi-output, imx4-407366 [002] .... 346821.408705: tegra_channel_capture_frame: sof:346840.398933888
vi-output, imx4-407366 [002] .... 346821.408707: tegra_channel_capture_frame: eof:346840.398999968
vi-output, imx4-407366 [002] .... 346821.419833: tegra_channel_capture_frame: sof:346840.410024640
vi-output, imx4-407366 [002] .... 346821.419834: tegra_channel_capture_frame: eof:346840.410091040
vi-output, imx4-407366 [002] .... 346821.430760: tegra_channel_capture_frame: sof:346840.421115392
vi-output, imx4-407366 [002] .... 346821.430761: tegra_channel_capture_frame: eof:346840.421189408
vi-output, imx4-407366 [002] .... 346821.441846: tegra_channel_capture_frame: sof:346840.432206144
vi-output, imx4-407366 [002] .... 346821.441846: tegra_channel_capture_frame: eof:346840.432280000
kworker/2:2-406908 [002] .... 346821.458697: rtcpu_vinotify_event: tstamp:10838762535651 cch:0 vi:0 tag:FS channel:0x00 frame:1 vi_tstamp:346840398746304 data:0x0000000100000010
kworker/2:2-406908 [002] .... 346821.458698: rtcpu_vinotify_event: tstamp:10838762535786 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:1 vi_tstamp:346840398746368 data:0x0000000800000000
kworker/2:2-406908 [002] .... 346821.458698: rtcpu_vinotify_event: tstamp:10838762535939 cch:0 vi:0 tag:CHANSEL_EMBED_SOF channel:0x23 frame:1 vi_tstamp:346840398751488 data:0x0000000000000004
kworker/2:2-406908 [002] .... 346821.458699: rtcpu_vinotify_event: tstamp:10838762536067 cch:0 vi:0 tag:CHANSEL_EMBED_EOF channel:0x23 frame:1 vi_tstamp:346840398755552 data:0x0000000000000008
kworker/2:2-406908 [002] .... 346821.458699: rtcpu_vinotify_event: tstamp:10838762536219 cch:0 vi:0 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:1 vi_tstamp:346840398756352 data:0x0000000000000000
kworker/2:2-406908 [002] .... 346821.458700: rtcpu_vinotify_event: tstamp:10838762536353 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:346840398794432 data:0x0000000003020001
kworker/2:2-406908 [002] .... 346821.458701: rtcpu_vinotify_event: tstamp:10838762536504 cch:0 vi:0 tag:CHANSEL_NOMATCH channel:0x01 frame:1 vi_tstamp:346840398756416 data:0x00000000000006e9
kworker/2:2-406908 [002] .... 346821.458701: rtcpu_vinotify_event: tstamp:10838762536634 cch:0 vi:0 tag:CHANSEL_PXL_SOF channel:0x23 frame:1 vi_tstamp:346840398933888 data:0x0000000000000001
kworker/2:2-406908 [002] .... 346821.458702: rtcpu_vinotify_event: tstamp:10838762536783 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:346840398942080 data:0x0000000008020001
kworker/2:2-406908 [002] .... 346821.458702: rtcpu_vinotify_event: tstamp:10838762536913 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:346840398937920 data:0x0000000000000100
kworker/2:2-406908 [002] .... 346821.458703: rtcpu_vinotify_event: tstamp:10838762537062 cch:0 vi:0 tag:VIFALC_ACTIONLST channel:0x23 frame:1 vi_tstamp:346840399002144 data:0x0000000001020001
kworker/2:2-406908 [002] .... 346821.458703: rtcpu_vinotify_event: tstamp:10838762803807 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:346840409580128 data:0x00000000086f0182
kworker/2:2-406908 [002] .... 346821.458704: rtcpu_vinotify_event: tstamp:10838762821268 cch:0 vi:0 tag:ATOMP_FRAME_DONE channel:0x23 frame:1 vi_tstamp:346840409580960 data:0x0000000000000000
kworker/2:2-406908 [002] .... 346821.458705: rtcpu_vinotify_event: tstamp:10838762821399 cch:0 vi:0 tag:CHANSEL_FAULT channel:0x23 frame:1 vi_tstamp:346840409580992 data:0x00000000087001c0
kworker/2:2-406908 [002] .... 346821.458706: rtcpu_vinotify_event: tstamp:10838762821551 cch:0 vi:0 tag:FE channel:0x00 frame:1 vi_tstamp:346840409738272 data:0x0000000100000020
kworker/2:2-406908 [002] .... 346821.458706: rtcpu_vinotify_event: tstamp:10838762821683 cch:0 vi:0 tag:ATOMP_FE channel:0x00 frame:1 vi_tstamp:346840409738336 data:0x0000000800000000
kworker/2:2-406908 [002] .... 346821.458707: rtcpu_vinotify_event: tstamp:10838762821832 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:346840409812384 data:0x359d580010000000
kworker/2:2-406908 [002] .... 346821.458708: rtcpu_vinotify_event: tstamp:10838762821962 cch:0 vi:0 tag:FS channel:0x00 frame:2 vi_tstamp:346840409837056 data:0x0000000200000010
kworker/2:2-406908 [002] .... 346821.458708: rtcpu_vinotify_event: tstamp:10838762822111 cch:0 vi:0 tag:ATOMP_FS channel:0x00 frame:2 vi_tstamp:346840409837120 data:0x0000000800000000
kworker/2:2-406908 [002] .... 346821.458709: rtcpu_vinotify_event: tstamp:10838762822242 cch:0 vi:0 tag:CHANSEL_EMBED_SOF channel:0x23 frame:2 vi_tstamp:346840409842240 data:0x0000000000000004
kworker/2:2-406908 [002] .... 346821.458709: rtcpu_vinotify_event: tstamp:10838762822387 cch:0 vi:0 tag:CHANSEL_EMBED_EOF channel:0x23 frame:2 vi_tstamp:346840409846304 data:0x0000000000000008
kworker/2:2-406908 [002] .... 346821.458710: rtcpu_vinotify_event: tstamp:10838762822517 cch:0 vi:0 tag:ATOMP_EMB_DATA_DONE channel:0x23 frame:2 vi_tstamp:346840409847136 data:0x0000000000000000
...
I guess jetpack6 just takes action when jetpack5 ignores that error?
Yes, please make sure the sensor output pixel per line for it.
Thanks
1 Like
Indeed the driver had an issue where width/height were incorrectly programmed.
After ensuring sensor width/height are programmed properly, CHANSEL_FAULT error is gone.
I saw CAPTURE_CHANNEL_ERROR_EMBED_INFRINGE (bit 14) reported after that fix, but that’s because I had forgotten to restore embedded_metadata_height = “1” in the DTS.
Frames are now all accounted for!
Long story short Jetpack5 didn’t seem to mind the extra pixels, but jetpack6 now is less lenient, which I like better.
Thanks for the support!
This topic was automatically closed 14 days after the last reply. New replies are no longer allowed.