Got "PXL_SOF syncpt timeout! err "

Hello forums,

I am using TX2 with Jetpack4.2-R32.1 to driver a pre-configured csi video(1080P@60fps UYVY, 4lane)

when I use V4L2 Test Bench to grab video stream I got bellow error:

[  126.377747] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  126.384193] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  126.597845] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  126.604506] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[  126.817836] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[  126.824479] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel

and /sys/kernel/debug/tracing/trace captured nothing when I use v4l2-ctl:

root@nvidia:~# sudo v4l2-ctl -d /dev/video0 --set-fmt-video=width=1920,height=1080,pixelformat=UYVY --set-ctrl bypass_mode=0 --stream-mmap --stream-count=1 --stream-to=tt.raw
^C
root@nvidia:~# cat /sys/kernel/debug/tracing/trace
# tracer: nop
#
# entries-in-buffer/entries-written: 0/0   #P:6
#
#                              _-----=> irqs-off
#                             / _----=> need-resched
#                            | / _---=> hardirq/softirq
#                            || / _--=> preempt-depth
#                            ||| /     delay
#           TASK-PID   CPU#  ||||    TIMESTAMP  FUNCTION
#              | |       |   ||||       |         |
root@nvidia:~#

There’s no data for the trace? Did you enable it before capture by v4l2-ctl?

sudo su
echo 1 > /sys/kernel/debug/tracing/tracing_on
echo 30720 > /sys/kernel/debug/tracing/buffer_size_kb
echo 1 > /sys/kernel/debug/tracing/events/tegra_rtcpu/enable
echo 1 > /sys/kernel/debug/tracing/events/freertos/enable
echo 2 > /sys/kernel/debug/camrtc/log-level
echo 1 > /sys/kernel/debug/tracing/events/camera_common/enable
echo > /sys/kernel/debug/tracing/trace

sorry, it’s my bad.

here is the capture log:

root@nvidia:/home/nvidia/workspace/iic# cat  /sys/kernel/debug/tracing/trace
# tracer: nop
#
# entries-in-buffer/entries-written: 595/595   #P:6
#
#                              _-----=> irqs-off
#                             / _----=> need-resched
#                            | / _---=> hardirq/softirq
#                            || / _--=> preempt-depth
#                            ||| /     delay
#           TASK-PID   CPU#  ||||    TIMESTAMP  FUNCTION
#              | |       |   ||||       |         |
        v4l2-ctl-9725  [001] .... 11587.871968: tegra_channel_open: vi-output, ov5693_sdi1 2-0036
        v4l2-ctl-9725  [001] .... 11587.880778: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt cb
 vi-output, ov56-9726  [005] .... 11587.881058: tegra_channel_set_stream: enable : 0x1
 vi-output, ov56-9726  [000] .... 11587.883590: tegra_channel_set_stream: 150c0000.nvcsi--3 : 0x1
 vi-output, ov56-9726  [000] .... 11587.883598: csi_s_stream: enable : 0x1
 vi-output, ov56-9726  [000] .... 11587.883644: tegra_channel_set_stream: ov5693_sdi1 2-0036 : 0x1
     kworker/2:0-9563  [002] .... 11587.923282: rtos_queue_peek_from_isr_failed: tstamp:362504234837 queue:0x0b4b4500
     kworker/2:0-9563  [002] .... 11587.923296: rtcpu_start: tstamp:362504236795
     kworker/2:0-9563  [002] .... 11587.923305: rtos_queue_send_from_isr_failed: tstamp:362504276085 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11587.923310: rtos_queue_send_from_isr_failed: tstamp:362504276190 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11587.923316: rtos_queue_send_from_isr_failed: tstamp:362504276294 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11587.923322: rtos_queue_send_from_isr_failed: tstamp:362504276398 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11587.923401: rtos_queue_send_from_isr_failed: tstamp:362504276500 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11587.923406: rtos_queue_send_from_isr_failed: tstamp:362504276602 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11587.923411: rtos_queue_send_from_isr_failed: tstamp:362504276703 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11587.923416: rtos_queue_send_from_isr_failed: tstamp:362504276804 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11587.923423: rtos_queue_send_failed: tstamp:362504277268 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11587.923427: rtos_queue_send_from_isr_failed: tstamp:362504282381 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11587.923433: rtos_queue_send_from_isr_failed: tstamp:362504282483 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11587.923437: rtos_queue_send_from_isr_failed: tstamp:362504282587 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11587.923442: rtos_queue_send_from_isr_failed: tstamp:362504282689 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11587.923447: rtos_queue_send_from_isr_failed: tstamp:362504282789 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11587.923452: rtos_queue_send_from_isr_failed: tstamp:362504282890 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11587.923457: rtos_queue_send_from_isr_failed: tstamp:362504282990 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11587.923462: rtos_queue_send_from_isr_failed: tstamp:362504283091 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11587.923467: rtos_queue_send_failed: tstamp:362504283960 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.091380: rtos_queue_peek_from_isr_failed: tstamp:362509235472 queue:0x0b4b4500
 vi-output, ov56-9726  [000] .... 11588.105693: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt cb
 vi-output, ov56-9726  [000] .... 11588.105743: tegra_channel_capture_frame: sof:-549620695340.-270403540224
     kworker/2:0-9563  [002] .... 11588.147640: rtos_queue_send_from_isr_failed: tstamp:362511261992 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.147665: rtos_queue_send_from_isr_failed: tstamp:362511262481 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.147676: rtos_queue_send_from_isr_failed: tstamp:362511262939 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.147684: rtos_queue_send_from_isr_failed: tstamp:362511263408 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.147693: rtos_queue_send_from_isr_failed: tstamp:362511263989 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.147703: rtos_queue_send_from_isr_failed: tstamp:362511264440 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.147846: rtos_queue_send_from_isr_failed: tstamp:362511264893 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.147857: rtos_queue_send_from_isr_failed: tstamp:362511265344 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.147874: rtos_queue_send_failed: tstamp:362511267618 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.147886: rtos_queue_send_from_isr_failed: tstamp:362511275424 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.147894: rtos_queue_send_from_isr_failed: tstamp:362511275885 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.147903: rtos_queue_send_from_isr_failed: tstamp:362511276335 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.147912: rtos_queue_send_from_isr_failed: tstamp:362511276789 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.147921: rtos_queue_send_from_isr_failed: tstamp:362511277239 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.147932: rtos_queue_send_from_isr_failed: tstamp:362511277690 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.147941: rtos_queue_send_from_isr_failed: tstamp:362511278146 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.147950: rtos_queue_send_from_isr_failed: tstamp:362511278605 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.147959: rtos_queue_send_failed: tstamp:362511280654 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.147969: rtos_queue_send_from_isr_failed: tstamp:362511295724 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.147977: rtos_queue_send_from_isr_failed: tstamp:362511296206 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.147985: rtos_queue_send_from_isr_failed: tstamp:362511296694 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.147995: rtos_queue_send_from_isr_failed: tstamp:362511297157 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.148003: rtos_queue_send_from_isr_failed: tstamp:362511297620 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.148013: rtos_queue_send_from_isr_failed: tstamp:362511298072 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.148021: rtos_queue_send_from_isr_failed: tstamp:362511298530 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.148030: rtos_queue_send_from_isr_failed: tstamp:362511298997 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.148039: rtos_queue_send_failed: tstamp:362511300946 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.148047: rtos_queue_send_from_isr_failed: tstamp:362511308924 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.148057: rtos_queue_send_from_isr_failed: tstamp:362511309379 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.148065: rtos_queue_send_from_isr_failed: tstamp:362511309836 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.148075: rtos_queue_send_from_isr_failed: tstamp:362511310240 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.148083: rtos_queue_send_from_isr_failed: tstamp:362511310414 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.148093: rtos_queue_send_from_isr_failed: tstamp:362511310590 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.148101: rtos_queue_send_from_isr_failed: tstamp:362511310764 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.148109: rtos_queue_send_from_isr_failed: tstamp:362511310939 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.148117: rtos_queue_send_failed: tstamp:362511312848 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.203469: rtos_queue_peek_from_isr_failed: tstamp:362514235500 queue:0x0b4b4500
 vi-output, ov56-9726  [000] .... 11588.325362: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt cb
 vi-output, ov56-9726  [000] .... 11588.325402: tegra_channel_capture_frame: sof:-549620695340.-270403540224
     kworker/2:0-9563  [002] .... 11588.371486: rtos_queue_send_from_isr_failed: tstamp:362518128581 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371511: rtos_queue_send_from_isr_failed: tstamp:362518129070 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.371522: rtos_queue_send_from_isr_failed: tstamp:362518129524 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.371532: rtos_queue_send_from_isr_failed: tstamp:362518129975 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.371542: rtos_queue_send_from_isr_failed: tstamp:362518130581 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.371551: rtos_queue_send_from_isr_failed: tstamp:362518131045 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.371713: rtos_queue_send_from_isr_failed: tstamp:362518131499 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.371722: rtos_queue_send_from_isr_failed: tstamp:362518131950 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.371738: rtos_queue_send_failed: tstamp:362518134184 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371750: rtos_queue_send_from_isr_failed: tstamp:362518140766 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371760: rtos_queue_send_from_isr_failed: tstamp:362518141321 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.371769: rtos_queue_send_from_isr_failed: tstamp:362518141775 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.371779: rtos_queue_send_from_isr_failed: tstamp:362518142228 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.371790: rtos_queue_send_from_isr_failed: tstamp:362518142684 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.371798: rtos_queue_send_from_isr_failed: tstamp:362518143135 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.371808: rtos_queue_send_from_isr_failed: tstamp:362518143614 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.371816: rtos_queue_send_from_isr_failed: tstamp:362518144066 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.371825: rtos_queue_send_failed: tstamp:362518145884 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371835: rtos_queue_send_from_isr_failed: tstamp:362518157025 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371843: rtos_queue_send_from_isr_failed: tstamp:362518157518 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.371853: rtos_queue_send_from_isr_failed: tstamp:362518157976 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.371861: rtos_queue_send_from_isr_failed: tstamp:362518158433 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.371871: rtos_queue_send_from_isr_failed: tstamp:362518158885 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.371879: rtos_queue_send_from_isr_failed: tstamp:362518159336 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.371887: rtos_queue_send_from_isr_failed: tstamp:362518159791 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.371897: rtos_queue_send_from_isr_failed: tstamp:362518160241 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.371906: rtos_queue_send_failed: tstamp:362518162003 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371916: rtos_queue_send_from_isr_failed: tstamp:362518167751 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371924: rtos_queue_send_from_isr_failed: tstamp:362518168218 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.371934: rtos_queue_send_from_isr_failed: tstamp:362518168676 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.371942: rtos_queue_send_from_isr_failed: tstamp:362518169130 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.371951: rtos_queue_send_from_isr_failed: tstamp:362518169712 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.371959: rtos_queue_send_from_isr_failed: tstamp:362518170176 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.371968: rtos_queue_send_from_isr_failed: tstamp:362518170631 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.371976: rtos_queue_send_from_isr_failed: tstamp:362518171086 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.371984: rtos_queue_send_failed: tstamp:362518177267 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.371999: rtos_queue_peek_from_isr_failed: tstamp:362519235420 queue:0x0b4b4500
 vi-output, ov56-9726  [000] .... 11588.545325: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt cb
 vi-output, ov56-9726  [000] .... 11588.545367: tegra_channel_capture_frame: sof:-549620695340.-270403540224
     kworker/2:0-9563  [002] .... 11588.547386: rtos_queue_peek_from_isr_failed: tstamp:362524235430 queue:0x0b4b4500
     kworker/2:0-9563  [002] .... 11588.547407: rtos_queue_send_from_isr_failed: tstamp:362525002344 queue:0x0b4a7258
     kworker/2:0-9563  [002] .... 11588.547416: rtos_queue_send_from_isr_failed: tstamp:362525002828 queue:0x0b4aad68
     kworker/2:0-9563  [002] .... 11588.547427: rtos_queue_send_from_isr_failed: tstamp:362525003284 queue:0x0b4ac998
     kworker/2:0-9563  [002] .... 11588.547435: rtos_queue_send_from_isr_failed: tstamp:362525003739 queue:0x0b4ae518
     kworker/2:0-9563  [002] .... 11588.547444: rtos_queue_send_from_isr_failed: tstamp:362525004190 queue:0x0b4af2d8
     kworker/2:0-9563  [002] .... 11588.547591: rtos_queue_send_from_isr_failed: tstamp:362525004648 queue:0x0b4b0098
     kworker/2:0-9563  [002] .... 11588.547600: rtos_queue_send_from_isr_failed: tstamp:362525005104 queue:0x0b4b0e58
     kworker/2:0-9563  [002] .... 11588.547610: rtos_queue_send_from_isr_failed: tstamp:362525005560 queue:0x0b4b1c18
     kworker/2:0-9563  [002] .... 11588.547621: rtos_queue_send_failed: tstamp:362525007725 queue:0x0b4a7258

The trace log shows didn’t get any validate data from MIPI bus. You may need to probe the signal to make sure of it.

Okay, thanks ShaneCCC.

other thing I want to know, if mipi csi data changed from 1080P@30pfs to 1080P@60fps, is it necessary to modify the kernel code and dtb?

No need for bring up, you can modify it after all things are up.

oot@localhost:/home/ubuntu# cat /sys/kernel/debug/tracing/trace

tracer: nop

entries-in-buffer/entries-written: 480/480 #P:4

_-----=> irqs-off

/ _----=> need-resched

| / _—=> hardirq/softirq

|| / _–=> preempt-depth

||| / delay

TASK-PID CPU# |||| TIMESTAMP FUNCTION

| | | |||| | |

    v4l2-ctl-17957 [000] ....  2334.852500: tegra_channel_open: vi-output, ov2735 2-003c
    v4l2-ctl-17957 [000] ....  2334.853713: tegra_channel_set_power: ov2735 2-003c : 0x1
    v4l2-ctl-17957 [000] ....  2334.853731: camera_common_s_power: status : 0x1
    v4l2-ctl-17957 [005] ....  2334.890475: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x1
    v4l2-ctl-17957 [005] ....  2334.890484: csi_s_power: enable : 0x1
    v4l2-ctl-17957 [000] ....  2334.913937: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 20

vi-output, ov27-17958 [003] … 2334.914051: tegra_channel_set_stream: enable : 0x1
vi-output, ov27-17958 [004] … 2334.919709: tegra_channel_set_stream: 150c0000.nvcsi–1 : 0x1
vi-output, ov27-17958 [004] … 2334.919712: csi_s_stream: enable : 0x1
vi-output, ov27-17958 [004] … 2334.919739: tegra_channel_set_stream: ov2735 2-003c : 0x1
kworker/4:1-1161 [004] … 2334.942261: rtos_queue_peek_from_isr_failed: tstamp:73243845391 queue:0x0b4b4500
kworker/4:1-1161 [004] … 2334.942264: rtcpu_start: tstamp:73243846435
kworker/4:1-1161 [004] … 2334.942265: rtos_queue_send_from_isr_failed: tstamp:73243855291 queue:0x0b4a7258
kworker/4:1-1161 [004] … 2334.942266: rtos_queue_send_from_isr_failed: tstamp:73243855400 queue:0x0b4aad68
kworker/4:1-1161 [004] … 2334.942267: rtos_queue_send_from_isr_failed: tstamp:73243855509 queue:0x0b4ac998
kworker/4:1-1161 [004] … 2334.942267: rtos_queue_send_from_isr_failed: tstamp:73243855616 queue:0x0b4ae518
kworker/4:1-1161 [004] … 2334.942268: rtos_queue_send_from_isr_failed: tstamp:73243855720 queue:0x0b4af2d8
kworker/4:1-1161 [004] … 2334.942269: rtos_queue_send_from_isr_failed: tstamp:73243855825 queue:0x0b4b0098
kworker/4:1-1161 [004] … 2334.942269: rtos_queue_send_from_isr_failed: tstamp:73243855930 queue:0x0b4b0e58

i am getting below error on ov2735 can any one help on this

The trace tell the NVCSI/VI didn’t receive any validate package from the MIPI bus.
You may need to probe the signal to make sure the sensor output data as MIPI spec.