Dual Custom Sensor run error issue on Jetson Tx2

jpchae · June 8, 2020, 3:35am

Hello,

I’m trying to develop two custom camera sensor on a jetson Tx2 board.

I checked the device registeration “video0 , video1”.

I tested the gst-launch-1.0 command and got the error as below.

Please, give me good advise.

It is serial log message.

[  291.284528] s5k5e8 2-0010: s5k5e8_open:
[  291.288705] s5k5e8 2-0018: s5k5e8_open:
[  292.631146] s5k5e8 2-0010: s5k5e8_start_streaming: start streaming
[  298.829394] host1x 13e10000.host1x: CaptureSchedule: syncpoint id 35 (15600000.isp_nvargus-daemo                                       n_4) stuck waiting 18, timeout=-1
[  302.669570] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 31 (15600000.isp_nvargus-daemo                                       n_0) clientid 4, HW thresh 0, done 2
[  302.682860] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 32 (15600000.isp_nvargus-daemo                                       n_1) clientid 4, HW thresh 0, done 1
[  302.682874] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 33 (15600000.isp_nvargus-daemo                                       n_2) clientid 4, HW thresh 0, done 1
[  302.682938] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 34 (15600000.isp_nvargus-daemo                                       n_3) clientid 4, HW thresh 0, done 1
[  302.682951] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 36 (15600000.isp_nvargus-daemo                                       n_5) clientid 4, HW thresh 0, done 1
[  302.682961] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 35 (15600000.isp_nvargus-daemo                                       n_4) clientid 4, HW thresh 15, done 15
[  302.925464] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 45 (15700000.vi_2) clientid 13                                       , HW thresh 0, done 1
[  302.936393] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 44 (15700000.vi_1) clientid 13                                       , HW thresh 0, done 1
[  302.936406] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 43 (15700000.vi_0) clientid 13                                       , HW thresh 1, done 1
[  312.909239] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 31 (15600000.isp_nvargus-daemo                                       n_0) clientid 4, HW thresh 4, done 6
[  312.922103] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 32 (15600000.isp_nvargus-daemo                                       n_1) clientid 4, HW thresh 2, done 3
[  312.922107] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 33 (15600000.isp_nvargus-daemo                                       n_2) clientid 4, HW thresh 2, done 3
[  312.922111] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 34 (15600000.isp_nvargus-daemo                                       n_3) clientid 4, HW thresh 2, done 3
[  312.922114] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 36 (15600000.isp_nvargus-daemo                                       n_5) clientid 4, HW thresh 2, done 3
[  312.922117] host1x 13e10000.host1x: cdma_handle_timeout: timeout: 35 (15600000.isp_nvargus-daemo                                       n_4) clientid 4, HW thresh 31, done 31

It is dmesg message.

[  291.284528] s5k5e8 2-0010: s5k5e8_open:
[  291.288705] s5k5e8 2-0018: s5k5e8_open:
[  292.631146] s5k5e8 2-0010: s5k5e8_start_streaming: start streaming 
[  294.157243] fence timeout on [ffffffc14b8359c0] after 1500ms
[  294.157258] fence timeout on [ffffffc14b835b40] after 1500ms
[  294.157277] name=[nvhost_sync:31], current value=0 waiting value=1
[  294.157285] name=[nvhost_sync:34], current value=0 waiting value=1
[  294.157299] ---- mlocks ----
[  294.157302] ---- mlocks ----
[  294.157323] 8: locked by channel 7
[  294.157326] 8: locked by channel 7


[  294.157359] ---- syncpts ----
[  294.157362] ---- syncpts ----
[  294.157387] id 5 (disp_d) min 4013 max 4013 refs 1 (previous client : )
[  294.157402] id 6 (disp_e) min 3 max 3 refs 1 (previous client : )
[  294.157417] id 7 (disp_f) min 3 max 3 refs 1 (previous client : )
[  294.157432] id 8 (vblank1) min 9979 max -6 refs 1 (previous client : )
[  294.157466] id 20 (gp10b_507) min 530 max 530 refs 1 (previous client : )
[  294.157480] id 21 (gp10b_506) min 22 max 22 refs 1 (previous client : )
[  294.157495] id 22 (gp10b_505) min 566 max 566 refs 1 (previous client : gp10b_505)
[  294.157509] id 23 (gp10b_504) min 6 max 6 refs 1 (previous client : )
[  294.157524] id 24 (gp10b_503) min 114522 max 114522 refs 1 (previous client : )
[  294.157538] id 25 (gp10b_502) min 14 max 14 refs 1 (previous client : )
[  294.157556] id 28 (gp10b_501) min 20412 max 20412 refs 1 (previous client : gp10b_501)
[  294.157573] id 31 (15600000.isp_nvargus-daemon_0) min 0 max 4 refs 4 (previous client : )
[  294.157587] id 32 (15600000.isp_nvargus-daemon_1) min 0 max 2 refs 4 (previous client : )
[  294.157600] id 33 (15600000.isp_nvargus-daemon_2) min 0 max 2 refs 4 (previous client : )
[  294.157614] id 34 (15600000.isp_nvargus-daemon_3) min 0 max 2 refs 4 (previous client : )
[  294.157627] id 35 (15600000.isp_nvargus-daemon_4) min 15 max 26 refs 9 (previous client : )
[  294.157641] id 36 (15600000.isp_nvargus-daemon_5) min 0 max 2 refs 4 (previous client : )
[  294.157656] id 38 (gp10b_499) min 12 max 12 refs 1 (previous client : )
[  294.157675] id 42 (15700000.vi_0) min 14 max 14 refs 2 (previous client : 15700000.vi_0)
[  294.157689] id 43 (15700000.vi_0) min 1 max 2 refs 4 (previous client : 15700000.vi_0)
[  294.157703] id 44 (15700000.vi_1) min 0 max 2 refs 4 (previous client : 15700000.vi_1)
[  294.157717] id 45 (15700000.vi_2) min 0 max 2 refs 4 (previous client : 15700000.vi_2)
[  294.157731] id 46 (150c0000.nvcsi_0) min 26 max 26 refs 2 (previous client : 150c0000.nvcsi_0)
[  294.157748] id 49 (gp10b_495) min 8 max 8 refs 1 (previous client : )
[  294.157761] id 50 (gp10b_494) min 6 max 6 refs 1 (previous client : )
[  294.157775] id 51 (gp10b_493) min 6 max 6 refs 1 (previous client : )
[  294.157788] id 52 (gp10b_492) min 6 max 6 refs 1 (previous client : )
[  294.157802] id 53 (gp10b_491) min 6 max 6 refs 1 (previous client : )
[  294.157815] id 54 (gp10b_490) min 6 max 6 refs 1 (previous client : )

[  294.158840] ---- channels ----
[  294.158879] id 5 (disp_d) min 4013 max 4013 refs 1 (previous client : )
[  294.158885] 
               channel 2 - 15820000.se

[  294.158896] NvHost basic channel registers:
[  294.158900] id 6 (disp_e) min 3 max 3 refs 1 (previous client : )
[  294.158912] id 7 (disp_f) min 3 max 3 refs 1 (previous client : )
[  294.158915] CMDFIFO_STAT_0:  00002040
[  294.158923] CMDFIFO_RDATA_0: 15899408
[  294.158931] id 8 (vblank1) min 9980 max -6 refs 1 (previous client : )
[  294.158938] CMDP_OFFSET_0:   00000000
[  294.158948] CMDP_CLASS_0:    00000000
[  294.158957] CHANNELSTAT_0:   00000000
[  294.158964] id 20 (gp10b_507) min 530 max 530 refs 1 (previous client : )
[  294.158967] The CDMA sync queue is empty.

[  294.158984] id 21 (gp10b_506) min 22 max 22 refs 1 (previous client : )
[  294.158989] 
               channel 3 - 15830000.se

[  294.158994] NvHost basic channel registers:
[  294.159003] id 22 (gp10b_505) min 566 max 566 refs 1 (previous client : gp10b_505)
[  294.159005] CMDFIFO_STAT_0:  00002040
[  294.159012] CMDFIFO_RDATA_0: 00481105
[  294.159019] id 23 (gp10b_504) min 6 max 6 refs 1 (previous client : )
[  294.159029] CMDP_OFFSET_0:   00000000
[  294.159035] id 24 (gp10b_503) min 114522 max 114522 refs 1 (previous client : )
[  294.159042] CMDP_CLASS_0:    00000000
[  294.159049] id 25 (gp10b_502) min 14 max 14 refs 1 (previous client : )
[  294.159051] CHANNELSTAT_0:   00000000
[  294.159057] The CDMA sync queue is empty.

[  294.159068] id 28 (gp10b_501) min 20412 max 20412 refs 1 (previous client : gp10b_501)
[  294.159082] 
               channel 4 - 15840000.se

[  294.159085] id 31 (15600000.isp_nvargus-daemon_0) min 0 max 4 refs 4 (previous client : )
[  294.159092] NvHost basic channel registers:
[  294.159097] id 32 (15600000.isp_nvargus-daemon_1) min 0 max 2 refs 4 (previous client : )
[  294.159105] CMDFIFO_STAT_0:  00002040
[  294.159108] id 33 (15600000.isp_nvargus-daemon_2) min 0 max 2 refs 4 (previous client : )
[  294.159116] CMDFIFO_RDATA_0: 46487124
[  294.159119] id 34 (15600000.isp_nvargus-daemon_3) min 0 max 2 refs 4 (previous client : )
[  294.159131] id 35 (15600000.isp_nvargus-daemon_4) min 15 max 26 refs 9 (previous client : )
[  294.159133] CMDP_OFFSET_0:   00000000
[  294.159139] CMDP_CLASS_0:    00000000
[  294.159146] id 36 (15600000.isp_nvargus-daemon_5) min 0 max 2 refs 4 (previous client : )
[  294.159149] CHANNELSTAT_0:   00000000
[  294.159154] The CDMA sync queue is empty.

[  294.159164] id 38 (gp10b_499) min 12 max 12 refs 1 (previous client : )
[  294.159177] 
               channel 7 - 15600000.isp

It is trace debugging message.

# entries-in-buffer/entries-written: 530/530   #P:6
#
#                              _-----=> irqs-off
#                             / _----=> need-resched
#                            | / _---=> hardirq/softirq
#                            || / _--=> preempt-depth
#                            ||| /     delay
#           TASK-PID   CPU#  ||||    TIMESTAMP  FUNCTION
#              | |       |   ||||       |         |
 gst-plugin-scan-8502  [002] ....   291.292962: tegra_channel_open: vi-output, s5k5e8 2-0010
 gst-plugin-scan-8502  [002] ....   291.294109: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
 gst-plugin-scan-8502  [002] ....   291.294144: camera_common_s_power: status : 0x1
 gst-plugin-scan-8502  [002] ....   291.298581: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
 gst-plugin-scan-8502  [002] ....   291.298585: csi_s_power: enable : 0x1
 gst-plugin-scan-8502  [002] ....   291.300554: tegra_channel_close: vi-output, s5k5e8 2-0010
 gst-plugin-scan-8502  [002] ....   291.300559: tegra_channel_set_power: s5k5e8 2-0010 : 0x0
 gst-plugin-scan-8502  [002] ....   291.300568: camera_common_s_power: status : 0x0
 gst-plugin-scan-8502  [002] ....   291.302678: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x0
 gst-plugin-scan-8502  [002] ....   291.302680: csi_s_power: enable : 0x0
 gst-plugin-scan-8502  [002] ....   291.303029: tegra_channel_open: vi-output, s5k5e8 2-0018
 gst-plugin-scan-8502  [002] ....   291.303037: tegra_channel_set_power: s5k5e8 2-0018 : 0x1
 gst-plugin-scan-8502  [002] ....   291.303051: camera_common_s_power: status : 0x1
 gst-plugin-scan-8502  [002] ....   291.307440: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x1
 gst-plugin-scan-8502  [002] ....   291.307443: csi_s_power: enable : 0x1
 gst-plugin-scan-8502  [002] ....   291.307498: tegra_channel_close: vi-output, s5k5e8 2-0018
 gst-plugin-scan-8502  [002] ....   291.307502: tegra_channel_set_power: s5k5e8 2-0018 : 0x0
 gst-plugin-scan-8502  [002] ....   291.307513: camera_common_s_power: status : 0x0
 gst-plugin-scan-8502  [002] ....   291.309617: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x0
 gst-plugin-scan-8502  [002] ....   291.309619: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.122976: tegra_channel_open: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.124145: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
  nvargus-daemon-8508  [002] ....   292.124164: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.128612: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
  nvargus-daemon-8508  [002] ....   292.128616: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.130527: tegra_channel_close: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.130531: tegra_channel_set_power: s5k5e8 2-0010 : 0x0
  nvargus-daemon-8508  [002] ....   292.130538: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.132653: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x0
  nvargus-daemon-8508  [002] ....   292.132655: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.133047: tegra_channel_open: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.133052: tegra_channel_set_power: s5k5e8 2-0018 : 0x1
  nvargus-daemon-8508  [002] ....   292.133062: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.137474: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x1
  nvargus-daemon-8508  [002] ....   292.137476: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.137678: tegra_channel_close: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.137681: tegra_channel_set_power: s5k5e8 2-0018 : 0x0
  nvargus-daemon-8508  [002] ....   292.137688: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.139797: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x0
  nvargus-daemon-8508  [002] ....   292.139800: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.151509: tegra_channel_open: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.151517: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
  nvargus-daemon-8508  [002] ....   292.151551: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.155976: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
  nvargus-daemon-8508  [002] ....   292.155979: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.156214: tegra_channel_close: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.156217: tegra_channel_set_power: s5k5e8 2-0010 : 0x0
  nvargus-daemon-8508  [002] ....   292.156224: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.158342: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x0
  nvargus-daemon-8508  [002] ....   292.158345: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.158682: tegra_channel_open: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.158690: tegra_channel_set_power: s5k5e8 2-0018 : 0x1
  nvargus-daemon-8508  [002] ....   292.158702: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.163095: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x1
  nvargus-daemon-8508  [002] ....   292.163098: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.163330: tegra_channel_close: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.163333: tegra_channel_set_power: s5k5e8 2-0018 : 0x0
  nvargus-daemon-8508  [002] ....   292.163339: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.165444: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x0
  nvargus-daemon-8508  [002] ....   292.165446: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.206162: tegra_channel_open: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.206175: tegra_channel_set_power: s5k5e8 2-0018 : 0x1
  nvargus-daemon-8508  [002] ....   292.206215: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.210650: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x1
  nvargus-daemon-8508  [002] ....   292.210655: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.210707: tegra_channel_close: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.210712: tegra_channel_set_power: s5k5e8 2-0018 : 0x0
  nvargus-daemon-8508  [002] ....   292.210723: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.212840: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x0
  nvargus-daemon-8508  [002] ....   292.212843: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.213234: tegra_channel_open: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.213239: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
  nvargus-daemon-8508  [002] ....   292.213247: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.218197: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
  nvargus-daemon-8508  [002] ....   292.218200: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.218230: tegra_channel_close: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.218233: tegra_channel_set_power: s5k5e8 2-0010 : 0x0
  nvargus-daemon-8508  [002] ....   292.218240: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.220357: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x0
  nvargus-daemon-8508  [002] ....   292.220360: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.220700: tegra_channel_open: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.220706: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
  nvargus-daemon-8508  [002] ....   292.220717: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.225175: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
  nvargus-daemon-8508  [002] ....   292.225178: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.225391: tegra_channel_close: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.225393: tegra_channel_set_power: s5k5e8 2-0010 : 0x0
  nvargus-daemon-8508  [002] ....   292.225399: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.227515: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x0
  nvargus-daemon-8508  [002] ....   292.227517: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.227865: tegra_channel_open: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.227871: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
  nvargus-daemon-8508  [002] ....   292.227882: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.232288: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
  nvargus-daemon-8508  [002] ....   292.232291: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.232557: tegra_channel_close: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.232560: tegra_channel_set_power: s5k5e8 2-0010 : 0x0
  nvargus-daemon-8508  [002] ....   292.232565: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.234683: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x0
  nvargus-daemon-8508  [002] ....   292.234724: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.289576: tegra_channel_open: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.289595: tegra_channel_set_power: s5k5e8 2-0018 : 0x1
  nvargus-daemon-8508  [002] ....   292.289610: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.294064: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x1
  nvargus-daemon-8508  [002] ....   292.294073: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.294141: tegra_channel_close: vi-output, s5k5e8 2-0018
  nvargus-daemon-8508  [002] ....   292.294146: tegra_channel_set_power: s5k5e8 2-0018 : 0x0
  nvargus-daemon-8508  [002] ....   292.294159: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.296272: tegra_channel_set_power: 150c0000.nvcsi--1 : 0x0
  nvargus-daemon-8508  [002] ....   292.296274: csi_s_power: enable : 0x0
  nvargus-daemon-8508  [002] ....   292.296617: tegra_channel_open: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.296623: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
  nvargus-daemon-8508  [002] ....   292.296633: camera_common_s_power: status : 0x1
  nvargus-daemon-8508  [002] ....   292.301015: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
  nvargus-daemon-8508  [002] ....   292.301019: csi_s_power: enable : 0x1
  nvargus-daemon-8508  [002] ....   292.301063: tegra_channel_close: vi-output, s5k5e8 2-0010
  nvargus-daemon-8508  [002] ....   292.301068: tegra_channel_set_power: s5k5e8 2-0010 : 0x0
  nvargus-daemon-8508  [002] ....   292.301078: camera_common_s_power: status : 0x0
  nvargus-daemon-8508  [002] ....   292.303233: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x0
  nvargus-daemon-8508  [002] ....   292.303235: csi_s_power: enable : 0x0
     kworker/1:0-18    [001] ....   292.365156: rtos_queue_peek_from_isr_failed: tstamp:9418626956 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   292.365163: rtcpu_start: tstamp:9418628381
     kworker/1:0-18    [001] ....   292.365165: rtos_queue_send_from_isr_failed: tstamp:9418637863 queue:0x0b4a7258
     kworker/1:0-18    [001] ....   292.365166: rtos_queue_send_from_isr_failed: tstamp:9418637968 queue:0x0b4aad68
     kworker/1:0-18    [001] ....   292.365168: rtos_queue_send_from_isr_failed: tstamp:9418638072 queue:0x0b4ac998
     kworker/1:0-18    [001] ....   292.365169: rtos_queue_send_from_isr_failed: tstamp:9418638177 queue:0x0b4ae518
     kworker/1:0-18    [001] ....   292.365170: rtos_queue_send_from_isr_failed: tstamp:9418638278 queue:0x0b4af2d8
     kworker/1:0-18    [001] ....   292.365194: rtos_queue_send_from_isr_failed: tstamp:9418638379 queue:0x0b4b0098
     kworker/1:0-18    [001] ....   292.365195: rtos_queue_send_from_isr_failed: tstamp:9418638485 queue:0x0b4b0e58
     kworker/1:0-18    [001] ....   292.365196: rtos_queue_send_from_isr_failed: tstamp:9418638586 queue:0x0b4b1c18
     kworker/1:0-18    [001] ....   292.365199: rtos_queue_send_failed: tstamp:9418639036 queue:0x0b4a7258
     kworker/1:0-18    [001] ....   292.477313: rtos_queue_peek_from_isr_failed: tstamp:9423627321 queue:0x0b4b4500
 CaptureSchedule-8527  [003] ....   292.582826: tegra_channel_open: vi-output, s5k5e8 2-0010
 CaptureSchedule-8527  [003] ....   292.582836: tegra_channel_set_power: s5k5e8 2-0010 : 0x1
 CaptureSchedule-8527  [003] ....   292.582847: camera_common_s_power: status : 0x1
 CaptureSchedule-8527  [003] ....   292.587166: tegra_channel_set_power: 150c0000.nvcsi--2 : 0x1
 CaptureSchedule-8527  [003] ....   292.587170: csi_s_power: enable : 0x1
     kworker/1:0-18    [001] ....   292.589162: rtos_queue_send_from_isr_failed: tstamp:9427027079 queue:0x0b4a7258
     kworker/1:0-18    [001] ....   292.589167: rtos_queue_send_from_isr_failed: tstamp:9427027190 queue:0x0b4aad68
     kworker/1:0-18    [001] ....   292.589169: rtos_queue_send_from_isr_failed: tstamp:9427027296 queue:0x0b4ac998
     kworker/1:0-18    [001] ....   292.589170: rtos_queue_send_from_isr_failed: tstamp:9427027399 queue:0x0b4ae518
     kworker/1:0-18    [001] ....   292.589172: rtos_queue_send_from_isr_failed: tstamp:9427027500 queue:0x0b4af2d8
     kworker/1:0-18    [001] ....   292.589173: rtos_queue_send_from_isr_failed: tstamp:9427027601 queue:0x0b4b0098
     kworker/1:0-18    [001] ....   292.589174: rtos_queue_send_from_isr_failed: tstamp:9427027704 queue:0x0b4b0e58
     kworker/1:0-18    [001] ....   292.589197: rtos_queue_send_from_isr_failed: tstamp:9427027806 queue:0x0b4b1c18
     kworker/1:0-18    [001] ....   292.589200: rtos_queue_send_failed: tstamp:9427028252 queue:0x0b4a7258
     kworker/1:0-18    [001] ....   292.589201: rtos_queue_send_from_isr_failed: tstamp:9427278388 queue:0x0b4a7258
     kworker/1:0-18    [001] ....   292.589202: rtos_queue_send_from_isr_failed: tstamp:9427278493 queue:0x0b4aad68
     kworker/1:0-18    [001] ....   292.589204: rtos_queue_send_from_isr_failed: tstamp:9427278595 queue:0x0b4ac998
     kworker/1:0-18    [001] ....   292.589205: rtos_queue_send_from_isr_failed: tstamp:9427278699 queue:0x0b4ae518
     kworker/1:0-18    [001] ....   292.589206: rtos_queue_send_from_isr_failed: tstamp:9427278812 queue:0x0b4af2d8
     kworker/1:0-18    [001] ....   292.589208: rtos_queue_send_from_isr_failed: tstamp:9427278914 queue:0x0b4b0098
     kworker/1:0-18    [001] ....   292.589209: rtos_queue_send_from_isr_failed: tstamp:9427279015 queue:0x0b4b0e58
     kworker/1:0-18    [001] ....   292.589210: rtos_queue_send_from_isr_failed: tstamp:9427279115 queue:0x0b4b1c18
     kworker/1:0-18    [001] ....   292.589212: rtos_queue_send_failed: tstamp:9427279563 queue:0x0b4a7258
 CaptureSchedule-8527  [003] ....   292.589945: tegra_channel_set_stream: enable : 0x1
 CaptureSchedule-8527  [003] ....   292.591286: tegra_channel_set_stream: 150c0000.nvcsi--2 : 0x1
 CaptureSchedule-8527  [003] ....   292.591289: csi_s_stream: enable : 0x1
 CaptureSchedule-8527  [003] ....   292.591289: tegra_channel_set_stream: s5k5e8 2-0010 : 0x1
     kworker/1:0-18    [001] ....   292.645223: rtos_queue_peek_from_isr_failed: tstamp:9428627324 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   292.701162: rtos_queue_send_from_isr_failed: tstamp:9429931884 queue:0x0b4a7258
     kworker/1:0-18    [001] ....   292.701168: rtos_queue_send_from_isr_failed: tstamp:9429932028 queue:0x0b4aad68
     kworker/1:0-18    [001] ....   292.701171: rtos_queue_send_from_isr_failed: tstamp:9429932170 queue:0x0b4ac998
     kworker/1:0-18    [001] ....   292.701173: rtos_queue_send_from_isr_failed: tstamp:9429932308 queue:0x0b4ae518
     kworker/1:0-18    [001] ....   292.701175: rtos_queue_send_from_isr_failed: tstamp:9429932446 queue:0x0b4af2d8
     kworker/1:0-18    [001] ....   292.701205: rtos_queue_send_from_isr_failed: tstamp:9429932583 queue:0x0b4b0098
     kworker/1:0-18    [001] ....   292.701206: rtos_queue_send_from_isr_failed: tstamp:9429932721 queue:0x0b4b0e58
     kworker/1:0-18    [001] ....   292.701207: rtos_queue_send_from_isr_failed: tstamp:9429932858 queue:0x0b4b1c18
     kworker/1:0-18    [001] ....   292.701210: rtos_queue_send_failed: tstamp:9429934352 queue:0x0b4a7258
     kworker/1:0-18    [001] ....   292.813300: rtos_queue_peek_from_isr_failed: tstamp:9433627342 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   292.981269: rtos_queue_peek_from_isr_failed: tstamp:9438627345 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   293.149302: rtos_queue_peek_from_isr_failed: tstamp:9443627351 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   293.317391: rtos_queue_peek_from_isr_failed: tstamp:9448627361 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   293.485311: rtos_queue_peek_from_isr_failed: tstamp:9453627365 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   293.597366: rtos_queue_peek_from_isr_failed: tstamp:9458627443 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   293.765285: rtos_queue_peek_from_isr_failed: tstamp:9463627391 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   293.933296: rtos_queue_peek_from_isr_failed: tstamp:9468627386 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   294.101269: rtos_queue_peek_from_isr_failed: tstamp:9473627392 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   294.269134: rtos_queue_peek_from_isr_failed: tstamp:9478627391 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   294.437364: rtos_queue_peek_from_isr_failed: tstamp:9483627417 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   294.605288: rtos_queue_peek_from_isr_failed: tstamp:9488627414 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   294.717279: rtos_queue_peek_from_isr_failed: tstamp:9493627420 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   294.885279: rtos_queue_peek_from_isr_failed: tstamp:9498627426 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   295.053264: rtos_queue_peek_from_isr_failed: tstamp:9503627431 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   295.221282: rtos_queue_peek_from_isr_failed: tstamp:9508627442 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   295.389268: rtos_queue_peek_from_isr_failed: tstamp:9513627448 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   295.557344: rtos_queue_peek_from_isr_failed: tstamp:9518627454 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   295.725309: rtos_queue_peek_from_isr_failed: tstamp:9523627472 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   295.837286: rtos_queue_peek_from_isr_failed: tstamp:9528627467 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   296.005304: rtos_queue_peek_from_isr_failed: tstamp:9533627473 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   296.173353: rtos_queue_peek_from_isr_failed: tstamp:9538627480 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   296.341320: rtos_queue_peek_from_isr_failed: tstamp:9543627488 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   296.509309: rtos_queue_peek_from_isr_failed: tstamp:9548627496 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   296.677353: rtos_queue_peek_from_isr_failed: tstamp:9553627502 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   296.845352: rtos_queue_peek_from_isr_failed: tstamp:9558627508 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   296.957293: rtos_queue_peek_from_isr_failed: tstamp:9563627513 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   297.125379: rtos_queue_peek_from_isr_failed: tstamp:9568627559 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   297.293346: rtos_queue_peek_from_isr_failed: tstamp:9573627540 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   297.461324: rtos_queue_peek_from_isr_failed: tstamp:9578627576 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   297.629410: rtos_queue_peek_from_isr_failed: tstamp:9583627546 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   297.797312: rtos_queue_peek_from_isr_failed: tstamp:9588627549 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   297.965321: rtos_queue_peek_from_isr_failed: tstamp:9593627555 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   298.077290: rtos_queue_peek_from_isr_failed: tstamp:9598627563 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   298.245286: rtos_queue_peek_from_isr_failed: tstamp:9603627570 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   298.413260: rtos_queue_peek_from_isr_failed: tstamp:9608627576 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   298.581306: rtos_queue_peek_from_isr_failed: tstamp:9613627582 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   298.749317: rtos_queue_peek_from_isr_failed: tstamp:9618627594 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   298.917339: rtos_queue_peek_from_isr_failed: tstamp:9623627604 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   299.085322: rtos_queue_peek_from_isr_failed: tstamp:9628627693 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   299.197302: rtos_queue_peek_from_isr_failed: tstamp:9633627695 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   299.365343: rtos_queue_peek_from_isr_failed: tstamp:9638627706 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   299.533332: rtos_queue_peek_from_isr_failed: tstamp:9643627711 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   299.701311: rtos_queue_peek_from_isr_failed: tstamp:9648627716 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   299.869321: rtos_queue_peek_from_isr_failed: tstamp:9653627724 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   300.037349: rtos_queue_peek_from_isr_failed: tstamp:9658627734 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   300.205378: rtos_queue_peek_from_isr_failed: tstamp:9663627740 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   300.317367: rtos_queue_peek_from_isr_failed: tstamp:9668627749 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   300.485337: rtos_queue_peek_from_isr_failed: tstamp:9673627752 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   300.653369: rtos_queue_peek_from_isr_failed: tstamp:9678627758 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   300.821351: rtos_queue_peek_from_isr_failed: tstamp:9683627768 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   300.989316: rtos_queue_peek_from_isr_failed: tstamp:9688627774 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   301.157359: rtos_queue_peek_from_isr_failed: tstamp:9693627780 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   301.325347: rtos_queue_peek_from_isr_failed: tstamp:9698627789 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   301.437330: rtos_queue_peek_from_isr_failed: tstamp:9703627793 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   301.605387: rtos_queue_peek_from_isr_failed: tstamp:9708627738 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   301.773353: rtos_queue_peek_from_isr_failed: tstamp:9713627809 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   301.941368: rtos_queue_peek_from_isr_failed: tstamp:9718627814 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   302.109338: rtos_queue_peek_from_isr_failed: tstamp:9723627821 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   302.277342: rtos_queue_peek_from_isr_failed: tstamp:9728627841 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   302.445391: rtos_queue_peek_from_isr_failed: tstamp:9733627834 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   302.557353: rtos_queue_peek_from_isr_failed: tstamp:9738627853 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   302.757183: rtos_queue_peek_from_isr_failed: tstamp:9743627785 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   302.951636: rtos_queue_peek_from_isr_failed: tstamp:9748627857 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   303.061388: rtos_queue_peek_from_isr_failed: tstamp:9753627871 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   303.229345: rtos_queue_peek_from_isr_failed: tstamp:9758627869 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   303.397357: rtos_queue_peek_from_isr_failed: tstamp:9763627875 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   303.565335: rtos_queue_peek_from_isr_failed: tstamp:9768627880 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   303.677441: rtos_queue_peek_from_isr_failed: tstamp:9773627890 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   303.845300: rtos_queue_peek_from_isr_failed: tstamp:9778627893 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   304.013307: rtos_queue_peek_from_isr_failed: tstamp:9783627903 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   304.181353: rtos_queue_peek_from_isr_failed: tstamp:9788627908 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   304.349376: rtos_queue_peek_from_isr_failed: tstamp:9793627802 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   304.517376: rtos_queue_peek_from_isr_failed: tstamp:9798627925 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   304.685298: rtos_queue_peek_from_isr_failed: tstamp:9803627927 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   304.797366: rtos_queue_peek_from_isr_failed: tstamp:9808627937 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   304.965328: rtos_queue_peek_from_isr_failed: tstamp:9813627945 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   305.133350: rtos_queue_peek_from_isr_failed: tstamp:9818627949 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   305.301386: rtos_queue_peek_from_isr_failed: tstamp:9823627957 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   305.469396: rtos_queue_peek_from_isr_failed: tstamp:9828628078 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   305.637303: rtos_queue_peek_from_isr_failed: tstamp:9833627933 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   305.805184: rtos_queue_peek_from_isr_failed: tstamp:9838627877 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   305.917386: rtos_queue_peek_from_isr_failed: tstamp:9843628032 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   306.085318: rtos_queue_peek_from_isr_failed: tstamp:9848627926 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   306.253322: rtos_queue_peek_from_isr_failed: tstamp:9853628036 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   306.421219: rtos_queue_peek_from_isr_failed: tstamp:9858628046 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   306.589265: rtos_queue_peek_from_isr_failed: tstamp:9863627907 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   306.757332: rtos_queue_peek_from_isr_failed: tstamp:9868628059 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   306.925444: rtos_queue_peek_from_isr_failed: tstamp:9873628142 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   307.037405: rtos_queue_peek_from_isr_failed: tstamp:9878628023 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   307.205329: rtos_queue_peek_from_isr_failed: tstamp:9883627929 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   307.373212: rtos_queue_peek_from_isr_failed: tstamp:9888627942 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   307.541232: rtos_queue_peek_from_isr_failed: tstamp:9893627948 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   307.709345: rtos_queue_peek_from_isr_failed: tstamp:9898627986 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   307.877304: rtos_queue_peek_from_isr_failed: tstamp:9903627981 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   308.045410: rtos_queue_peek_from_isr_failed: tstamp:9908628021 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   308.157292: rtos_queue_peek_from_isr_failed: tstamp:9913628030 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   308.325298: rtos_queue_peek_from_isr_failed: tstamp:9918628000 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   308.493263: rtos_queue_peek_from_isr_failed: tstamp:9923628020 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   308.661350: rtos_queue_peek_from_isr_failed: tstamp:9928628028 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   308.829233: rtos_queue_peek_from_isr_failed: tstamp:9933627996 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   308.997322: rtos_queue_peek_from_isr_failed: tstamp:9938628070 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   309.165292: rtos_queue_peek_from_isr_failed: tstamp:9943628044 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   309.277294: rtos_queue_peek_from_isr_failed: tstamp:9948628041 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   309.445300: rtos_queue_peek_from_isr_failed: tstamp:9953628048 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   309.613360: rtos_queue_peek_from_isr_failed: tstamp:9958628058 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   309.781314: rtos_queue_peek_from_isr_failed: tstamp:9963628063 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   309.949338: rtos_queue_peek_from_isr_failed: tstamp:9968628067 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   310.117337: rtos_queue_peek_from_isr_failed: tstamp:9973628075 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   310.285304: rtos_queue_peek_from_isr_failed: tstamp:9978628082 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   310.397392: rtos_queue_peek_from_isr_failed: tstamp:9983628088 queue:0x0b4b4500
     kworker/1:0-18    [001] ....   310.565416: rtos_queue_peek_from_isr_failed: tstamp:9988628095 queue:0x0b4b4500

JerryChang · June 8, 2020, 4:51am

hello jpchae,

there’s fence timeout failure which usually caused by VI engine cannot receive the expect streaming.
could you please narrow down the issue by access the sensor stream with v4l2 standard controls, you may check Applications Using V4L2 IOCTL Directly for sample commands,
in addition, please refer to Debug Tips session and examine your sensor driver.
thanks

jpchae · June 8, 2020, 6:59am

Hello JerryChang,

as your recommend , i tested vtl2 ioctl directly as shown bleow.

v4l2-ctl --set-fmt-video=width=2608,height=1960,pixelformat=BA10 --set-ctrl bypass_mode=0 --stream-mmap --stream-count=1 --stream-to=s5k5e8_2.raw -d /dev/video0

also i tested v4l2-compliance , but nothing special error issue.
I am not sure how to fix the error message.
please, give me good advise.

it is tracing log message.
# tracer: nop
#
# entries-in-buffer/entries-written: 2281/2281 #P:6
#
# _-----=> irqs-off
# / _----=> need-resched
# | / _—=> hardirq/softirq
# || / _–=> preempt-depth
# ||| / delay
# TASK-PID CPU# |||| TIMESTAMP FUNCTION
# | | | |||| | |
v4l2-ctl-8525 [001] … 254.861394: tegra_channel_open: vi-output, s5k5e8 2-0018
v4l2-ctl-8525 [001] … 254.862557: tegra_channel_set_power: s5k5e8 2-0018 : 0x1
v4l2-ctl-8525 [001] … 254.862574: camera_common_s_power: status : 0x1
v4l2-ctl-8525 [001] … 254.867096: tegra_channel_set_power: 150c0000.nvcsi–1 : 0x1
v4l2-ctl-8525 [001] … 254.867101: csi_s_power: enable : 0x1
v4l2-ctl-8525 [001] … 254.892027: tegra_channel_capture_setup: vnc_id 0 W 2608 H 1960 fmt 20
vi-output, s5k5-8526 [002] … 254.892261: tegra_channel_set_stream: enable : 0x1
vi-output, s5k5-8526 [002] … 254.894044: tegra_channel_set_stream: 150c0000.nvcsi–1 : 0x1
vi-output, s5k5-8526 [002] … 254.894047: csi_s_stream: enable : 0x1
vi-output, s5k5-8526 [002] … 254.894076: tegra_channel_set_stream: s5k5e8 2-0018 : 0x1
kworker/2:0-24 [002] … 254.942206: rtos_queue_peek_from_isr_failed: tstamp:8249331566 queue:0x0b4b4500
kworker/2:0-24 [002] … 254.942216: rtcpu_start: tstamp:8249334719
kworker/2:0-24 [002] … 254.942220: rtos_queue_send_from_isr_failed: tstamp:8249348113 queue:0x0b4a7258
kworker/2:0-24 [002] … 254.942223: rtos_queue_send_from_isr_failed: tstamp:8249348218 queue:0x0b4aad68
kworker/2:0-24 [002] … 254.942225: rtos_queue_send_from_isr_failed: tstamp:8249348322 queue:0x0b4ac998
kworker/2:0-24 [002] … 254.942228: rtos_queue_send_from_isr_failed: tstamp:8249348425 queue:0x0b4ae518
kworker/2:0-24 [002] … 254.942230: rtos_queue_send_from_isr_failed: tstamp:8249348528 queue:0x0b4af2d8
kworker/2:0-24 [002] … 254.942271: rtos_queue_send_from_isr_failed: tstamp:8249348629 queue:0x0b4b0098
kworker/2:0-24 [002] … 254.942273: rtos_queue_send_from_isr_failed: tstamp:8249348733 queue:0x0b4b0e58
kworker/2:0-24 [002] … 254.942275: rtos_queue_send_from_isr_failed: tstamp:8249348834 queue:0x0b4b1c18
kworker/2:0-24 [002] … 254.942278: rtos_queue_send_failed: tstamp:8249349293 queue:0x0b4a7258
kworker/2:0-24 [002] … 254.942280: rtos_queue_send_from_isr_failed: tstamp:8249354599 queue:0x0b4a7258
kworker/2:0-24 [002] … 254.942282: rtos_queue_send_from_isr_failed: tstamp:8249354703 queue:0x0b4aad68
kworker/2:0-24 [002] … 254.942283: rtos_queue_send_from_isr_failed: tstamp:8249354805 queue:0x0b4ac998
kworker/2:0-24 [002] … 254.942285: rtos_queue_send_from_isr_failed: tstamp:8249354907 queue:0x0b4ae518
kworker/2:0-24 [002] … 254.942286: rtos_queue_send_from_isr_failed: tstamp:8249355009 queue:0x0b4af2d8
kworker/2:0-24 [002] … 254.942288: rtos_queue_send_from_isr_failed: tstamp:8249355110 queue:0x0b4b0098
kworker/2:0-24 [002] … 254.942290: rtos_queue_send_from_isr_failed: tstamp:8249355221 queue:0x0b4b0e58
kworker/2:0-24 [002] … 254.942291: rtos_queue_send_from_isr_failed: tstamp:8249355325 queue:0x0b4b1c18
kworker/2:0-24 [002] … 254.942293: rtos_queue_send_failed: tstamp:8249356231 queue:0x0b4a7258
kworker/2:0-24 [002] … 255.052082: rtos_queue_peek_from_isr_failed: tstamp:8254332063 queue:0x0b4b4500
kworker/2:0-24 [002] … 255.167458: rtos_queue_send_from_isr_failed: tstamp:8257797966 queue:0x0b4a7258
kworker/2:0-24 [002] … 255.167466: rtos_queue_send_from_isr_failed: tstamp:8257798153 queue:0x0b4aad68
kworker/2:0-24 [002] … 255.167469: rtos_queue_send_from_isr_failed: tstamp:8257798329 queue:0x0b4ac998
kworker/2:0-24 [002] … 255.167472: rtos_queue_send_from_isr_failed: tstamp:8257798505 queue:0x0b4ae518
kworker/2:0-24 [002] … 255.167475: rtos_queue_send_from_isr_failed: tstamp:8257798678 queue:0x0b4af2d8
kworker/2:0-24 [002] … 255.167478: rtos_queue_send_from_isr_failed: tstamp:8257798852 queue:0x0b4b0098
kworker/2:0-24 [002] … 255.167480: rtos_queue_send_from_isr_failed: tstamp:8257799028 queue:0x0b4b0e58
kworker/2:0-24 [002] … 255.167483: rtos_queue_send_from_isr_failed: tstamp:8257799201 queue:0x0b4b1c18
kworker/2:0-24 [002] … 255.167487: rtos_queue_send_failed: tstamp:8257800124 queue:0x0b4a7258

it is dmesg message

[ 254.935814] s5k5e8 2-0018: s5k5e8_start_streaming: start streaming
[ 255.144283] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 255.150971] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 255.162514] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 255.170816] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 255.384189] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 255.391183] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 255.403462] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 255.411771] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 255.624159] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 255.630988] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 255.644690] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 255.652876] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 255.864158] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 255.871005] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 255.882513] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 255.890651] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 256.100054] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 256.106553] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 256.116616] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 256.124714] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 256.335973] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 256.342434] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 256.352435] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 256.360571] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 256.571979] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 256.578450] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 256.588693] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 256.596890] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 256.807985] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 256.814483] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 256.824567] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 256.832748] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 257.043956] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 257.050540] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 257.060456] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 257.068559] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 257.280004] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 257.286625] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 257.296540] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 257.304665] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 257.515961] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 257.522555] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 257.532485] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 257.540683] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 257.751917] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 257.758429] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 257.768532] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 257.776759] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 257.987994] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 257.994559] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 258.004689] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 258.012886] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 258.224256] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 258.231149] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 258.241498] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 258.249704] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 258.460011] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 258.466506] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 258.476542] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 258.484736] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 258.695947] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 258.702527] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 258.712437] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 258.720560] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 258.931942] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 258.938513] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 258.950378] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 258.958517] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089
[ 259.167883] tegra-vi4 15700000.vi: PXL_SOF syncpt timeout! err = -11
[ 259.174508] tegra-vi4 15700000.vi: tegra_channel_error_recovery: attempting to reset the capture channel
[ 259.184657] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_INTR_STATUS 0x00000089
[ 259.192861] nvcsi 150c0000.nvcsi: csi4_cil_check_status (3) CILA_ERR_INTR_STATUS 0x00000089

For reference, the v4l2-compliance test log message is also attached.

v4l2-compliance -d /dev/video0
v4l2-compliance SHA   : not available

Driver Info:
	Driver name   : tegra-video
	Card type     : vi-output, s5k5e8 2-0010
	Bus info      : platform:15700000.vi:2
	Driver version: 4.9.140
	Capabilities  : 0x84200001
		Video Capture
		Streaming
		Extended Pix Format
		Device Capabilities
	Device Caps   : 0x04200001
		Video Capture
		Streaming
		Extended Pix Format

Compliance test for device /dev/video0 (not using libv4l2):

Required ioctls:
	test VIDIOC_QUERYCAP: OK

Allow for multiple opens:
	test second video open: OK
	test VIDIOC_QUERYCAP: OK
	test VIDIOC_G/S_PRIORITY: OK
	test for unlimited opens: OK

Debug ioctls:
	test VIDIOC_DBG_G/S_REGISTER: OK (Not Supported)
	test VIDIOC_LOG_STATUS: OK

Input ioctls:
	test VIDIOC_G/S_TUNER/ENUM_FREQ_BANDS: OK (Not Supported)
	test VIDIOC_G/S_FREQUENCY: OK (Not Supported)
	test VIDIOC_S_HW_FREQ_SEEK: OK (Not Supported)
	test VIDIOC_ENUMAUDIO: OK (Not Supported)
	test VIDIOC_G/S/ENUMINPUT: OK
	test VIDIOC_G/S_AUDIO: OK (Not Supported)
	Inputs: 1 Audio Inputs: 0 Tuners: 0

Output ioctls:
	test VIDIOC_G/S_MODULATOR: OK (Not Supported)
	test VIDIOC_G/S_FREQUENCY: OK (Not Supported)
	test VIDIOC_ENUMAUDOUT: OK (Not Supported)
	test VIDIOC_G/S/ENUMOUTPUT: OK (Not Supported)
	test VIDIOC_G/S_AUDOUT: OK (Not Supported)
	Outputs: 0 Audio Outputs: 0 Modulators: 0

Input/Output configuration ioctls:
	test VIDIOC_ENUM/G/S/QUERY_STD: OK (Not Supported)
	test VIDIOC_ENUM/G/S/QUERY_DV_TIMINGS: OK (Not Supported)
	test VIDIOC_DV_TIMINGS_CAP: OK (Not Supported)
	test VIDIOC_G/S_EDID: OK (Not Supported)

Test input 0:

	Control ioctls:
		test VIDIOC_QUERY_EXT_CTRL/QUERYMENU: OK
		test VIDIOC_QUERYCTRL: OK
		test VIDIOC_G/S_CTRL: OK
		test VIDIOC_G/S/TRY_EXT_CTRLS: OK
		test VIDIOC_(UN)SUBSCRIBE_EVENT/DQEVENT: OK
		test VIDIOC_G/S_JPEGCOMP: OK (Not Supported)
		Standard Controls: 1 Private Controls: 15

	Format ioctls:
		test VIDIOC_ENUM_FMT/FRAMESIZES/FRAMEINTERVALS: OK
		fail: v4l2-test-formats.cpp(1184): ret && node->has_frmintervals
		test VIDIOC_G/S_PARM: FAIL
		test VIDIOC_G_FBUF: OK (Not Supported)
		test VIDIOC_G_FMT: OK
		test VIDIOC_TRY_FMT: OK
		test VIDIOC_S_FMT: OK
		test VIDIOC_G_SLICED_VBI_CAP: OK (Not Supported)
		test Cropping: OK (Not Supported)
		test Composing: OK (Not Supported)
		test Scaling: OK (Not Supported)

	Codec ioctls:
		test VIDIOC_(TRY_)ENCODER_CMD: OK (Not Supported)
		test VIDIOC_G_ENC_INDEX: OK (Not Supported)
		test VIDIOC_(TRY_)DECODER_CMD: OK (Not Supported)

	Buffer ioctls:
		test VIDIOC_REQBUFS/CREATE_BUFS/QUERYBUF: OK
		test VIDIOC_EXPBUF: OK

Test input 0:


Total: 43, Succeeded: 42, Failed: 1, Warnings: 0

JerryChang · June 8, 2020, 7:40am

there’re errors in clk and data lanes.
you may also access Technical Reference Manual from download center, please check register description of NVCSI_PHY_0_CILA_INTR_STATUS_CILA_0
for more details.

several suggestions as below…

could you please review port bindings for VI, NvCSI, and sensor modules in the device tree.
may I know what’s your sensor types? please check Sensor Pixel Clock if you’re develop a typical bayer sensor; please review SerDes Pixel Clock for sensor modules that use serializer/deserializer chips.
could you please boost system performance for verification. you may refer to Maximizing Jetson TX2 Performance, and nvpmodel for the steps.

jpchae · June 8, 2020, 8:51am

Hello, JerryChang,

Thank you for your advise.

I solved error message.

It is hardware problem. mipi-GND and digital-GND are not connected.

They are connected and then v4l2 ioctl directly command is good working.

Thank you very much.