[OpenSIPS-Users] RTPEngine log

[Alexey Kazantsev]

Hi list,
I would like to discuss RTPEngine logs,
to know your opinion if such log is normal or not.
 
The client complained that haven’t heard anything during the call.
 
10.45.145.33 is Asterisk
195.209.XXX.YY is OpenSIPS + RTPEngine
217.66.157.207 is cell phone network from which an UAC connects to OpenSIPS.
 
What seems strange to me is that «Confirmed peer address as 217.66.157.207:3311»
entry is written after «Received command delete» and «Replying to delete».
Not sure, but I think the order of these actions is not normal.
 
Also not normal is «Port  195.209.XXX.YY:17340 <>  217.66.157.207:22292, SSRC 0, 1 p, 80 b, 0 e, 49 ts»,
indicating that there was no traffic between OpenSIPS/RTPEngine and cell phone provider network.
 
I also noticed (need to consult documentation, if it’s mandatory or not) that the RTCP port is chosen
like RTP port +1. But in case of traffic between OpenSIPS/RTPEngine and cell phone provider 
network it is «217.66.157.207:22292, SSRC» and «217.66.157.207:3311  (RTCP)» respectively.
 
 
Here’s the log:
 
11:31:25 :  INFO: [call-id]: [control] Received command 'offer' from 127.0.0.1:60257
11:31:25 :  NOTICE: [call-id]: [core] Creating new call
11:31:25 :  INFO: [call-id]: [control] Replying to 'offer' from 127.0.0.1:60257 (elapsed time 0.000356 sec)
11:31:36 :  INFO: [call-id]: [control] Received command 'answer' from 127.0.0.1:52752
11:31:36 :  INFO: [call-id]: [control] Replying to 'answer' from 127.0.0.1:52752 (elapsed time 0.000203 sec)
11:31:36 :  INFO: [call-id port 17341]: [ice] ICE negotiated: local interface 195.209.XXX.YY
11:31:36 :  INFO: [call-id port 17340]: [ice] ICE negotiated: local interface 195.209.XXX.YY
11:31:36 :  INFO: [call-id port 17341]: [ice] ICE negotiated: local interface 195.209.XXX.YY
 
 
11:31:40 :  INFO: [call-id port 17528]: [core] Confirmed peer address as 10.45.145.33:15300
11:31:41 :  INFO: [call-id port 17529]: [core] Confirmed peer address as 10.45.145.33:15301
11:31:46 :  INFO: [call-id port 17529]: [core] Confirmed peer address as 10.45.145.33:15301
 
11:31:55 :  INFO: [call-id]: [control] Received command 'delete' from 127.0.0.1:60220
11:31:55 :  INFO: [call-id]: [core] Scheduling deletion of call branch 'l.fUeNRwzO0hVlBwsOkFqp8.yDNt9CAg' (via-branch '') in 30 seconds
11:31:55 :  INFO: [call-id]: [control] Replying to 'delete' from 127.0.0.1:60220 (elapsed time 0.000164 sec)
 
11:31:55 :  INFO: [call-id port 17341]: [core] Confirmed peer address as 217.66.157.207:3311
 
11:32:25 :  INFO: [call-id]: [core] Call branch 'l.fUeNRwzO0hVlBwsOkFqp8.yDNt9CAg' (via-branch '') deleted, no more branches remaining
11:32:25 :  INFO: [call-id]: [core] Final packet stats:
11:32:25 :  INFO: [call-id]: [core] --- Tag 'as2639e1b3', created 1:00 ago for branch '', in dialogue with 'l.fUeNRwzO0hVlBwsOkFqp8.yDNt9CAg'
11:32:25 :  INFO: [call-id]: [core] ------ Media #1 (audio over RTP/AVP) using iLBC/8000
11:32:25 :  INFO: [call-id]: [core] --------- Port    10.45.144.69:17528 <>    10.45.145.33:15300, SSRC 419a413, 595 p, 36890 b, 0 e, 30 ts
11:32:25 :  INFO: [call-id]: [core] --------- Port    10.45.144.69:17529 <>    10.45.145.33:15301 (RTCP), SSRC 419a413, 3 p, 192 b, 0 e, 34 ts
11:32:25 :  INFO: [call-id]: [core] --- Tag 'l.fUeNRwzO0hVlBwsOkFqp8.yDNt9CAg', created 1:00 ago for branch '', in dialogue with 'as2639e1b3'
11:32:25 :  INFO: [call-id]: [core] ------ Media #1 (audio over RTP/AVP) using unknown codec
11:32:25 :  INFO: [call-id]: [core] --------- Port  195.209.XXX.YY:17340 <>  217.66.157.207:22292, SSRC 0, 1 p, 80 b, 0 e, 49 ts
11:32:25 :  INFO: [call-id]: [core] --------- Port  195.209.XXX.YY:17341 <>  217.66.157.207:3311  (RTCP), SSRC 40172629, 7 p, 476 b, 0 e, 30 ts
 
-----------------------------------------------
BR, Alexey
https://alexeyka.zantsev.com/
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20220905/b61d2504/attachment.html>