Subscribe request offer with a=inactive

[Thomas de Rooij]

Hi all,
I'm facing an issue where a subscribe request (with flags 'all siprec') results in an SDP offer with one a=sendonly and one a=inactive. I'm issuing the subscribe request command immediately after the answer command (when processing the 200 OK). Could it be that the rtpengine has not yet received any packets from the calling party as the answer SDP has not yet reached the calling party? Hope that I'm just missing something small.

Kind regards, Thomas

[Richard Fuchs]

The RTP reception state should not have any effect on what gets
advertised in the SDP. Could it be that the call you're subscribing to
has one-way media to begin with (i.e. one side recvonly, the other
sendonly)?

Feel free to post a concrete example from the debug log if you can't
figure it out.

Cheers

[Thomas de Rooij]

Hi Richard,

Thanks for the reply.

Here is the debug log:

2026-02-03T07:06:06.702049+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Response dump for 'delete' to 10.1.1.1:54665: { "created": 1770098760, "created_us": 960914, "created_ts": 1770098760960914, "last signal": 1770098761, "last redis update": 0, "SSRC": { "134299360": { "bytes": 172, "packets": 1, "last RTP timestamp": 0, "last RTP seq": 250 }, "1636209728": { "bytes": 172, "packets": 1, "last RTP timestamp": 8000, "last RTP seq": 56781 } }, "tags": { "b3df5d13e7ec73bfafed95b5dac37227-bdcc": { "tag": "b3df5d13e7ec73bfafed95b5dac37227-bdcc", "created": 1770098760, "subscriptions": [ { "tag": "gv7jFe0gp58gm", "type": "offer/answer" } ], "subscribers": [ { "tag": "gv7jFe0gp58gm", "type": "pub/sub" } ], "medias": [ { "index": 1, "type": "audio", "protocol": "RTP/AVP", "codec": "PCMA/8000", "streams": [ { "endpoint": { "family": "IPv4", "address": "9.9.9.9", "port": 44638 }, "advertised endpoint": { "family": "IPv4", "address": "9.9.9.9", "port": 44638 }, "last packet": 1770098766, "last kernel packet": 1770098766, "last user packet": 1770098761, "SSRC": 134299360, "flags": [ "RTP", "filled" ], "stats": { "packets": 251, "bytes": 43156, "errors": 0 }, "stats_out": { "packets": 214, "bytes": 36808, "errors": 0 } }, { "endpoint": { "family": "IPv4", "address": "9.9.9.9", "port": 44639 }, "advertised endpoint": { "family": "IPv4", "address": "9.9.9.9", "port": 44639 }, "last packet": 1770098766, "last kernel packet": 1770098766, "last user packet": 1770098766, "SSRC": 134299360, "flags": [ "RTCP", "filled" ], "stats": { "packets": 3, "bytes": 228, "errors": 0 }, "stats_out": { "packets": 1, "bytes": 112, "errors": 0 } } ], "flags": [ "initialized", "send", "recv" ], "ingress SSRCs": [ { "SSRC": 134299360, "bytes": 172, "packets": 1, "last RTP timestamp": 0, "last RTP seq": 250 } ], "egress SSRCs": [ { "SSRC": 1636209728, "bytes": 34240, "packets": 214, "last RTP timestamp": 42240, "last RTP seq": 56781 } ] } ], "VSC": [  ] }
2026-02-03T07:06:06.701983+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Replying to 'delete' from 10.1.1.1:54665 (elapsed time 0.000307 sec)
2026-02-03T07:06:06.701950+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --------- Port      10.3.1.1:59737 <>                :0     (RTCP), SSRC 0, in 0 p, 0 b, 0 e, 5 ts, out 0 p, 0 b, 0 e
2026-02-03T07:06:06.701917+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --------- Port      10.3.1.1:59736 <>                :0    , SSRC 0, in 0 p, 0 b, 0 e, 5 ts, out 0 p, 0 b, 0 e
2026-02-03T07:06:06.701887+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ------ Media #2 (audio over RTP/AVP) using unknown codec
2026-02-03T07:06:06.701857+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ---     subscribed to media with monologue tag 'gv7jFe0gp58gm' (index: 1)
2026-02-03T07:06:06.701823+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --------- Port      10.3.1.1:44035 <>   10.2.1.1:20867 (RTCP), SSRC 61869440, in 1 p, 112 b, 0 e, 1 ts, out 2 p, 136 b, 0 e
2026-02-03T07:06:06.701787+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --------- Port      10.3.1.1:44034 <>   10.2.1.1:20866, SSRC 61869440, in 215 p, 36980 b, 0 e, 0 ts, out 251 p, 43156 b, 0 e
2026-02-03T07:06:06.701742+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ------ Media #1 (audio over RTP/AVP) using PCMA/8000
2026-02-03T07:06:06.701709+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ---     subscription for media with monologue tag 'gv7jFe0gp58gm' (index: 2)
2026-02-03T07:06:06.701676+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ---     subscription for media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1)
2026-02-03T07:06:06.701645+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ---     subscribed to media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1)
2026-02-03T07:06:06.701611+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --- Tag 'gv7jFe0gp58gm', created 0:05 ago for branch ''
2026-02-03T07:06:06.701567+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --------- Port   9.9.9.9:46871 <>   9.9.9.9:44639 (RTCP), SSRC 8013ee0, in 3 p, 228 b, 0 e, 0 ts, out 1 p, 112 b, 0 e
2026-02-03T07:06:06.701519+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --------- Port   9.9.9.9:46870 <>   9.9.9.9:44638, SSRC 8013ee0, in 251 p, 43156 b, 0 e, 0 ts, out 214 p, 36808 b, 0 e
2026-02-03T07:06:06.701476+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ------ Media #1 (audio over RTP/AVP) using PCMA/8000
2026-02-03T07:06:06.701433+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ---     subscription for media with monologue tag 'gv7jFe0gp58gm' (index: 1)
2026-02-03T07:06:06.701388+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] ---     subscribed to media with monologue tag 'gv7jFe0gp58gm' (index: 1)
2026-02-03T07:06:06.701337+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] --- Tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc', created 0:05 ago for branch ''
2026-02-03T07:06:06.701286+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Final packet stats:
2026-02-03T07:06:06.701236+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Deleting entire call
2026-02-03T07:06:06.701192+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 10.3.1.1:59736 (destroying monologue)
2026-02-03T07:06:06.701138+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 10.3.1.1:59737 (destroying monologue)
2026-02-03T07:06:06.700833+01:00 rtpengine - - -  ci=B2B.504.5147210.1770098756.1249588088, created_from=10.1.1.1:34910, last_signal=1770098761, tos=184, ml0_start_time=1770098760.960914, ml0_end_time=1770098766.699393, ml0_duration=5.738479, ml0_termination=REGULAR, ml0_local_tag=b3df5d13e7ec73bfafed95b5dac37227-bdcc, ml0_local_tag_type=FROM_TAG, ml0_remote_tag=gv7jFe0gp58gm, payload_type=8, ml0_midx1_rtp_endpoint_ip=9.9.9.9, ml0_midx1_rtp_endpoint_port=44638, ml0_midx1_rtp_local_relay_ip=9.9.9.9, ml0_midx1_rtp_local_relay_port=46870, ml0_midx1_rtp_relayed_packets=251, ml0_midx1_rtp_relayed_bytes=43156, ml0_midx1_rtp_relayed_errors=0, ml0_midx1_rtp_last_packet=1770098766, ml0_midx1_rtp_in_tos_tclass=0, ml0_midx1_rtcp_endpoint_ip=9.9.9.9, ml0_midx1_rtcp_endpoint_port=44639, ml0_midx1_rtcp_local_relay_ip=9.9.9.9, ml0_midx1_rtcp_local_relay_port=46871, ml0_midx1_rtcp_relayed_packets=3, ml0_midx1_rtcp_relayed_bytes=228, ml0_midx1_rtcp_relayed_errors=0, ml0_midx1_rtcp_last_packet=1770098766, ml0_midx1_rtcp_in_tos_tclass=0, ml1_start_time=1770098761.411982, ml1_end_time=1770098766.699393, ml1_duration=5.287411, ml1_termination=REGULAR, ml1_local_tag=gv7jFe0gp58gm, ml1_local_tag_type=TO_TAG, ml1_remote_tag=b3df5d13e7ec73bfafed95b5dac37227-bdcc, ml1_remote_tag=gv7jFe0gp58gm, payload_type=8, ml1_midx1_rtp_endpoint_ip=10.2.1.1, ml1_midx1_rtp_endpoint_port=20866, ml1_midx1_rtp_local_relay_ip=10.3.1.1, ml1_midx1_rtp_local_relay_port=44034, ml1_midx1_rtp_relayed_packets=215, ml1_midx1_rtp_relayed_bytes=36980, ml1_midx1_rtp_relayed_errors=0, ml1_midx1_rtp_last_packet=1770098766, ml1_midx1_rtp_in_tos_tclass=0, ml1_midx1_rtcp_endpoint_ip=10.2.1.1, ml1_midx1_rtcp_endpoint_port=20867, ml1_midx1_rtcp_local_relay_ip=10.3.1.1, ml1_midx1_rtcp_local_relay_port=44035, ml1_midx1_rtcp_relayed_packets=1, ml1_midx1_rtcp_relayed_bytes=112, ml1_midx1_rtcp_relayed_errors=0, ml1_midx1_rtcp_last_packet=1770098765, ml1_midx1_rtcp_in_tos_tclass=0, payload_type=unknown, ml1_midx
2026-02-03T07:06:06.700775+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Destroying monologue 'gv7jFe0gp58gm' ()
2026-02-03T07:06:06.700727+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Deleting call branch 'gv7jFe0gp58gm' (via-branch '')
2026-02-03T07:06:06.700683+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 9.9.9.9:46871 (destroying monologue)
2026-02-03T07:06:06.700634+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Removing media stream from kernel: local 9.9.9.9:46871 (destroying monologue)
2026-02-03T07:06:06.700592+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 10.3.1.1:44035 (destroying monologue)
2026-02-03T07:06:06.700550+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Removing media stream from kernel: local 10.3.1.1:44035 (destroying monologue)
2026-02-03T07:06:06.700510+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 10.3.1.1:44034 (destroying monologue)
2026-02-03T07:06:06.700465+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Removing media stream from kernel: local 10.3.1.1:44034 (destroying monologue)
2026-02-03T07:06:06.700418+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 9.9.9.9:46870 (destroying monologue)
2026-02-03T07:06:06.700373+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Removing media stream from kernel: local 9.9.9.9:46870 (destroying monologue)
2026-02-03T07:06:06.700321+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Destroying monologue 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' ()
2026-02-03T07:06:06.700276+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] Deleting call branch 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (via-branch '')
2026-02-03T07:06:06.700222+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Dump for 'delete' from 10.1.1.1:54665: { "supports": [ "load limit" ], "ICE": "remove", "received-from": [ "IP4", "9.9.9.9" ], "call-id": "B2B.504.5147210.1770098756.1249588088", "from-tag": "b3df5d13e7ec73bfafed95b5dac37227-bdcc", "command": "delete", "sip-message-type": "sip_request", "flags": [ "port-latching" ] }
2026-02-03T07:06:06.700060+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Received command 'delete' from 10.1.1.1:54665
2026-02-03T07:06:06.665884+01:00 rtpengine - - -  [B2B.504.5147210.1770098756.1249588088] version=2, padding=0, count=1, payloadtype=200, length=12, ssrc=134299360, ntp_sec=3979087566, ntp_fractions=665719885, rtp_ts=734184935, sender_packets=251, sender_bytes=40144, ssrc=1636209728, fraction_lost=0, packet_loss=0, last_seq=56779, jitter=0, last_sr=248343580, delay_since_last_sr=80674, version=2, padding=0, count=1, payloadtype=202, length=3
2026-02-03T07:06:06.665821+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] dequeue send queue to Homer
2026-02-03T07:06:06.665745+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] JSON to send to Homer: '{ "sender_information":{"ntp_timestamp_sec":3979087566,"ntp_timestamp_usec":665719885,"octets":40144,"rtp_timestamp":734184935, "packets":251},"ssrc":134299360,"type":200,"report_count":1,"report_blocks":[{"source_ssrc":1636209728,"highest_seq_no":56779,"fraction_lost":0,"ia_jitter":0,"packets_lost":0,"lsr":248343580,"dlsr":80674}],"sdes_report_count":1,"sdes_information": [ {"sdes_chunk_ssrc":134299360,"type":1,"text":"Nokia"}] }'
2026-02-03T07:06:06.665700+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [rtcp] Calling handler for RTCP packet type 203
2026-02-03T07:06:06.665659+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [rtcp] Calling handler for RTCP packet type 202
2026-02-03T07:06:06.665619+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] No payload type known for RTCP RR, discarding
2026-02-03T07:06:06.665575+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Calculated round-trip time for 61869440 is 5288 us
2026-02-03T07:06:06.665530+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] RR from 8013ee0 about 61869440: FL 0 TL 0 HSR 56779 J 0 LSR 248343580 DLSR 80674
2026-02-03T07:06:06.665478+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] SR from 8013ee0: RTP TS 734184935 PC 251 OC 40144 NTP TS 3979087566/665719885=3979087566.155000
2026-02-03T07:06:06.665400+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [rtcp] Calling handler for RTCP packet type 200
2026-02-03T07:06:06.665247+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Handling packet: remote 9.9.9.9:44639 (expected: 9.9.9.9:44639) -> local 9.9.9.9:46871
2026-02-03T07:06:05.430138+01:00 rtpengine - - -  [B2B.504.5147210.1770098756.1249588088] version=2, padding=0, count=1, payloadtype=200, length=12, ssrc=1636209728, ntp_sec=3979087565, ntp_fractions=1813833408, rtp_ts=32000, sender_packets=151, sender_bytes=24160, ssrc=134299360, fraction_lost=0, packet_loss=0, last_seq=186, jitter=0, last_sr=0, delay_since_last_sr=0, version=2, padding=0, count=1, payloadtype=202, length=14
2026-02-03T07:06:05.430100+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] Kernelizing media stream: 10.2.1.1:20867 -> 10.3.1.1:44035 | 9.9.9.9:46871 -> 9.9.9.9:44639
2026-02-03T07:06:05.430065+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] Kernelizing media stream: remote 10.2.1.1:20867 -> local 10.3.1.1:44035
2026-02-03T07:06:05.430033+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] dequeue send queue to Homer
2026-02-03T07:06:05.429977+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] JSON to send to Homer: '{ "sender_information":{"ntp_timestamp_sec":3979087565,"ntp_timestamp_usec":1813833408,"octets":24160,"rtp_timestamp":32000, "packets":151},"ssrc":1636209728,"type":200,"report_count":1,"report_blocks":[{"source_ssrc":134299360,"highest_seq_no":186,"fraction_lost":0,"ia_jitter":0,"packets_lost":0,"lsr":0,"dlsr":0}],"sdes_report_count":1,"sdes_information": [ {"sdes_chunk_ssrc":1636209728,"type":1,"text":"61869440"},{"sdes_chunk_ssrc":1636209728,"type":7,"text":"FreeSWITCH.org -- Come to ClueCon.com"}] }'
2026-02-03T07:06:05.429942+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [rtcp] Calling handler for RTCP packet type 202
2026-02-03T07:06:05.429860+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] No payload type known for RTCP RR, discarding
2026-02-03T07:06:05.429829+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] RR from 61869440 about 8013ee0: FL 0 TL 0 HSR 186 J 0 LSR 0 DLSR 0
2026-02-03T07:06:05.429798+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] SR from 61869440: RTP TS 32000 PC 151 OC 24160 NTP TS 3979087565/1813833408=3979087565.422316
2026-02-03T07:06:05.429764+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [rtcp] Calling handler for RTCP packet type 200
2026-02-03T07:06:05.429706+01:00 rtpengine - - -  [B2B.504.5147210.1770098756.1249588088] version=2, padding=0, count=1, payloadtype=200, length=12, ssrc=1636209728, ntp_sec=3979087565, ntp_fractions=1813833408, rtp_ts=32000, sender_packets=151, sender_bytes=24160, ssrc=134299360, fraction_lost=0, packet_loss=0, last_seq=186, jitter=0, last_sr=0, delay_since_last_sr=0, version=2, padding=0, count=1, payloadtype=202, length=14
2026-02-03T07:06:05.429657+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] New egress (direct) SSRC for: :0 SSRC: 61869440
2026-02-03T07:06:05.429624+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Forward to sink endpoint: local 9.9.9.9:46871 -> remote 9.9.9.9:44639
2026-02-03T07:06:05.429587+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] dequeue send queue to Homer
2026-02-03T07:06:05.429518+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] JSON to send to Homer: '{ "sender_information":{"ntp_timestamp_sec":3979087565,"ntp_timestamp_usec":1813833408,"octets":24160,"rtp_timestamp":32000, "packets":151},"ssrc":1636209728,"type":200,"report_count":1,"report_blocks":[{"source_ssrc":134299360,"highest_seq_no":186,"fraction_lost":0,"ia_jitter":0,"packets_lost":0,"lsr":0,"dlsr":0}],"sdes_report_count":1,"sdes_information": [ {"sdes_chunk_ssrc":1636209728,"type":1,"text":"61869440"},{"sdes_chunk_ssrc":1636209728,"type":7,"text":"FreeSWITCH.org -- Come to ClueCon.com"}] }'
2026-02-03T07:06:05.429486+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [rtcp] Calling handler for RTCP packet type 202
2026-02-03T07:06:05.429450+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] No payload type known for RTCP RR, discarding
2026-02-03T07:06:05.429405+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] RR from 61869440 about 8013ee0: FL 0 TL 0 HSR 186 J 0 LSR 0 DLSR 0
2026-02-03T07:06:05.429348+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] SR from 61869440: RTP TS 32000 PC 151 OC 24160 NTP TS 3979087565/1813833408=3979087565.422316
2026-02-03T07:06:05.429315+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [rtcp] Calling handler for RTCP packet type 200
2026-02-03T07:06:05.429274+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] Handling packet: remote 10.2.1.1:20867 (expected: 10.2.1.1:20867) -> local 10.3.1.1:44035
2026-02-03T07:06:05.429118+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] Confirmed peer address as 10.2.1.1:20867
2026-02-03T07:06:02.423133+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] Kernelizing media stream: 10.2.1.1:20866 -> 10.3.1.1:44034 | 9.9.9.9:46871 -> 9.9.9.9:44639
2026-02-03T07:06:02.423109+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] Kernelizing media stream: 10.2.1.1:20866 -> 10.3.1.1:44034 | 9.9.9.9:46870 -> 9.9.9.9:44638
2026-02-03T07:06:02.423060+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] Kernelizing media stream: remote 10.2.1.1:20866 -> local 10.3.1.1:44034
2026-02-03T07:06:02.423040+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] New egress (direct) SSRC for: 9.9.9.9:44638 SSRC: 61869440
2026-02-03T07:06:02.423014+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] Handling packet: remote 10.2.1.1:20866 (expected: 10.2.1.1:20866) -> local 10.3.1.1:44034 (RTP seq 56567 TS 8000 SSRC 61869440)
2026-02-03T07:06:02.422989+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] Confirmed peer address as 10.2.1.1:20866
2026-02-03T07:06:02.422869+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] New ingress SSRC for: 10.2.1.1:20866 SSRC: 61869440
2026-02-03T07:06:01.642298+01:00 rtpengine - - -  [B2B.504.5147210.1770098756.1249588088] version=2, padding=0, count=0, payloadtype=200, length=6, ssrc=134299360, ntp_sec=3979087561, ntp_fractions=588410479, rtp_ts=734144791, sender_packets=1, sender_bytes=160, version=2, padding=0, count=1, payloadtype=202, length=3
2026-02-03T07:06:01.642255+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Kernelizing media stream: 9.9.9.9:44639 -> 9.9.9.9:46871 | 10.3.1.1:44035 -> 10.2.1.1:20867
2026-02-03T07:06:01.642228+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Kernelizing media stream: remote 9.9.9.9:44639 -> local 9.9.9.9:46871
2026-02-03T07:06:01.642195+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44035]: [core] Forward to sink endpoint: local 10.3.1.1:44035 -> remote 10.2.1.1:20867
2026-02-03T07:06:01.642165+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] dequeue send queue to Homer
2026-02-03T07:06:01.642136+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Connection to Homer at 10.2.1.141:9060 has been established
2026-02-03T07:06:01.642106+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Connecting to Homer at 10.2.1.141:9060
2026-02-03T07:06:01.642069+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] JSON to send to Homer: '{ "sender_information":{"ntp_timestamp_sec":3979087561,"ntp_timestamp_usec":588410479,"octets":160,"rtp_timestamp":734144791, "packets":1},"ssrc":134299360,"type":200,"report_count":0,"report_blocks":[],"sdes_report_count":1,"sdes_information": [ {"sdes_chunk_ssrc":134299360,"type":1,"text":"Nokia"}] }'
2026-02-03T07:06:01.642037+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [rtcp] Calling handler for RTCP packet type 202
2026-02-03T07:06:01.642004+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] SR from 8013ee0: RTP TS 734144791 PC 1 OC 160 NTP TS 3979087561/588410479=3979087561.137000
2026-02-03T07:06:01.641965+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [rtcp] Calling handler for RTCP packet type 200
2026-02-03T07:06:01.641927+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Handling packet: remote 9.9.9.9:44639 (expected: 9.9.9.9:44639) -> local 9.9.9.9:46871
2026-02-03T07:06:01.641854+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46871]: [core] Confirmed peer address as 9.9.9.9:44639
2026-02-03T07:06:01.622435+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46870]: [core] Kernelizing media stream: 9.9.9.9:44638 -> 9.9.9.9:46870 | 10.3.1.1:44035 -> 10.2.1.1:20867
2026-02-03T07:06:01.622405+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46870]: [core] Kernelizing media stream: 9.9.9.9:44638 -> 9.9.9.9:46870 | 10.3.1.1:44034 -> 10.2.1.1:20866
2026-02-03T07:06:01.622369+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46870]: [core] Kernelizing media stream: remote 9.9.9.9:44638 -> local 9.9.9.9:46870
2026-02-03T07:06:01.622334+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/gv7jFe0gp58gm/1 port 44034]: [core] Forward to sink endpoint: local 10.3.1.1:44034 -> remote 10.2.1.1:20866 (RTP seq 0 TS 734144615 SSRC 8013ee0)
2026-02-03T07:06:01.622288+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46870]: [core] New egress (direct) SSRC for: 10.2.1.1:20866 SSRC: 8013ee0
2026-02-03T07:06:01.622255+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46870]: [core] Handling packet: remote 9.9.9.9:44638 (expected: 9.9.9.9:44638) -> local 9.9.9.9:46870 (RTP seq 0 TS 734144615 SSRC 8013ee0)
2026-02-03T07:06:01.622216+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46870]: [core] Confirmed peer address as 9.9.9.9:44638
2026-02-03T07:06:01.622068+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088/b3df5d13e7ec73bfafed95b5dac37227-bdcc/1 port 46870]: [core] New ingress SSRC for: 9.9.9.9:44638 SSRC: 8013ee0
2026-02-03T07:06:01.417212+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Response dump for 'subscribe request' to 10.1.1.1:40335: { "sdp": "v=0
2026-02-03T07:06:01.417180+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Replying to 'subscribe request' from 10.1.1.1:40335 (elapsed time 0.000798 sec)
2026-02-03T07:06:01.417154+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Opened 2 socket(s) from port '59736' (on interface '10.3.1.1') for a media relay
2026-02-03T07:06:01.417125+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for port = '59737'
2026-02-03T07:06:01.417082+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for port = '59736'
2026-02-03T07:06:01.417055+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for RTP/RTCP ports (allocation attempt = '1')
2026-02-03T07:06:01.417030+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to find RTP/RTCP ports (allocation attempt = '0')
2026-02-03T07:06:01.417004+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:01.416983+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:01.416953+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:01.416932+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:01.416911+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for CN/8000/ (13)
2026-02-03T07:06:01.416886+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:01.416864+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:01.416842+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:01.416817+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:01.416792+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.416767+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for telephone-event/8000/0-15 (100)
2026-02-03T07:06:01.416741+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.416716+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:01.416694+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:01.416667+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:01.416629+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:01.416596+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for PCMA/8000/ (8)
2026-02-03T07:06:01.416571+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:01.416550+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:01.416518+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for gv7jFe0gp58gm #2 -> gv7jFe0gp58gm #1
2026-02-03T07:06:01.416495+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec CN/8000/ (13)
2026-02-03T07:06:01.416466+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec telephone-event/8000/0-15 (100)
2026-02-03T07:06:01.416436+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec PCMA/8000/ (8)
2026-02-03T07:06:01.416407+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Subscribing media with monologue tag 'gv7jFe0gp58gm' (index: 2) to media with monologue tag 'gv7jFe0gp58gm' (index: 1)
2026-02-03T07:06:01.416383+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:01.416358+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:01.416333+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:01.416311+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:01.416284+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:01.416262+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:01.416232+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:01.416207+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:01.416185+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.416146+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.416119+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:01.416086+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:01.416057+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:01.416036+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:01.416007+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:01.415985+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:01.415960+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for gv7jFe0gp58gm #1 -> b3df5d13e7ec73bfafed95b5dac37227-bdcc #1
2026-02-03T07:06:01.415939+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec CN/8000/ (13)
2026-02-03T07:06:01.415910+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec telephone-event/8000/0-15 (100)
2026-02-03T07:06:01.415889+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec PCMA/8000/ (8)
2026-02-03T07:06:01.415864+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Subscribing media with monologue tag 'gv7jFe0gp58gm' (index: 1) to media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1)
2026-02-03T07:06:01.415842+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unsubscribing media with monologue tag 'gv7jFe0gp58gm' (index: 1) from media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1)
2026-02-03T07:06:01.415814+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Dump for 'subscribe request' from 10.1.1.1:40335: { "supports": [ "load limit" ], "call-id": "B2B.504.5147210.1770098756.1249588088", "to-tag": "gv7jFe0gp58gm", "command": "subscribe request", "sip-message-type": "sip_reply", "rtpp-flags": "all siprec" }
2026-02-03T07:06:01.415784+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Received command 'subscribe request' from 10.1.1.1:40335
2026-02-03T07:06:01.415416+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Response dump for 'answer' to 10.1.1.1:40335: { "sdp": "v=0
2026-02-03T07:06:01.415370+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Replying to 'answer' from 10.1.1.1:40335 (elapsed time 0.001169 sec)
2026-02-03T07:06:01.415338+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] set FILLED flag for stream, local 10.3.1.1:44035 remote 10.2.1.1:20867
2026-02-03T07:06:01.415310+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] set FILLED flag for stream, local 10.3.1.1:44034 remote 10.2.1.1:20866
2026-02-03T07:06:01.415285+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:01.415260+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:01.415235+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:01.415214+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:01.415193+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:01.415171+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:01.415143+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:01.415118+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:01.415093+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.415068+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.415045+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:01.415005+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:01.414981+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:01.414955+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:01.414933+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:01.414905+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:01.414876+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for gv7jFe0gp58gm #1 -> b3df5d13e7ec73bfafed95b5dac37227-bdcc #1
2026-02-03T07:06:01.414851+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:01.414826+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:01.414801+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:01.414779+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:01.414751+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:01.414729+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:01.414701+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:01.414676+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:01.414651+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.414626+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.414604+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:01.414561+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:01.414535+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:01.414512+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:01.414487+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:01.414460+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:01.414439+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for b3df5d13e7ec73bfafed95b5dac37227-bdcc #1 -> gv7jFe0gp58gm #1
2026-02-03T07:06:01.414413+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Updating supplemental codecs for gv7jFe0gp58gm #1
2026-02-03T07:06:01.414371+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Updating supplemental codecs for b3df5d13e7ec73bfafed95b5dac37227-bdcc #1
2026-02-03T07:06:01.414346+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:01.414320+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:01.414299+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:01.414269+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:01.414241+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for CN/8000/ (13)
2026-02-03T07:06:01.414213+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:01.414191+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:01.414158+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:01.414133+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:01.414109+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.414065+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for telephone-event/8000/0-15 (100)
2026-02-03T07:06:01.414039+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.414014+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:01.413989+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:01.413964+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:01.413940+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:01.413915+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for PCMA/8000/ (8)
2026-02-03T07:06:01.413892+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:01.413863+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:01.413835+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for b3df5d13e7ec73bfafed95b5dac37227-bdcc #1 -> gv7jFe0gp58gm #1
2026-02-03T07:06:01.413810+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] telephone-event/8000/1 payload type 100 already present, skip
2026-02-03T07:06:01.413785+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] CN/8000/1 payload type 13 already present, skip
2026-02-03T07:06:01.413760+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] telephone-event/8000/1 payload type 100 already present, skip
2026-02-03T07:06:01.413735+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] CN/8000/1 payload type 13 already present, skip
2026-02-03T07:06:01.413713+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] telephone-event/8000/1 payload type 100 already present, skip
2026-02-03T07:06:01.413683+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] CN/8000/1 payload type 13 already present, skip
2026-02-03T07:06:01.413651+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Reverse codec for CN/8000/ (13) is CN/8000/ (13)
2026-02-03T07:06:01.413628+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Reverse codec for telephone-event/8000/0-15 (100) is telephone-event/8000/0-15 (100)
2026-02-03T07:06:01.413583+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Reverse codec for PCMA/8000/ (8) is PCMA/8000/ (8)
2026-02-03T07:06:01.413558+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Codec answer for gv7jFe0gp58gm #1
2026-02-03T07:06:01.413533+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:01.413508+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:01.413474+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:01.413450+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:01.413421+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:01.413399+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:01.413374+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:01.413352+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:01.413326+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.413301+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:01.413276+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:01.413250+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:01.413224+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:01.413202+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:01.413172+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:01.413150+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:01.413103+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for gv7jFe0gp58gm #1 -> b3df5d13e7ec73bfafed95b5dac37227-bdcc #1
2026-02-03T07:06:01.413077+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Codec AMR-WB not present for stripping
2026-02-03T07:06:01.413051+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Codec AMR not present for stripping
2026-02-03T07:06:01.413025+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec CN/8000/ (13)
2026-02-03T07:06:01.412999+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec telephone-event/8000/0-15 (100)
2026-02-03T07:06:01.412976+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec PCMA/8000/ (8)
2026-02-03T07:06:01.412945+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Updating codecs for answerer gv7jFe0gp58gm #1
2026-02-03T07:06:01.412917+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Subscribing media with monologue tag 'gv7jFe0gp58gm' (index: 1) to media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1)
2026-02-03T07:06:01.412888+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Subscribing media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1) to media with monologue tag 'gv7jFe0gp58gm' (index: 1)
2026-02-03T07:06:01.412863+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unsubscribing media with monologue tag 'gv7jFe0gp58gm' (index: 1) from media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1)
2026-02-03T07:06:01.412833+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unsubscribing media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1) from media with monologue tag 'gv7jFe0gp58gm' (index: 1)
2026-02-03T07:06:01.412805+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 10.3.1.1:44035 (dialogue signalling event)
2026-02-03T07:06:01.412776+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 9.9.9.9:46871 (dialogue signalling event)
2026-02-03T07:06:01.412746+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 9.9.9.9:46870 (dialogue signalling event)
2026-02-03T07:06:01.412709+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Unconfirming peer address for local 10.3.1.1:44034 (dialogue signalling event)
2026-02-03T07:06:01.412672+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] replace-session-connection flag encountered, but not supported anymore.
2026-02-03T07:06:01.412645+01:00 rtpengine 246824 - -  ", "ICE": "remove", "replace": [ "origin", "session-connection" ], "codec": { "strip": [ "AMR", "AMR-WB" ] }, "transport-protocol": "RTP/AVP", "received-from": [ "IP4", "10.9.1.1" ], "call-id": "B2B.504.5147210.1770098756.1249588088", "from-tag": "b3df5d13e7ec73bfafed95b5dac37227-bdcc", "to-tag": "gv7jFe0gp58gm", "command": "answer", "sip-message-type": "sip_reply", "flags": [ "port-latching" ] }
2026-02-03T07:06:01.412278+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Dump for 'answer' from 10.1.1.1:40335: { "supports": [ "load limit" ], "sdp": "v=0
2026-02-03T07:06:01.412154+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Received command 'answer' from 10.1.1.1:40335
2026-02-03T07:06:00.967059+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Response dump for 'offer' to 10.1.1.1:34910: { "sdp": "v=0
2026-02-03T07:06:00.967026+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Replying to 'offer' from 10.1.1.1:34910 (elapsed time 0.000827 sec)
2026-02-03T07:06:00.966995+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] set FILLED flag for stream, local 9.9.9.9:46871 remote 9.9.9.9:44639
2026-02-03T07:06:00.966971+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] set FILLED flag for stream, local 9.9.9.9:46870 remote 9.9.9.9:44638
2026-02-03T07:06:00.966917+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Opened 2 socket(s) from port '46870' (on interface '9.9.9.9') for a media relay
2026-02-03T07:06:00.966850+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for port = '46871'
2026-02-03T07:06:00.966782+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for port = '46870'
2026-02-03T07:06:00.966708+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for RTP/RTCP ports (allocation attempt = '1')
2026-02-03T07:06:00.966648+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to find RTP/RTCP ports (allocation attempt = '0')
2026-02-03T07:06:00.966580+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Opened 2 socket(s) from port '44034' (on interface '10.3.1.1') for a media relay
2026-02-03T07:06:00.966510+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for port = '44035'
2026-02-03T07:06:00.966443+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for port = '44034'
2026-02-03T07:06:00.966375+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to bind the socket for RTP/RTCP ports (allocation attempt = '2')
2026-02-03T07:06:00.966306+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to find RTP/RTCP ports (allocation attempt = '1')
2026-02-03T07:06:00.966237+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Trying to find RTP/RTCP ports (allocation attempt = '0')
2026-02-03T07:06:00.966148+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:00.966085+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:00.966015+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:00.965938+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:00.965878+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:00.965818+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:00.965758+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:00.965699+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:00.965639+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:00.965578+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:00.965519+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:00.965459+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:00.965399+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:00.965337+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:00.965277+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:00.965216+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:00.965155+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for  #1 -> b3df5d13e7ec73bfafed95b5dac37227-bdcc #1
2026-02-03T07:06:00.965092+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Updating supplemental codecs for  #1
2026-02-03T07:06:00.964983+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for CN/8000/ (13) with DTMF 100, CN 13
2026-02-03T07:06:00.964916+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for CN/8000/1
2026-02-03T07:06:00.964849+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec CN/8000/ (13) for passthrough (to 13)
2026-02-03T07:06:00.964774+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for CN/8000/ is CN/8000/1/ (13)
2026-02-03T07:06:00.964707+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for CN/8000/ (13)
2026-02-03T07:06:00.964641+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec CN/8000/1/ (13)
2026-02-03T07:06:00.964573+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for telephone-event/8000/0-15 (100) with DTMF 100, CN 13
2026-02-03T07:06:00.964505+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for telephone-event/8000/1
2026-02-03T07:06:00.964438+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec telephone-event/8000/0-15 (100) for passthrough (to 100)
2026-02-03T07:06:00.964365+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for telephone-event/8000/0-15 is telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:00.964304+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for telephone-event/8000/0-15 (100)
2026-02-03T07:06:00.964242+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec telephone-event/8000/1/0-15 (100)
2026-02-03T07:06:00.964181+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Using passthrough handler for PCMA/8000/ (8) with DTMF 100, CN 13
2026-02-03T07:06:00.964112+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Shutting down codec handler for PCMA/8000/1
2026-02-03T07:06:00.964042+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink supports codec PCMA/8000/ (8) for passthrough (to 8)
2026-02-03T07:06:00.963965+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Sink codec for PCMA/8000/ is PCMA/8000/1/ (8)
2026-02-03T07:06:00.963845+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Creating codec handler for PCMA/8000/ (8)
2026-02-03T07:06:00.963776+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Checking receiver codec PCMA/8000/1/ (8)
2026-02-03T07:06:00.963669+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Default sink codec is PCMA/8000/ (8)
2026-02-03T07:06:00.963601+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Setting up codec handlers for  #1 -> b3df5d13e7ec73bfafed95b5dac37227-bdcc #1
2026-02-03T07:06:00.963533+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Updating supplemental codecs for  #1
2026-02-03T07:06:00.963464+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Codec AMR-WB not present for stripping
2026-02-03T07:06:00.963370+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Codec AMR not present for stripping
2026-02-03T07:06:00.963309+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec CN/8000/ (13)
2026-02-03T07:06:00.963248+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec telephone-event/8000/0-15 (100)
2026-02-03T07:06:00.963186+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec PCMA/8000/ (8)
2026-02-03T07:06:00.963119+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Updating offer codecs for answerer  #1
2026-02-03T07:06:00.963044+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec CN/8000/ (13)
2026-02-03T07:06:00.962974+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec telephone-event/8000/0-15 (100)
2026-02-03T07:06:00.962897+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Adding codec PCMA/8000/ (8)
2026-02-03T07:06:00.962813+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [codec] Updating codecs for offerer b3df5d13e7ec73bfafed95b5dac37227-bdcc #1
2026-02-03T07:06:00.962745+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] setting other slot to 0, setting slot to 0
2026-02-03T07:06:00.962677+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Subscribing media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1) to media with monologue tag '' (index: 1)
2026-02-03T07:06:00.962607+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] Subscribing media with monologue tag '' (index: 1) to media with monologue tag 'b3df5d13e7ec73bfafed95b5dac37227-bdcc' (index: 1)
2026-02-03T07:06:00.962538+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [core] No matching media (index: 1) using subscription, just use an index.
2026-02-03T07:06:00.962475+01:00 rtpengine 246824 - -  NOTICE: [B2B.504.5147210.1770098756.1249588088]: [core] Creating new call
2026-02-03T07:06:00.962401+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [core] replace-session-connection flag encountered, but not supported anymore.
2026-02-03T07:06:00.962286+01:00 rtpengine 246824 - -  ", "ICE": "remove", "direction": [ "public", "private" ], "replace": [ "origin", "session-connection" ], "codec": { "strip": [ "AMR", "AMR-WB" ] }, "received-from": [ "IP4", "9.9.9.9" ], "call-id": "B2B.504.5147210.1770098756.1249588088", "from-tag": "b3df5d13e7ec73bfafed95b5dac37227-bdcc", "command": "offer", "sip-message-type": "sip_request", "flags": [ "port-latching" ] }
2026-02-03T07:06:00.961156+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Dump for 'offer' from 10.1.1.1:34910: { "supports": [ "load limit" ], "sdp": "v=0
2026-02-03T07:06:00.961028+01:00 rtpengine 246824 - -  INFO: [B2B.504.5147210.1770098756.1249588088]: [control] Received command 'offer' from 10.1.1.1:34910

The offer SDP from rtpengine:

v=0
o=snip
s=-
t=0 0
m=audio 44034 RTP/AVP 8 100 13
c=IN IP4 10.x.x.x
a=rtpmap:8 PCMA/8000
a=rtpmap:100 telephone-event/8000
a=fmtp:100 0-15
a=rtpmap:13 CN/8000
a=sendrecv
a=rtcp:44035
a=ptime:20
a=maxptime:60

The corresponding answer SDP from rtpengine:

v=0
o=snip
t=0 0
m=audio 46870 RTP/AVP 8 100 13
c=IN IP4 9.9.9.9
a=rtpmap:8 PCMA/8000
a=rtpmap:100 telephone-event/8000
a=fmtp:100 0-15
a=rtpmap:13 CN/8000
a=sendrecv
a=rtcp:46871
a=ptime:20

Both have a=sendrecv and there is certainly two way audio established (after the subscribe request though).

Kind regards, Thomas

Op vrijdag 30 januari 2026 om 13:16:19 UTC+1 schreef rfuchs:

[Richard Fuchs]

Took me a while to realize that this log is backwards. How did you even do that? Also some lines seem to be missing, as are the SDP bodies. Luckily the crucial part is there:

2026-02-03T07:06:01.415814+01:00 rtpengine 246824 - -  DEBUG: [B2B.504.5147210.1770098756.1249588088]: [control] Dump for 'subscribe request' from 10.1.1.1:40335: { "supports": [ "load limit" ], "call-id": "B2B.504.5147210.1770098756.1249588088", "to-tag": "gv7jFe0gp58gm", "command": "subscribe request", "sip-message-type": "sip_reply", "rtpp-flags": "all siprec" }

The problem is that this includes a to-tag, so it's trying to use the existing B party as the SIPREC receiver.

How did you trigger this request? It may indicate a problem in whatever the control module was.

Cheers

--
You received this message because you are subscribed to the Google Groups "Sipwise rtpengine" group.
To unsubscribe from this group and stop receiving emails from it, send an email to rtpengine+...@googlegroups.com.
To view this discussion visit https://groups.google.com/d/msgid/rtpengine/c71a539d-460e-4d5d-89c0-ec97e564b152n%40googlegroups.com.

[Thomas de Rooij]

Sorry, logs are downloaded from our central logging solution, which always shows the newest logs on top. Logging is shipped via UDP and rate limited so it could be that some logging is missing indeed.

The request is triggered using:

rtpengine_subscribe_request("all siprec", "$avp(siprec_offer)", "$avp(siprec_to_tag)", "siprec_streams", "none");

from the Kamailio 6.1.0 nightly:

https://www.kamailio.org/docs/modules/6.1.x/modules/rtpengine.html#rtpengine.f.rtpengine_subscribe_request

FYI $avp(siprec_to_tag) is the AVP where the new to-tag should be stored, not supplying the to-tag.

Op dinsdag 3 februari 2026 om 12:48:13 UTC+1 schreef rfuchs:

[Richard Fuchs]

On 04/02/2026 03.48, Thomas de Rooij wrote:

Sorry, logs are downloaded from our central logging solution, which always shows the newest logs on top. Logging is shipped via UDP and rate limited so it could be that some logging is missing indeed.

The request is triggered using:

rtpengine_subscribe_request("all siprec", "$avp(siprec_offer)", "$avp(siprec_to_tag)", "siprec_streams", "none");

from the Kamailio 6.1.0 nightly:

https://www.kamailio.org/docs/modules/6.1.x/modules/rtpengine.html#rtpengine.f.rtpengine_subscribe_request

FYI $avp(siprec_to_tag) is the AVP where the new to-tag should be stored, not supplying the to-tag.

Ok, looks like the commit that introduced support for subscribe_request method has a booboo with regards to the to-tag handling. I'll see how best to fix it.

In the meantime, as a workaround you can try omitting the last argument ("none") altogether and see if that changes anything.

Cheers

[Thomas de Rooij]

I have removed the "none" argument and that indeed seems to resolve the issue.

Initially I added this because I only got an error from the Kamailio function.

Described this here: https://github.com/kamailio/kamailio/issues/4564#issuecomment-3784380097

I did upgrade to rtpengine 13.5 in the mean time and now it seems to work without the "none" argument somehow.

So my problem seems solved now. Thanks for the help.

Is the problem in the rtpengine_subscribe_request in Kamailio or the handling of the subscribe request command in rtpengine?

Kind regards, Thomas

Op woensdag 4 februari 2026 om 12:29:11 UTC+1 schreef rfuchs:

[Richard Fuchs]

On 04/02/2026 08.08, Thomas de Rooij wrote:
> Is the problem in the rtpengine_subscribe_request in Kamailio or the
> handling of the subscribe request command in rtpengine?

It's a combination of both, but I think I'll fix it on the rtpengine
side for the sake of consistency.

Cheers