KAMAILIO LOG /usr/sbin/kamailio[6908]: NOTICE: {2 2 INVITE f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.} acc [acc.c:279]: acc_log_request(): ACC: transaction answered: timestamp=1599048259;method=INVITE;from_tag=904c5107;to_tag=aee3e27066a043e19f73cbff37e50689;call_id=f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.;code=200;reason=OK;src_user=301;src_domain=192.168.20.58;src_ip=192.168.20.254;dst_ouser=302;dst_user=302;dst_domain=192.168.20.254 /usr/sbin/kamailio[6896]: NOTICE: {2 5 BYE f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.} acc [acc.c:279]: acc_log_request(): ACC: transaction answered: timestamp=1599048282;method=BYE;from_tag=904c5107;to_tag=aee3e27066a043e19f73cbff37e50689;call_id=f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.;code=200;reason=OK;src_user=301;src_domain=192.168.20.58;src_ip=192.168.20.254;dst_ouser=302;dst_user=302;dst_domain=192.168.20.254 RTPPTOXY LOG rtpproxy[10858]: DBUG:GLOBAL:rtpp_command_split:396: received command "6904_4 UZ8c0,8,101 f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI. 192.168.20.254 39784 904c5107;1" rtpproxy[10858]: INFO:GLOBAL:rtpp_command_ul_handle:504: new IPv4/IPv4 session f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI., tag 904c5107;1 requested, type strong rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_command_ul_handle:567: new session on IPv4 port 48754 created, tag 904c5107;1 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_prefill_addr:704: pre-filling caller's RTP address with 192.168.20.254:39784 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_prefill_addr:704: pre-filling caller's RTCP address with 192.168.20.254:39785 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_command_ul_handle:633: RTP packets from caller will be resized to 8 milliseconds rtpproxy[10858]: DBUG:GLOBAL:rtpc_doreply:192: sending reply "48754 192.168.20.57\n" rtpproxy[10858]: DBUG:GLOBAL:rtpp_command_split:396: received command "6908_4 Lc8,101 f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI. 192.168.20.254 4008 904c5107;1 aee3e27066a043e19f73cbff37e50689;1" rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_command_ul_handle:493: lookup on ports 48754/51782, session timer restarted rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_prefill_addr:704: pre-filling callee's RTP address with 192.168.20.254:4008 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_prefill_addr:704: pre-filling callee's RTCP address with 192.168.20.254:4009 rtpproxy[10858]: DBUG:GLOBAL:rtpc_doreply:192: sending reply "51782 192.168.20.57\n" rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:_rtpp_stream_latch:528: callee's address latched in: 192.168.20.254:4008 (RTP), SSRC=0x6E7E3EE9, Seq=21048 rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:update_rtpp_stats:479: 0x6E7E3EE9/21050: delta = 2 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:_rtpp_stream_latch:528: caller's address latched in: 192.168.20.254:39785 (RTCP), SSRC=UNKNOWN, Seq=UNKNOWN rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:_rtpp_stream_latch:528: caller's address latched in: 192.168.20.254:39784 (RTP), SSRC=0x38869261, Seq=4428 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:_rtpp_stream_latch:528: callee's address latched in: 192.168.20.254:4009 (RTCP), SSRC=UNKNOWN, Seq=UNKNOWN rtpproxy[10858]: DBUG:GLOBAL:rtpp_command_split:396: received command "6920_4 P-1 f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI. /media/moh.wav session aee3e27066a043e19f73cbff37e50689;1 904c5107;1" rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_handle_play:376: rtpp_server_ctor("/media/moh.wav", 8, -1) failed rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_handle_play:376: rtpp_server_ctor("/media/moh.wav", 101, -1) failed rtpproxy[10858]: ERR:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_handle_play:405: can't create player: rtpp_server_ctor() failed rtpproxy[10858]: DBUG:GLOBAL:rtpc_doreply:192: sending reply "E60\n" rtpproxy[10858]: DBUG:GLOBAL:rtpp_command_split:396: received command "6920_5 P-1 f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI. /media/moh.wav session 904c5107;1 aee3e27066a043e19f73cbff37e50689;1" rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_handle_play:376: rtpp_server_ctor("/media/moh.wav", 0, -1) failed rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_handle_play:376: rtpp_server_ctor("/media/moh.wav", 8, -1) failed rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_handle_play:376: rtpp_server_ctor("/media/moh.wav", 101, -1) failed rtpproxy[10858]: ERR:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_handle_play:405: can't create player: rtpp_server_ctor() failed rtpproxy[10858]: DBUG:GLOBAL:rtpc_doreply:192: sending reply "E60\n" rtpproxy[10858]: DBUG:GLOBAL:rtpp_command_split:396: received command "6898_4 UZ8c8,101 f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI. 192.168.20.254 39784 904c5107;1 aee3e27066a043e19f73cbff37e50689;1" rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_command_ul_handle:487: adding strong flag to existing session, new=1/0/0 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_command_ul_handle:493: lookup on ports 48754/51782, session timer restarted rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_command_ul_handle:633: RTP packets from caller will be resized to 8 milliseconds rtpproxy[10858]: DBUG:GLOBAL:rtpc_doreply:192: sending reply "48754 192.168.20.57\n" rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:update_jitter_stats:183: ##### LARGE VALUE #####0x6E7E3EE9,316,9061568898,50880,9061502308,50720,0,66430 rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:update_rtpp_stats:434: 0x6E7E3EE9/21365: delta rtime=14.616972, delta ts=6.340000 rtpproxy[10858]: DBUG:GLOBAL:rtpp_command_split:396: received command "6902_4 Lc8,101 f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI. 192.168.20.254 4008 904c5107;1 aee3e27066a043e19f73cbff37e50689;1" rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_command_ul_handle:493: lookup on ports 48754/51782, session timer restarted rtpproxy[10858]: DBUG:GLOBAL:rtpc_doreply:192: sending reply "51782 192.168.20.57\n" rtpproxy[10858]: DBUG:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:update_rtpp_stats:434: 0x38869261/5010: delta rtime=19.745952, delta ts=19.630000 rtpproxy[10858]: DBUG:GLOBAL:rtpp_command_split:396: received command "6900_4 D f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI. 904c5107 aee3e27066a043e19f73cbff37e50689" rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_cmd_delete_ematch:109: forcefully deleting session 1 on ports 48754/51782 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_pipe_get_stats:189: RTP stats: 657 in from callee, 733 in from caller, 2123 relayed, 0 dropped, 0 ignored rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_pipe_get_stats:197: RTP times: caller: first in at 1599048429.634996, duration 22.902097, longest IPI 8.323691 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_pipe_get_stats:203: RTP times: callee: first in at 1599048429.780907, duration 22.636016, longest IPI 7.986439 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_pipe_get_stats:189: RTCP stats: 7 in from callee, 7 in from caller, 14 relayed, 0 dropped, 0 ignored rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_session_dtor:220: session on ports 48754/51782 is cleaned up rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_dtor:294: RTP stream from callee: SSRC=0x6E7E3EE9, ssrc_changes=1, psent=658, precvd=657, plost=1, pdups=0 rtpproxy[10858]: INFO:f954ba62f373b86cMTg0YzVlODc0NTBiZGU1ZGJiYmNmYjY1MDc0MjIwMzI.:rtpp_stream_dtor:294: RTP stream from caller: SSRC=0x38869261, ssrc_changes=1, psent=733, precvd=733, plost=0, pdups=0 rtpproxy[10858]: DBUG:GLOBAL:rtpc_doreply:192: sending reply "0\n"