0:15:22.619765455 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49689, rtptime 466806066, pts 0:15:23.019954758, dts 99:99:99.999999999
0:15:22.619774087 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:22.619782462 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 148 bytes
0:15:22.619791980 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.619791099 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49690, PC: 19013, OC: 17360099
0:15:22.619798194 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.619801161 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:22.619807578 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.619816922 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.619813521 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83024768, rtptime 466815066, clock-rate 90000, diff 8981, jitter: 8877,437500
0:15:22.619823673 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:22.619826955 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:22.619828541 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 152
0:15:22.619832420 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:22.619845580 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 152
0:15:22.619854298 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:22.619855255 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 148
0:15:22.619864400 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.619863618 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49690 at time 0:15:22.497506110, discont 0, rtx 0
0:15:22.619866764 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.619876332 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.619879863 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.619883949 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 148
0:15:22.619873013 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.000231104 rtptime 0:00:00.100000000, clock-rate 90000, diff 0:00:00.099768896, jitter: 0:00:00.098639385
0:15:22.619892307 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:22.619894084 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49690, got #49690, gap of 0
0:15:22.619899573 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:22.619906258 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466815066, gstrtp 1:26:26.834066666, base 1:26:26.034044444, send_diff 0:00:00.800022222
0:15:22.619915410 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.497506110, base 0:15:22.496157302, recv_diff 0:00:00.001348808, slope 4745
0:15:22.619924079 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 8, delta -798673414
0:15:22.619928673 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -275816549, out 0:15:23.020362975
0:15:22.619934631 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2422:calculate_packet_spacing:<rtpjitterbuffer0>[00m new packet spacing 0:00:00.000408217 old packet spacing 0:00:00.065845852 combined to 0:00:00.049486443
0:15:22.619942572 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:22.619947786 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49690, now 1 packets, head: 1, percent -1
0:15:22.619956984 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:22.619958881 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 14 seconds, 370631 usec
0:15:22.619966068 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49690, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:22.619976557 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:22.619980090 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:22.619982688 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:22.619986164 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:22.619986481 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49690, rtptime 466815066, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:22.619991944 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:22.619998651 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 164 bytes
0:15:22.620008042 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.620007192 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 186 on channel 0
0:15:22.620013779 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.620025603 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.620021844 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:22.620034829 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.620037023 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49691, PC: 19014, OC: 17360273
0:15:22.620040304 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 168
0:15:22.620046749 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:22.620050155 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 168
0:15:22.620058412 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 164
0:15:22.620055559 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83024790, rtptime 466824066, clock-rate 90000, diff 8978, jitter: 8883,750000
0:15:22.620063304 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.620069889 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:22.620076610 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:22.620077628 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.620084552 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:22.620088613 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.620098501 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.620097560 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:22.620107013 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 164
0:15:22.620108416 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49691 at time 0:15:22.497750702, discont 0, rtx 0
0:15:22.620113356 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:22.620119676 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.000244592 rtptime 0:00:00.100000000, clock-rate 90000, diff 0:00:00.099755408, jitter: 0:00:00.098709136
0:15:22.620136186 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49691, got #49691, gap of 0
0:15:22.620142747 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:22.620146765 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466824066, gstrtp 1:26:26.934066666, base 1:26:26.034044444, send_diff 0:00:00.900022222
0:15:22.620157338 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.497750702, base 0:15:22.496157302, recv_diff 0:00:00.001593400, slope 4518
0:15:22.620166073 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 9, delta -898428822
0:15:22.620170378 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -401895534, out 0:15:22.994283990
0:15:22.620176680 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:916:rtp_jitter_buffer_calculate_pts:[00m backwards timestamps, using previous time
0:15:22.620181015 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:22.620186713 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49691, now 1 packets, head: 1, percent -1
0:15:22.620195810 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:22.620197468 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 14 seconds, 370392 usec
0:15:22.620202962 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49691, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:22.620210574 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:22.620215632 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:22.620216642 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:22.620219724 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49691, rtptime 466824066, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:22.620222827 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:22.620231310 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 174 bytes
0:15:22.620238989 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 195 on channel 0
0:15:22.620241169 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:22.620241121 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.620248641 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:22.620259391 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.620262751 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.620260508 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49692, PC: 19015, OC: 17360456
0:15:22.620266898 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.620272923 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:22.620278318 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 178
0:15:22.620281885 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 178
0:15:22.620278794 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83024811, rtptime 466833065, clock-rate 90000, diff 8978, jitter: 8889,625000
0:15:22.620284510 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 174
0:15:22.620288976 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:22.620296921 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.620300497 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.620295732 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:22.620304720 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.620309006 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.620311230 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 174
0:15:22.620311010 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:22.620317744 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:22.620322523 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:22.620330358 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49692 at time 0:15:22.497972897, discont 0, rtx 0
0:15:22.620339685 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.000222195 rtptime 0:00:00.099988888, clock-rate 90000, diff 0:00:00.099766693, jitter: 0:00:00.098775233
0:15:22.620351821 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49692, got #49692, gap of 0
0:15:22.620357165 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:22.620361333 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466833065, gstrtp 1:26:27.034055555, base 1:26:26.034044444, send_diff 0:00:01.000011111
0:15:22.620370499 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.497972897, base 0:15:22.496157302, recv_diff 0:00:00.001815595, slope 4406
0:15:22.620379245 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 10, delta -998195516
0:15:22.620384164 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -550970529, out 0:15:22.945197884
0:15:22.620390079 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:916:rtp_jitter_buffer_calculate_pts:[00m backwards timestamps, using previous time
0:15:22.620394527 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:22.620400539 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49692, now 1 packets, head: 1, percent -1
0:15:22.620409457 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:22.620411508 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 14 seconds, 370178 usec
0:15:22.620418067 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49692, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:22.620428229 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:22.620434844 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:22.620437021 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:22.620449847 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49692, rtptime 466833065, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:22.620460756 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 183 bytes
0:15:22.620463592 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.620467861 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.620470625 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.620475005 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.620480254 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 187
0:15:22.620483745 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 187
0:15:22.620486436 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 183
0:15:22.620489760 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.620491922 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.620495329 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.620498550 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.620500636 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 183
0:15:22.620506541 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:22.693593267 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:22.693618483 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:22.693627054 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 162 on channel 0
0:15:22.693643768 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:22.693660805 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49693, PC: 19016, OC: 17360606
0:15:22.693668367 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:22.693678896 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83031416, rtptime 466842067, clock-rate 90000, diff 2397, jitter: 8483,812500
0:15:22.693693207 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:22.693696645 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:22.693701974 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:22.693707976 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:22.693723662 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49693 at time 0:15:22.571365715, discont 0, rtx 0
0:15:22.693732855 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.073392818 rtptime 0:00:00.100022222, clock-rate 90000, diff 0:00:00.026629404, jitter: 0:00:00.094266118
0:15:22.693746558 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49693, got #49693, gap of 0
0:15:22.693752271 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:22.693756170 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466842067, gstrtp 1:26:27.134077777, base 1:26:26.034044444, send_diff 0:00:01.100033333
0:15:22.693765772 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.571365715, base 0:15:22.496157302, recv_diff 0:00:00.075208413, slope 117
0:15:22.693774542 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 11, delta -1024824920
0:15:22.693779445 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -694311482, out 0:15:22.901879153
0:15:22.693785422 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:916:rtp_jitter_buffer_calculate_pts:[00m backwards timestamps, using previous time
0:15:22.693790156 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:22.693800136 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49693, now 1 packets, head: 1, percent -1
0:15:22.693810466 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 14 seconds, 296779 usec
0:15:22.693824000 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:22.693844649 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49693, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:22.693862340 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:22.693880869 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:22.693885015 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:22.693913445 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49693, rtptime 466842067, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:22.693938775 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 150 bytes
0:15:22.693944408 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.693958318 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.693963154 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.693968844 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.693982585 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 154
0:15:22.693989164 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 154
0:15:22.693994117 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 150
0:15:22.694000211 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.694003545 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.694010195 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.694015492 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.694018960 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 150
0:15:22.694033562 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:22.798159096 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:22.798191592 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:22.798202834 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 180 on channel 0
0:15:22.798222974 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:22.798241890 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49694, PC: 19017, OC: 17360774
0:15:22.798249922 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:22.798256571 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83040828, rtptime 466851067, clock-rate 90000, diff 412, jitter: 7979,312500
0:15:22.798269814 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:22.798273903 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:22.798279714 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:22.798291804 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:22.798302538 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49694 at time 0:15:22.675944551, discont 0, rtx 0
0:15:22.798313479 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.104578836 rtptime 0:00:00.100000000, clock-rate 90000, diff 0:00:00.004578836, jitter: 0:00:00.088660662
0:15:22.798328754 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49694, got #49694, gap of 0
0:15:22.798334806 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:22.798340058 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466851067, gstrtp 1:26:27.234077777, base 1:26:26.034044444, send_diff 0:00:01.200033333
0:15:22.798350584 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.675944551, base 0:15:22.496157302, recv_diff 0:00:00.179787249, slope 53
0:15:22.798360110 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 12, delta -1020246084
0:15:22.798366132 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -813296319, out 0:15:22.882894316
0:15:22.798372581 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:916:rtp_jitter_buffer_calculate_pts:[00m backwards timestamps, using previous time
0:15:22.798378302 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:22.798387767 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49694, now 1 packets, head: 1, percent -1
0:15:22.798400170 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 14 seconds, 192189 usec
0:15:22.798411656 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:22.798436170 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49694, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:22.798456475 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:22.798477030 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:22.798481572 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:22.798512752 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49694, rtptime 466851067, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:22.798541807 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 168 bytes
0:15:22.798553231 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.798564637 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.798570596 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.798576560 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.798591577 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 172
0:15:22.798598743 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 172
0:15:22.798604025 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 168
0:15:22.798610983 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.798615421 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.798623269 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.798629141 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.798633045 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 168
0:15:22.798648730 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:22.898749680 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:22.898790278 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:22.898805278 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 192 on channel 0
0:15:22.898834540 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:22.898860562 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49695, PC: 19018, OC: 17360954
0:15:22.898871622 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:22.898880250 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83049883, rtptime 466860066, clock-rate 90000, diff 56, jitter: 7484,125000
0:15:22.898898134 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:22.898903967 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:22.898920752 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:22.898929844 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:22.898943612 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49695 at time 0:15:22.776585286, discont 0, rtx 0
0:15:22.898957877 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.100640735 rtptime 0:00:00.099988888, clock-rate 90000, diff 0:00:00.000651847, jitter: 0:00:00.083160111
0:15:22.898977997 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49695, got #49695, gap of 0
0:15:22.898986436 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:22.898992825 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466860066, gstrtp 1:26:27.334066666, base 1:26:26.034044444, send_diff 0:00:01.300022222
0:15:22.899007604 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.776585286, base 0:15:22.496157302, recv_diff 0:00:00.280427984, slope 37
0:15:22.899021107 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 13, delta -1019594238
0:15:22.899028856 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -902667152, out 0:15:22.893512372
0:15:22.899037533 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:916:rtp_jitter_buffer_calculate_pts:[00m backwards timestamps, using previous time
0:15:22.899045044 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:22.899057385 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49695, now 1 packets, head: 1, percent -1
0:15:22.899073567 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 14 seconds, 91516 usec
0:15:22.899086700 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:22.899120008 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49695, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:22.899146757 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:22.899173187 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:22.899179550 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:22.899219677 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49695, rtptime 466860066, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:22.899265720 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 180 bytes
0:15:22.899275096 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.899289902 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.899297301 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.899305119 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.899324667 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 184
0:15:22.899334781 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 184
0:15:22.899342204 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 180
0:15:22.899351697 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.899357090 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.899367514 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.899375884 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.899381405 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 180
0:15:22.899402846 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:22.988084646 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:22.988139953 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:22.988161715 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 166 on channel 0
0:15:22.988200905 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:22.988232044 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49696, PC: 19019, OC: 17361108
0:15:22.988245578 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:22.988257759 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83057927, rtptime 466869067, clock-rate 90000, diff 957, jitter: 7076,187500
0:15:22.988291336 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:22.988299121 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:22.988309894 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:22.988321160 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:22.988337806 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49696 at time 0:15:22.865978919, discont 0, rtx 0
0:15:22.988354909 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.089393633 rtptime 0:00:00.100011111, clock-rate 90000, diff 0:00:00.010617478, jitter: 0:00:00.078626196
0:15:22.988381960 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49696, got #49696, gap of 0
0:15:22.988392976 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:22.988401404 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466869067, gstrtp 1:26:27.434077777, base 1:26:26.034044444, send_diff 0:00:01.400033333
0:15:22.988420191 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.865978919, base 0:15:22.496157302, recv_diff 0:00:00.369821617, slope 30
0:15:22.988437110 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 14, delta -1030211716
0:15:22.988447273 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -965163988, out 0:15:22.931026647
0:15:22.988458194 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:916:rtp_jitter_buffer_calculate_pts:[00m backwards timestamps, using previous time
0:15:22.988467536 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:22.988483736 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49696, now 1 packets, head: 1, percent -1
0:15:22.988504949 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 14 seconds, 2086 usec
0:15:22.988520557 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:22.988563130 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49696, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:22.988596266 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:22.988630641 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:22.988645916 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:22.988689860 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49696, rtptime 466869067, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:22.988740476 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 154 bytes
0:15:22.988752348 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:22.988770770 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:22.988780287 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:22.988790403 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:22.988813829 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 158
0:15:22.988826573 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 158
0:15:22.988835907 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 154
0:15:22.988848456 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:22.988855472 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:22.988868706 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:22.988879407 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:22.988886463 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 154
0:15:22.988912286 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:23.107248445 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:23.107296889 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:23.107326300 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 162 on channel 0
0:15:23.107360952 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:23.107390106 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49697, PC: 19020, OC: 17361258
0:15:23.107404371 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:23.107426699 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83068651, rtptime 466878068, clock-rate 90000, diff 1723, jitter: 6741,625000
0:15:23.107449462 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:23.107456755 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:23.107468033 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:23.107479039 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:23.107496052 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49697 at time 0:15:22.985136812, discont 0, rtx 0
0:15:23.107513938 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.119157893 rtptime 0:00:00.100011111, clock-rate 90000, diff 0:00:00.019146782, jitter: 0:00:00.074908732
0:15:23.107540303 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49697, got #49697, gap of 0
0:15:23.107551464 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:23.107559698 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466878068, gstrtp 1:26:27.534088888, base 1:26:26.034044444, send_diff 0:00:01.500044444
0:15:23.107579123 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:22.985136812, base 0:15:22.496157302, recv_diff 0:00:00.488979510, slope 24
0:15:23.107597036 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 15, delta -1011064934
0:15:23.107607524 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -1001753335, out 0:15:22.994448411
0:15:23.107618750 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:916:rtp_jitter_buffer_calculate_pts:[00m backwards timestamps, using previous time
0:15:23.107628215 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:23.107644337 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49697, now 1 packets, head: 1, percent -1
0:15:23.107665561 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 13 seconds, 882925 usec
0:15:23.107680885 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:23.107723253 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49697, dts 99:99:99.999999999, pts 0:15:23.020362975
0:15:23.107760323 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:23.107804027 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:23.107813047 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:23.107860660 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49697, rtptime 466878068, pts 0:15:23.020362975, dts 99:99:99.999999999
0:15:23.107911489 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 150 bytes
0:15:23.107923096 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:23.107940363 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:23.107949908 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:23.107959855 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:23.107984336 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 154
0:15:23.107997377 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 154
0:15:23.108006699 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 150
0:15:23.108018983 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:23.108026019 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:23.108039113 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:23.108050142 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:23.108057130 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 150
0:15:23.108083183 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:23.198520033 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:23.198550959 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:23.198561879 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 281 on channel 0
0:15:23.198586383 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:23.198621401 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49698, PC: 19021, OC: 17361527
0:15:23.198631825 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:23.198639740 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83076862, rtptime 466887067, clock-rate 90000, diff 788, jitter: 6369,500000
0:15:23.198655060 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:23.198660054 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:23.198667331 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:23.198675605 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:23.198687860 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49698 at time 0:15:23.076329520, discont 0, rtx 0
0:15:23.198699688 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.091192708 rtptime 0:00:00.099988888, clock-rate 90000, diff 0:00:00.008796180, jitter: 0:00:00.070776697
0:15:23.198718072 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49698, got #49698, gap of 0
0:15:23.198725269 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:23.198730541 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466887067, gstrtp 1:26:27.634077777, base 1:26:26.034044444, send_diff 0:00:01.600033333
0:15:23.198743378 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:23.076329520, base 0:15:22.496157302, recv_diff 0:00:00.580172218, slope 22
0:15:23.198755100 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 16, delta -1019861115
0:15:23.198761948 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -1019966698, out 0:15:23.076223937
0:15:23.198770615 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2422:calculate_packet_spacing:<rtpjitterbuffer0>[00m new packet spacing 0:00:00.055860962 old packet spacing 0:00:00.049486443 combined to 0:00:00.054267332
0:15:23.198783269 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:23.198796048 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49698, now 1 packets, head: 1, percent -1
0:15:23.198810945 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 13 seconds, 791779 usec
0:15:23.198824894 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:23.198859724 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49698, dts 99:99:99.999999999, pts 0:15:23.076223937
0:15:23.198882627 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:23.198906805 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:23.198912395 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:23.198948177 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49698, rtptime 466887067, pts 0:15:23.076223937, dts 99:99:99.999999999
0:15:23.198983403 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 269 bytes
0:15:23.198991822 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:23.199004581 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:23.199011221 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:23.199018237 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:23.199035424 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 273
0:15:23.199044457 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 273
0:15:23.199050885 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 269
0:15:23.199059048 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:23.199063767 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:23.199072870 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:23.199080046 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:23.199084925 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 269
0:15:23.199103297 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:23.218459656 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession gstrtpsession.c:1153:rtcp_thread:<rtpsession0>[00m unlocked 0, current 2:56:57.894212261
0:15:23.218511355 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3985:rtp_session_on_timeout:[00m reporting at 2:56:57.894212261, NTP time 1032397:24:46.266456000, running-time 0:15:23.096087841
0:15:23.218565191 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:1660:session_update_ptp:[00m doing point-to-point: 1
0:15:23.218576073 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3070:calculate_rtcp_interval:[00m next deterministic interval: 0:00:05.000000000, first 0
0:15:23.218588268 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3075:calculate_rtcp_interval:[00m next interval: 0:00:05.000000000
0:15:23.218597332 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:4000:rtp_session_on_timeout:[00m interval 0:00:05.000000000
0:15:23.218610946 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3548:session_cleanup:[00m look at b7579b11, generation 191
0:15:23.218620856 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3552:session_cleanup:[00m Timing out collisions for b7579b11
0:15:23.218627932 [332m 7237[00m 0x7fac5004f1e0 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:3585:session_cleanup:[00m timeout base interval 0:00:05.000000000
0:15:23.218636408 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3611:session_cleanup:[00m removing timeout source b7579b11, last 2:41:37.385241908
0:15:23.218647767 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3548:session_cleanup:[00m look at 731d4df2, generation 191
0:15:23.218655201 [332m 7237[00m 0x7fac5004f1e0 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:3585:session_cleanup:[00m timeout base interval 0:00:05.000000000
0:15:23.218667593 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:1660:session_update_ptp:[00m doing point-to-point: 1
0:15:23.218676233 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3802:is_rtcp_time:[00m forward reconsideration 0:00:05.372114067, elapsed 0:00:05.236057675
0:15:23.218689239 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3820:is_rtcp_time:[00m reconsider RTCP for 2:56:58.030268653
0:15:23.218724740 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3202:rtp_session_next_timeout:[00m current time: 2:56:57.894212261, next time: 2:56:58.030268653
0:15:23.218739030 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3260:rtp_session_next_timeout:[00m current time: 2:56:57.894212261, next time: 2:56:58.030268653
0:15:23.218752972 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession gstrtpsession.c:1125:rtcp_thread:<rtpsession0>[00m next check time 2:56:58.030268653
0:15:23.287649080 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:23.287692287 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:23.287716062 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 160 on channel 0
0:15:23.287752890 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:23.287788275 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49699, PC: 19022, OC: 17361675
0:15:23.287807807 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:23.287820160 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83084887, rtptime 466896067, clock-rate 90000, diff 975, jitter: 6032,312500
0:15:23.287857887 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:23.287865235 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:23.287876229 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:23.287887550 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:23.287904858 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49699 at time 0:15:23.165546276, discont 0, rtx 0
0:15:23.287922121 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.089216756 rtptime 0:00:00.100000000, clock-rate 90000, diff 0:00:00.010783244, jitter: 0:00:00.067027106
0:15:23.287950823 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49699, got #49699, gap of 0
0:15:23.287960923 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:23.287968808 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466896067, gstrtp 1:26:27.734077777, base 1:26:26.034044444, send_diff 0:00:01.700033333
0:15:23.287988586 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:23.165546276, base 0:15:22.496157302, recv_diff 0:00:00.669388974, slope 20
0:15:23.288005480 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 17, delta -1030644359
0:15:23.288015287 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -1027681308, out 0:15:23.168509327
0:15:23.288028221 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2422:calculate_packet_spacing:<rtpjitterbuffer0>[00m new packet spacing 0:00:00.092285390 old packet spacing 0:00:00.054267332 combined to 0:00:00.082780875
0:15:23.288046499 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:23.288065714 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49699, now 1 packets, head: 1, percent -1
0:15:23.288086831 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 13 seconds, 702503 usec
0:15:23.288105255 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:23.288149649 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49699, dts 99:99:99.999999999, pts 0:15:23.168509327
0:15:23.288183342 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:23.288217645 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:23.288233521 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
0:15:23.288276791 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m    rtpbasedepayload gstrtpbasedepayload.c:359:gst_rtp_base_depayload_handle_buffer:<rec_h264_depay>[00m discont 0, seqnum 49699, rtptime 466896067, pts 0:15:23.168509327, dts 99:99:99.999999999
0:15:23.288325091 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:967:gst_rtp_h264_depay_process:<rec_h264_depay>[00m receiving 148 bytes
0:15:23.288337423 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:987:gst_rtp_h264_depay_process:<rec_h264_depay>[00m NRI 2, Type 1
0:15:23.288355905 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:777:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m handle NAL type 1
0:15:23.288365394 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:846:gst_rtp_h264_depay_handle_nal:<rec_h264_depay>[00m adding NAL to picture adapter
0:15:23.288375618 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m        rtph264depay gstrtph264depay.c:744:gst_rtp_h264_complete_au:<rec_h264_depay>[00m taking completed AU
0:15:23.288400039 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:986:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m processing packet buffer of size 152
0:15:23.288413184 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:992:gst_h264_parse_handle_frame_packetized:<rec_h264_parser>[00m AVC nal offset 152
0:15:23.288422806 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:721:gst_h264_parse_process_nal:<rec_h264_parser>[00m processing nal of type 1 Slice, size 148
0:15:23.288435094 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:827:gst_h264_parse_process_nal:<rec_h264_parser>[00m first_mb_in_slice = 0
0:15:23.288442187 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:830:gst_h264_parse_process_nal:<rec_h264_parser>[00m frame start: 1
0:15:23.288454832 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:840:gst_h264_parse_process_nal:<rec_h264_parser>[00m parse result 0, first MB: 0, slice type: 0
0:15:23.288465701 [332m 7237[00m 0x55635c168f20 [33;01mLOG    [00m [00m           h264parse gsth264parse.c:895:gst_h264_parse_process_nal:<rec_h264_parser>[00m collecting NAL in AVC frame
0:15:23.288472740 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:428:gst_h264_parse_wrap_nal:<rec_h264_parser>[00m nal length 148
0:15:23.288498773 [332m 7237[00m 0x55635c168f20 [37mDEBUG  [00m [00m           h264parse gsth264parse.c:186:gst_h264_parse_reset_frame:<rec_h264_parser>[00m reset frame
0:15:23.354769964 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession gstrtpsession.c:1153:rtcp_thread:<rtpsession0>[00m unlocked 0, current 2:56:58.030520526
0:15:23.354830997 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3985:rtp_session_on_timeout:[00m reporting at 2:56:58.030520526, NTP time 1032397:24:46.402765000, running-time 0:15:23.232396120
0:15:23.354883189 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:1660:session_update_ptp:[00m doing point-to-point: 1
0:15:23.354895842 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3070:calculate_rtcp_interval:[00m next deterministic interval: 0:00:05.000000000, first 0
0:15:23.354910776 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3075:calculate_rtcp_interval:[00m next interval: 0:00:05.000000000
0:15:23.354933227 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:4000:rtp_session_on_timeout:[00m interval 0:00:05.000000000
0:15:23.354950719 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3548:session_cleanup:[00m look at b7579b11, generation 191
0:15:23.354962578 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3552:session_cleanup:[00m Timing out collisions for b7579b11
0:15:23.354971856 [332m 7237[00m 0x7fac5004f1e0 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:3585:session_cleanup:[00m timeout base interval 0:00:05.000000000
0:15:23.354982376 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3611:session_cleanup:[00m removing timeout source b7579b11, last 2:41:37.385241908
0:15:23.354996268 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3548:session_cleanup:[00m look at 731d4df2, generation 191
0:15:23.355005163 [332m 7237[00m 0x7fac5004f1e0 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:3585:session_cleanup:[00m timeout base interval 0:00:05.000000000
0:15:23.355019296 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:1660:session_update_ptp:[00m doing point-to-point: 1
0:15:23.355029852 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3802:is_rtcp_time:[00m forward reconsideration 0:00:02.284836103, elapsed 0:00:05.372365940
0:15:23.355047737 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3849:is_rtcp_time:[00m can send RTCP now, next 2:57:00.315356629
0:15:23.355058133 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:4048:rtp_session_on_timeout:[00m doing RTCP generation 191 for 2 sources, early 0, may suppress 0
0:15:23.355077658 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3331:session_start_rtcp:[00m create RR for SSRC b7579b11
0:15:23.355090415 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3375:session_report_blocks:[00m create RB for SSRC 731d4df2
0:15:23.355098181 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m           rtpsource rtpsource.c:1579:rtp_source_get_new_rb:[00m ext_max 49699, expected 19022, received 19022, base_seq 30678
0:15:23.355110485 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m           rtpsource rtpsource.c:1596:rtp_source_get_new_rb:[00m add RR for SSRC 731d4df2
0:15:23.355118488 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m           rtpsource rtpsource.c:1600:rtp_source_get_new_rb:[00m fraction 0, lost 0, extseq 49699, jitter 6032
0:15:23.355129825 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m           rtpsource rtpsource.c:1608:rtp_source_get_new_rb:[00m last SR time diff 0:00:00.736436893
0:15:23.355140470 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m           rtpsource rtpsource.c:1618:rtp_source_get_new_rb:[00m LSR 4899:8cc7, DLSR 0000:bc87
0:15:23.355165704 [332m 7237[00m 0x7fac5004f1e0 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:3943:update_generation:[00m reported source b7579b11, new generation: 192
0:15:23.355176789 [332m 7237[00m 0x7fac5004f1e0 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:3943:update_generation:[00m reported source 731d4df2, new generation: 192
0:15:23.355185265 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3948:update_generation:[00m all reported, generation now 192
0:15:23.355193259 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:4065:rtp_session_on_timeout:[00m Time since last regular RTCP: 2:56:58.030520526 - 2:56:52.658154586 = 0:00:05.372365940
0:15:23.355211717 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:4072:rtp_session_on_timeout:[00m Time since last RTCP: 2:56:58.030520526 - 2:56:52.658154586 = 0:00:05.372365940
0:15:23.355262934 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:4107:rtp_session_on_timeout:[00m 0x7fac5004a2d0, sending RTCP packet, avg size 100, 108
0:15:23.355283931 [332m 7237[00m 0x7fac5004f1e0 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1440:gst_rtp_session_send_rtcp:<rtpsession0>[00m sending RTCP
0:15:23.355303479 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:2501:gst_rtspsrc_sink_chain:<rtsp_rec_src>[00m sending 80 bytes RTCP
0:15:23.355407791 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:2503:gst_rtspsrc_sink_chain:<rtsp_rec_src>[00m sent RTCP, 0
0:15:23.355443933 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3202:rtp_session_next_timeout:[00m current time: 2:56:58.030520526, next time: 2:57:00.315356629
0:15:23.355463466 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession rtpsession.c:3260:rtp_session_next_timeout:[00m current time: 2:56:58.030520526, next time: 2:57:00.315356629
0:15:23.355479041 [332m 7237[00m 0x7fac5004f1e0 [37mDEBUG  [00m [00m          rtpsession gstrtpsession.c:1125:rtcp_thread:<rtpsession0>[00m next check time 2:57:00.315356629
0:15:23.399925540 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4656:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m we received a server message
0:15:23.399973521 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4690:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m got data message
0:15:23.400000155 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4455:gst_rtspsrc_handle_data:<rtsp_rec_src>[00m pushing data of size 177 on channel 0
0:15:23.400039072 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1907:gst_rtp_session_chain_recv_rtp:<rtpsession0>[00m received RTP packet
0:15:23.400077596 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:1166:update_receiver_stats:[00m seq 49700, PC: 19023, OC: 17361840
0:15:23.400097662 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:946:calculate_jitter:[00m SSRC 731d4df2 got payload 96
0:15:23.400112929 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:979:calculate_jitter:[00m rtparrival 83094993, rtptime 466905067, clock-rate 90000, diff 1106, jitter: 5724,437500
0:15:23.400141924 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m           rtpsource rtpsource.c:887:push_packet:[00m pushing new packet
0:15:23.400152095 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession rtpsession.c:1387:source_push_rtp:[00m source 731d4df2 pushed receiver RTP packet
0:15:23.400165842 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m          rtpsession gstrtpsession.c:1320:gst_rtp_session_process_rtp:<rtpsession0>[00m pushing received RTP packet
0:15:23.400178475 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m        rtpssrcdemux gstrtpssrcdemux.c:625:gst_rtp_ssrc_demux_chain:<rtpssrcdemux0>[00m received buffer of SSRC 731d4df2
0:15:23.400196738 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2853:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Received packet #49700 at time 0:15:23.277837593, discont 0, rtx 0
0:15:23.400216322 [332m 7237[00m 0x55635c169050 [33;01mLOG    [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2589:calculate_jitter:<rtpjitterbuffer0>[00m dtsdiff 0:00:00.112291317 rtptime 0:00:00.100000000, clock-rate 90000, diff 0:00:00.012291317, jitter: 0:00:00.063606119
0:15:23.400249931 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2951:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m expected #49700, got #49700, gap of 0
0:15:23.400273301 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2980:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Clearing gap packets
0:15:23.400282722 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:785:rtp_jitter_buffer_calculate_pts:[00m extrtp 466905067, gstrtp 1:26:27.834077777, base 1:26:26.034044444, send_diff 0:00:01.800033333
0:15:23.400304391 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:563:calculate_skew:[00m time 0:15:23.277837593, base 0:15:22.496157302, recv_diff 0:00:00.781680291, slope 18
0:15:23.400323692 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:580:calculate_skew:[00m filling 18, delta -1018353042
0:15:23.400334589 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer rtpjitterbuffer.c:664:calculate_skew:[00m skew -1030081379, out 0:15:23.266109256
0:15:23.400348754 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:2422:calculate_packet_spacing:<rtpjitterbuffer0>[00m new packet spacing 0:00:00.097599929 old packet spacing 0:00:00.082780875 combined to 0:00:00.093895165
0:15:23.400368799 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3110:gst_rtp_jitter_buffer_chain:[00m signal event
0:15:23.400388905 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3122:gst_rtp_jitter_buffer_chain:<rtpjitterbuffer0>[00m Pushed packet #49700, now 1 packets, head: 1, percent -1
0:15:23.400413734 [332m 7237[00m 0x55635c169050 [37mDEBUG  [00m [00m             rtspsrc gstrtspsrc.c:4646:gst_rtspsrc_loop_interleaved:<rtsp_rec_src>[00m doing receive with timeout 13 seconds, 590177 usec
0:15:23.400432385 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event done
0:15:23.400480162 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3378:pop_and_push_next:<rtpjitterbuffer0>[00m Pushing buffer 49700, dts 99:99:99.999999999, pts 0:15:23.266109256
0:15:23.400517432 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m          rtpptdemux gstrtpptdemux.c:361:gst_rtp_pt_demux_chain:<rtpptdemux0>[00m received buffer for pt 96
0:15:23.400554228 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:3502:handle_next_buffer:<rtpjitterbuffer0>[00m no buffer, going to wait
0:15:23.400563458 [332m 7237[00m 0x7fac5004f000 [37mDEBUG  [00m [00m     rtpjitterbuffer gstrtpjitterbuffer.c:4028:gst_rtp_jitter_buffer_loop:[00m waiting event
