*** DeepStream: Launched RTSP Streaming at rtsp://localhost:8554/ds-test *** WARNING: infer_proto_utils.cpp:144 auto-update preprocess.network_format to IMAGE_FORMAT_RGB INFO: infer_grpc_backend.cpp:169 TritonGrpcBackend id:1 initialized for model: yolo Runtime commands: h: Print this help q: Quit p: Pause r: Resume ** INFO: : Pipeline ready **PERF: FPS 0 (Avg) **PERF: 0.00 (0.00) ** INFO: : Pipeline running 0:00:12.728369982 21201 0x7ff94c050f00 DEBUG v4l2videodec gstv4l2videodec.c:1404:gst_v4l2_video_dec_handle_frame: Handling frame 9 0:00:12.728390620 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:2077:gst_v4l2_buffer_pool_process: process buffer 0x7ff944014a38 0:00:12.728467421 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:2280:gst_v4l2_buffer_pool_process: alloc buffer from our pool 0:00:12.728485141 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1641:gst_v4l2_buffer_pool_acquire_buffer: acquire 0:00:12.728504307 21201 0x7ff94c050f00 LOG bufferpool gstbufferpool.c:1123:default_acquire_buffer: acquired buffer 0x7ff9500335a0 0:00:12.728521634 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:144:gst_v4l2_buffer_pool_copy_buffer: copying buffer 0:00:12.728538878 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:176:gst_v4l2_buffer_pool_copy_buffer: copy raw bytes 0:00:12.728026856 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003100 does not match 00001010 0:00:12.727976543 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_sub_bin_udpsink1 is sink 0:00:12.728632705 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.728590346 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1800:gst_buffer_map_range: buffer 0x7ff950033900, idx 0, length -1, flags 0001 0:00:12.728663532 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6aa20: 1 0:00:12.728789349 21201 0x55adeed15760 DEBUG bin gstbin.c:878:find_message: we found a message 0x55adefe6aa20 from sink_sub_bin_udpsink1 matching types 10000000 0:00:12.728674403 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003080 does not match 00001012 0:00:12.728750906 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:244:_get_merged_memory: buffer 0x7ff950033900, idx 0, length 2 0:00:12.728840785 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 2 with flags 0x00003400 does not match 00001012 0:00:12.728807542 21201 0x55adeed15760 DEBUG bin gstbin.c:1036:is_stream_start: sink 'sink_sub_bin_udpsink1' posted STREAM_START 0:00:12.728901988 21201 0x55adeed0d1e0 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.728918209 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:138:gst_memory_init: new memory 0x7ff944333020, maxsize:146 offset:0 size:139 0:00:12.728902762 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_sub_bin_rtppay1 is not sink 0:00:12.728943220 21201 0x7ff94c050f00 DEBUG GST_PERFORMANCE gstbuffer.c:292:_get_merged_memory: memcpy 6 bytes for merge 0x7ff944333020 from memory 0x7ff9443488a0 0:00:12.728968087 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.728964690 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child h264-parser is not sink 0:00:12.729006151 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_sub_bin_encoder1 is not sink 0:00:12.729016898 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003080 does not match 00001010 0:00:12.729028118 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_sub_bin_transform1 is not sink 0:00:12.729018980 21201 0x7ff94c050f00 DEBUG GST_PERFORMANCE gstbuffer.c:292:_get_merged_memory: memcpy 133 bytes for merge 0x7ff944333020 from memory 0x7ff94434a000 0:00:12.729051047 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_sub_bin_cap_filter1 is not sink 0:00:12.729042569 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 2 with flags 0x00003400 does not match 00001010 0:00:12.729093678 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_sub_bin_queue1 is not sink 0:00:12.729100188 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:314:_replace_memory: buffer 0x7ff950033900 replace 0-2 with memory 0x7ff944333020 0:00:12.729118421 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6aa20: 1 0:00:12.729129670 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff9443488a0 0:00:12.729098200 21201 0x55adeed0d1e0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_multi_queue_chain with buffer buffer: 0x7ff95007b240, pts 0:00:00.600000000, dts 0:00:00.600000000, dur 0:00:00.040000000, size 221, offset none, offset_end none, flags 0x2000 0:00:12.729147934 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff94434a000 0:00:12.729186360 21201 0x55adeed15760 DEBUG bin gstbin.c:956:bin_remove_messages: deleting message 0x55adefe6aa20 of type stream-start (types 0x10000000) 0:00:12.729196823 21201 0x55adeed0d1e0 LOG multiqueue gstmultiqueue.c:2117:gst_multi_queue_chain: SingleQueue 0 : about to enqueue buffer 0x7ff95007b240 with id 21 (pts:0:00:00.600000000 dts:0:00:00.600000000 dur:0:00:00.040000000) 0:00:12.729192800 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1904:gst_buffer_fill: buffer 0x7ff9500335a0, offset 0, size 139 0:00:12.729230924 21201 0x55adeed0d1e0 LOG data_queue_dataflow gstdataqueue.c:507:gst_data_queue_push: queue:0x55adeecb2c40 before pushing: 5 visible items, 41254 bytes, 200000000 ns, 5 elements 0:00:12.729237193 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x55adefe6aa20, stream-start from sink_sub_bin_udpsink1 0:00:12.729273000 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:303:gst_message_new_custom: source sink_sub_bin1: creating new message 0x55adefe6aaa0 stream-start 0:00:12.729285284 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1656:gst_buffer_resize_range: trim 0x7ff9500335a0 0-139 size:4194304 offs:0 max:4194304 0:00:12.729308170 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff950033900 to 0x7ff9500335a0, offset 0-139/139 0:00:12.729282574 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2861:single_queue_check_full: queue 0: visible 5/5, bytes 41254/2097152, time 200000000/0 0:00:12.729320016 21201 0x55adeed15760 DEBUG bin gstbin.c:3587:bin_do_stream_start: all sinks posted STREAM_START, posting seqnum #216 0:00:12.729324694 21201 0x7ff94c050f00 LOG GST_PERFORMANCE gstv4l2bufferpool.c:303:gst_v4l2_buffer_pool_copy_buffer: slow copy into buffer 0x7ff9500335a0 0:00:12.729373644 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1375:gst_v4l2_buffer_pool_qbuf: queuing buffer 1 0:00:12.729357703 21201 0x55adeed0d1e0 LOG multiqueue gstmultiqueue.c:2755:single_queue_overrun_cb: Single Queue 0: EOS 0, visible 5/5, bytes 41254/2097152, time 200000000/0 0:00:12.729402001 21201 0x7ff94c050f00 LOG v4l2allocator gstv4l2allocator.c:1405:gst_v4l2_allocator_qbuf: queued buffer 1 (flags 0x2) 0:00:12.729407072 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2807:single_queue_overrun_cb: Queue 0 is filled, signalling overrun 0:00:12.729415447 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:753:_gst_buffer_dispose: release 0x7ff9500335a0 to pool 0x7ff94431af00 0:00:12.729386281 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:340:gst_bus_post: [msg 0x55adefe6aaa0] posting on bus stream-start message: 0x55adefe6aaa0, time 99:99:99.999999999, seq-num 216, element 'sink_sub_bin1', GstMessageStreamStart, group-id=(uint)2; 0:00:12.729440759 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1656:gst_buffer_resize_range: trim 0x7ff9500335a0 0-4194304 size:139 offs:0 max:4194304 0:00:12.729468596 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1719:gst_v4l2_buffer_pool_release_buffer: release buffer 0x7ff9500335a0 0:00:12.729479866 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1810:gst_v4l2_buffer_pool_release_buffer: buffer 1 is queued 0:00:12.729490301 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1449:gst_v4l2_buffer_pool_dqbuf: dequeueing a buffer 0:00:12.729465423 21201 0x55adeed15760 DEBUG bin gstbin.c:3718:gst_bin_handle_message_func: [msg 0x55adefe6aaa0] handling child sink_sub_bin1 message of type stream-start 0:00:12.729506866 21201 0x7ff94c050f00 LOG v4l2allocator gstv4l2allocator.c:1463:gst_v4l2_allocator_dqbuf: dequeued buffer 0 (flags 0x0) 0:00:12.729526377 21201 0x55adeed15760 DEBUG bin gstbin.c:882:find_message: no message found matching types 10000000 0:00:12.729556450 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1510:gst_v4l2_buffer_pool_dqbuf: dequeued buffer 0x55adf1cc4ea0 seq:0 (ix=0), mem 0x7ff94401e050 used 64, plane=0, flags 00000000, ts 0:00:00.000000000, pool-queued=1, buffer=0x55adf1cc4ea0 0:00:12.729568713 21201 0x55adeed15760 DEBUG bin gstbin.c:889:find_message: stream-start 0:00:12.729593722 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1719:gst_v4l2_buffer_pool_release_buffer: release buffer 0x55adf1cc4ea0 0:00:12.729594404 21201 0x55adeed15760 DEBUG bin gstbin.c:928:bin_replace_message: got new message 0x55adefe6aaa0, stream-start from sink_sub_bin1 0:00:12.729439756 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2861:single_queue_check_full: queue 0: visible 5/5, bytes 41254/2097152, time 200000000/0 0:00:12.729621434 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_sub_bin1 is sink 0:00:12.729646574 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6aaa0: 1 0:00:12.729673637 21201 0x55adeed15760 DEBUG bin gstbin.c:878:find_message: we found a message 0x55adefe6aaa0 from sink_sub_bin1 matching types 10000000 0:00:12.729676015 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1794:gst_v4l2_buffer_pool_release_buffer: buffer 0 not queued, putting on free list 0:00:12.729689175 21201 0x55adeed15760 DEBUG bin gstbin.c:1036:is_stream_start: sink 'sink_sub_bin1' posted STREAM_START 0:00:12.729706178 21201 0x7ff94c050f00 LOG bufferpool gstbufferpool.c:1285:default_release_buffer: released buffer 0x55adf1cc4ea0 0 0:00:12.729761392 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_bin_tee is not sink 0:00:12.729776280 21201 0x7ff94c050f00 DEBUG GST_BUFFER gstbuffer.c:1451:gst_buffer_is_memory_range_writable: idx 0, length -1 0:00:12.729794830 21201 0x7ff95002faa0 LOG v4l2allocator gstv4l2allocator.c:1463:gst_v4l2_allocator_dqbuf: dequeued buffer 1 (flags 0x0) 0:00:12.729795478 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_bin_queue is not sink 0:00:12.729811259 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff94434a900 0:00:12.729831173 21201 0x7ff95002faa0 LOG v4l2allocator gstv4l2allocator.c:1500:gst_v4l2_allocator_dqbuf: Dequeued capture buffer, length: 0 bytesused: 64 data_offset: 0 0:00:12.729845019 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6aaa0: 1 0:00:12.729865459 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff950033900 to 0x7ff94434a900, offset 0-0/139 0:00:12.729899094 21201 0x55adeed15760 DEBUG bin gstbin.c:956:bin_remove_messages: deleting message 0x55adefe6aaa0 of type stream-start (types 0x10000000) 0:00:12.729899043 21201 0x7ff95002faa0 LOG v4l2bufferpool gstv4l2bufferpool.c:1510:gst_v4l2_buffer_pool_dqbuf: dequeued buffer 0x7ff944024000 seq:0 (ix=1), mem 0x7ff95000a610 used 64, plane=0, flags 00000000, ts 0:00:00.320000000, pool-queued=2, buffer=0x7ff944024000 0:00:12.729912193 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff950033900 0:00:12.729929732 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:303:gst_message_new_custom: source sink_bin: creating new message 0x55adefe6ab20 stream-start 0:00:12.729949372 21201 0x7ff95002faa0 LOG v4l2videodec gstv4l2videodec.c:1238:gst_v4l2_video_dec_loop: Process output buffer 0:00:12.729965598 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff944333020 0:00:12.729982894 21201 0x55adeed15760 DEBUG bin gstbin.c:3587:bin_do_stream_start: all sinks posted STREAM_START, posting seqnum #216 0:00:12.730022995 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_video_decoder_chain with buffer 0x7ff950033900, returned ok 0:00:12.730000540 21201 0x7ff95002faa0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:2077:gst_v4l2_buffer_pool_process: process buffer 0x7ff937ff4718 0:00:12.730054230 21201 0x7ff95002faa0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:2127:gst_v4l2_buffer_pool_process: num_queued 2 copy_threshold 4 dynamic_allocation 0 0:00:12.730061386 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_base_transform_chain with buffer 0x7ff950033900, returned ok 0:00:12.730085530 21201 0x7ff95002faa0 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x55adf1cc4d80 to 0x7ff944024000, offset 0-0/0 0:00:12.730093146 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2581:gst_base_parse_push_frame: frame pushed, flow ok 0:00:12.730092174 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:340:gst_bus_post: [msg 0x55adefe6ab20] posting on bus stream-start message: 0x55adefe6ab20, time 99:99:99.999999999, seq-num 216, element 'sink_bin', GstMessageStreamStart, group-id=(uint)2; 0:00:12.730107960 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:2960:gst_video_decoder_finish_frame: finish frame 0x7ff9440144b0 0:00:12.730122592 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2206:gst_base_parse_handle_buffer: handle_frame skipped 0, flushed 133 0:00:12.730161360 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:2597:gst_video_decoder_prepare_finish_frame: n 4 in 0 out 0 0:00:12.730141836 21201 0x55adeed15760 DEBUG bin gstbin.c:3718:gst_bin_handle_message_func: [msg 0x55adefe6ab20] handling child sink_bin message of type stream-start 0:00:12.730189363 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:2605:gst_video_decoder_prepare_finish_frame: finish frame 0x7ff9440144b0 (#4) sync:0 PTS:0:00:00.320000000 DTS:0:00:00.160000000 0:00:12.730191610 21201 0x7ff94c050f00 DEBUG baseparse gstbaseparse.c:3244:gst_base_parse_chain: not enough data available (only 0 bytes) 0:00:12.730232995 21201 0x7ff95002faa0 DEBUG videodecoder gstvideodecoder.c:2647:gst_video_decoder_prepare_finish_frame: sync timestamp 0:00:00.320000000 diff -0:00:00.320000000 0:00:12.730236151 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:3306:gst_base_parse_chain: chain leaving 0:00:12.730206317 21201 0x55adeed15760 DEBUG bin gstbin.c:882:find_message: no message found matching types 10000000 0:00:12.730253767 21201 0x7ff95002faa0 DEBUG default gstvideoutils.c:46:_gst_video_codec_frame_free: free frame 0x7ff9440144b0 0:00:12.730270762 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_base_parse_chain with buffer 0x7ff94434a5a0, returned ok 0:00:12.730283278 21201 0x55adeed15760 DEBUG bin gstbin.c:889:find_message: stream-start 0:00:12.730302774 21201 0x7ff95002faa0 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x55adf1cc4d80 0:00:12.730319679 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:2028:gst_multi_queue_loop: sq:0 AFTER PUSHING sq->srcresult: ok (is_eos:0) 0:00:12.730334112 21201 0x55adeed15760 DEBUG bin gstbin.c:928:bin_replace_message: got new message 0x55adefe6ab20, stream-start from sink_bin 0:00:12.730365349 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1798:gst_multi_queue_loop: SingleQueue 0 : trying to pop an object 0:00:12.730365788 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:3098:gst_video_decoder_clip_and_push_buf: accepting buffer inside segment: 0:00:00.320000000 0:00:00.360000000 seg 0:00:00.000000000 to 99:99:99.999999999 time 0:00:00.000000000 0:00:12.730380939 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child osd_bin is not sink 0:00:12.730398040 21201 0x7ff94c050f00 LOG data_queue_dataflow gstdataqueue.c:586:gst_data_queue_pop: queue:0x55adeecb2c40 before popping: 5 visible items, 41254 bytes, 200000000 ns, 5 elements 0:00:12.730432329 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child sink_bin is sink 0:00:12.730428603 21201 0x7ff95002faa0 DEBUG videodecoder gstvideodecoder.c:3168:gst_video_decoder_clip_and_push_buf: pushing buffer 0x7ff944024000 of size 64, PTS 0:00:00.320000000, dur 0:00:00.040000000 0:00:12.730449149 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6ab20: 1 0:00:12.730477114 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7ff944024000, pts 0:00:00.320000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.730480184 21201 0x55adeed15760 DEBUG bin gstbin.c:878:find_message: we found a message 0x55adefe6ab20 from sink_bin matching types 10000000 0:00:12.730484444 21201 0x7ff94c050f00 LOG data_queue_dataflow gstdataqueue.c:609:gst_data_queue_pop: queue:0x55adeecb2c40 after popping: 4 visible items, 1809 bytes, 160000000 ns, 4 elements 0:00:12.730500334 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.730516781 21201 0x55adeed15760 DEBUG bin gstbin.c:1036:is_stream_start: sink 'sink_bin' posted STREAM_START 0:00:12.730531171 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:1820:gst_multi_queue_loop: SingleQueue 0 : newid:16 , oldid:15 0:00:12.730572749 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:1832:gst_multi_queue_loop: CHECKING sq->srcresult: ok 0:00:12.730554299 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2861:single_queue_check_full: queue 0: visible 4/5, bytes 1809/2097152, time 200000000/0 0:00:12.730562985 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6ab20: 1 0:00:12.730612940 21201 0x55adeed0d1e0 LOG data_queue_dataflow gstdataqueue.c:530:gst_data_queue_push: queue:0x55adeecb2c40 after pushing: 5 visible items, 2030 bytes, 200000000 ns, 5 elements 0:00:12.730585688 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:2616:compute_high_id: inspecting sq:0 , nextid:16, oldid:15, srcresult:ok 0:00:12.730550631 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003040 does not match 00001012 0:00:12.730622908 21201 0x55adeed15760 DEBUG bin gstbin.c:956:bin_remove_messages: deleting message 0x55adefe6ab20 of type stream-start (types 0x10000000) 0:00:12.730649346 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:2642:compute_high_id: Highid is now : 15, lowest non-linked 4294967295 0:00:12.730687770 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:303:gst_message_new_custom: source processing_bin_0: creating new message 0x55adefe6aba0 stream-start 0:00:12.730667090 21201 0x7ff95002faa0 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.730698087 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:1941:gst_multi_queue_loop: sq:0 BEFORE PUSHING sq->srcresult: ok 0:00:12.730715057 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.730713821 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:1508:apply_buffer: queue 0, sink position updated to 0:00:00.640000000 0:00:12.730738479 21201 0x55adeed15760 DEBUG bin gstbin.c:3587:bin_do_stream_start: all sinks posted STREAM_START, posting seqnum #216 0:00:12.730756943 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003040 does not match 00001010 0:00:12.730800882 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:340:gst_bus_post: [msg 0x55adefe6aba0] posting on bus stream-start message: 0x55adefe6aba0, time 99:99:99.999999999, seq-num 216, element 'processing_bin_0', GstMessageStreamStart, group-id=(uint)2; 0:00:12.730793110 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:1380:update_time_level: queue 0 sink_segment.position:0:00:00.640000000, sink_time:+0:00:00.560000000 0:00:12.730845808 21201 0x55adeed15760 DEBUG bin gstbin.c:3718:gst_bin_handle_message_func: [msg 0x55adefe6aba0] handling child processing_bin_0 message of type stream-start 0:00:12.730858105 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7ff944024000, pts 0:00:00.320000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.730848114 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:1431:update_time_level: queue 0, sink +0:00:00.560000000, src +0:00:00.320000000 0:00:12.730863388 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x7ff954002840: 0 0:00:12.730916021 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1508:apply_buffer: queue 0, src position updated to 0:00:00.440000000 0:00:12.730887901 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_tee_chain with buffer buffer: 0x7ff944024000, pts 0:00:00.320000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.730914033 21201 0x55adeed0d1e0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_multi_queue_chain with buffer 0x7ff95007b240, returned ok 0:00:12.730919382 21201 0x55adeed15760 DEBUG bin gstbin.c:882:find_message: no message found matching types 10000000 0:00:12.730978220 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.730955479 21201 0x7ff95002faa0 DEBUG tee gsttee.c:1020:gst_tee_chain: received buffer 0x7ff944024000 0:00:12.731023867 21201 0x7ff95002faa0 LOG tee gsttee.c:934:gst_tee_handle_data: Starting to push buffer 0x7ff944024000 0:00:12.731006976 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003100 does not match 00001001 0:00:12.731047160 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_queue_chain with buffer buffer: 0x7ff944024000, pts 0:00:00.320000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.731029870 21201 0x55adeed15760 DEBUG bin gstbin.c:889:find_message: stream-start 0:00:12.731078887 21201 0x7ff95002faa0 LOG queue_dataflow gstqueue.c:1200:gst_queue_chain_buffer_or_list: received buffer 0x7ff944024000 of size 64, time 0:00:00.320000000, duration 0:00:00.040000000 0:00:12.731089111 21201 0x55adeed15760 DEBUG bin gstbin.c:928:bin_replace_message: got new message 0x55adefe6aba0, stream-start from processing_bin_0 0:00:12.730939807 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1431:update_time_level: queue 0, sink +0:00:00.560000000, src +0:00:00.360000000 0:00:12.731127998 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1636:gst_single_queue_push_one: SingleQueue 0 : Pushing buffer 0x7ff94434a7e0 with ts 0:00:00.400000000 0:00:12.731136672 21201 0x7ff95002faa0 LOG queue gstqueue.c:633:apply_buffer: sink position updated to 0:00:00.360000000 0:00:12.731150841 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_base_parse_chain with buffer buffer: 0x7ff94434a7e0, pts 0:00:00.480000000, dts 0:00:00.400000000, dur 0:00:00.040000000, size 39445, offset none, offset_end none, flags 0x0 0:00:12.731166887 21201 0x7ff94c050f00 DEBUG baseparse gstbaseparse.c:3074:gst_base_parse_chain: chain 0:00:12.731155412 21201 0x7ff95002faa0 LOG queue gstqueue.c:530:update_time_level: update sink time 0:00:12.731190143 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:3190:gst_base_parse_chain: buffer size: 39445, offset = -1, dts 0:00:00.400000000, pts 0:00:00.480000000 0:00:12.731219353 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5449:gst_qtdemux_combine_flows: flow return: ok 0:00:12.731230203 21201 0x7ff94c050f00 LOG adapter gstadapter.c:390:gst_adapter_push: pushing 0x7ff94434a7e0 first 39445 bytes 0:00:12.731214633 21201 0x7ff95002faa0 LOG queue gstqueue.c:547:update_time_level: sink +0:00:00.360000000, src +0:00:00.280000000 0:00:12.731263647 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_queue_chain with buffer 0x7ff944024000, returned ok 0:00:12.731275467 21201 0x7ff94c050f00 LOG adapter gstadapter.c:294:update_timestamps_and_offset: new pts 0:00:00.480000000 0:00:12.731285317 21201 0x7ff95002faa0 LOG tee gsttee.c:939:gst_tee_handle_data: Pushing item 0x7ff944024000 yielded result ok 0:00:12.731268779 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5457:gst_qtdemux_combine_flows: combined flow return: ok 0:00:12.731190738 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child common_analytics_tee is not sink 0:00:12.731308499 21201 0x7ff95002faa0 LOG tee gsttee.c:974:gst_tee_handle_data: Replacing ret val -1 with 0 0:00:12.731331074 21201 0x7ff94c050f00 LOG adapter gstadapter.c:300:update_timestamps_and_offset: new dts 0:00:00.400000000 0:00:12.731329670 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc avc1, pad video_0 0:00:12.731345545 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child primary_gie_bin is not sink 0:00:12.731366134 21201 0x7ff95002faa0 LOG tee gsttee.c:934:gst_tee_handle_data: Starting to push buffer 0x7ff944024000 0:00:12.731377854 21201 0x7ff94c050f00 LOG adapter gstadapter.c:973:gst_adapter_get_buffer: getting buffer of 39445 bytes 0:00:12.731433035 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_queue_chain with buffer buffer: 0x7ff944024000, pts 0:00:00.320000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.731407239 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child processing_bin_0 is sink 0:00:12.731462875 21201 0x7ff95002faa0 LOG queue_dataflow gstqueue.c:1200:gst_queue_chain_buffer_or_list: received buffer 0x7ff944024000 of size 64, time 0:00:00.320000000, duration 0:00:00.040000000 0:00:12.731439028 21201 0x7ff94c050f00 LOG adapter gstadapter.c:988:gst_adapter_get_buffer: providing buffer of 39445 bytes as head buffer 0:00:12.731488566 21201 0x7ff95002faa0 LOG queue gstqueue.c:633:apply_buffer: sink position updated to 0:00:00.360000000 0:00:12.731393198 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9347:qtdemux_parse_samples: parsing up to sample 16 0:00:12.731545306 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9372:qtdemux_parse_samples: sample 16 has size 242 0:00:12.731507236 21201 0x7ff95002faa0 LOG queue gstqueue.c:530:update_time_level: update sink time 0:00:12.731563582 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9500:qtdemux_parse_samples: creating entry 16 with offset 120425 and size 242 0:00:12.731586423 21201 0x7ff95002faa0 LOG queue gstqueue.c:547:update_time_level: sink +0:00:00.360000000, src +0:00:00.320000000 0:00:12.731575166 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6aba0: 1 0:00:12.731617957 21201 0x7ff95002faa0 LOG queue_dataflow gstqueue.c:749:gst_queue_locked_enqueue_buffer: (fakequeue:sink) signal ADD: 1 of 0-200 buffers, 64 of 0-10485760 bytes, 40000000 of 0-1000000000 ns, 1 items 0:00:12.731546467 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff94434a000 0:00:12.731642408 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_queue_chain with buffer 0x7ff944024000, returned ok 0:00:12.731665074 21201 0x7ff95002faa0 LOG tee gsttee.c:939:gst_tee_handle_data: Pushing item 0x7ff944024000 yielded result ok 0:00:12.731678298 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9556:qtdemux_parse_samples: sample 16: index 16, timestamp 0:00:00.640000000 0:00:12.731680052 21201 0x55adeeb10d80 LOG queue_dataflow gstqueue.c:1525:gst_queue_loop: (fakequeue:src) received ADD: 1 of 0-200 buffers, 64 of 0-10485760 bytes, 40000000 of 0-1000000000 ns, 1 items 0:00:12.731711878 21201 0x55adeeb10d80 DEBUG queue_dataflow gstqueue.c:1528:gst_queue_loop: queue is not empty 0:00:12.731682244 21201 0x7ff95002faa0 LOG tee gsttee.c:974:gst_tee_handle_data: Replacing ret val 0 with 0 0:00:12.731741989 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9616:qtdemux_parse_samples: samples at 160 is keyframe 0:00:12.731749348 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff94434a7e0 to 0x7ff94434a000, offset 0-39445/39445 0:00:12.731741839 21201 0x7ff95002faa0 DEBUG tee gsttee.c:1024:gst_tee_chain: handled buffer ok 0:00:12.731726128 21201 0x55adeed15760 DEBUG bin gstbin.c:878:find_message: we found a message 0x55adefe6aba0 from processing_bin_0 matching types 10000000 0:00:12.731787339 21201 0x55adeeb10d80 LOG queue_dataflow gstqueue.c:835:gst_queue_locked_dequeue: retrieved buffer 0x7ff944024000 from queue 0:00:12.731782875 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_tee_chain with buffer 0x7ff944024000, returned ok 0:00:12.731842460 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_proxy_pad_chain_default with buffer 0x7ff944024000, returned ok 0:00:12.731860300 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.731827252 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6521:gst_qtdemux_loop: loop at position 4002921, state 0:00:12.731798395 21201 0x55adeed15760 DEBUG bin gstbin.c:1036:is_stream_start: sink 'processing_bin_0' posted STREAM_START 0:00:12.731847306 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff94434a000, idx -1, mem 0x7ff950088000 0:00:12.731911181 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child demuxer is not sink 0:00:12.731823041 21201 0x55adeeb10d80 LOG queue gstqueue.c:633:apply_buffer: src position updated to 0:00:00.360000000 0:00:12.731896517 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5249:gst_qtdemux_prepare_current_sample: segment active, index = 24 of 1296 0:00:12.731919038 21201 0x7ff94c050f00 DEBUG GST_PERFORMANCE gstminiobject.c:527:ensure_priv_data: allocating private data GstMemory miniobject 0x7ff950088000 0:00:12.731964141 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc mp4a, pad audio_0 0:00:12.731971964 21201 0x7ff94c050f00 DEBUG GST_PERFORMANCE gstminiobject.c:427:gst_mini_object_make_writable: copy GstBuffer miniobject 0x7ff94434a7e0 -> 0x7ff94434a000 0:00:12.731879790 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003040 does not match 00001001 0:00:12.731992632 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2184:gst_base_parse_handle_buffer: handling buffer of size 39445 with dts 0:00:00.400000000, pts 0:00:00.480000000, duration 99:99:99.999999999 0:00:12.732010967 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2147:gst_base_parse_prepare_frame: preparing frame at offset 18446744073709551615 (0xffffffffffffffff) of size 39445 0:00:12.731942731 21201 0x55adeeb10d80 LOG queue gstqueue.c:539:update_time_level: update src time 0:00:12.732048622 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_proxy_pad_chain_default with buffer 0x7ff944024000, returned ok 0:00:12.732021650 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:801:gst_base_parse_update_frame: marking as new frame 0:00:12.732019158 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9723:qtdemux_parse_samples: Tried to parse up to sample 24 but this sample has already been parsed 0:00:12.732052116 21201 0x55adeeb10d80 LOG queue gstqueue.c:547:update_time_level: sink +0:00:00.360000000, src +0:00:00.360000000 0:00:12.732111853 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:6367:gst_qtdemux_loop_state_movie: pushing from track-id 2, empty 0 offset 119862, size 279, dts=0:00:00.557278911, pts=0:00:00.557278911, duration 0:00:00.023219955 0:00:12.732069153 21201 0x7ff95002faa0 LOG v4l2videodec gstv4l2videodec.c:1216:gst_v4l2_video_dec_loop: Allocate output buffer 0:00:12.732084371 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1800:gst_buffer_map_range: buffer 0x7ff94434a000, idx 0, length -1, flags 0001 0:00:12.732055246 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child tiler_tee is not sink 0:00:12.732132445 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6427:gst_qtdemux_loop_state_movie: reading 279 bytes @ 119862 0:00:12.732135909 21201 0x55adeeb10d80 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_base_sink_chain with buffer buffer: 0x7ff944024000, pts 0:00:00.320000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.732152680 21201 0x7ff95002faa0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1641:gst_v4l2_buffer_pool_acquire_buffer: acquire 0:00:12.732166094 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:244:_get_merged_memory: buffer 0x7ff94434a000, idx 0, length 1 0:00:12.732181424 21201 0x55adeed15760 DEBUG GST_STATES gstbin.c:1971:bin_element_is_sink: child multi_src_bin is sink 0:00:12.732202584 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_type_find_element_getrange, offset 119862, size 279 0:00:12.732230864 21201 0x7ff95002faa0 LOG v4l2bufferpool gstv4l2bufferpool.c:1449:gst_v4l2_buffer_pool_dqbuf: dequeueing a buffer 0:00:12.732229444 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:3523:gst_base_sink_chain_unlocked: got times start: 0:00:00.320000000, end: 0:00:00.360000000 0:00:12.732246142 21201 0x7ff94c050f00 LOG h264parse gsth264parse.c:1100:gst_h264_parse_handle_frame_packetized: processing packet buffer of size 39445 0:00:12.732316525 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:2110:gst_base_sink_get_sync_times: got times start: 0:00:00.320000000, stop: 0:00:00.360000000, do_sync 1 0:00:12.732279341 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_proxy_pad_getrange_default, offset 119862, size 279 0:00:12.732344238 21201 0x55adeeb10d80 LOG basesink gstbasesink.c:2653:gst_base_sink_do_sync: avg frame diff 0:00:00.040000000 0:00:12.732326028 21201 0x7ff94c050f00 DEBUG codecparsers_h264 gsth264parser.c:242:gst_h264_parse_nalu_header: Nal type 5, ref_idc 3 0:00:12.732374688 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:2693:gst_base_sink_do_sync: reset rc_time to time 0:00:00.320000000 0:00:12.732259090 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6aba0: 0 0:00:12.732407919 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:2704:gst_base_sink_do_sync: possibly waiting for clock to reach 0:00:00.320000000, adjusted 0:00:00.320000000 0:00:12.732381242 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:1107:gst_h264_parse_handle_frame_packetized: AVC nal offset 39445 0:00:12.732443470 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:833:gst_h264_parse_process_nal: processing nal of type 5 Slice IDR, size 39441 0:00:12.732455282 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:942:gst_h264_parse_process_nal: first_mb_in_slice = 0 0:00:12.732465830 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:945:gst_h264_parse_process_nal: frame start: 1 0:00:12.732454159 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_base_src_getrange, offset 119862, size 279 0:00:12.732428776 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:2326:gst_base_sink_wait_clock: sync disabled 0:00:12.732505307 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2411:gst_base_src_update_length: reading offset 119862, length 279, size 4002921, segment.stop -1, maxsize 4002921 0:00:12.732523060 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:2712:gst_base_sink_do_sync: clock returned 4, jitter 0:00:00.000000000 0:00:12.732535992 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2519:gst_base_src_get_range: calling create offset 119862 length 279, time 0 0:00:12.732479562 21201 0x7ff94c050f00 DEBUG codecparsers_h264 gsth264parser.c:2142:gst_h264_parser_parse_slice_hdr: parsing "Slice header", slice type 7 0:00:12.732559922 21201 0x55adeed0d1e0 DEBUG GST_MEMORY gstmemory.c:138:gst_memory_init: new memory 0x7ff95000d570, maxsize:286 offset:0 size:279 0:00:12.732413501 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x7ff954002840: 1 0:00:12.732582148 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:3641:gst_base_sink_chain_unlocked: rendering object 0x7ff944024000 0:00:12.732606241 21201 0x55adeeb10d80 DEBUG basesink gstbasesink.c:3680:gst_base_sink_chain_unlocked: object unref after render 0x7ff944024000 0:00:12.732578808 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff95007b360 0:00:12.732635901 21201 0x55adeeb10d80 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_base_sink_chain with buffer 0x7ff944024000, returned ok 0:00:12.732648133 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff95007b360, idx -1, mem 0x7ff95000d570 0:00:12.732612546 21201 0x7ff94c050f00 DEBUG codecparsers_h264 gsth264parser.c:700:gst_h264_slice_parse_dec_ref_pic_marking: parsing "Decoded reference picture marking" 0:00:12.732669585 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:898:gst_buffer_new_allocate: new buffer 0x7ff95007b360 of size 279 from allocator (nil) 0:00:12.732680135 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:953:gst_h264_parse_process_nal: parse result 0, first MB: 0, slice type: 7 0:00:12.732688451 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:1800:gst_buffer_map_range: buffer 0x7ff95007b360, idx 0, length -1, flags 0002 0:00:12.732697633 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:977:gst_h264_parse_process_nal: marking IDR in frame at offset 0 0:00:12.732691151 21201 0x55adeeb10d80 DEBUG queue_dataflow gstqueue.c:1516:gst_queue_loop: queue is empty 0:00:12.732700910 21201 0x55adeed15760 DEBUG bin gstbin.c:878:find_message: we found a message 0x7ff954002840 from multi_src_bin matching types 10000000 0:00:12.732741822 21201 0x55adeeb10d80 LOG queue_dataflow gstqueue.c:1525:gst_queue_loop: (fakequeue:src) wait for ADD: 0 of 0-200 buffers, 0 of 0-10485760 bytes, 0 of 0-1000000000 ns, 0 items 0:00:12.732752910 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:244:_get_merged_memory: buffer 0x7ff95007b360, idx 0, length 1 0:00:12.732712456 21201 0x7ff94c050f00 LOG h264parse gsth264parse.c:1010:gst_h264_parse_process_nal: collecting NAL in AVC frame 0:00:12.732792420 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:431:gst_h264_parse_wrap_nal: nal length 39441 0:00:12.732744200 21201 0x55adeed15760 DEBUG bin gstbin.c:1036:is_stream_start: sink 'multi_src_bin' posted STREAM_START 0:00:12.732777707 21201 0x55adeed0d1e0 LOG filesrc gstfilesrc.c:362:gst_file_src_fill: Reading 279 bytes at offset 0x1d436 0:00:12.732831035 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x55adefe6aba0: 1 0:00:12.732847207 21201 0x55adeed15760 DEBUG bin gstbin.c:956:bin_remove_messages: deleting message 0x55adefe6aba0 of type stream-start (types 0x10000000) 0:00:12.732853825 21201 0x55adeed0d1e0 LOG basesrc gstbasesrc.c:2336:gst_base_src_do_sync: created PTS 99:99:99.999999999 0:00:12.732867953 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2375:gst_base_src_do_sync: no sync needed 0:00:12.732875181 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:138:gst_memory_init: new memory 0x7ff94434b000, maxsize:39452 offset:0 size:39445 0:00:12.732859077 21201 0x55adeed15760 LOG bin gstbin.c:860:message_check: looking at message 0x7ff954002840: 1 0:00:12.732880134 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2612:gst_base_src_get_range: buffer ok 0:00:12.732911663 21201 0x55adeed15760 DEBUG bin gstbin.c:956:bin_remove_messages: deleting message 0x7ff954002840 of type stream-start (types 0x10000000) 0:00:12.732929916 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x7ff954002840, stream-start from multi_src_bin 0:00:12.732895595 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff94434a120 0:00:12.732949298 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5848:gst_qtdemux_push_buffer: Pushing buffer with dts 0:00:00.557278911, pts 0:00:00.557278911, duration 0:00:00.023219955 on pad audio_0 0:00:12.732950056 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:303:gst_message_new_custom: source pipeline: creating new message 0x55adefe6ac20 stream-start 0:00:12.732965259 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff94434a120, idx -1, mem 0x7ff94434b000 0:00:12.732978546 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4661:gst_pad_push_data: pushing, but it was not linked 0:00:12.733011890 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:898:gst_buffer_new_allocate: new buffer 0x7ff94434a120 of size 39445 from allocator (nil) 0:00:12.732994953 21201 0x55adeed15760 DEBUG bin gstbin.c:3587:bin_do_stream_start: all sinks posted STREAM_START, posting seqnum #216 0:00:12.733033893 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1904:gst_buffer_fill: buffer 0x7ff94434a120, offset 0, size 4 0:00:12.733020713 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff95007b360 0:00:12.733059514 21201 0x55adeed0d1e0 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff95000d570 0:00:12.733070441 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1904:gst_buffer_fill: buffer 0x7ff94434a120, offset 4, size 39441 0:00:12.733075856 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5449:gst_qtdemux_combine_flows: flow return: not-linked 0:00:12.733106420 21201 0x55adeed0d1e0 DEBUG flowcombiner gstflowcombiner.c:220:gst_flow_combiner_get_flow: Combining flow returns 0:00:12.733121854 21201 0x55adeed0d1e0 DEBUG flowcombiner gstflowcombiner.c:243:gst_flow_combiner_get_flow: Combined flow return: ok (0) 0:00:12.733107909 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:340:gst_bus_post: [msg 0x55adefe6ac20] posting on bus stream-start message: 0x55adefe6ac20, time 99:99:99.999999999, seq-num 216, element 'pipeline', GstMessageStreamStart, group-id=(uint)2; 0:00:12.733140741 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1656:gst_buffer_resize_range: trim 0x7ff94434a120 0-39445 size:39445 offs:0 max:39452 0:00:12.733134606 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5457:gst_qtdemux_combine_flows: combined flow return: ok 0:00:12.733163810 21201 0x7ff94c050f00 LOG adapter gstadapter.c:390:gst_adapter_push: pushing 0x7ff94434a120 first 39445 bytes 0:00:12.733170025 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:383:gst_bus_post: [msg 0x55adefe6ac20] pushing on async queue 0:00:12.733184249 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc mp4a, pad audio_0 0:00:12.733198970 21201 0x7ff94c050f00 DEBUG codecparsers_h264 gsth264parser.c:1460:gst_h264_parser_identify_nalu_avc: Can't parse, buffer has too small size 39445, offset 39445 0:00:12.733211510 21201 0x55adeed15760 LOG GST_POLL gstpoll.c:290:raise_wakeup: 0x55adeea4bf20: raise 0:00:12.733223665 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9347:qtdemux_parse_samples: parsing up to sample 25 0:00:12.733266962 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9372:qtdemux_parse_samples: sample 25 has size 284 0:00:12.733271418 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:386:gst_bus_post: [msg 0x55adefe6ac20] pushed on async queue 0:00:12.733280228 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9500:qtdemux_parse_samples: creating entry 25 with offset 120141 and size 284 0:00:12.733273519 21201 0x7ff94c050f00 DEBUG GST_CAPS gstpad.c:2704:gst_pad_has_current_caps: check current pad caps video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)2.2, profile=(string)high, width=(int)596, height=(int)336, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true 0:00:12.733314080 21201 0x7ff94c050f00 LOG adapter gstadapter.c:973:gst_adapter_get_buffer: getting buffer of 39445 bytes 0:00:12.733325031 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:379:gst_bus_post: [msg 0x55adefe6aba0] dropped 0:00:12.733320101 21201 0x55adee97a800 LOG GST_BUS gstbus.c:547:gst_bus_timed_pop_filtered: have 1 messages 0:00:12.733332679 21201 0x7ff94c050f00 LOG adapter gstadapter.c:988:gst_adapter_get_buffer: providing buffer of 39445 bytes as head buffer 0:00:12.733321819 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9556:qtdemux_parse_samples: sample 25: index 25, timestamp 0:00:00.580498866 0:00:12.733374614 21201 0x7ff94c050f00 LOG adapter gstadapter.c:634:gst_adapter_flush_unchecked: flushing 39445 bytes 0:00:12.733394592 21201 0x7ff94c050f00 LOG adapter gstadapter.c:655:gst_adapter_flush_unchecked: flushing out head buffer 0:00:12.733411709 21201 0x7ff94c050f00 LOG adapter gstadapter.c:668:gst_adapter_flush_unchecked: adapter empty now 0:00:12.733357833 21201 0x55adee97a800 LOG GST_POLL gstpoll.c:314:release_wakeup: 0x55adeea4bf20: release 0:00:12.733435015 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff94434a000 to 0x7ff94434a120, offset 0-39445/39445 0:00:12.733418118 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9661:qtdemux_parse_samples: setting all keyframes 0:00:12.733457651 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2680:gst_base_parse_finish_frame: finished frame at offset 41884, flushing size 39445 0:00:12.733468632 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6521:gst_qtdemux_loop: loop at position 4002921, state 0:00:12.733362017 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x55adefe6aba0, stream-start from processing_bin_0 0:00:12.733493373 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:379:gst_bus_post: [msg 0x55adefe6ab20] dropped 0:00:12.733500043 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5249:gst_qtdemux_prepare_current_sample: segment active, index = 25 of 1296 0:00:12.733518479 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc mp4a, pad audio_0 0:00:12.733500954 21201 0x7ff94c050f00 LOG adapter gstadapter.c:634:gst_adapter_flush_unchecked: flushing 39445 bytes 0:00:12.733515988 21201 0x55adee97a800 DEBUG GST_BUS gstbus.c:570:gst_bus_timed_pop_filtered: got message 0x55adefe6ac20, stream-start from pipeline, type mask is 4294967295 0:00:12.733525235 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x55adefe6ab20, stream-start from sink_bin 0:00:12.733565449 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:379:gst_bus_post: [msg 0x55adefe6aaa0] dropped 0:00:12.733571286 21201 0x7ff94c050f00 LOG adapter gstadapter.c:655:gst_adapter_flush_unchecked: flushing out head buffer 0:00:12.733577449 21201 0x55adee97a800 DEBUG GST_BUS gstbus.c:848:gst_bus_source_dispatch: source 0x55adeeaf9f40 calling dispatch with stream-start message: 0x55adefe6ac20, time 99:99:99.999999999, seq-num 216, element 'pipeline', GstMessageStreamStart, group-id=(uint)2; 0:00:12.733605626 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9723:qtdemux_parse_samples: Tried to parse up to sample 25 but this sample has already been parsed 0:00:12.733613992 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff94434a7e0 0:00:12.733604666 21201 0x55adee97a800 DEBUG NVDS_APP deepstream_app.c:74:bus_callback: Received message on bus: source pipeline, msg_type stream-start 0:00:12.733632440 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:6367:gst_qtdemux_loop_state_movie: pushing from track-id 2, empty 0 offset 120141, size 284, dts=0:00:00.580498866, pts=0:00:00.580498866, duration 0:00:00.023219954 0:00:12.733649304 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6427:gst_qtdemux_loop_state_movie: reading 284 bytes @ 120141 0:00:12.733657333 21201 0x55adeed15760 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x55adefe6aaa0, stream-start from sink_sub_bin1 0:00:12.733666314 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_type_find_element_getrange, offset 120141, size 284 0:00:12.733644930 21201 0x55adee97a800 LOG GST_MESSAGE gstmessage.c:208:_gst_message_free: finalize message 0x55adefe6ac20, stream-start from pipeline 0:00:12.733682781 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_proxy_pad_getrange_default, offset 120141, size 284 0:00:12.733662736 21201 0x7ff94c050f00 LOG adapter gstadapter.c:668:gst_adapter_flush_unchecked: adapter empty now 0:00:12.733715989 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff94434a000 0:00:12.733730912 21201 0x55adee97a800 DEBUG GST_BUS gstbus.c:854:gst_bus_source_dispatch: source 0x55adeeaf9f40 handler returns 1 0:00:12.733700849 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_base_src_getrange, offset 120141, size 284 0:00:12.733701501 21201 0x55adeed15760 DEBUG GST_BUS gstbus.c:379:gst_bus_post: [msg 0x55adefe6aa20] dropped 0:00:12.733765264 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2411:gst_base_src_update_length: reading offset 120141, length 284, size 4002921, segment.stop -1, maxsize 4002921 0:00:12.733735221 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff950088000 0:00:12.733792152 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:5781:gst_pad_send_event_unchecked: sent event, ret ok 0:00:12.733800870 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:1948:gst_base_parse_add_index_entry: Adding key=1 index entry 0:00:00.480000000 @ offset 0x0000a39c 0:00:12.733818464 21201 0x7ff94c050f00 LOG default gstmemindex.c:270:gst_mem_index_add_entry: added this entry 0:00:12.733796036 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2519:gst_base_src_get_range: calling create offset 120141 length 284, time 0 0:00:12.733824702 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5164:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:12.733856125 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2427:gst_base_parse_push_frame: processing buffer of size 39445 with dts 0:00:00.400000000, pts 0:00:00.480000000, duration 0:00:00.040000000 0:00:12.733852365 21201 0x55adeed0d1e0 DEBUG GST_MEMORY gstmemory.c:138:gst_memory_init: new memory 0x7ff950086000, maxsize:291 offset:0 size:284 0:00:12.733865663 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event stream-start 0:00:12.733897038 21201 0x7ff94c050f00 DEBUG GST_CAPS gstpad.c:2704:gst_pad_has_current_caps: check current pad caps video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, level=(string)2.2, profile=(string)high, width=(int)596, height=(int)336, framerate=(fraction)25/1, pixel-aspect-ratio=(fraction)1/1, interlace-mode=(string)progressive, chroma-format=(string)4:2:0, bit-depth-luma=(uint)8, bit-depth-chroma=(uint)8, parsed=(boolean)true 0:00:12.733888689 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff95007b480 0:00:12.733926521 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:1891:gst_base_parse_update_bitrates: frame bitrate 7889000, avg bitrate 1479418 0:00:12.733911398 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5415:gst_pad_push_event_unchecked: sent event 0x7ff94c009ad0 (stream-start) to peerpad , ret ok 0:00:12.733952117 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff95007b480, idx -1, mem 0x7ff950086000 0:00:12.733950571 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:138:gst_memory_init: new memory 0x7ff9443488a0, maxsize:6 offset:0 size:6 0:00:12.733966406 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3936:push_sticky: event stream-start marked received 0:00:12.733978860 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:898:gst_buffer_new_allocate: new buffer 0x7ff95007b480 of size 284 from allocator (nil) 0:00:12.734016729 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:5781:gst_pad_send_event_unchecked: sent event, ret ok 0:00:12.733995379 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff94434a6c0 0:00:12.734024214 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:1800:gst_buffer_map_range: buffer 0x7ff95007b480, idx 0, length -1, flags 0002 0:00:12.734055624 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff94434a120 to 0x7ff94434a6c0, offset 0-39445/39445 0:00:12.734033099 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5164:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:12.734078472 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff94434a6c0, idx -1, mem 0x7ff94434b000 0:00:12.734062600 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:244:_get_merged_memory: buffer 0x7ff95007b480, idx 0, length 1 0:00:12.734098241 21201 0x7ff94c050f00 DEBUG GST_PERFORMANCE gstminiobject.c:527:ensure_priv_data: allocating private data GstMemory miniobject 0x7ff94434b000 0:00:12.734104476 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event stream-start 0:00:12.734136180 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff94434a6c0, idx 0, mem 0x7ff9443488a0 0:00:12.734117206 21201 0x55adeed0d1e0 LOG filesrc gstfilesrc.c:362:gst_file_src_fill: Reading 284 bytes at offset 0x1d54d 0:00:12.734153126 21201 0x7ff94c050f00 LOG h264parse gsth264parse.c:2645:gst_h264_parse_pre_push_frame: IDR nal at offset 6 0:00:12.734149397 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5415:gst_pad_push_event_unchecked: sent event 0x7ff94c009ad0 (stream-start) to peerpad , ret ok 0:00:12.734184210 21201 0x55adeed0d1e0 LOG basesrc gstbasesrc.c:2336:gst_base_src_do_sync: created PTS 99:99:99.999999999 0:00:12.734191781 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3936:push_sticky: event stream-start marked received 0:00:12.734187554 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1955:gst_buffer_extract: buffer 0x7ff94434a6c0, offset 0, size 6 0:00:12.734198374 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2375:gst_base_src_do_sync: no sync needed 0:00:12.734209347 21201 0x55adeed15760 DEBUG baseparse gstbaseparse.c:1516:gst_base_parse_sink_event_default: event handled 0:00:12.734220874 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:2468:gst_h264_parse_handle_sps_pps_nals: - inserting SPS/PPS 0:00:12.734232139 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2612:gst_base_src_get_range: buffer ok 0:00:12.734248753 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:5781:gst_pad_send_event_unchecked: sent event, ret ok 0:00:12.734288306 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5848:gst_qtdemux_push_buffer: Pushing buffer with dts 0:00:00.580498866, pts 0:00:00.580498866, duration 0:00:00.023219954 on pad audio_0 0:00:12.734262952 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:2472:gst_h264_parse_handle_sps_pps_nals: inserting SPS nal 0:00:12.734289622 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5164:store_sticky_event: Removing pending EOS and StreamGroupDone events 0:00:12.734322790 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1955:gst_buffer_extract: buffer 0x7ff950033240, offset 0, size 26 0:00:12.734343073 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:2488:gst_h264_parse_handle_sps_pps_nals: inserting PPS nal 0:00:12.734350189 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event stream-start 0:00:12.734370521 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5415:gst_pad_push_event_unchecked: sent event 0x7ff94c009ad0 (stream-start) to peerpad , ret ok 0:00:12.734303521 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4661:gst_pad_push_data: pushing, but it was not linked 0:00:12.734376444 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1955:gst_buffer_extract: buffer 0x7ff950033480, offset 0, size 5 0:00:12.734387491 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3936:push_sticky: event stream-start marked received 0:00:12.734399871 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff95007b480 0:00:12.734434700 21201 0x55adeed15760 DEBUG GST_CAPS gstpad.c:2732:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:12.734441797 21201 0x55adeed0d1e0 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff950086000 0:00:12.734458544 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5449:gst_qtdemux_combine_flows: flow return: not-linked 0:00:12.734443644 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1955:gst_buffer_extract: buffer 0x7ff94434a6c0, offset 6, size 39445 0:00:12.734469030 21201 0x55adeed0d1e0 DEBUG flowcombiner gstflowcombiner.c:220:gst_flow_combiner_get_flow: Combining flow returns 0:00:12.734495908 21201 0x55adeed0d1e0 DEBUG flowcombiner gstflowcombiner.c:243:gst_flow_combiner_get_flow: Combined flow return: ok (0) 0:00:12.734502810 21201 0x55adeed15760 INFO GST_EVENT gstevent.c:820:gst_event_new_caps: creating caps event video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono 0:00:12.734507236 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5457:gst_qtdemux_combine_flows: combined flow return: ok 0:00:12.734529894 21201 0x55adeed15760 DEBUG GST_EVENT gstevent.c:308:gst_event_new_custom: creating new event 0x7ff9a8019010 caps 12814 0:00:12.734547633 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event caps 0:00:12.734555318 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc mp4a, pad audio_0 0:00:12.734570911 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9347:qtdemux_parse_samples: parsing up to sample 26 0:00:12.734531333 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff94434a480 0:00:12.734585193 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9372:qtdemux_parse_samples: sample 26 has size 285 0:00:12.734578791 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:5230:store_sticky_event: notify caps 0:00:12.734615322 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from src (caps) 0:00:12.734626519 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:138:gst_memory_init: new memory 0x7ff944348810, maxsize:39490 offset:0 size:39490 0:00:12.734635788 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from src (caps) 0:00:12.734652078 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from src (caps) 0:00:12.734619212 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9419:qtdemux_parse_samples: entry 8 has first_chunk 15, last_chunk 16, samples_per_chunk 1sample desc ID: 0 0:00:12.734665429 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff94434a480, idx -1, mem 0x7ff944348810 0:00:12.734690042 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff94434a6c0 to 0x7ff94434a480, offset 0-39451/39451 0:00:12.734698502 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from src (caps) 0:00:12.734715483 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from src (caps) 0:00:12.734723839 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff94434a6c0 0:00:12.734731493 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3985:check_sticky: pushing all sticky events 0:00:12.734693424 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9500:qtdemux_parse_samples: creating entry 26 with offset 120667 and size 285 0:00:12.734765929 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9556:qtdemux_parse_samples: sample 26: index 26, timestamp 0:00:00.603718820 0:00:12.734769385 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff9443488a0 0:00:12.734789491 21201 0x7ff94c050f00 DEBUG h264parse gsth264parse.c:186:gst_h264_parse_reset_frame: reset frame 0:00:12.734748953 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3914:push_sticky: event stream-start was already received 0:00:12.734794503 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9661:qtdemux_parse_samples: setting all keyframes 0:00:12.734824310 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5408:gst_pad_push_event_unchecked: sending event 0x7ff9a8019010 (caps) to peerpad 0:00:12.734834278 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6521:gst_qtdemux_loop: loop at position 4002921, state 0:00:12.734815254 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:937:gst_base_parse_queue_tag_event_update: upstream : taglist, video-codec=(string)"H.264\ /\ AVC", language-code=(string)en, bitrate=(uint)960432; 0:00:12.734865666 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:939:gst_base_parse_queue_tag_event_update: parser : taglist, video-codec=(string)"H.264\ \(High\ Profile\)"; 0:00:12.734868686 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5249:gst_qtdemux_prepare_current_sample: segment active, index = 16 of 750 0:00:12.734885124 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:941:gst_base_parse_queue_tag_event_update: mode : 2 0:00:12.734900655 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc avc1, pad video_0 0:00:12.734923326 21201 0x55adeed15760 DEBUG GST_EVENT gstpad.c:5697:gst_pad_send_event_unchecked: have event type caps event: 0x7ff9a8019010, time 99:99:99.999999999, seq-num 403, GstEventCaps, caps=(GstCaps)"video/x-h264\,\ stream-format\=\(string\)byte-stream\,\ alignment\=\(string\)au\,\ profile\=\(string\)NULL\,\ level\=\(string\)NULL\,\ width\=\(int\)1920\,\ height\=\(int\)1080\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ framerate\=\(fraction\)25/1\,\ interlace-mode\=\(string\)progressive\,\ colorimetry\=\(string\)bt709\,\ chroma-site\=\(string\)mpeg2\,\ multiview-mode\=\(string\)mono\,\ multiview-flags\=\(GstVideoMultiviewFlagsSet\)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono"; 0:00:12.734926601 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9723:qtdemux_parse_samples: Tried to parse up to sample 16 but this sample has already been parsed 0:00:12.734927667 21201 0x7ff94c050f00 DEBUG baseparse gstbaseparse.c:947:gst_base_parse_queue_tag_event_update: merged : taglist, video-codec=(string)"H.264\ \(High\ Profile\)", language-code=(string)en, bitrate=(uint)960432; 0:00:12.734971874 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:6367:gst_qtdemux_loop_state_movie: pushing from track-id 1, empty 0 offset 120425, size 242, dts=0:00:00.640000000, pts=0:00:00.680000000, duration 0:00:00.040000000 0:00:12.734987605 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6427:gst_qtdemux_loop_state_movie: reading 242 bytes @ 120425 0:00:12.734965681 21201 0x55adeed15760 DEBUG GST_CAPS gstutils.c:3140:gst_pad_query_accept_caps: accept caps of video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono 0:00:12.734997576 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:961:gst_base_parse_queue_tag_event_update: adding min bitrate 26600 0:00:12.735001598 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_type_find_element_getrange, offset 120425, size 242 0:00:12.735035961 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:966:gst_base_parse_queue_tag_event_update: adding max bitrate 7889000 0:00:12.735020992 21201 0x55adeed15760 DEBUG query gstquery.c:679:gst_query_new_custom: creating new query 0x7ff9a8008940 accept-caps 0:00:12.735063639 21201 0x7ff94c050f00 DEBUG GST_EVENT gstevent.c:308:gst_event_new_custom: creating new event 0x7ff944010040 tag 20510 0:00:12.735063480 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_proxy_pad_getrange_default, offset 120425, size 242 0:00:12.735093731 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4053:gst_pad_query: doing query 0x7ff9a8008940 (accept-caps) 0:00:12.735087209 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event tag 0:00:12.735123097 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3985:check_sticky: pushing all sticky events 0:00:12.735131812 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_base_src_getrange, offset 120425, size 242 0:00:12.735112221 21201 0x55adeed15760 DEBUG baseparse gstbaseparse.c:1587:gst_base_parse_sink_query: accept-caps query 0:00:12.735156558 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event stream-start was already received 0:00:12.735164012 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2411:gst_base_src_update_length: reading offset 120425, length 242, size 4002921, segment.stop -1, maxsize 4002921 0:00:12.735174029 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event caps was already received 0:00:12.735188331 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2519:gst_base_src_get_range: calling create offset 120425 length 242, time 0 0:00:12.735203586 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event segment was already received 0:00:12.735208448 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3129:gst_pad_query_accept_caps_default: query accept-caps accept-caps query: 0x7ff9a8008940, GstQueryAcceptCaps, caps=(GstCaps)"video/x-h264\,\ stream-format\=\(string\)byte-stream\,\ alignment\=\(string\)au\,\ profile\=\(string\)NULL\,\ level\=\(string\)NULL\,\ width\=\(int\)1920\,\ height\=\(int\)1080\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ framerate\=\(fraction\)25/1\,\ interlace-mode\=\(string\)progressive\,\ colorimetry\=\(string\)bt709\,\ chroma-site\=\(string\)mpeg2\,\ multiview-mode\=\(string\)mono\,\ multiview-flags\=\(GstVideoMultiviewFlagsSet\)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono", result=(boolean)false; 0:00:12.735229399 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5408:gst_pad_push_event_unchecked: sending event 0x7ff944010040 (tag) to peerpad 0:00:12.735232355 21201 0x55adeed0d1e0 DEBUG GST_MEMORY gstmemory.c:138:gst_memory_init: new memory 0x7ff95007a190, maxsize:249 offset:0 size:242 0:00:12.735272595 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3154:gst_pad_query_accept_caps_default: allowed caps intersect video/x-h264, caps video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono 0:00:12.735290001 21201 0x7ff94c050f00 DEBUG GST_EVENT gstpad.c:5697:gst_pad_send_event_unchecked: have event type tag event: 0x7ff944010040, time 99:99:99.999999999, seq-num 404, GstTagList-stream, taglist=(taglist)"taglist\,\ video-codec\=\(string\)\"H.264\\\ \\\(High\\\ Profile\\\)\"\,\ language-code\=\(string\)en\,\ bitrate\=\(uint\)960432\,\ minimum-bitrate\=\(uint\)26600\,\ maximum-bitrate\=\(uint\)7889000\;"; 0:00:12.735277645 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff95007b480 0:00:12.735316391 21201 0x7ff94c050f00 LOG capsfilter gstcapsfilter.c:520:gst_capsfilter_sink_event: Forwarding tag event 0:00:12.735299949 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3437:gst_pad_query_default: not forwarding 0x7ff9a8008940 (accept-caps) query 0:00:12.735337063 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event tag 0:00:12.735342013 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:450:_memory_add: buffer 0x7ff95007b480, idx -1, mem 0x7ff95007a190 0:00:12.735369735 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3985:check_sticky: pushing all sticky events 0:00:12.735385920 21201 0x55adeed15760 LOG baseparse gstbaseparse.c:1594:gst_base_parse_sink_query: accept-caps query result: 1 accept-caps query: 0x7ff9a8008940, GstQueryAcceptCaps, caps=(GstCaps)"video/x-h264\,\ stream-format\=\(string\)byte-stream\,\ alignment\=\(string\)au\,\ profile\=\(string\)NULL\,\ level\=\(string\)NULL\,\ width\=\(int\)1920\,\ height\=\(int\)1080\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ framerate\=\(fraction\)25/1\,\ interlace-mode\=\(string\)progressive\,\ colorimetry\=\(string\)bt709\,\ chroma-site\=\(string\)mpeg2\,\ multiview-mode\=\(string\)mono\,\ multiview-flags\=\(GstVideoMultiviewFlagsSet\)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono", result=(boolean)true; 0:00:12.735411444 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4076:gst_pad_query: sent query 0x7ff9a8008940 (accept-caps), result 1 0:00:12.735390108 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event stream-start was already received 0:00:12.735417123 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:898:gst_buffer_new_allocate: new buffer 0x7ff95007b480 of size 242 from allocator (nil) 0:00:12.735444975 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event caps was already received 0:00:12.735457155 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:1800:gst_buffer_map_range: buffer 0x7ff95007b480, idx 0, length -1, flags 0002 0:00:12.735428001 21201 0x55adeed15760 DEBUG default gstutils.c:3146:gst_pad_query_accept_caps: query returned 1 0:00:12.735481019 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event segment was already received 0:00:12.735485782 21201 0x55adeed15760 DEBUG baseparse gstbaseparse.c:1179:gst_base_parse_sink_event_default: handling event 12814, caps 0:00:12.735503796 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5408:gst_pad_push_event_unchecked: sending event 0x7ff944010040 (tag) to peerpad 0:00:12.735486967 21201 0x55adeed0d1e0 LOG GST_BUFFER gstbuffer.c:244:_get_merged_memory: buffer 0x7ff95007b480, idx 0, length 1 0:00:12.735560102 21201 0x55adeed0d1e0 LOG filesrc gstfilesrc.c:362:gst_file_src_fill: Reading 242 bytes at offset 0x1d669 0:00:12.735559170 21201 0x7ff94c050f00 DEBUG GST_EVENT gstpad.c:5697:gst_pad_send_event_unchecked: have event type tag event: 0x7ff944010040, time 99:99:99.999999999, seq-num 404, GstTagList-stream, taglist=(taglist)"taglist\,\ video-codec\=\(string\)\"H.264\\\ \\\(High\\\ Profile\\\)\"\,\ language-code\=\(string\)en\,\ bitrate\=\(uint\)960432\,\ minimum-bitrate\=\(uint\)26600\,\ maximum-bitrate\=\(uint\)7889000\;"; 0:00:12.735563835 21201 0x55adeed15760 DEBUG baseparse gstbaseparse.c:1188:gst_base_parse_sink_event_default: caps: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono 0:00:12.735578686 21201 0x55adeed0d1e0 LOG basesrc gstbasesrc.c:2336:gst_base_src_do_sync: created PTS 99:99:99.999999999 0:00:12.735625883 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2375:gst_base_src_do_sync: no sync needed 0:00:12.735637393 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2612:gst_base_src_get_range: buffer ok 0:00:12.735595270 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.735615795 21201 0x55adeed15760 DEBUG GST_CAPS gstpad.c:2732:gst_pad_get_current_caps: get current pad caps (NULL) 0:00:12.735666185 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x000031d0 does not match 00001042 0:00:12.735661585 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5848:gst_qtdemux_push_buffer: Pushing buffer with dts 0:00:00.640000000, pts 0:00:00.680000000, duration 0:00:00.040000000 on pad video_0 0:00:12.735688540 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.735694215 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.735708289 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.735717334 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003100 does not match 00001012 0:00:12.735719854 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3549:probe_hook_marshal: hook 1 with flags 0x000031d0 matches 0:00:12.735730714 21201 0x55adeed0d1e0 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.735733214 21201 0x55adeed15760 DEBUG h264parse gsth264parse.c:334:gst_h264_parse_format_from_caps: parsing caps: video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono 0:00:12.735742987 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3602:probe_hook_marshal: probe returned OK 0:00:12.735777077 21201 0x55adeed15760 DEBUG h264parse gsth264parse.c:2911:gst_h264_parse_set_caps: have bytestream h264 0:00:12.735786933 21201 0x7ff94c050f00 DEBUG videodecoder gstvideodecoder.c:1365:gst_video_decoder_sink_event: received event 20510, tag 0:00:12.735754127 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.735809915 21201 0x7ff94c050f00 INFO videodecoder gstvideodecoder.c:1312:gst_video_decoder_sink_event_default: upstream tags: taglist, video-codec=(string)"H.264\ \(High\ Profile\)", language-code=(string)en, bitrate=(uint)960432, minimum-bitrate=(uint)26600, maximum-bitrate=(uint)7889000; 0:00:12.735799475 21201 0x55adeed15760 DEBUG GST_PROPERTIES gstpad.c:2817:gst_pad_get_allowed_caps: getting allowed caps 0:00:12.735815273 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003100 does not match 00001010 0:00:12.735829605 21201 0x7ff94c050f00 LOG videodecoder gstvideodecoder.c:818:gst_video_decoder_create_merged_tags_event: upstream : taglist, video-codec=(string)"H.264\ \(High\ Profile\)", language-code=(string)en, bitrate=(uint)960432, minimum-bitrate=(uint)26600, maximum-bitrate=(uint)7889000; 0:00:12.735840389 21201 0x55adeed15760 DEBUG GST_CAPS gstutils.c:3057:gst_pad_query_caps: get pad caps with filter (NULL) 0:00:12.735851608 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.735880988 21201 0x55adeed15760 DEBUG query gstquery.c:679:gst_query_new_custom: creating new query 0x7ff9a8008850 caps 0:00:12.735899866 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4053:gst_pad_query: doing query 0x7ff9a8008850 (caps) 0:00:12.735906856 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003080 does not match 00001012 0:00:12.735924018 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 2 with flags 0x00003400 does not match 00001012 0:00:12.735864769 21201 0x7ff94c050f00 LOG videodecoder gstvideodecoder.c:819:gst_video_decoder_create_merged_tags_event: decoder : (NULL) 0:00:12.735937031 21201 0x55adeed0d1e0 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.735938899 21201 0x55adeed15760 DEBUG baseparse gstbaseparse.c:1610:gst_base_parse_src_query: caps query: caps query: 0x7ff9a8008850, GstQueryCaps, filter=(GstCaps)"NULL", caps=(GstCaps)"NULL"; 0:00:12.735950690 21201 0x7ff94c050f00 LOG videodecoder gstvideodecoder.c:820:gst_video_decoder_create_merged_tags_event: mode : 3 0:00:12.735983667 21201 0x55adeed15760 DEBUG GST_CAPS gstpad.c:3182:gst_pad_query_caps_default: query caps caps query: 0x7ff9a8008850, GstQueryCaps, filter=(GstCaps)"NULL", caps=(GstCaps)"NULL"; 0:00:12.735997602 21201 0x7ff94c050f00 DEBUG videodecoder gstvideodecoder.c:826:gst_video_decoder_create_merged_tags_event: merged : taglist, video-codec=(string)"H.264\ \(High\ Profile\)", language-code=(string)en, bitrate=(uint)960432, minimum-bitrate=(uint)26600, maximum-bitrate=(uint)7889000; 0:00:12.735961981 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.735999558 21201 0x55adeed15760 DEBUG GST_CAPS gstpad.c:3200:gst_pad_query_caps_default: fixed pad caps: trying pad caps 0:00:12.736016268 21201 0x7ff94c050f00 DEBUG GST_EVENT gstevent.c:308:gst_event_new_custom: creating new event 0x7ff94400f8a0 tag 20510 0:00:12.736027615 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003080 does not match 00001010 0:00:12.736039638 21201 0x55adeed15760 DEBUG GST_CAPS gstpad.c:3206:gst_pad_query_caps_default: trying pad template caps 0:00:12.736053088 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:5781:gst_pad_send_event_unchecked: sent event, ret ok 0:00:12.736065954 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 2 with flags 0x00003400 does not match 00001010 0:00:12.736088708 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event tag 0:00:12.736087948 21201 0x55adeed15760 DEBUG GST_CAPS gstpad.c:3235:gst_pad_query_caps_default: using caps 0x55adeebbc0f0 video/x-h264, parsed=(boolean)true, stream-format=(string){ avc, avc3, byte-stream }, alignment=(string){ au, nal } 0:00:12.736110900 21201 0x55adeed0d1e0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_multi_queue_chain with buffer buffer: 0x7ff95007b480, pts 0:00:00.680000000, dts 0:00:00.640000000, dur 0:00:00.040000000, size 242, offset none, offset_end none, flags 0x2000 0:00:12.736115715 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5415:gst_pad_push_event_unchecked: sent event 0x7ff944010040 (tag) to peerpad , ret ok 0:00:12.736130283 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3437:gst_pad_query_default: not forwarding 0x7ff9a8008850 (caps) query 0:00:12.736153097 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3936:push_sticky: event tag marked received 0:00:12.736154495 21201 0x55adeed0d1e0 LOG multiqueue gstmultiqueue.c:2117:gst_multi_queue_chain: SingleQueue 0 : about to enqueue buffer 0x7ff95007b480 with id 22 (pts:0:00:00.680000000 dts:0:00:00.640000000 dur:0:00:00.040000000) 0:00:12.736183175 21201 0x55adeed15760 LOG baseparse gstbaseparse.c:1618:gst_base_parse_src_query: caps query result: 1 caps query: 0x7ff9a8008850, GstQueryCaps, filter=(GstCaps)"NULL", caps=(GstCaps)"video/x-h264\,\ parsed\=\(boolean\)true\,\ stream-format\=\(string\)\{\ avc\,\ avc3\,\ byte-stream\ \}\,\ alignment\=\(string\)\{\ au\,\ nal\ \}"; 0:00:12.736204402 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4076:gst_pad_query: sent query 0x7ff9a8008850 (caps), result 1 0:00:12.736213454 21201 0x55adeed0d1e0 LOG data_queue_dataflow gstdataqueue.c:507:gst_data_queue_push: queue:0x55adeecb2c40 before pushing: 5 visible items, 2030 bytes, 200000000 ns, 5 elements 0:00:12.736224950 21201 0x55adeed15760 DEBUG GST_CAPS gstutils.c:3064:gst_pad_query_caps: query returned video/x-h264, parsed=(boolean)true, stream-format=(string){ avc, avc3, byte-stream }, alignment=(string){ au, nal } 0:00:12.736235440 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2861:single_queue_check_full: queue 0: visible 5/5, bytes 2030/2097152, time 200000000/0 0:00:12.736178172 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event tag was already received 0:00:12.736262616 21201 0x55adeed15760 DEBUG query gstquery.c:679:gst_query_new_custom: creating new query 0x7ff9a8009000 caps 0:00:12.736270518 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:5781:gst_pad_send_event_unchecked: sent event, ret ok 0:00:12.736284765 21201 0x7ff94c050f00 LOG GST_EVENT gstevent.c:222:_gst_event_free: freeing event 0x7ff94400f830 type tag 0:00:12.736257824 21201 0x55adeed0d1e0 LOG multiqueue gstmultiqueue.c:2755:single_queue_overrun_cb: Single Queue 0: EOS 0, visible 5/5, bytes 2030/2097152, time 200000000/0 0:00:12.736308107 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2807:single_queue_overrun_cb: Queue 0 is filled, signalling overrun 0:00:12.736314621 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event tag 0:00:12.736294887 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4180:gst_pad_peer_query: peer query 0x7ff9a8009000 (caps) 0:00:12.736331611 21201 0x7ff94c050f00 LOG GST_PADS gstpad.c:5415:gst_pad_push_event_unchecked: sent event 0x7ff944010040 (tag) to peerpad , ret ok 0:00:12.736345394 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3936:push_sticky: event tag marked received 0:00:12.736352541 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2861:single_queue_check_full: queue 0: visible 5/5, bytes 2030/2097152, time 200000000/0 0:00:12.736357179 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3914:push_sticky: event tag was already received 0:00:12.736375756 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4053:gst_pad_query: doing query 0x7ff9a8009000 (caps) 0:00:12.736397754 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff94434a120 0:00:12.736411879 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff94434b000 0:00:12.736399812 21201 0x55adeed15760 DEBUG rtpbasepayload gstrtpbasepayload.c:623:gst_rtp_base_payload_query_default: getting caps with filter video/x-h264, parsed=(boolean)true, stream-format=(string){ avc, avc3, byte-stream }, alignment=(string){ au, nal } 0:00:12.736425964 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2578:gst_base_parse_push_frame: pushing frame (39490 bytes) now.. 0:00:12.736443122 21201 0x55adeed15760 DEBUG GST_CAPS gstutils.c:3103:gst_pad_peer_query_caps: get pad peer caps with filter (NULL) 0:00:12.736464142 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_base_transform_chain with buffer buffer: 0x7ff94434a480, pts 0:00:00.480000000, dts 0:00:00.400000000, dur 0:00:00.040000000, size 39490, offset 41884, offset_end none, flags 0x4000 0:00:12.736469103 21201 0x55adeed15760 DEBUG query gstquery.c:679:gst_query_new_custom: creating new query 0x7ff9a8008b70 caps 0:00:12.736486315 21201 0x7ff94c050f00 DEBUG basetransform gstbasetransform.c:1991:default_submit_input_buffer: handling buffer 0x7ff94434a480 of size 39490, PTS 0:00:00.480000000 and offset 41884 0:00:12.736493725 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4180:gst_pad_peer_query: peer query 0x7ff9a8008b70 (caps) 0:00:12.736497990 21201 0x7ff94c050f00 DEBUG basetransform gstbasetransform.c:2111:default_generate_output: calling prepare buffer 0:00:12.736524095 21201 0x7ff94c050f00 DEBUG basetransform gstbasetransform.c:2117:default_generate_output: using allocated buffer in 0x7ff94434a480, out 0x7ff94434a480 0:00:12.736535234 21201 0x7ff94c050f00 DEBUG basetransform gstbasetransform.c:2135:default_generate_output: doing inplace transform 0:00:12.736511068 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4053:gst_pad_query: doing query 0x7ff9a8008b70 (caps) 0:00:12.736546796 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.736562788 21201 0x55adeed15760 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.736585910 21201 0x55adeed15760 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003010 does not match 00001202 0:00:12.736589282 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x000031d0 does not match 00001012 0:00:12.736600776 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.736613346 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.736626445 21201 0x55adeed15760 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.736638449 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.736654975 21201 0x55adeed15760 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003010 does not match 00001200 0:00:12.736678568 21201 0x7ff94c050f00 LOG GST_SCHEDULING gstpad.c:3549:probe_hook_marshal: hook 1 with flags 0x000031d0 matches 0:00:12.736691125 21201 0x7ff94c050f00 DEBUG GST_PADS gstpad.c:3602:probe_hook_marshal: probe returned OK 0:00:12.736680535 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4076:gst_pad_query: sent query 0x7ff9a8008b70 (caps), result 1 0:00:12.736709896 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_video_decoder_chain with buffer buffer: 0x7ff94434a480, pts 0:00:00.480000000, dts 0:00:00.400000000, dur 0:00:00.040000000, size 39490, offset 41884, offset_end none, flags 0x4000 0:00:12.736715290 21201 0x55adeed15760 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.736734815 21201 0x7ff94c050f00 LOG videodecoder gstvideodecoder.c:2407:gst_video_decoder_chain: chain PTS 0:00:00.480000000, DTS 0:00:00.400000000 duration 0:00:00.040000000 size 39490 flags 4000 0:00:12.736740398 21201 0x55adeed15760 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003010 does not match 00002200 0:00:12.736748519 21201 0x7ff94c050f00 LOG videodecoder gstvideodecoder.c:2569:gst_video_decoder_new_frame: Created new frame 0x7ff944014b10 (sfn:10) 0:00:12.736755662 21201 0x55adeed15760 DEBUG GST_CAPS gstutils.c:3110:gst_pad_peer_query_caps: peer query returned ANY 0:00:12.736759813 21201 0x7ff94c050f00 LOG videodecoder gstvideodecoder.c:2115:gst_video_decoder_chain_forward: Marking current_frame as sync point 0:00:12.736782294 21201 0x7ff94c050f00 DEBUG videodecoder gstvideodecoder.c:3380:gst_video_decoder_decode_frame: PTS delta 80 ms 0:00:12.736787506 21201 0x55adeed15760 DEBUG rtph264pay gstrtph264pay.c:342:gst_rtp_h264_pay_getcaps: Intersect video/x-h264, stream-format=(string)avc, alignment=(string)au; video/x-h264, stream-format=(string)byte-stream, alignment=(string){ nal, au } and filter video/x-h264, parsed=(boolean)true, stream-format=(string){ avc, avc3, byte-stream }, alignment=(string){ au, nal } 0:00:12.736798046 21201 0x7ff94c050f00 LOG videodecoder gstvideodecoder.c:3387:gst_video_decoder_decode_frame: PTS 0:00:00.480000000, DTS 0:00:00.400000000, dist 10 0:00:12.736823660 21201 0x7ff94c050f00 DEBUG v4l2videodec gstv4l2videodec.c:1404:gst_v4l2_video_dec_handle_frame: Handling frame 10 0:00:12.736832401 21201 0x55adeed15760 LOG rtph264pay gstrtph264pay.c:352:gst_rtp_h264_pay_getcaps: returning caps video/x-h264, parsed=(boolean)true, stream-format=(string)avc, alignment=(string)au; video/x-h264, parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string){ au, nal } 0:00:12.736835149 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:2077:gst_v4l2_buffer_pool_process: process buffer 0x7ff944014b48 0:00:12.736853290 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4076:gst_pad_query: sent query 0x7ff9a8009000 (caps), result 1 0:00:12.736859953 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:2280:gst_v4l2_buffer_pool_process: alloc buffer from our pool 0:00:12.736875888 21201 0x55adeed15760 DEBUG GST_CAPS gstpad.c:2835:gst_pad_get_allowed_caps: allowed caps video/x-h264, parsed=(boolean)true, stream-format=(string)avc, alignment=(string)au; video/x-h264, parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string){ au, nal } 0:00:12.736879739 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1641:gst_v4l2_buffer_pool_acquire_buffer: acquire 0:00:12.736900238 21201 0x55adeed15760 DEBUG h264parse gsth264parse.c:374:gst_h264_parse_negotiate: allowed caps: video/x-h264, parsed=(boolean)true, stream-format=(string)avc, alignment=(string)au; video/x-h264, parsed=(boolean)true, stream-format=(string)byte-stream, alignment=(string){ au, nal } 0:00:12.736904420 21201 0x7ff94c050f00 LOG bufferpool gstbufferpool.c:1123:default_acquire_buffer: acquired buffer 0x55adf1cc4ea0 0:00:12.736922838 21201 0x55adeed15760 DEBUG h264parse gsth264parse.c:380:gst_h264_parse_negotiate: negotiating with caps: video/x-h264, parsed=(boolean)true, stream-format=(string)avc, alignment=(string)au 0:00:12.736942973 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:144:gst_v4l2_buffer_pool_copy_buffer: copying buffer 0:00:12.736955038 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:176:gst_v4l2_buffer_pool_copy_buffer: copy raw bytes 0:00:12.736943838 21201 0x55adeed15760 DEBUG h264parse gsth264parse.c:334:gst_h264_parse_format_from_caps: parsing caps: video/x-h264, parsed=(boolean)true, stream-format=(string)avc, alignment=(string)au 0:00:12.736970781 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1800:gst_buffer_map_range: buffer 0x7ff94434a480, idx 0, length -1, flags 0001 0:00:12.736986441 21201 0x55adeed15760 DEBUG h264parse gsth264parse.c:409:gst_h264_parse_negotiate: selected format avc, alignment au 0:00:12.736992621 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:244:_get_merged_memory: buffer 0x7ff94434a480, idx 0, length 1 0:00:12.737003901 21201 0x55adeed15760 DEBUG baseparse gstbaseparse.c:1516:gst_base_parse_sink_event_default: event handled 0:00:12.737014833 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1904:gst_buffer_fill: buffer 0x55adf1cc4ea0, offset 0, size 39490 0:00:12.737029269 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:5781:gst_pad_send_event_unchecked: sent event, ret ok 0:00:12.737046805 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5224:store_sticky_event: stored sticky event caps 0:00:12.737060200 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:5230:store_sticky_event: notify caps 0:00:12.737070334 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1656:gst_buffer_resize_range: trim 0x55adf1cc4ea0 0-39490 size:4194304 offs:0 max:4194304 0:00:12.737076029 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from sink (caps) 0:00:12.737086871 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff94434a480 to 0x55adf1cc4ea0, offset 0-39490/39490 0:00:12.737093122 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from sink (caps) 0:00:12.737098579 21201 0x7ff94c050f00 LOG GST_PERFORMANCE gstv4l2bufferpool.c:303:gst_v4l2_buffer_pool_copy_buffer: slow copy into buffer 0x55adf1cc4ea0 0:00:12.737109954 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from sink (caps) 0:00:12.737123587 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1375:gst_v4l2_buffer_pool_qbuf: queuing buffer 0 0:00:12.737139217 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from sink (caps) 0:00:12.737157696 21201 0x7ff94c050f00 LOG v4l2allocator gstv4l2allocator.c:1405:gst_v4l2_allocator_qbuf: queued buffer 0 (flags 0x2) 0:00:12.737163995 21201 0x55adeed15760 LOG GST_PROPERTIES gstobject.c:471:gst_object_dispatch_properties_changed: deep notification from sink (caps) 0:00:12.737170775 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:753:_gst_buffer_dispose: release 0x55adf1cc4ea0 to pool 0x7ff94431af00 0:00:12.737194833 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:1656:gst_buffer_resize_range: trim 0x55adf1cc4ea0 0-4194304 size:39490 offs:0 max:4194304 0:00:12.737200463 21201 0x55adeed15760 LOG GST_PADS gstpad.c:5415:gst_pad_push_event_unchecked: sent event 0x7ff9a8019010 (caps) to peerpad , ret ok 0:00:12.737206703 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1719:gst_v4l2_buffer_pool_release_buffer: release buffer 0x55adf1cc4ea0 0:00:12.737218827 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3936:push_sticky: event caps marked received 0:00:12.737231483 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1810:gst_v4l2_buffer_pool_release_buffer: buffer 0 is queued 0:00:12.737249056 21201 0x55adeed15760 DEBUG query gstquery.c:679:gst_query_new_custom: creating new query 0x7ff9a80089e0 allocation 0:00:12.737255327 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1449:gst_v4l2_buffer_pool_dqbuf: dequeueing a buffer 0:00:12.737266664 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4180:gst_pad_peer_query: peer query 0x7ff9a80089e0 (allocation) 0:00:12.737281927 21201 0x7ff94c050f00 LOG v4l2allocator gstv4l2allocator.c:1463:gst_v4l2_allocator_dqbuf: dequeued buffer 1 (flags 0x0) 0:00:12.737294782 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4053:gst_pad_query: doing query 0x7ff9a80089e0 (allocation) 0:00:12.737317238 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1510:gst_v4l2_buffer_pool_dqbuf: dequeued buffer 0x7ff9500335a0 seq:0 (ix=1), mem 0x7ff94401e0e0 used 64, plane=0, flags 00000000, ts 0:00:00.000000000, pool-queued=1, buffer=0x7ff9500335a0 0:00:12.737319775 21201 0x55adeed15760 DEBUG baseparse gstbaseparse.c:1587:gst_base_parse_sink_query: allocation query 0:00:12.737332150 21201 0x7ff94c050f00 DEBUG v4l2bufferpool gstv4l2bufferpool.c:1719:gst_v4l2_buffer_pool_release_buffer: release buffer 0x7ff9500335a0 0:00:12.737348555 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3437:gst_pad_query_default: forwarding 0x7ff9a80089e0 (allocation) query 0:00:12.737356318 21201 0x7ff94c050f00 LOG v4l2bufferpool gstv4l2bufferpool.c:1794:gst_v4l2_buffer_pool_release_buffer: buffer 1 not queued, putting on free list 0:00:12.737365734 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:2897:gst_pad_iterate_internal_links_default: Making iterator 0:00:12.737378575 21201 0x7ff94c050f00 LOG bufferpool gstbufferpool.c:1285:default_release_buffer: released buffer 0x7ff9500335a0 0 0:00:12.737395243 21201 0x55adeed15760 LOG GST_PADS gstpad.c:3006:gst_pad_forward: calling forward function on pad h264-parser:src 0:00:12.737402366 21201 0x7ff94c050f00 DEBUG GST_BUFFER gstbuffer.c:1451:gst_buffer_is_memory_range_writable: idx 0, length -1 0:00:12.737415544 21201 0x55adeed15760 LOG GST_PADS gstpad.c:3373:query_forward_func: query peer 0x7ff9a80089e0 (allocation) of h264-parser:src 0:00:12.737423279 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff94434a5a0 0:00:12.737434488 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4180:gst_pad_peer_query: peer query 0x7ff9a80089e0 (allocation) 0:00:12.737450696 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff94434a480 to 0x7ff94434a5a0, offset 0-0/39490 0:00:12.737462826 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4053:gst_pad_query: doing query 0x7ff9a80089e0 (allocation) 0:00:12.737471159 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff94434a480 0:00:12.737463595 21201 0x7ff95002faa0 LOG v4l2allocator gstv4l2allocator.c:1463:gst_v4l2_allocator_dqbuf: dequeued buffer 2 (flags 0x0) 0:00:12.737485797 21201 0x7ff94c050f00 DEBUG GST_MEMORY gstmemory.c:88:_gst_memory_free: free memory 0x7ff944348810 0:00:12.737519061 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:3437:gst_pad_query_default: not forwarding 0x7ff9a80089e0 (allocation) query 0:00:12.737531253 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_video_decoder_chain with buffer 0x7ff94434a480, returned ok 0:00:12.737504505 21201 0x7ff95002faa0 LOG v4l2allocator gstv4l2allocator.c:1500:gst_v4l2_allocator_dqbuf: Dequeued capture buffer, length: 0 bytesused: 64 data_offset: 0 0:00:12.737539736 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4076:gst_pad_query: sent query 0x7ff9a80089e0 (allocation), result 0 0:00:12.737548758 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_base_transform_chain with buffer 0x7ff94434a480, returned ok 0:00:12.737573518 21201 0x7ff95002faa0 LOG v4l2bufferpool gstv4l2bufferpool.c:1510:gst_v4l2_buffer_pool_dqbuf: dequeued buffer 0x7ff950033000 seq:0 (ix=2), mem 0x7ff95000a6a0 used 64, plane=0, flags 00000000, ts 0:00:00.360000000, pool-queued=1, buffer=0x7ff950033000 0:00:12.737574529 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4122:gst_pad_query: query failed 0:00:12.737589506 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2581:gst_base_parse_push_frame: frame pushed, flow ok 0:00:12.737610916 21201 0x7ff95002faa0 LOG v4l2videodec gstv4l2videodec.c:1238:gst_v4l2_video_dec_loop: Process output buffer 0:00:12.737627508 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4243:gst_pad_peer_query: query failed 0:00:12.737641441 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:2206:gst_base_parse_handle_buffer: handle_frame skipped 0, flushed 39445 0:00:12.737660170 21201 0x7ff95002faa0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:2077:gst_v4l2_buffer_pool_process: process buffer 0x7ff937ff4718 0:00:12.737689604 21201 0x7ff94c050f00 DEBUG baseparse gstbaseparse.c:3244:gst_base_parse_chain: not enough data available (only 0 bytes) 0:00:12.737719487 21201 0x55adeed15760 LOG baseparse gstbaseparse.c:1594:gst_base_parse_sink_query: allocation query result: 0 allocation query: 0x7ff9a80089e0, GstQueryAllocation, caps=(GstCaps)"video/x-h264\,\ stream-format\=\(string\)byte-stream\,\ alignment\=\(string\)au\,\ profile\=\(string\)NULL\,\ level\=\(string\)NULL\,\ width\=\(int\)1920\,\ height\=\(int\)1080\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ framerate\=\(fraction\)25/1\,\ interlace-mode\=\(string\)progressive\,\ colorimetry\=\(string\)bt709\,\ chroma-site\=\(string\)mpeg2\,\ multiview-mode\=\(string\)mono\,\ multiview-flags\=\(GstVideoMultiviewFlagsSet\)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono", need-pool=(boolean)true; 0:00:12.737722852 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:3306:gst_base_parse_chain: chain leaving 0:00:12.737746733 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4076:gst_pad_query: sent query 0x7ff9a80089e0 (allocation), result 0 0:00:12.737711179 21201 0x7ff95002faa0 DEBUG v4l2bufferpool gstv4l2bufferpool.c:2127:gst_v4l2_buffer_pool_process: num_queued 1 copy_threshold 4 dynamic_allocation 0 0:00:12.737783962 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4122:gst_pad_query: query failed 0:00:12.737784710 21201 0x7ff95002faa0 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff950033c60 to 0x7ff950033000, offset 0-0/0 0:00:12.737791628 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_base_parse_chain with buffer 0x7ff94434a7e0, returned ok 0:00:12.737801972 21201 0x55adeed15760 DEBUG GST_PADS gstpad.c:4243:gst_pad_peer_query: query failed 0:00:12.737822861 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:2960:gst_video_decoder_finish_frame: finish frame 0x7ff9440148f0 0:00:12.737839860 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:2028:gst_multi_queue_loop: sq:0 AFTER PUSHING sq->srcresult: ok (is_eos:0) 0:00:12.737851163 21201 0x55adeed15760 DEBUG videoencoder gstvideoencoder.c:1773:gst_video_encoder_negotiate_default: didn't get downstream ALLOCATION hints 0:00:12.737872238 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:2597:gst_video_decoder_prepare_finish_frame: n 4 in 0 out 0 0:00:12.737885199 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1798:gst_multi_queue_loop: SingleQueue 0 : trying to pop an object 0:00:12.737922542 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:4216:gst_v4l2_object_set_format: Setting format to video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono 0:00:12.737925649 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:2605:gst_video_decoder_prepare_finish_frame: finish frame 0x7ff9440148f0 (#8) sync:0 PTS:0:00:00.360000000 DTS:0:00:00.320000000 0:00:12.737963976 21201 0x55adeed15760 DEBUG video-info video-info.c:403:gst_video_info_from_caps: parsing caps video/x-h264, stream-format=(string)byte-stream, alignment=(string)au, profile=(string)NULL, level=(string)NULL, width=(int)1920, height=(int)1080, pixel-aspect-ratio=(fraction)1/1, framerate=(fraction)25/1, interlace-mode=(string)progressive, colorimetry=(string)bt709, chroma-site=(string)mpeg2, multiview-mode=(string)mono, multiview-flags=(GstVideoMultiviewFlagsSet)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono 0:00:12.737955717 21201 0x7ff94c050f00 LOG data_queue_dataflow gstdataqueue.c:586:gst_data_queue_pop: queue:0x55adeecb2c40 before popping: 5 visible items, 2030 bytes, 200000000 ns, 5 elements 0:00:12.738000205 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3540:gst_v4l2_object_set_format_full: progressive video 0:00:12.738005420 21201 0x7ff95002faa0 DEBUG videodecoder gstvideodecoder.c:2647:gst_video_decoder_prepare_finish_frame: sync timestamp 0:00:00.360000000 diff -0:00:00.360000000 0:00:12.738025096 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3676:gst_v4l2_object_set_format_full: Desired format 1920x1080, format H264 stride: 0 0:00:12.738027437 21201 0x7ff94c050f00 LOG data_queue_dataflow gstdataqueue.c:609:gst_data_queue_pop: queue:0x55adeecb2c40 after popping: 4 visible items, 1699 bytes, 160000000 ns, 4 elements 0:00:12.738066097 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3726:gst_v4l2_object_set_format_full: Desired format is 1920x1080, format H264, nb planes 1 0:00:12.738045411 21201 0x7ff95002faa0 DEBUG default gstvideoutils.c:46:_gst_video_codec_frame_free: free frame 0x7ff9440148f0 0:00:12.738086815 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:2861:single_queue_check_full: queue 0: visible 4/5, bytes 1699/2097152, time 200000000/0 0:00:12.738076228 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:1820:gst_multi_queue_loop: SingleQueue 0 : newid:17 , oldid:16 0:00:12.738109079 21201 0x55adeed0d1e0 LOG data_queue_dataflow gstdataqueue.c:530:gst_data_queue_push: queue:0x55adeecb2c40 after pushing: 5 visible items, 1941 bytes, 200000000 ns, 5 elements 0:00:12.738093406 21201 0x7ff95002faa0 LOG GST_BUFFER gstbuffer.c:768:_gst_buffer_free: finalize 0x7ff950033c60 0:00:12.738130788 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:1508:apply_buffer: queue 0, sink position updated to 0:00:00.680000000 0:00:12.738159032 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3735:gst_v4l2_object_set_format_full: stride 0 0:00:12.738182052 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:1380:update_time_level: queue 0 sink_segment.position:0:00:00.680000000, sink_time:+0:00:00.600000000 0:00:12.738174292 21201 0x7ff95002faa0 LOG videodecoder gstvideodecoder.c:3098:gst_video_decoder_clip_and_push_buf: accepting buffer inside segment: 0:00:00.360000000 0:00:00.400000000 seg 0:00:00.000000000 to 99:99:99.999999999 time 0:00:00.000000000 0:00:12.738206827 21201 0x55adeed0d1e0 DEBUG multiqueue gstmultiqueue.c:1431:update_time_level: queue 0, sink +0:00:00.600000000, src +0:00:00.360000000 0:00:12.738228915 21201 0x7ff95002faa0 DEBUG videodecoder gstvideodecoder.c:3168:gst_video_decoder_clip_and_push_buf: pushing buffer 0x7ff950033000 of size 64, PTS 0:00:00.360000000, dur 0:00:00.040000000 0:00:12.738241383 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:1832:gst_multi_queue_loop: CHECKING sq->srcresult: ok 0:00:12.738232167 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3868:gst_v4l2_object_set_format_full: Got format of 1920x1080, format H264, nb planes 1, colorspace 0 0:00:12.738240909 21201 0x55adeed0d1e0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_multi_queue_chain with buffer 0x7ff95007b480, returned ok 0:00:12.738277759 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.738285743 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:2616:compute_high_id: inspecting sq:0 , nextid:17, oldid:16, srcresult:ok 0:00:12.738277848 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7ff950033000, pts 0:00:00.360000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.738312368 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.738322994 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003100 does not match 00001001 0:00:12.738332110 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003040 does not match 00001012 0:00:12.738267063 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3878:gst_v4l2_object_set_format_full: stride 0, sizeimage 4194304 0:00:12.738368847 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:2196:gst_v4l2_object_get_colorspace: Unknown enum v4l2_colorspace 0 0:00:12.738375855 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5449:gst_qtdemux_combine_flows: flow return: ok 0:00:12.738350498 21201 0x7ff95002faa0 DEBUG GST_PADS gstpad.c:3815:do_probe_callbacks: data is passed 0:00:12.738401201 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3725:do_probe_callbacks: do probes 0:00:12.738412626 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3954:gst_v4l2_object_set_format_full: Desired framerate: 25/1 0:00:12.738432046 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3403:gst_v4l2_object_save_format: Got sizeimage 4194304 0:00:12.738443117 21201 0x7ff95002faa0 LOG GST_SCHEDULING gstpad.c:3612:probe_hook_marshal: hook 1 with flags 0x00003040 does not match 00001010 0:00:12.738419637 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5457:gst_qtdemux_combine_flows: combined flow return: ok 0:00:12.738470196 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_proxy_pad_chain_default with buffer buffer: 0x7ff950033000, pts 0:00:00.360000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.738448941 21201 0x55adeed15760 DEBUG v4l2 gstv4l2object.c:3090:gst_v4l2_object_setup_pool: initializing the capture system 0:00:12.738511640 21201 0x55adeed15760 INFO v4l2 gstv4l2object.c:3173:gst_v4l2_object_setup_pool: accessing buffers via mode 2 0:00:12.738521394 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc avc1, pad video_0 0:00:12.738497996 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_tee_chain with buffer buffer: 0x7ff950033000, pts 0:00:00.360000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.738527542 21201 0x55adeed15760 LOG v4l2 gstv4l2object.c:3182:gst_v4l2_object_setup_pool: initiating buffer pool 0:00:12.738447625 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:2642:compute_high_id: Highid is now : 16, lowest non-linked 4294967295 0:00:12.738582579 21201 0x55adeed15760 DEBUG GST_POLL gstpoll.c:681:gst_poll_new: 0x7ff9a000bf70: new controllable : 1 0:00:12.738574963 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9347:qtdemux_parse_samples: parsing up to sample 17 0:00:12.738551967 21201 0x7ff95002faa0 DEBUG tee gsttee.c:1020:gst_tee_chain: received buffer 0x7ff950033000 0:00:12.738626503 21201 0x55adeed15760 DEBUG GST_POLL gstpoll.c:848:gst_poll_add_fd_unlocked: 0x7ff9a000bf70: fd (fd:59, idx:0) 0:00:12.738613257 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9372:qtdemux_parse_samples: sample 17 has size 227 0:00:12.738588208 21201 0x7ff94c050f00 LOG multiqueue gstmultiqueue.c:1941:gst_multi_queue_loop: sq:0 BEFORE PUSHING sq->srcresult: ok 0:00:12.738658711 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9500:qtdemux_parse_samples: creating entry 17 with offset 120952 and size 227 0:00:12.738632603 21201 0x7ff95002faa0 LOG tee gsttee.c:934:gst_tee_handle_data: Starting to push buffer 0x7ff950033000 0:00:12.738647038 21201 0x55adeed15760 DEBUG GST_POLL gstpoll.c:1014:gst_poll_fd_ctl_read_unlocked: 0x7ff9a000bf70: fd (fd:59, idx:0), active : 1 0:00:12.738677151 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9556:qtdemux_parse_samples: sample 17: index 17, timestamp 0:00:00.680000000 0:00:12.738717271 21201 0x55adeed15760 LOG GST_POLL gstpoll.c:290:raise_wakeup: 0x7ff9a000bf70: raise 0:00:12.738724115 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:9616:qtdemux_parse_samples: samples at 170 is keyframe 0:00:12.738699330 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1508:apply_buffer: queue 0, src position updated to 0:00:00.480000000 0:00:12.738694764 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_queue_chain with buffer buffer: 0x7ff950033000, pts 0:00:00.360000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:12.738752291 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1431:update_time_level: queue 0, sink +0:00:00.600000000, src +0:00:00.400000000 0:00:12.738771339 21201 0x7ff94c050f00 DEBUG multiqueue gstmultiqueue.c:1636:gst_single_queue_push_one: SingleQueue 0 : Pushing buffer 0x7ff94434ac60 with ts 0:00:00.440000000 0:00:12.738771727 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6521:gst_qtdemux_loop: loop at position 4002921, state 0:00:12.738787099 21201 0x7ff95002faa0 LOG queue_dataflow gstqueue.c:1200:gst_queue_chain_buffer_or_list: received buffer 0x7ff950033000 of size 64, time 0:00:00.360000000, duration 0:00:00.040000000 0:00:12.738813283 21201 0x7ff95002faa0 LOG queue gstqueue.c:633:apply_buffer: sink position updated to 0:00:00.400000000 0:00:12.738819567 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:5249:gst_qtdemux_prepare_current_sample: segment active, index = 26 of 1296 0:00:12.738785663 21201 0x55adeed15760 DEBUG bufferpool gstbufferpool.c:180:gst_buffer_pool_init: created 0:00:12.738857342 21201 0x7ff95002faa0 LOG queue gstqueue.c:530:update_time_level: update sink time 0:00:12.738869086 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9333:qtdemux_parse_samples: parsing samples for stream fourcc mp4a, pad audio_0 0:00:12.738886230 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:9723:qtdemux_parse_samples: Tried to parse up to sample 26 but this sample has already been parsed 0:00:12.738791004 21201 0x7ff94c050f00 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_base_parse_chain with buffer buffer: 0x7ff94434ac60, pts 0:00:00.560000000, dts 0:00:00.440000000, dur 0:00:00.040000000, size 331, offset none, offset_end none, flags 0x2000 0:00:12.738903115 21201 0x7ff95002faa0 LOG queue gstqueue.c:547:update_time_level: sink +0:00:00.400000000, src +0:00:00.280000000 0:00:12.738908525 21201 0x7ff94c050f00 DEBUG baseparse gstbaseparse.c:3074:gst_base_parse_chain: chain 0:00:12.738910959 21201 0x55adeed0d1e0 DEBUG qtdemux qtdemux.c:6367:gst_qtdemux_loop_state_movie: pushing from track-id 2, empty 0 offset 120667, size 285, dts=0:00:00.603718820, pts=0:00:00.603718820, duration 0:00:00.023219955 0:00:12.738941613 21201 0x7ff94c050f00 LOG baseparse gstbaseparse.c:3190:gst_base_parse_chain: buffer size: 331, offset = -1, dts 0:00:00.440000000, pts 0:00:00.560000000 0:00:12.738924790 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4329:gst_pad_chain_data_unchecked: called chainfunction &gst_queue_chain with buffer 0x7ff950033000, returned ok 0:00:12.738971434 21201 0x7ff95002faa0 LOG tee gsttee.c:939:gst_tee_handle_data: Pushing item 0x7ff950033000 yielded result ok 0:00:12.738978175 21201 0x55adeed0d1e0 LOG qtdemux qtdemux.c:6427:gst_qtdemux_loop_state_movie: reading 285 bytes @ 120667 0:00:12.738957084 21201 0x7ff94c050f00 LOG adapter gstadapter.c:390:gst_adapter_push: pushing 0x7ff94434ac60 first 331 bytes 0:00:12.738938557 21201 0x55adeed15760 WARN structure gststructure.c:1861:priv_gst_structure_append_to_gstring: No value transform to serialize field 'params' of type 'GstAllocationParams' WARNING: Num classes mismatch. Configured: 0:00:13.769962395 21201 0x55adeed15760 DEBUG v4l2bufferpool gstv4l2bufferpool.c:753:gst_v4l2_buffer_pool_set_config: config GstBufferPoolConfig, caps=(GstCaps)"video/x-h264\,\ stream-format\=\(string\)byte-stream\,\ alignment\=\(string\)au\,\ profile\=\(string\)NULL\,\ level\=\(string\)NULL\,\ width\=\(int\)1920\,\ height\=\(int\)1080\,\ pixel-aspect-ratio\=\(fraction\)1/1\,\ framerate\=\(fraction\)25/1\,\ interlace-mode\=\(string\)progressive\,\ colorimetry\=\(string\)bt709\,\ chroma-site\=\(string\)mpeg2\,\ multiview-mode\=\(string\)mono\,\ multiview-flags\=\(GstVideoMultiviewFlagsSet\)0:ffffffff:/right-view-first/left-flipped/left-flopped/right-flipped/right-flopped/half-aspect/mixed-mono", size=(uint)4194304, min-buffers=(uint)0, max-buffers=(uint)0, allocator=(GstAllocator)"NULL", params=(GstAllocationParams)NULL; 0:00:13.769894260 21201 0x7ff95002faa0 LOG tee gsttee.c:974:gst_tee_handle_data: Replacing ret val -1 with 0 0:00:13.769938174 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_type_find_element_getrange, offset 120667, size 285 0:00:13.769881178 21201 0x7ff94c050f00 LOG adapter gstadapter.c:294:update_timestamps_and_offset: new pts 0:00:00.560000000 0:00:13.770089568 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_proxy_pad_getrange_default, offset 120667, size 285 0:00:13.770106869 21201 0x7ff94c050f00 LOG adapter gstadapter.c:300:update_timestamps_and_offset: new dts 0:00:00.440000000 0:00:13.770054708 21201 0x7ff95002faa0 LOG tee gsttee.c:934:gst_tee_handle_data: Starting to push buffer 0x7ff950033000 2, detected by network: 00:00:13.770127557 21201 0x7ff94c050f00 LOG adapter gstadapter.c:973:gst_adapter_get_buffer: getting buffer of 331 bytes 0:00:13.770218038 21201 0x7ff94c050f00 LOG adapter gstadapter.c:988:gst_adapter_get_buffer: providing buffer of 331 bytes as head buffer 0:00:13.770024022 21201 0x55adeed15760 INFO v4l2bufferpool gstv4l2bufferpool.c:801:gst_v4l2_buffer_pool_set_config: increasing minimum buffers to 2 0:00:13.770120356 21201 0x55adeed0d1e0 LOG GST_SCHEDULING gstpad.c:4787:gst_pad_get_range_unchecked: calling getrangefunc gst_base_src_getrange, offset 120667, size 285 0:00:13.770280829 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:844:gst_buffer_new: new 0x7ff94434a120 0:00:13.770244672 21201 0x7ff95002faa0 DEBUG GST_SCHEDULING gstpad.c:4323:gst_pad_chain_data_unchecked: calling chainfunction &gst_queue_chain with buffer buffer: 0x7ff950033000, pts 0:00:00.360000000, dts 99:99:99.999999999, dur 0:00:00.040000000, size 64, offset none, offset_end none, flags 0x0 0:00:13.770249339 21201 0x55adeed15760 INFO v4l2bufferpool gstv4l2bufferpool.c:815:gst_v4l2_buffer_pool_set_config: reducing maximum buffers to 64 0:00:13.770343615 21201 0x55adeed0d1e0 DEBUG basesrc gstbasesrc.c:2411:gst_base_src_update_length: reading offset 120667, length 285, size 4002921, segment.stop -1, maxsize 4002921 0:00:13.770335173 21201 0x7ff95002faa0 LOG queue_dataflow gstqueue.c:1200:gst_queue_chain_buffer_or_list: received buffer 0x7ff950033000 of size 64, time 0:00:00.360000000, duration 0:00:00.040000000 0:00:13.770307405 21201 0x7ff94c050f00 LOG GST_BUFFER gstbuffer.c:556:gst_buffer_copy_into: copy 0x7ff94434ac60 to 0x7ff94434a120, offset 0-331/331 0:00:13.770395190 21201 0x7ff95002faa0 LOG queue gstqueue.c:633:apply_buffer: sink position updated to 0:00:00.400000000 0:00:13.770418772 21201 0x7ff95002faa0 LOG queue gstqueue.c:530:update_time_level: update sink time Segmentation fault (core dumped)