# tracer: nop # # entries-in-buffer/entries-written: 118/118 #P:12 # # _-----=> irqs-off # / _----=> need-resched # | / _---=> hardirq/softirq # || / _--=> preempt-depth # ||| / delay # TASK-PID CPU# |||| TIMESTAMP FUNCTION # | | | |||| | | v4l2-ctl-3027 [007] .... 900.126862: tegra_channel_open: vi-output, imx390 2-001b v4l2-ctl-3027 [007] .... 900.130508: tegra_channel_set_power: imx390 2-001b : 0x1 v4l2-ctl-3027 [007] .... 900.130516: camera_common_s_power: status : 0x1 v4l2-ctl-3027 [007] .... 900.130521: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1 v4l2-ctl-3027 [007] .... 900.130522: csi_s_power: enable : 0x1 v4l2-ctl-3027 [007] .... 900.130570: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13 v4l2-ctl-3027 [001] .... 900.139403: tegra_channel_set_stream: enable : 0x1 v4l2-ctl-3027 [001] .... 900.141046: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1 v4l2-ctl-3027 [001] .... 900.141048: csi_s_stream: enable : 0x1 v4l2-ctl-3027 [001] .... 900.141393: tegra_channel_set_stream: imx390 2-001b : 0x1 kworker/1:0-2980 [001] .... 900.167053: rtcpu_string: tstamp:28576649751 id:0x04010000 str:"VM0 activating." kworker/1:0-2980 [001] .... 900.167054: rtcpu_string: tstamp:28576754109 id:0x04010000 str:"NVCSILP clock rate = 408000000 Hz. " kworker/1:0-2980 [001] .... 900.167175: rtcpu_string: tstamp:28576820576 id:0x04010000 str:"VI5: tegra_vi_channel_alloc() " kworker/1:0-2980 [001] .... 900.167178: rtcpu_string: tstamp:28576825016 id:0x04010000 str:"VI5: unit 0 stream ID table: " kworker/1:0-2980 [001] .... 900.167180: rtcpu_string: tstamp:28576825261 id:0x04010000 str:"VI5: unit 0 stream ID table from THI: " kworker/1:0-2980 [001] .... 900.167182: rtcpu_string: tstamp:28576825542 id:0x04010000 str:"VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00 " kworker/1:0-2980 [001] .... 900.167185: rtcpu_string: tstamp:28576830854 id:0x04010000 str:"VI5: vi5_trace_enable: configuring trace buffer " kworker/1:0-2980 [001] .... 900.167185: rtcpu_string: tstamp:28576830976 id:0x04010000 str:"at iova=0xc55dda00 size=32768 " kworker/1:0-2980 [001] .... 900.167188: rtcpu_string: tstamp:28576832167 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35 " kworker/1:0-2980 [001] .... 900.167190: rtcpu_string: tstamp:28576844944 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/1:0-2980 [001] .... 900.167191: rtcpu_string: tstamp:28576845049 id:0x04010000 str:"t. 0 " kworker/1:0-2980 [001] .... 900.167193: rtcpu_string: tstamp:28576845285 id:0x04010000 str:"VI5: channel_submit(35, bfeba000) " kworker/1:0-2980 [001] .... 900.167195: rtcpu_string: tstamp:28576845779 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/1:0-2980 [001] .... 900.167196: rtcpu_string: tstamp:28576845883 id:0x04010000 str:"t = 314 " kworker/1:0-2980 [001] .... 900.167198: rtcpu_string: tstamp:28576846189 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/1:0-2980 [001] .... 900.167198: rtcpu_string: tstamp:28576846290 id:0x04010000 str:"t = 345 " kworker/1:0-2980 [001] .... 900.167200: rtcpu_string: tstamp:28576846591 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/1:0-2980 [001] .... 900.167200: rtcpu_string: tstamp:28576846692 id:0x04010000 str:"t = 359 " kworker/1:0-2980 [001] .... 900.167202: rtcpu_string: tstamp:28576846997 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/1:0-2980 [001] .... 900.167202: rtcpu_string: tstamp:28576847097 id:0x04010000 str:"t = 389 " kworker/1:0-2980 [001] .... 900.167204: rtcpu_string: tstamp:28576847394 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/1:0-2980 [001] .... 900.167204: rtcpu_string: tstamp:28576847496 id:0x04010000 str:"t = 403 " kworker/1:0-2980 [001] .... 900.167206: rtcpu_string: tstamp:28576847793 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/1:0-2980 [001] .... 900.167207: rtcpu_string: tstamp:28576847893 id:0x04010000 str:"t = 417 " kworker/1:0-2980 [001] .... 900.167208: rtcpu_string: tstamp:28576848187 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/1:0-2980 [001] .... 900.167209: rtcpu_string: tstamp:28576848288 id:0x04010000 str:" = 419 " kworker/1:0-2980 [001] .... 900.167210: rtcpu_string: tstamp:28576848663 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/1:0-2980 [001] .... 900.167212: rtcpu_string: tstamp:28576848855 id:0x04010000 str:"VI5: enabling stream 0 " kworker/1:0-2980 [001] .... 900.167214: rtcpu_string: tstamp:28576850456 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/1:0-2980 [001] .... 900.167214: rtcpu_string: tstamp:28576850558 id:0x04010000 str:"t. 0 " kworker/1:0-2980 [001] .... 900.167216: rtcpu_string: tstamp:28576850792 id:0x04010000 str:"VI5: channel_submit(35, bfeba180) " kworker/1:0-2980 [001] .... 900.167218: rtcpu_string: tstamp:28576851185 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/1:0-2980 [001] .... 900.167218: rtcpu_string: tstamp:28576851285 id:0x04010000 str:"t = 314 " kworker/1:0-2980 [001] .... 900.167220: rtcpu_string: tstamp:28576851588 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/1:0-2980 [001] .... 900.167220: rtcpu_string: tstamp:28576851701 id:0x04010000 str:"t = 345 " kworker/1:0-2980 [001] .... 900.167222: rtcpu_string: tstamp:28576851998 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/1:0-2980 [001] .... 900.167223: rtcpu_string: tstamp:28576852098 id:0x04010000 str:"t = 359 " kworker/1:0-2980 [001] .... 900.167224: rtcpu_string: tstamp:28576852396 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/1:0-2980 [001] .... 900.167225: rtcpu_string: tstamp:28576852496 id:0x04010000 str:"t = 389 " kworker/1:0-2980 [001] .... 900.167227: rtcpu_string: tstamp:28576852819 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/1:0-2980 [001] .... 900.167227: rtcpu_string: tstamp:28576852919 id:0x04010000 str:"t = 403 " kworker/1:0-2980 [001] .... 900.167229: rtcpu_string: tstamp:28576853213 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/1:0-2980 [001] .... 900.167229: rtcpu_string: tstamp:28576853313 id:0x04010000 str:"t = 417 " kworker/1:0-2980 [001] .... 900.167231: rtcpu_string: tstamp:28576853606 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/1:0-2980 [001] .... 900.167231: rtcpu_string: tstamp:28576853707 id:0x04010000 str:" = 419 " kworker/1:0-2980 [001] .... 900.167233: rtcpu_string: tstamp:28576854082 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/1:0-2980 [001] .... 900.167235: rtcpu_string: tstamp:28576904398 id:0x04010000 str:"tegra_nvcsi_stream_set_config(vm0, stream=0, csi" kworker/1:0-2980 [001] .... 900.167235: rtcpu_string: tstamp:28576904498 id:0x04010000 str:"=0) " kworker/1:0-2980 [001] .... 900.167237: rtcpu_string: tstamp:28576904872 id:0x04010000 str:"MIPI clock = 1400000 kHz, tHS-SETTLE = 0, tCLK-S" kworker/1:0-2980 [001] .... 900.167238: rtcpu_string: tstamp:28576904974 id:0x04010000 str:"ETTLE = 0 " kworker/1:0-2980 [001] .... 900.167239: rtcpu_string: tstamp:28576906422 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0) " kworker/1:0-2980 [001] .... 900.167242: rtcpu_string: tstamp:28576912499 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 48 " kworker/1:0-2980 [001] .... 900.167244: rtcpu_string: tstamp:28576912759 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 48 " kworker/1:0-2980 [001] .... 900.167246: rtcpu_string: tstamp:28576913012 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 75 " kworker/1:0-2980 [001] .... 900.167248: rtcpu_string: tstamp:28576913562 id:0x04010000 str:"Deskew setup message sent for port 0 num_lane 2 " kworker/1:0-2980 [001] .... 900.167251: rtcpu_vinotify_event: tstamp:28577163654 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:914459184800 data:0xc55cff0010000000 kworker/1:0-2980 [001] .... 900.167252: rtcpu_vinotify_event: tstamp:28577163788 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:914459191392 data:0x0000000031000001 kworker/1:0-2980 [001] .... 900.167252: rtcpu_vinotify_event: tstamp:28577163938 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:914459350176 data:0xc55cfc0010000000 kworker/1:0-2980 [001] .... 900.167252: rtcpu_vinotify_event: tstamp:28577164066 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:914459357248 data:0x0000000031000002 vi-output, imx3-3029 [000] .... 902.885507: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13 kworker/1:0-2980 [001] .... 902.915078: rtcpu_string: tstamp:28662619503 id:0x04010000 str:"VI5: tegra_vi_channel_release(35) " kworker/1:0-2980 [001] .... 902.915092: rtcpu_string: tstamp:28662619774 id:0x04010000 str:"VI5: vi5_channel_disable(35) " kworker/1:0-2980 [001] .... 902.915096: rtcpu_string: tstamp:28662634073 id:0x04010000 str:"VI5: vi5 reset completed in 1 retries " kworker/1:0-2980 [001] .... 902.915100: rtcpu_string: tstamp:28662634371 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x00 " kworker/1:0-2980 [001] .... 902.915104: rtcpu_string: tstamp:28662634589 id:0x04010000 str:"VI5: disabling stream 0 " kworker/1:0-2980 [001] .... 902.915108: rtcpu_string: tstamp:28662669528 id:0x04010000 str:"VI5: tegra_vi_channel_alloc() " kworker/1:0-2980 [001] .... 902.915111: rtcpu_string: tstamp:28662669975 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35 " kworker/1:0-2980 [001] .... 902.915114: rtcpu_string: tstamp:28662678424 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/1:0-2980 [001] .... 902.915115: rtcpu_string: tstamp:28662678526 id:0x04010000 str:"t. 0 " kworker/1:0-2980 [001] .... 902.915119: rtcpu_string: tstamp:28662678763 id:0x04010000 str:"VI5: channel_submit(35, bfebb000) " kworker/1:0-2980 [001] .... 902.915122: rtcpu_string: tstamp:28662679254 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/1:0-2980 [001] .... 902.915123: rtcpu_string: tstamp:28662679357 id:0x04010000 str:"t = 314 " kworker/1:0-2980 [001] .... 902.915127: rtcpu_string: tstamp:28662679660 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/1:0-2980 [001] .... 902.915128: rtcpu_string: tstamp:28662679761 id:0x04010000 str:"t = 345 " kworker/1:0-2980 [001] .... 902.915131: rtcpu_string: tstamp:28662680060 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/1:0-2980 [001] .... 902.915132: rtcpu_string: tstamp:28662680162 id:0x04010000 str:"t = 359 " kworker/1:0-2980 [001] .... 902.915136: rtcpu_string: tstamp:28662680460 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/1:0-2980 [001] .... 902.915137: rtcpu_string: tstamp:28662680560 id:0x04010000 str:"t = 389 " kworker/1:0-2980 [001] .... 902.915140: rtcpu_string: tstamp:28662680857 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/1:0-2980 [001] .... 902.915141: rtcpu_string: tstamp:28662680958 id:0x04010000 str:"t = 403 " kworker/1:0-2980 [001] .... 902.915144: rtcpu_string: tstamp:28662681255 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/1:0-2980 [001] .... 902.915145: rtcpu_string: tstamp:28662681356 id:0x04010000 str:"t = 417 " kworker/1:0-2980 [001] .... 902.915148: rtcpu_string: tstamp:28662681649 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/1:0-2980 [001] .... 902.915149: rtcpu_string: tstamp:28662681752 id:0x04010000 str:" = 419 " kworker/1:0-2980 [001] .... 902.915153: rtcpu_string: tstamp:28662682120 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/1:0-2980 [001] .... 902.915156: rtcpu_string: tstamp:28662682311 id:0x04010000 str:"VI5: enabling stream 0 " kworker/1:0-2980 [001] .... 902.915159: rtcpu_string: tstamp:28662683921 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/1:0-2980 [001] .... 902.915160: rtcpu_string: tstamp:28662684020 id:0x04010000 str:"t. 0 " kworker/1:0-2980 [001] .... 902.915164: rtcpu_string: tstamp:28662684253 id:0x04010000 str:"VI5: channel_submit(35, bfebb180) " kworker/1:0-2980 [001] .... 902.915167: rtcpu_string: tstamp:28662684645 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/1:0-2980 [001] .... 902.915168: rtcpu_string: tstamp:28662684744 id:0x04010000 str:"t = 314 " kworker/1:0-2980 [001] .... 902.915171: rtcpu_string: tstamp:28662685048 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/1:0-2980 [001] .... 902.915172: rtcpu_string: tstamp:28662685148 id:0x04010000 str:"t = 345 " kworker/1:0-2980 [001] .... 902.915175: rtcpu_string: tstamp:28662685443 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/1:0-2980 [001] .... 902.915176: rtcpu_string: tstamp:28662685547 id:0x04010000 str:"t = 359 " kworker/1:0-2980 [001] .... 902.915179: rtcpu_string: tstamp:28662685844 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/1:0-2980 [001] .... 902.915180: rtcpu_string: tstamp:28662685943 id:0x04010000 str:"t = 389 " kworker/1:0-2980 [001] .... 902.915184: rtcpu_string: tstamp:28662686238 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/1:0-2980 [001] .... 902.915184: rtcpu_string: tstamp:28662686338 id:0x04010000 str:"t = 403 " kworker/1:0-2980 [001] .... 902.915188: rtcpu_string: tstamp:28662686644 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/1:0-2980 [001] .... 902.915189: rtcpu_string: tstamp:28662686744 id:0x04010000 str:"t = 417 " kworker/1:0-2980 [001] .... 902.915192: rtcpu_string: tstamp:28662687036 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/1:0-2980 [001] .... 902.915193: rtcpu_string: tstamp:28662687134 id:0x04010000 str:" = 419 " kworker/1:0-2980 [001] .... 902.915196: rtcpu_string: tstamp:28662687505 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/1:0-2980 [001] .... 902.915201: rtcpu_vinotify_event: tstamp:28662688807 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:917205853440 data:0xc55cff0010000000 kworker/1:0-2980 [001] .... 902.915202: rtcpu_vinotify_event: tstamp:28662688939 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:917205860064 data:0x0000000031000001 kworker/1:0-2980 [001] .... 902.915202: rtcpu_vinotify_event: tstamp:28662689088 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:917206019488 data:0xc55cfc0010000000 kworker/1:0-2980 [001] .... 902.915203: rtcpu_vinotify_event: tstamp:28663026782 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:917206043936 data:0x0000000031000002