# tracer: nop # # entries-in-buffer/entries-written: 135/135 #P:12 # # _-----=> irqs-off # / _----=> need-resched # | / _---=> hardirq/softirq # || / _--=> preempt-depth # ||| / delay # TASK-PID CPU# |||| TIMESTAMP FUNCTION # | | | |||| | | v4l2-ctl-2303 [006] .... 201.620379: tegra_channel_open: vi-output, imx390 2-001b v4l2-ctl-2303 [006] .... 201.625898: tegra_channel_set_power: imx390 2-001b : 0x1 v4l2-ctl-2303 [006] .... 201.625913: camera_common_s_power: status : 0x1 v4l2-ctl-2303 [006] .... 201.625922: tegra_channel_set_power: 13e40000.host1x:nvcsi@15a00000- : 0x1 v4l2-ctl-2303 [006] .... 201.625925: csi_s_power: enable : 0x1 v4l2-ctl-2303 [001] .... 201.631599: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13 kworker/6:4-124 [006] .... 201.647006: rtcpu_string: tstamp:6748174498 id:0x04010000 str:"VM0 activating." kworker/6:4-124 [006] .... 201.647008: rtcpu_string: tstamp:6748536416 id:0x04010000 str:"NVCSILP clock rate = 408000000 Hz. " v4l2-ctl-2303 [001] .... 201.650774: tegra_channel_set_stream: enable : 0x1 v4l2-ctl-2303 [001] .... 201.662651: tegra_channel_set_stream: 13e40000.host1x:nvcsi@15a00000- : 0x1 v4l2-ctl-2303 [001] .... 201.662653: csi_s_stream: enable : 0x1 v4l2-ctl-2303 [001] .... 201.662981: tegra_channel_set_stream: imx390 2-001b : 0x1 kworker/6:4-124 [006] .... 201.703000: rtcpu_string: tstamp:6748638548 id:0x04010000 str:"VI5: tegra_vi_channel_alloc() " kworker/6:4-124 [006] .... 201.703136: rtcpu_string: tstamp:6748643458 id:0x04010000 str:"VI5: unit 0 stream ID table: " kworker/6:4-124 [006] .... 201.703141: rtcpu_string: tstamp:6748643780 id:0x04010000 str:"VI5: unit 0 stream ID table from THI: " kworker/6:4-124 [006] .... 201.703146: rtcpu_string: tstamp:6748644134 id:0x04010000 str:"VI5: FALCON_CSB_AFBIF_STREAMID_CTL = 0x00 " kworker/6:4-124 [006] .... 201.703151: rtcpu_string: tstamp:6748653684 id:0x04010000 str:"VI5: vi5_trace_enable: configuring trace buffer " kworker/6:4-124 [006] .... 201.703152: rtcpu_string: tstamp:6748653847 id:0x04010000 str:"at iova=0xc55dda00 size=32768 " kworker/6:4-124 [006] .... 201.703158: rtcpu_string: tstamp:6748655114 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35 " kworker/6:4-124 [006] .... 201.703162: rtcpu_string: tstamp:6748667040 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/6:4-124 [006] .... 201.703163: rtcpu_string: tstamp:6748667203 id:0x04010000 str:"t. 0 " kworker/6:4-124 [006] .... 201.703168: rtcpu_string: tstamp:6748667503 id:0x04010000 str:"VI5: channel_submit(35, bfebf000) " kworker/6:4-124 [006] .... 201.703173: rtcpu_string: tstamp:6748668072 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/6:4-124 [006] .... 201.703174: rtcpu_string: tstamp:6748668234 id:0x04010000 str:"t = 314 " kworker/6:4-124 [006] .... 201.703179: rtcpu_string: tstamp:6748668597 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/6:4-124 [006] .... 201.703180: rtcpu_string: tstamp:6748668759 id:0x04010000 str:"t = 345 " kworker/6:4-124 [006] .... 201.703185: rtcpu_string: tstamp:6748669117 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/6:4-124 [006] .... 201.703186: rtcpu_string: tstamp:6748669278 id:0x04010000 str:"t = 359 " kworker/6:4-124 [006] .... 201.703190: rtcpu_string: tstamp:6748669636 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/6:4-124 [006] .... 201.703191: rtcpu_string: tstamp:6748669798 id:0x04010000 str:"t = 389 " kworker/6:4-124 [006] .... 201.703196: rtcpu_string: tstamp:6748670153 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/6:4-124 [006] .... 201.703197: rtcpu_string: tstamp:6748670315 id:0x04010000 str:"t = 403 " kworker/6:4-124 [006] .... 201.703201: rtcpu_string: tstamp:6748670669 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/6:4-124 [006] .... 201.703202: rtcpu_string: tstamp:6748670831 id:0x04010000 str:"t = 417 " kworker/6:4-124 [006] .... 201.703205: rtcpu_string: tstamp:6748671184 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/6:4-124 [006] .... 201.703206: rtcpu_string: tstamp:6748671346 id:0x04010000 str:" = 419 " kworker/6:4-124 [006] .... 201.703209: rtcpu_string: tstamp:6748671853 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/6:4-124 [006] .... 201.703212: rtcpu_string: tstamp:6748672114 id:0x04010000 str:"VI5: enabling stream 0 " kworker/6:4-124 [006] .... 201.703216: rtcpu_string: tstamp:6748674344 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/6:4-124 [006] .... 201.703216: rtcpu_string: tstamp:6748674507 id:0x04010000 str:"t. 0 " kworker/6:4-124 [006] .... 201.703220: rtcpu_string: tstamp:6748674803 id:0x04010000 str:"VI5: channel_submit(35, bfebf180) " kworker/6:4-124 [006] .... 201.703223: rtcpu_string: tstamp:6748675279 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/6:4-124 [006] .... 201.703224: rtcpu_string: tstamp:6748675444 id:0x04010000 str:"t = 314 " kworker/6:4-124 [006] .... 201.703227: rtcpu_string: tstamp:6748675817 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/6:4-124 [006] .... 201.703228: rtcpu_string: tstamp:6748675982 id:0x04010000 str:"t = 345 " kworker/6:4-124 [006] .... 201.703231: rtcpu_string: tstamp:6748676341 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/6:4-124 [006] .... 201.703231: rtcpu_string: tstamp:6748676504 id:0x04010000 str:"t = 359 " kworker/6:4-124 [006] .... 201.703235: rtcpu_string: tstamp:6748676864 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/6:4-124 [006] .... 201.703236: rtcpu_string: tstamp:6748677028 id:0x04010000 str:"t = 389 " kworker/6:4-124 [006] .... 201.703238: rtcpu_string: tstamp:6748677386 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/6:4-124 [006] .... 201.703239: rtcpu_string: tstamp:6748677548 id:0x04010000 str:"t = 403 " kworker/6:4-124 [006] .... 201.703242: rtcpu_string: tstamp:6748677906 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/6:4-124 [006] .... 201.703243: rtcpu_string: tstamp:6748678068 id:0x04010000 str:"t = 417 " kworker/6:4-124 [006] .... 201.703246: rtcpu_string: tstamp:6748678426 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/6:4-124 [006] .... 201.703247: rtcpu_string: tstamp:6748678588 id:0x04010000 str:" = 419 " kworker/6:4-124 [006] .... 201.703250: rtcpu_string: tstamp:6748679095 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/6:4-124 [006] .... 201.703253: rtcpu_string: tstamp:6749051263 id:0x04010000 str:"tegra_nvcsi_stream_set_config(vm0, stream=0, csi" kworker/6:4-124 [006] .... 201.703254: rtcpu_string: tstamp:6749051364 id:0x04010000 str:"=0) " kworker/6:4-124 [006] .... 201.703257: rtcpu_string: tstamp:6749051735 id:0x04010000 str:"MIPI clock = 400000 kHz, tHS-SETTLE = 0, tCLK-SE" kworker/6:4-124 [006] .... 201.703258: rtcpu_string: tstamp:6749051835 id:0x04010000 str:"TTLE = 0 " kworker/6:4-124 [006] .... 201.703261: rtcpu_string: tstamp:6749053187 id:0x04010000 str:"tegra_nvcsi_stream_open(vm0, stream=0, csi=0) " kworker/6:4-124 [006] .... 201.703266: rtcpu_string: tstamp:6749059317 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 61 " kworker/6:4-124 [006] .... 201.703269: rtcpu_string: tstamp:6749059580 id:0x04010000 str:"nvcsi_calc_ths_settle ths_settle 61 " kworker/6:4-124 [006] .... 201.703272: rtcpu_string: tstamp:6749059834 id:0x04010000 str:"nvcsi_calc_tclk_settle tclk_settle 75 " kworker/6:4-124 [006] .... 201.703277: rtcpu_vinotify_error: tstamp:6749063587 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:215969928928 data:0x0000000000000001 kworker/6:4-124 [006] .... 201.703278: rtcpu_string: tstamp:6749064096 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame" kworker/6:4-124 [006] .... 201.703278: rtcpu_string: tstamp:6749064198 id:0x04010000 str:"=0 ts=215969928928 data=0x00000001 ext_data=0x00" kworker/6:4-124 [006] .... 201.703279: rtcpu_string: tstamp:6749064300 id:0x04010000 str:"000000 " kworker/6:4-124 [006] .... 201.703283: rtcpu_string: tstamp:6749064641 id:0x04010000 str:"VI5: error_to_notify_bits CSIMUX_STREAM error de" kworker/6:4-124 [006] .... 201.703284: rtcpu_string: tstamp:6749064753 id:0x04010000 str:"tected " kworker/6:4-124 [006] .... 201.703287: rtcpu_string: tstamp:6749065079 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0x3 tag 0x3, da" kworker/6:4-124 [006] .... 201.703288: rtcpu_string: tstamp:6749065179 id:0x04010000 str:"ta 0x1 " kworker/6:4-124 [006] .... 201.703292: rtcpu_vinotify_event: tstamp:6749120528 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:215957533696 data:0xc55cff0010000000 kworker/6:4-124 [006] .... 201.703292: rtcpu_vinotify_event: tstamp:6749120660 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:215957540288 data:0x0000000031000001 kworker/6:4-124 [006] .... 201.703293: rtcpu_vinotify_event: tstamp:6749120809 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:215957755328 data:0xc55cfc0010000000 kworker/6:4-124 [006] .... 201.703293: rtcpu_vinotify_event: tstamp:6749120940 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:215957762016 data:0x0000000031000002 kworker/6:4-124 [006] .... 201.703293: rtcpu_vinotify_event: tstamp:6749121088 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:215969928928 data:0x0000000000000001 vi-output, imx3-2305 [002] .... 204.261376: tegra_channel_capture_setup: vnc_id 0 W 1920 H 1080 fmt 13 kworker/6:4-124 [006] .... 204.279135: rtcpu_string: tstamp:6830205104 id:0x04010000 str:"VI5: tegra_vi_channel_release(35) " kworker/6:4-124 [006] .... 204.279149: rtcpu_string: tstamp:6830205375 id:0x04010000 str:"VI5: vi5_channel_disable(35) " kworker/6:4-124 [006] .... 204.279154: rtcpu_string: tstamp:6830223823 id:0x04010000 str:"VI5: vi5 reset completed in 1 retries " kworker/6:4-124 [006] .... 204.279157: rtcpu_string: tstamp:6830224121 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x00 " kworker/6:4-124 [006] .... 204.279162: rtcpu_string: tstamp:6830224339 id:0x04010000 str:"VI5: disabling stream 0 " kworker/6:4-124 [006] .... 204.279165: rtcpu_string: tstamp:6830262635 id:0x04010000 str:"VI5: tegra_vi_channel_alloc() " kworker/6:4-124 [006] .... 204.279169: rtcpu_string: tstamp:6830263181 id:0x04010000 str:"VI5: tegra_vi_channel_alloc: ch=35 " kworker/6:4-124 [006] .... 204.279172: rtcpu_string: tstamp:6830265387 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/6:4-124 [006] .... 204.279173: rtcpu_string: tstamp:6830265490 id:0x04010000 str:"t. 0 " kworker/6:4-124 [006] .... 204.279177: rtcpu_string: tstamp:6830265725 id:0x04010000 str:"VI5: channel_submit(35, bfebe000) " kworker/6:4-124 [006] .... 204.279180: rtcpu_string: tstamp:6830266215 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/6:4-124 [006] .... 204.279182: rtcpu_string: tstamp:6830266315 id:0x04010000 str:"t = 314 " kworker/6:4-124 [006] .... 204.279185: rtcpu_string: tstamp:6830266617 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/6:4-124 [006] .... 204.279186: rtcpu_string: tstamp:6830266717 id:0x04010000 str:"t = 345 " kworker/6:4-124 [006] .... 204.279190: rtcpu_string: tstamp:6830267013 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/6:4-124 [006] .... 204.279191: rtcpu_string: tstamp:6830267112 id:0x04010000 str:"t = 359 " kworker/6:4-124 [006] .... 204.279194: rtcpu_string: tstamp:6830267412 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/6:4-124 [006] .... 204.279195: rtcpu_string: tstamp:6830267509 id:0x04010000 str:"t = 389 " kworker/6:4-124 [006] .... 204.279199: rtcpu_string: tstamp:6830267804 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/6:4-124 [006] .... 204.279200: rtcpu_string: tstamp:6830267902 id:0x04010000 str:"t = 403 " kworker/6:4-124 [006] .... 204.279203: rtcpu_string: tstamp:6830268195 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/6:4-124 [006] .... 204.279204: rtcpu_string: tstamp:6830268294 id:0x04010000 str:"t = 417 " kworker/6:4-124 [006] .... 204.279207: rtcpu_string: tstamp:6830268584 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/6:4-124 [006] .... 204.279208: rtcpu_string: tstamp:6830268683 id:0x04010000 str:" = 419 " kworker/6:4-124 [006] .... 204.279211: rtcpu_string: tstamp:6830269052 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/6:4-124 [006] .... 204.279215: rtcpu_string: tstamp:6830269244 id:0x04010000 str:"VI5: enabling stream 0 " kworker/6:4-124 [006] .... 204.279218: rtcpu_string: tstamp:6830270831 id:0x04010000 str:"VI5: PFSD enabled for channel: NO, expected_coun" kworker/6:4-124 [006] .... 204.279219: rtcpu_string: tstamp:6830270931 id:0x04010000 str:"t. 0 " kworker/6:4-124 [006] .... 204.279224: rtcpu_vinotify_error: tstamp:6830273785 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:218568641152 data:0x0000000000000001 kworker/6:4-124 [006] .... 204.279225: rtcpu_string: tstamp:6830274266 id:0x04010000 str:"VI5: vi5_process_error_fifo: tag=0x03 ch=0 frame" kworker/6:4-124 [006] .... 204.279225: rtcpu_string: tstamp:6830274349 id:0x04010000 str:"=0 ts=218568641152 data=0x00000001 ext_data=0x00" kworker/6:4-124 [006] .... 204.279226: rtcpu_string: tstamp:6830274432 id:0x04010000 str:"000000 " kworker/6:4-124 [006] .... 204.279230: rtcpu_string: tstamp:6830274754 id:0x04010000 str:"VI5: error_to_notify_bits CSIMUX_STREAM error de" kworker/6:4-124 [006] .... 204.279231: rtcpu_string: tstamp:6830274836 id:0x04010000 str:"tected " kworker/6:4-124 [006] .... 204.279234: rtcpu_string: tstamp:6830275147 id:0x04010000 str:"VI5: vi5_notify_error(35) status 0x3 tag 0x3, da" kworker/6:4-124 [006] .... 204.279235: rtcpu_string: tstamp:6830275227 id:0x04010000 str:"ta 0x1 " kworker/6:4-124 [006] .... 204.279238: rtcpu_string: tstamp:6830275638 id:0x04010000 str:"VI5: channel_submit(35, bfebe180) " kworker/6:4-124 [006] .... 204.279242: rtcpu_string: tstamp:6830276028 id:0x04010000 str:"VI5: completion_actions[8] size = 30, next offse" kworker/6:4-124 [006] .... 204.279242: rtcpu_string: tstamp:6830276128 id:0x04010000 str:"t = 314 " kworker/6:4-124 [006] .... 204.279247: rtcpu_string: tstamp:6830276433 id:0x04010000 str:"VI5: completion_actions[0] size = 31, next offse" kworker/6:4-124 [006] .... 204.279248: rtcpu_string: tstamp:6830276534 id:0x04010000 str:"t = 345 " kworker/6:4-124 [006] .... 204.279252: rtcpu_string: tstamp:6830276831 id:0x04010000 str:"VI5: completion_actions[2] size = 14, next offse" kworker/6:4-124 [006] .... 204.279252: rtcpu_string: tstamp:6830276929 id:0x04010000 str:"t = 359 " kworker/6:4-124 [006] .... 204.279256: rtcpu_string: tstamp:6830277227 id:0x04010000 str:"VI5: completion_actions[1] size = 30, next offse" kworker/6:4-124 [006] .... 204.279257: rtcpu_string: tstamp:6830277329 id:0x04010000 str:"t = 389 " kworker/6:4-124 [006] .... 204.279260: rtcpu_string: tstamp:6830277636 id:0x04010000 str:"VI5: completion_actions[4] size = 14, next offse" kworker/6:4-124 [006] .... 204.279260: rtcpu_string: tstamp:6830277736 id:0x04010000 str:"t = 403 " kworker/6:4-124 [006] .... 204.279264: rtcpu_string: tstamp:6830278046 id:0x04010000 str:"VI5: completion_actions[3] size = 14, next offse" kworker/6:4-124 [006] .... 204.279265: rtcpu_string: tstamp:6830278145 id:0x04010000 str:"t = 417 " kworker/6:4-124 [006] .... 204.279268: rtcpu_string: tstamp:6830278436 id:0x04010000 str:"VI5: completion_actions[5] size = 2, next offset" kworker/6:4-124 [006] .... 204.279269: rtcpu_string: tstamp:6830278535 id:0x04010000 str:" = 419 " kworker/6:4-124 [006] .... 204.279272: rtcpu_string: tstamp:6830278899 id:0x04010000 str:"VI5: vi5_update_streams: ch=35 stream_mask=0x01 " kworker/6:4-124 [006] .... 204.279277: rtcpu_vinotify_event: tstamp:6830501551 cch:-1 vi:0 tag:CSIMUX_STREAM channel:0x00 frame:0 vi_tstamp:218568641152 data:0x0000000000000001 kworker/6:4-124 [006] .... 204.279277: rtcpu_vinotify_event: tstamp:6830501701 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:218568845184 data:0xc55cff0010000000 kworker/6:4-124 [006] .... 204.279278: rtcpu_vinotify_event: tstamp:6830501834 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:218568888896 data:0x0000000031000001 kworker/6:4-124 [006] .... 204.279278: rtcpu_vinotify_event: tstamp:6830501983 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:218569080800 data:0xc55cfc0010000000 kworker/6:4-124 [006] .... 204.279278: rtcpu_vinotify_event: tstamp:6830502124 cch:0 vi:0 tag:VIFALC_TDSTATE channel:0x23 frame:0 vi_tstamp:218569125216 data:0x0000000031000002