Hi,
I am just got a SOM-RK3399v2 Dev Kit and install the friendlycore-bionic-4.4-arm64-20200623. When I use the HDMI-IN with rkisp as input I found that it'll hang in gstreamer restarting.

Here is my gstreamer pipeline:
"rkisp device=/dev/video0 io-mode=1 ! capsfilter caps=\"video/x-raw,format=NV12,video/x-raw,format=NV12,framerate=30/1,width=1920,height=1080\" ! appsink"

And here is the stack trace of relative thread:
rkisp.hang.png
rkisp.hang.png (57.57 KiB) Viewed 8101 times


Log:
0:00:38.829919919  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:821:dequeue_buffer:<appsink0> dequeued buffer/list 0x7fa8016680
0:00:38.829933335  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1654:gst_app_sink_try_pull_sample:<appsink0> we have a buffer 0x7fa8016680
0:00:38.833251130  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3577:gst_base_sink_chain_unlocked:<appsink0> object unref after render 0x7fa8016680
0:00:38.833504585  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<appsink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x7fa8016680, returned ok
0:00:38.833754832  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<capsfilter0:sink> called chainfunction &gst_base_transform_chain with buffer 0x7fa8016680, returned ok
0:00:38.833905039  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2506:gst_base_src_get_range:<xcamsrc0> calling create offset 18446744073709551615 length 4096, time 0
0:00:38.835273106  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1146:gst_app_sink_is_eos:<appsink0> we are not yet EOS
0:00:38.835331147  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.835342813  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1641:gst_app_sink_try_pull_sample:<appsink0> waiting for a buffer
0:00:38.838304070  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fa8314f30, maxsize:3110407 offset:0 size:3110400
0:00:38.845948856  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.353344591
0:00:38.845974814  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.353344591
0:00:38.845989105  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2360:gst_base_src_do_sync:<xcamsrc0> no sync needed
0:00:38.846003105  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2597:gst_base_src_get_range:<xcamsrc0> buffer ok
0:00:38.846035188  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<capsfilter0:sink> calling chainfunction &gst_base_transform_chain with buffer buffer: 0x7fa8016ce0, pts 0:00:38.435625339, dts 0:00:38.435625339, dur 99:99:99.999999999, size 3110400, offset 2303, offset_end 2304, flags 0x0
0:00:38.846058813  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:1985:default_submit_input_buffer:<capsfilter0> handling buffer 0x7fa8016ce0 of size 3110400, PTS 0:00:38.435625339 and offset 2303
0:00:38.846072812  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2102:default_generate_output:<capsfilter0> calling prepare buffer
0:00:38.846087687  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2109:default_generate_output:<capsfilter0> using allocated buffer in 0x7fa8016ce0, out 0x7fa8016ce0
0:00:38.846099354  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2126:default_generate_output:<capsfilter0> doing inplace transform
0:00:38.846122687  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<appsink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x7fa8016ce0, pts 0:00:38.435625339, dts 0:00:38.435625339, dur 99:99:99.999999999, size 3110400, offset 2303, offset_end 2304, flags 0x0
0:00:38.846147770  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3421:gst_base_sink_chain_unlocked:<appsink0> got times start: 0:00:38.435625339, end: 99:99:99.999999999
0:00:38.846170228  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<appsink0> got times start: 0:00:38.435625339, stop: 99:99:99.999999999, do_sync 1
0:00:38.846181895  5601 0x55ac4678a0 DEBUG   default gstsegment.c:737:gst_segment_to_running_time_full: invalid position (-1)
0:00:38.846201728  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2591:gst_base_sink_do_sync:<appsink0> reset rc_time to time 0:00:38.435625339
0:00:38.846220686  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2603:gst_base_sink_do_sync:<appsink0> possibly waiting for clock to reach 0:00:38.435625339, adjusted 0:00:38.435625339
0:00:38.846235269  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:537:gst_clock_id_wait:<GstSystemClock> waiting on clock entry 0x7fa8006480
0:00:38.846252769  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.353653754
0:00:38.846268227  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.353653754
0:00:38.846302351  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstsystemclock.c:719:gst_system_clock_id_wait_jitter_unlocked: entry 0x7fa8006480 time 7:39:42.353344591 now 7:39:42.353653754 diff (time-now) -309163
0:00:38.846319268  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:547:gst_clock_id_wait:<GstSystemClock> done waiting entry 0x7fa8006480, res: 1 (early)
0:00:38.846337351  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2610:gst_base_sink_do_sync:<appsink0> clock returned 1, jitter 0:00:00.000309163
0:00:38.846349018  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2926:gst_base_sink_is_too_late:<appsink0> frame dropping disabled
0:00:38.846361851  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3538:gst_base_sink_chain_unlocked:<appsink0> rendering object 0x7fa8016ce0
0:00:38.846374684  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<appsink0> setting last buffer to 0x7fa8016ce0
0:00:38.846390434  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7fa801d700
0:00:38.846409975  5601 0x55ac4678a0 DEBUG   appsink gstappsink.c:876:gst_app_sink_render_common:<appsink0> pushing render buffer/list 0x7fa8016ce0 on queue (0)
0:00:38.846456350  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.846474433  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:821:dequeue_buffer:<appsink0> dequeued buffer/list 0x7fa8016ce0
0:00:38.846487558  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1654:gst_app_sink_try_pull_sample:<appsink0> we have a buffer 0x7fa8016ce0
0:00:38.847173091  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3577:gst_base_sink_chain_unlocked:<appsink0> object unref after render 0x7fa8016ce0
0:00:38.847373172  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<appsink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x7fa8016ce0, returned ok
0:00:38.847494212  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<capsfilter0:sink> called chainfunction &gst_base_transform_chain with buffer 0x7fa8016ce0, returned ok
0:00:38.847703918  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2506:gst_base_src_get_range:<xcamsrc0> calling create offset 18446744073709551615 length 4096, time 0
0:00:38.851831703  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1146:gst_app_sink_is_eos:<appsink0> we are not yet EOS
0:00:38.851881869  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.851893827  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1641:gst_app_sink_try_pull_sample:<appsink0> waiting for a buffer
0:00:38.854970000  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fa801d700, maxsize:3110407 offset:0 size:3110400
0:00:38.862231539  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.369628150
0:00:38.862280830  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.369628150
0:00:38.862295997  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2360:gst_base_src_do_sync:<xcamsrc0> no sync needed
0:00:38.862309705  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2597:gst_base_src_get_range:<xcamsrc0> buffer ok
0:00:38.862342663  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<capsfilter0:sink> calling chainfunction &gst_base_transform_chain with buffer buffer: 0x7fa8016680, pts 0:00:38.451908898, dts 0:00:38.451908898, dur 99:99:99.999999999, size 3110400, offset 2304, offset_end 2305, flags 0x0
0:00:38.862365996  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:1985:default_submit_input_buffer:<capsfilter0> handling buffer 0x7fa8016680 of size 3110400, PTS 0:00:38.451908898 and offset 2304
0:00:38.862379996  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2102:default_generate_output:<capsfilter0> calling prepare buffer
0:00:38.862394871  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2109:default_generate_output:<capsfilter0> using allocated buffer in 0x7fa8016680, out 0x7fa8016680
0:00:38.862406537  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2126:default_generate_output:<capsfilter0> doing inplace transform
0:00:38.862429870  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<appsink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x7fa8016680, pts 0:00:38.451908898, dts 0:00:38.451908898, dur 99:99:99.999999999, size 3110400, offset 2304, offset_end 2305, flags 0x0
0:00:38.862454078  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3421:gst_base_sink_chain_unlocked:<appsink0> got times start: 0:00:38.451908898, end: 99:99:99.999999999
0:00:38.862476537  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<appsink0> got times start: 0:00:38.451908898, stop: 99:99:99.999999999, do_sync 1
0:00:38.862488203  5601 0x55ac4678a0 DEBUG   default gstsegment.c:737:gst_segment_to_running_time_full: invalid position (-1)
0:00:38.862507161  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2591:gst_base_sink_do_sync:<appsink0> reset rc_time to time 0:00:38.451908898
0:00:38.862526411  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2603:gst_base_sink_do_sync:<appsink0> possibly waiting for clock to reach 0:00:38.451908898, adjusted 0:00:38.451908898
0:00:38.862540994  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:537:gst_clock_id_wait:<GstSystemClock> waiting on clock entry 0x7fa8006480
0:00:38.862558202  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.369959479
0:00:38.862573952  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.369959479
0:00:38.862602827  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstsystemclock.c:719:gst_system_clock_id_wait_jitter_unlocked: entry 0x7fa8006480 time 7:39:42.369628150 now 7:39:42.369959479 diff (time-now) -331329
0:00:38.862620910  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:547:gst_clock_id_wait:<GstSystemClock> done waiting entry 0x7fa8006480, res: 1 (early)
0:00:38.862647160  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2610:gst_base_sink_do_sync:<appsink0> clock returned 1, jitter 0:00:00.000331329
0:00:38.862659701  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2926:gst_base_sink_is_too_late:<appsink0> frame dropping disabled
0:00:38.862671951  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3538:gst_base_sink_chain_unlocked:<appsink0> rendering object 0x7fa8016680
0:00:38.862685076  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<appsink0> setting last buffer to 0x7fa8016680
0:00:38.862700826  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7fa8314f30
0:00:38.862719492  5601 0x55ac4678a0 DEBUG   appsink gstappsink.c:876:gst_app_sink_render_common:<appsink0> pushing render buffer/list 0x7fa8016680 on queue (0)
0:00:38.862762658  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.862779283  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:821:dequeue_buffer:<appsink0> dequeued buffer/list 0x7fa8016680
0:00:38.862792699  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1654:gst_app_sink_try_pull_sample:<appsink0> we have a buffer 0x7fa8016680
0:00:38.864135059  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3577:gst_base_sink_chain_unlocked:<appsink0> object unref after render 0x7fa8016680
0:00:38.864350306  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<appsink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x7fa8016680, returned ok
0:00:38.864476013  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<capsfilter0:sink> called chainfunction &gst_base_transform_chain with buffer 0x7fa8016680, returned ok
0:00:38.864673761  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2506:gst_base_src_get_range:<xcamsrc0> calling create offset 18446744073709551615 length 4096, time 0
0:00:38.868128095  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1146:gst_app_sink_is_eos:<appsink0> we are not yet EOS
0:00:38.868186136  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.868197803  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1641:gst_app_sink_try_pull_sample:<appsink0> waiting for a buffer
0:00:38.871669470  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fa8314f30, maxsize:3110407 offset:0 size:3110400
0:00:38.879280714  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.386678491
0:00:38.879305214  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.386678491
0:00:38.879320089  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2360:gst_base_src_do_sync:<xcamsrc0> no sync needed
0:00:38.879333505  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2597:gst_base_src_get_range:<xcamsrc0> buffer ok
0:00:38.879392421  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<capsfilter0:sink> calling chainfunction &gst_base_transform_chain with buffer buffer: 0x7fa8016df0, pts 0:00:38.468959239, dts 0:00:38.468959239, dur 99:99:99.999999999, size 3110400, offset 2305, offset_end 2306, flags 0x0
0:00:38.879416338  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:1985:default_submit_input_buffer:<capsfilter0> handling buffer 0x7fa8016df0 of size 3110400, PTS 0:00:38.468959239 and offset 2305
0:00:38.879430629  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2102:default_generate_output:<capsfilter0> calling prepare buffer
0:00:38.879445504  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2109:default_generate_output:<capsfilter0> using allocated buffer in 0x7fa8016df0, out 0x7fa8016df0
0:00:38.879457170  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2126:default_generate_output:<capsfilter0> doing inplace transform
0:00:38.879480504  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<appsink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x7fa8016df0, pts 0:00:38.468959239, dts 0:00:38.468959239, dur 99:99:99.999999999, size 3110400, offset 2305, offset_end 2306, flags 0x0
0:00:38.879505878  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3421:gst_base_sink_chain_unlocked:<appsink0> got times start: 0:00:38.468959239, end: 99:99:99.999999999
0:00:38.879528336  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<appsink0> got times start: 0:00:38.468959239, stop: 99:99:99.999999999, do_sync 1
0:00:38.879540003  5601 0x55ac4678a0 DEBUG   default gstsegment.c:737:gst_segment_to_running_time_full: invalid position (-1)
0:00:38.879560128  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2591:gst_base_sink_do_sync:<appsink0> reset rc_time to time 0:00:38.468959239
0:00:38.879579377  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2603:gst_base_sink_do_sync:<appsink0> possibly waiting for clock to reach 0:00:38.468959239, adjusted 0:00:38.468959239
0:00:38.879604460  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:537:gst_clock_id_wait:<GstSystemClock> waiting on clock entry 0x7fa8006480
0:00:38.879624585  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.387025279
0:00:38.879640335  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.387025279
0:00:38.879660751  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstsystemclock.c:719:gst_system_clock_id_wait_jitter_unlocked: entry 0x7fa8006480 time 7:39:42.386678491 now 7:39:42.387025279 diff (time-now) -346788
0:00:38.879676501  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:547:gst_clock_id_wait:<GstSystemClock> done waiting entry 0x7fa8006480, res: 1 (early)
0:00:38.879694876  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2610:gst_base_sink_do_sync:<appsink0> clock returned 1, jitter 0:00:00.000346788
0:00:38.879706543  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2926:gst_base_sink_is_too_late:<appsink0> frame dropping disabled
0:00:38.879727542  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3538:gst_base_sink_chain_unlocked:<appsink0> rendering object 0x7fa8016df0
0:00:38.879741542  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<appsink0> setting last buffer to 0x7fa8016df0
0:00:38.879757584  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7fa801d700
0:00:38.879776833  5601 0x55ac4678a0 DEBUG   appsink gstappsink.c:876:gst_app_sink_render_common:<appsink0> pushing render buffer/list 0x7fa8016df0 on queue (0)
0:00:38.879822041  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.879838666  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:821:dequeue_buffer:<appsink0> dequeued buffer/list 0x7fa8016df0
0:00:38.879851791  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1654:gst_app_sink_try_pull_sample:<appsink0> we have a buffer 0x7fa8016df0
0:00:38.881221858  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3577:gst_base_sink_chain_unlocked:<appsink0> object unref after render 0x7fa8016df0
0:00:38.881454897  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<appsink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x7fa8016df0, returned ok
0:00:38.881585854  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<capsfilter0:sink> called chainfunction &gst_base_transform_chain with buffer 0x7fa8016df0, returned ok
0:00:38.882059223  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2506:gst_base_src_get_range:<xcamsrc0> calling create offset 18446744073709551615 length 4096, time 0
0:00:38.885299185  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1146:gst_app_sink_is_eos:<appsink0> we are not yet EOS
0:00:38.885357810  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.885369476  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1641:gst_app_sink_try_pull_sample:<appsink0> waiting for a buffer
0:00:38.888152818  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fa801d700, maxsize:3110407 offset:0 size:3110400
0:00:38.895581481  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.402977508
0:00:38.895613856  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.402977508
0:00:38.895629897  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2360:gst_base_src_do_sync:<xcamsrc0> no sync needed
0:00:38.895643605  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2597:gst_base_src_get_range:<xcamsrc0> buffer ok
0:00:38.895676272  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<capsfilter0:sink> calling chainfunction &gst_base_transform_chain with buffer buffer: 0x7fa8016790, pts 0:00:38.485258256, dts 0:00:38.485258256, dur 99:99:99.999999999, size 3110400, offset 2306, offset_end 2307, flags 0x0
0:00:38.895699313  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:1985:default_submit_input_buffer:<capsfilter0> handling buffer 0x7fa8016790 of size 3110400, PTS 0:00:38.485258256 and offset 2306
0:00:38.895736646  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2102:default_generate_output:<capsfilter0> calling prepare buffer
0:00:38.895752396  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2109:default_generate_output:<capsfilter0> using allocated buffer in 0x7fa8016790, out 0x7fa8016790
0:00:38.895764354  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2126:default_generate_output:<capsfilter0> doing inplace transform
0:00:38.895788270  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<appsink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x7fa8016790, pts 0:00:38.485258256, dts 0:00:38.485258256, dur 99:99:99.999999999, size 3110400, offset 2306, offset_end 2307, flags 0x0
0:00:38.895812478  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3421:gst_base_sink_chain_unlocked:<appsink0> got times start: 0:00:38.485258256, end: 99:99:99.999999999
0:00:38.895835520  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<appsink0> got times start: 0:00:38.485258256, stop: 99:99:99.999999999, do_sync 1
0:00:38.895846895  5601 0x55ac4678a0 DEBUG   default gstsegment.c:737:gst_segment_to_running_time_full: invalid position (-1)
0:00:38.895866436  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2591:gst_base_sink_do_sync:<appsink0> reset rc_time to time 0:00:38.485258256
0:00:38.895885686  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2603:gst_base_sink_do_sync:<appsink0> possibly waiting for clock to reach 0:00:38.485258256, adjusted 0:00:38.485258256
0:00:38.895899977  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:537:gst_clock_id_wait:<GstSystemClock> waiting on clock entry 0x7fa8006480
0:00:38.895916894  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.403318462
0:00:38.895932352  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.403318462
0:00:38.895952477  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstsystemclock.c:719:gst_system_clock_id_wait_jitter_unlocked: entry 0x7fa8006480 time 7:39:42.402977508 now 7:39:42.403318462 diff (time-now) -340954
0:00:38.895968518  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:547:gst_clock_id_wait:<GstSystemClock> done waiting entry 0x7fa8006480, res: 1 (early)
0:00:38.895986601  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2610:gst_base_sink_do_sync:<appsink0> clock returned 1, jitter 0:00:00.000340954
0:00:38.895998268  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2926:gst_base_sink_is_too_late:<appsink0> frame dropping disabled
0:00:38.896010809  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3538:gst_base_sink_chain_unlocked:<appsink0> rendering object 0x7fa8016790
0:00:38.896023643  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<appsink0> setting last buffer to 0x7fa8016790
0:00:38.896038809  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7fa8314f30
0:00:38.896065350  5601 0x55ac4678a0 DEBUG   appsink gstappsink.c:876:gst_app_sink_render_common:<appsink0> pushing render buffer/list 0x7fa8016790 on queue (0)
0:00:38.896111433  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.896128933  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:821:dequeue_buffer:<appsink0> dequeued buffer/list 0x7fa8016790
0:00:38.896142350  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1654:gst_app_sink_try_pull_sample:<appsink0> we have a buffer 0x7fa8016790
0:00:38.897057172  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3577:gst_base_sink_chain_unlocked:<appsink0> object unref after render 0x7fa8016790
0:00:38.897174421  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<appsink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x7fa8016790, returned ok
0:00:38.897228670  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<capsfilter0:sink> called chainfunction &gst_base_transform_chain with buffer 0x7fa8016790, returned ok
0:00:38.897283211  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2506:gst_base_src_get_range:<xcamsrc0> calling create offset 18446744073709551615 length 4096, time 0
0:00:38.901468995  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1146:gst_app_sink_is_eos:<appsink0> we are not yet EOS
0:00:38.901523828  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.901535203  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1641:gst_app_sink_try_pull_sample:<appsink0> waiting for a buffer
0:00:38.904928705  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:139:gst_memory_init: new memory 0x7fa8314f30, maxsize:3110407 offset:0 size:3110400
0:00:38.912152912  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.419548938
0:00:38.912177703  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.419548938
0:00:38.912192578  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2360:gst_base_src_do_sync:<xcamsrc0> no sync needed
0:00:38.912206286  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2597:gst_base_src_get_range:<xcamsrc0> buffer ok
0:00:38.912238077  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<capsfilter0:sink> calling chainfunction &gst_base_transform_chain with buffer buffer: 0x7fa80168a0, pts 0:00:38.501829686, dts 0:00:38.501829686, dur 99:99:99.999999999, size 3110400, offset 2307, offset_end 2308, flags 0x0
0:00:38.912261119  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:1985:default_submit_input_buffer:<capsfilter0> handling buffer 0x7fa80168a0 of size 3110400, PTS 0:00:38.501829686 and offset 2307
0:00:38.912275118  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2102:default_generate_output:<capsfilter0> calling prepare buffer
0:00:38.912289993  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2109:default_generate_output:<capsfilter0> using allocated buffer in 0x7fa80168a0, out 0x7fa80168a0
0:00:38.912330826  5601 0x55ac4678a0 DEBUG   basetransform gstbasetransform.c:2126:default_generate_output:<capsfilter0> doing inplace transform
0:00:38.912355326  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4320:gst_pad_chain_data_unchecked:<appsink0:sink> calling chainfunction &gst_base_sink_chain with buffer buffer: 0x7fa80168a0, pts 0:00:38.501829686, dts 0:00:38.501829686, dur 99:99:99.999999999, size 3110400, offset 2307, offset_end 2308, flags 0x0
0:00:38.912380409  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3421:gst_base_sink_chain_unlocked:<appsink0> got times start: 0:00:38.501829686, end: 99:99:99.999999999
0:00:38.912402284  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2006:gst_base_sink_get_sync_times:<appsink0> got times start: 0:00:38.501829686, stop: 99:99:99.999999999, do_sync 1
0:00:38.912413950  5601 0x55ac4678a0 DEBUG   default gstsegment.c:737:gst_segment_to_running_time_full: invalid position (-1)
0:00:38.912433783  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2591:gst_base_sink_do_sync:<appsink0> reset rc_time to time 0:00:38.501829686
0:00:38.912452741  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2603:gst_base_sink_do_sync:<appsink0> possibly waiting for clock to reach 0:00:38.501829686, adjusted 0:00:38.501829686
0:00:38.912466741  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:537:gst_clock_id_wait:<GstSystemClock> waiting on clock entry 0x7fa8006480
0:00:38.912484241  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1046:gst_clock_get_internal_time:<GstSystemClock> internal time 7:39:42.419885518
0:00:38.912499699  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:1091:gst_clock_get_time:<GstSystemClock> adjusted time 7:39:42.419885518
0:00:38.912520116  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstsystemclock.c:719:gst_system_clock_id_wait_jitter_unlocked: entry 0x7fa8006480 time 7:39:42.419548938 now 7:39:42.419885518 diff (time-now) -336580
0:00:38.912536157  5601 0x55ac4678a0 DEBUG   GST_CLOCK gstclock.c:547:gst_clock_id_wait:<GstSystemClock> done waiting entry 0x7fa8006480, res: 1 (early)
0:00:38.912553948  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2610:gst_base_sink_do_sync:<appsink0> clock returned 1, jitter 0:00:00.000336580
0:00:38.912565615  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:2926:gst_base_sink_is_too_late:<appsink0> frame dropping disabled
0:00:38.912578157  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3538:gst_base_sink_chain_unlocked:<appsink0> rendering object 0x7fa80168a0
0:00:38.912591281  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:994:gst_base_sink_set_last_buffer_unlocked:<appsink0> setting last buffer to 0x7fa80168a0
0:00:38.912618406  5601 0x55ac4678a0 DEBUG   GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7fa801d700
0:00:38.912638531  5601 0x55ac4678a0 DEBUG   appsink gstappsink.c:876:gst_app_sink_render_common:<appsink0> pushing render buffer/list 0x7fa80168a0 on queue (0)
0:00:38.912683155  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.912699780  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:821:dequeue_buffer:<appsink0> dequeued buffer/list 0x7fa80168a0
0:00:38.912722530  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1654:gst_app_sink_try_pull_sample:<appsink0> we have a buffer 0x7fa80168a0
0:00:38.914088430  5601 0x55ac4678a0 DEBUG   basesink gstbasesink.c:3577:gst_base_sink_chain_unlocked:<appsink0> object unref after render 0x7fa80168a0
0:00:38.914228720  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<appsink0:sink> called chainfunction &gst_base_sink_chain with buffer 0x7fa80168a0, returned ok
0:00:38.914285303  5601 0x55ac4678a0 DEBUG   GST_SCHEDULING gstpad.c:4326:gst_pad_chain_data_unchecked:<capsfilter0:sink> called chainfunction &gst_base_transform_chain with buffer 0x7fa80168a0, returned ok
0:00:38.914340428  5601 0x55ac4678a0 DEBUG   basesrc gstbasesrc.c:2506:gst_base_src_get_range:<xcamsrc0> calling create offset 18446744073709551615 length 4096, time 0
0:00:38.918062884  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1146:gst_app_sink_is_eos:<appsink0> we are not yet EOS
0:00:38.918120925  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1630:gst_app_sink_try_pull_sample:<appsink0> trying to grab a buffer
0:00:38.918132591  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1641:gst_app_sink_try_pull_sample:<appsink0> waiting for a buffer
0:00:39.418246385  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1674:gst_app_sink_try_pull_sample:<appsink0> timeout expired, return NULL
0:00:39.419157833  5601 0x7fa0012520 DEBUG   appsink gstappsink.c:1146:gst_app_sink_is_eos:<appsink0> we are not yet EOS
0:00:39.419250290  5601 0x55ac20dc00 DEBUG   GST_STATES gstelement.c:2827:gst_element_set_state_func:<xcamsrc0> set_state to NULL
0:00:39.419274498  5601 0x55ac20dc00 DEBUG   GST_STATES gstelement.c:2852:gst_element_set_state_func:<xcamsrc0> setting target state to NULL
0:00:39.419294915  5601 0x55ac20dc00 DEBUG   GST_STATES gstelement.c:2865:gst_element_set_state_func:<xcamsrc0> current PLAYING, old_pending VOID_PENDING, next VOID_PENDING, old return SUCCESS
0:00:39.419312706  5601 0x55ac20dc00 DEBUG   GST_STATES gstelement.c:2899:gst_element_set_state_func:<xcamsrc0> intermediate: setting state from PLAYING to PAUSED
0:00:39.419330498  5601 0x55ac20dc00 DEBUG   basesrc gstbasesrc.c:3910:gst_base_src_change_state:<xcamsrc0> PLAYING->PAUSED
0:00:39.419344498  5601 0x55ac20dc00 DEBUG   basesrc gstbasesrc.c:3742:gst_base_src_set_playing:<xcamsrc0> unschedule clock
0:00:39.419358206  5601 0x55ac20dc00 DEBUG   basesrc gstbasesrc.c:3749:gst_base_src_set_playing:<xcamsrc0> live running 0
0:00:39.419370456  5601 0x55ac20dc00 DEBUG   GST_STATES gstelement.c:2995:gst_element_change_state:<xcamsrc0> element changed state NO_PREROLL
0:00:39.419388247  5601 0x55ac20dc00 INFO   GST_STATES gstelement.c:2651:gst_element_continue_state:<xcamsrc0> committing state from PLAYING to PAUSED, pending NULL, next READY
0:00:39.419405455  5601 0x55ac20dc00 INFO   GST_STATES gstelement.c:2579:_priv_gst_element_state_changed:<xcamsrc0> notifying about state-changed PLAYING to PAUSED (NULL pending)
0:00:39.419463205  5601 0x55ac20dc00 DEBUG   GST_BUS gstbus.c:318:gst_bus_post:<bus0> [msg 0x55ac46ad30] posting on bus state-changed message: 0x55ac46ad30, time 99:99:99.999999999, seq-num 39, element 'xcamsrc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PLAYING, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_NULL;
0:00:39.419509287  5601 0x55ac20dc00 DEBUG   bin gstbin.c:3717:gst_bin_handle_message_func:<pipeline0> [msg 0x55ac46ad30] handling child xcamsrc0 message of type state-changed
0:00:39.419524162  5601 0x55ac20dc00 DEBUG   bin gstbin.c:4054:gst_bin_handle_message_func:<pipeline0> posting message upward
0:00:39.419550995  5601 0x55ac20dc00 DEBUG   GST_BUS gstbus.c:318:gst_bus_post:<bus1> [msg 0x55ac46ad30] posting on bus state-changed message: 0x55ac46ad30, time 99:99:99.999999999, seq-num 39, element 'xcamsrc0', GstMessageStateChanged, old-state=(GstState)GST_STATE_PLAYING, new-state=(GstState)GST_STATE_PAUSED, pending-state=(GstState)GST_STATE_NULL;
0:00:39.419565578  5601 0x55ac20dc00 DEBUG   GST_BUS gstbus.c:357:gst_bus_post:<bus1> [msg 0x55ac46ad30] pushing on async queue
0:00:39.419591245  5601 0x55ac20dc00 DEBUG   GST_BUS gstbus.c:360:gst_bus_post:<bus1> [msg 0x55ac46ad30] pushed on async queue
0:00:39.419629744  5601 0x55ac20dc00 DEBUG   GST_BUS gstbus.c:353:gst_bus_post:<bus0> [msg 0x55ac46ad30] dropped
0:00:39.419645786  5601 0x55ac20dc00 INFO   GST_STATES gstelement.c:2658:gst_element_continue_state:<xcamsrc0> continue state change PAUSED to READY, final NULL
0:00:39.419660077  5601 0x55ac20dc00 DEBUG   GST_ELEMENT_PADS gstelement.c:3097:gst_element_pads_activate:<xcamsrc0> deactivate pads
0:00:39.419683702  5601 0x55ac20dc00 DEBUG   GST_PADS gstpad.c:1121:gst_pad_set_active:<xcamsrc0:src> deactivating pad from push mode
0:00:39.419698285  5601 0x55ac20dc00 DEBUG   GST_PADS gstpad.c:991:pre_activate:<xcamsrc0:src> setting PAD_MODE NONE, set flushing
0:00:39.419714910  5601 0x55ac20dc00 DEBUG   basesrc gstbasesrc.c:3858:gst_base_src_activate_mode:<xcamsrc0:src> activating in mode 1
0:00:39.419726868  5601 0x55ac20dc00 DEBUG   basesrc gstbasesrc.c:3792:gst_base_src_activate_push:<xcamsrc0> Deactivating in push mode
0:00:39.419738243  5601 0x55ac20dc00 DEBUG   basesrc gstbasesrc.c:3629:gst_base_src_stop:<xcamsrc0> stopping source
0:00:39.419750201  5601 0x55ac20dc00 DEBUG   basesrc gstbasesrc.c:3679:gst_base_src_set_flushing:<xcamsrc0> flushing 1
0:00:39.419775868  5601 0x55ac20dc00 DEBUG   GST_PADS gstpad.c:6288:gst_pad_stop_task:<xcamsrc0:src> stop task
0:00:39.419793951  5601 0x55ac20dc00 DEBUG   task gsttask.c:688:gst_task_set_state:<xcamsrc0:src> Changing task 0x55ac48e050 to state 1

0:01:24.000659198  5601 0x55ac20dc00 INFO   GST_INIT gst.c:1083:gst_deinit: deinitializing GStreamer