# tracer: nop # # entries-in-buffer/entries-written: 92/92 #P:8 # # _-----=> irqs-off # / _----=> need-resched # | / _---=> hardirq/softirq # || / _--=> preempt-depth # ||| / delay # TASK-PID CPU# |||| TIMESTAMP FUNCTION # | | | |||| | | kworker/1:2-130 [001] .... 436.166133: rtcpu_string: tstamp:14451798475 id:0x04010000 str:"VM0 deactivating." v4l2-ctl-131242 [003] .... 460.475316: tegra_channel_open: vi-output, imx390 8-0013 v4l2-ctl-131242 [003] .... 460.480770: tegra_channel_set_power: imx390 8-0013 : 0x1 v4l2-ctl-131242 [003] .... 460.480777: camera_common_s_power: status : 0x1 v4l2-ctl-131242 [003] .... 460.481138: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1 v4l2-ctl-131242 [003] .... 460.481140: csi_s_power: enable : 0x1 v4l2-ctl-131242 [003] .... 460.481651: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1536 fmt 13 v4l2-ctl-131242 [003] .... 460.487859: tegra_channel_set_stream: enable : 0x1 v4l2-ctl-131242 [003] .... 460.490040: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1 v4l2-ctl-131242 [003] .... 460.490042: csi_s_stream: enable : 0x1 v4l2-ctl-131242 [003] .... 460.490417: tegra_channel_set_stream: imx390 8-0013 : 0x1 kworker/1:2-130 [001] .... 460.509881: rtcpu_string: tstamp:15213347185 id:0x04010000 str:"VM0 activating." kworker/1:2-130 [001] .... 460.509883: rtcpu_string: tstamp:15213435023 id:0x04010000 str:"NVCSILP clock rate = 408000000 Hz. " kworker/1:2-130 [001] .... 460.513631: rtcpu_string: tstamp:15213461365 id:0x04010000 str:"VI5: tegra_vi_channel_alloc() " kworker/1:2-130 [001] .... 460.513633: rtcpu_string: tstamp:15213466228 id:0x04010000 str:"VI5: unit 0 stream ID table: " kworker/1:2-130 [001] .... 460.513634: rtcpu_string: tstamp:15213466470 id:0x04010000 str:"VI5: unit 0 stream ID table from THI: " kworker/1:2-130 [001] .... 460.513635: rtcpu_string: tstamp:15213466754 id:0x04010000 str:"VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00 " kworker/1:2-130 [001] .... 460.513636: rtcpu_string: tstamp:15213472132 id:0x04010000 str:"VI5: vi5_trace_enable: configuring trace buffer " kworker/1:2-130 [001] .... 460.513637: rtcpu_string: tstamp:15213472252 id:0x04010000 str:"at iova=0x8399e3300 size=32768 " kworker/1:2-130 [001] .... 460.513638: rtcpu_string: tstamp:15213473536 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35 " kworker/1:2-130 [001] .... 460.513641: rtcpu_string: tstamp:15213477709 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/1:2-130 [001] .... 460.513642: rtcpu_string: tstamp:15213477811 id:0x04010000 str:"t. 0 " kworker/1:2-130 [001] .... 460.513648: rtcpu_string: tstamp:15213478047 id:0x04010000 str:"VI5: channel_submit(35, bfeb6000) " kworker/1:2-130 [001] .... 460.513652: rtcpu_string: tstamp:15213478537 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/1:2-130 [001] .... 460.513653: rtcpu_string: tstamp:15213478641 id:0x04010000 str:"t = 314 " kworker/1:2-130 [001] .... 460.513663: rtcpu_string: tstamp:15213478948 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/1:2-130 [001] .... 460.513663: rtcpu_string: tstamp:15213479052 id:0x04010000 str:"t = 345 " kworker/1:2-130 [001] .... 460.513666: rtcpu_string: tstamp:15213479353 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/1:2-130 [001] .... 460.513667: rtcpu_string: tstamp:15213479454 id:0x04010000 str:"t = 359 " kworker/1:2-130 [001] .... 460.513668: rtcpu_string: tstamp:15213479755 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/1:2-130 [001] .... 460.513668: rtcpu_string: tstamp:15213479856 id:0x04010000 str:"t = 389 " kworker/1:2-130 [001] .... 460.513669: rtcpu_string: tstamp:15213480153 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/1:2-130 [001] .... 460.513669: rtcpu_string: tstamp:15213480254 id:0x04010000 str:"t = 403 " kworker/1:2-130 [001] .... 460.513671: rtcpu_string: tstamp:15213480554 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/1:2-130 [001] .... 460.513671: rtcpu_string: tstamp:15213480655 id:0x04010000 str:"t = 417 " kworker/1:2-130 [001] .... 460.513672: rtcpu_string: tstamp:15213480949 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/1:2-130 [001] .... 460.513672: rtcpu_string: tstamp:15213481050 id:0x04010000 str:" = 419 " kworker/1:2-130 [001] .... 460.513673: rtcpu_string: tstamp:15213481426 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/1:2-130 [001] .... 460.513675: rtcpu_string: tstamp:15213481620 id:0x04010000 str:"VI5: enabling stream 0 " kworker/1:2-130 [001] .... 460.513676: rtcpu_string: tstamp:15213483204 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/1:2-130 [001] .... 460.513676: rtcpu_string: tstamp:15213483305 id:0x04010000 str:"t. 0 " kworker/1:2-130 [001] .... 460.513677: rtcpu_string: tstamp:15213483537 id:0x04010000 str:"VI5: channel_submit(35, bfeb6180) " kworker/1:2-130 [001] .... 460.513678: rtcpu_string: tstamp:15213483933 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/1:2-130 [001] .... 460.513679: rtcpu_string: tstamp:15213484034 id:0x04010000 str:"t = 314 " kworker/1:2-130 [001] .... 460.513680: rtcpu_string: tstamp:15213484337 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/1:2-130 [001] .... 460.513680: rtcpu_string: tstamp:15213484440 id:0x04010000 str:"t = 345 " kworker/1:2-130 [001] .... 460.513681: rtcpu_string: tstamp:15213484738 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/1:2-130 [001] .... 460.513681: rtcpu_string: tstamp:15213484838 id:0x04010000 str:"t = 359 " kworker/1:2-130 [001] .... 460.513682: rtcpu_string: tstamp:15213485136 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/1:2-130 [001] .... 460.513683: rtcpu_string: tstamp:15213485240 id:0x04010000 str:"t = 389 " kworker/1:2-130 [001] .... 460.513684: rtcpu_string: tstamp:15213485537 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/1:2-130 [001] .... 460.513684: rtcpu_string: tstamp:15213485638 id:0x04010000 str:"t = 403 " kworker/1:2-130 [001] .... 460.513685: rtcpu_string: tstamp:15213485935 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/1:2-130 [001] .... 460.513685: rtcpu_string: tstamp:15213486036 id:0x04010000 str:"t = 417 " kworker/1:2-130 [001] .... 460.513686: rtcpu_string: tstamp:15213486329 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/1:2-130 [001] .... 460.513687: rtcpu_string: tstamp:15213486430 id:0x04010000 str:" = 419 " kworker/1:2-130 [001] .... 460.513688: rtcpu_string: tstamp:15213486806 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/1:2-130 [001] .... 460.513689: rtcpu_string: tstamp:15213546141 id:0x04010000 str:"tegra_nvcsi_stream_set_config(vm0, stream=0, csi" kworker/1:2-130 [001] .... 460.513689: rtcpu_string: tstamp:15213546242 id:0x04010000 str:"=0) " kworker/1:2-130 [001] .... 460.513690: rtcpu_string: tstamp:15213546616 id:0x04010000 str:"MIPI clock = 600000 kHz, tHS-SETTLE = 0, tCLK-SE" kworker/1:2-130 [001] .... 460.513690: rtcpu_string: tstamp:15213546719 id:0x04010000 str:"TTLE = 0 " kworker/1:2-130 [001] .... 460.513692: rtcpu_string: tstamp:15213548243 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0) " kworker/1:2-130 [001] .... 460.513693: rtcpu_string: tstamp:15213555110 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 55 " kworker/1:2-130 [001] .... 460.513695: rtcpu_string: tstamp:15213555372 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 55 " kworker/1:2-130 [001] .... 460.513696: rtcpu_string: tstamp:15213555630 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 55 " kworker/1:2-130 [001] .... 460.513697: rtcpu_string: tstamp:15213555900 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 75 " kworker/1:2-130 [001] .... 460.513699: rtcpu_vinotify_event: tstamp:15213804791 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:486831436256 data:0x399d580010000000 kworker/1:2-130 [001] .... 460.513700: rtcpu_vinotify_event: tstamp:15213804929 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:486831442720 data:0x0000000031000001 kworker/1:2-130 [001] .... 460.513700: rtcpu_vinotify_event: tstamp:15213805084 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:486831597696 data:0x399d550010000000 kworker/1:2-130 [001] .... 460.513700: rtcpu_vinotify_event: tstamp:15213805219 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:486831604256 data:0x0000000031000002 v4l2-ctl-131242 [001] .... 462.267579: tegra_channel_close: vi-output, imx390 8-0013 kworker/1:2-130 [001] .... 463.033856: rtcpu_string: tstamp:15292376784 id:0x04010000 str:"VI5: tegra_vi_channel_release(35) " kworker/1:2-130 [001] .... 463.039649: rtcpu_string: tstamp:15292377059 id:0x04010000 str:"VI5: vi5_channel_disable(35) " kworker/1:2-130 [001] .... 463.039658: rtcpu_string: tstamp:15292400669 id:0x04010000 str:"VI5: vi5 reset completed in 1 retries " kworker/1:2-130 [001] .... 463.039663: rtcpu_string: tstamp:15292400970 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x00 " kworker/1:2-130 [001] .... 463.050233: rtcpu_string: tstamp:15292401190 id:0x04010000 str:"VI5: disabling stream 0 " vi-output, imx3-131249 [007] .... 463.050320: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1536 fmt 13 v4l2-ctl-131242 [001] .... 463.059970: tegra_channel_set_stream: enable : 0x0 v4l2-ctl-131242 [001] .... 463.059972: tegra_channel_set_stream: imx390 8-0013 : 0x0 v4l2-ctl-131242 [001] .... 463.069458: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x0 v4l2-ctl-131242 [001] .... 463.069461: csi_s_stream: enable : 0x0 v4l2-ctl-131242 [002] .... 463.072526: tegra_channel_set_power: imx390 8-0013 : 0x0 v4l2-ctl-131242 [002] .... 463.072534: camera_common_s_power: status : 0x0 v4l2-ctl-131242 [002] .... 463.073318: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x0 v4l2-ctl-131242 [002] .... 463.073320: csi_s_power: enable : 0x0 kworker/1:2-130 [001] .... 463.109849: rtcpu_string: tstamp:15293556232 id:0x04010000 str:"VI5: tegra_vi_channel_alloc() " kworker/1:2-130 [001] .... 463.113456: rtcpu_string: tstamp:15293556722 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35 " kworker/1:2-130 [001] .... 463.113457: rtcpu_string: tstamp:15294221808 id:0x04010000 str:"VI5: tegra_vi_channel_release(35) " kworker/1:2-130 [001] .... 463.113459: rtcpu_string: tstamp:15294222086 id:0x04010000 str:"VI5: vi5_channel_disable(35) " kworker/1:2-130 [001] .... 463.113460: rtcpu_string: tstamp:15294244419 id:0x04010000 str:"VI5: vi5 reset completed in 1 retries " kworker/1:2-130 [001] .... 463.113461: rtcpu_string: tstamp:15294244720 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x00 "