Thanks, 

I think here's all of the call from before the called party answers: 


Jul 22 19:36:31 u363id562 rtpengine[16930]: Got valid command from 127.0.0.1:39090: offer - { "sdp": "v=0#015#012o=- 4878041229845783313 2 IN IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE audio#015#012a=msid-semantic: WMS a5c8QXN9lIWNnLI9gyw54a4ea3LY0iil8frd#015#012m=audio 56050 RTP/SAVPF 111 103 104 0 8 106 105 13 126#015#012c=IN IP4 2.2.2.2#015#012a=rtcp:56050 IN IP4 2.2.2.2#015#012a=candidate:3350409123 1 udp 2122260223 192.168.0.101 56050 typ host generation 0#015#012a=candidate:3350409123 2 udp 2122260223 192.168.0.101 56050 typ host generation 0#015#012a=candidate:2999745851 1 udp 2122194687 192.168.56.1 56051 typ host generation 0#015#012a=candidate:2999745851 2 udp 2122194687 192.168.56.1 56051 typ host generation 0#015#012a=candidate:2301678419 1 tcp 1518280447 192.168.0.101 0 typ host generation 0#015#012a=candidate:2301678419 2 tcp 1518280447 192.168.0.101 0 typ host generation 0#015#012a=candidate:4233069003 1 tcp 1518214911 192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp 1518214911 192.168.56.1 0 typ host generation 0#015#012a=candidate:1190865175 1 udp 1686052607 2.2.2.2 56050 typ srflx raddr 192.168.0.101 rport 56050 generation 0#015#012a=candidate:1190865175 2 udp 1686052607 2.2.2.2 56050 typ srflx raddr 192.168.0.101 rport 56050 generation 0#015#012a=ice-ufrag:/BpHYKAAtvbkO+46#015#012a=ice-pwd:JOPq6loMi7vLJMkwoN+06b9M#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256 03:48:83:8A:E5:CF:28:C5:28:43:8B:F2:3A:AF:0A:ED:6D:E7:63:EE:87:14:43:0B:61:C5:4F:BB:9F:96:90:AA#015#012a=setup:actpass#015#012a=mid:audio#015#012a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level#015#012a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=rtpmap:111 opus/48000/2#015#012a=fmtp:111 minptime=10#015#012a=rtpmap:103 ISAC/16000#015#012a=rtpmap:104 ISAC/32000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:106 CN/32000#015#012a=rtpmap:105 CN/16000#015#012a=rtpmap:13 CN/
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] Creating new call
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] Returning to SIP proxy: d3:sdp2175:v=0#015#012o=- 4878041229845783313 2 IN IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE audio#015#012a=msid-semantic: WMS a5c8QXN9lIWNnLI9gyw54a4ea3LY0iil8frd#015#012m=audio 30144 RTP/SAVPF 111 103 104 0 8 106 105 13 126#015#012c=IN IP4 1.1.1.1#015#012a=candidate:3350409123 1 udp 2122260223 192.168.0.101 56050 typ host generation 0#015#012a=candidate:3350409123 2 udp 2122260223 192.168.0.101 56050 typ host generation 0#015#012a=candidate:2999745851 1 udp 2122194687 192.168.56.1 56051 typ host generation 0#015#012a=candidate:2999745851 2 udp 2122194687 192.168.56.1 56051 typ host generation 0#015#012a=candidate:2301678419 1 tcp 1518280447 192.168.0.101 0 typ host generation 0#015#012a=candidate:2301678419 2 tcp 1518280447 192.168.0.101 0 typ host generation 0#015#012a=candidate:4233069003 1 tcp 1518214911 192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp 1518214911 192.168.56.1 0 typ host generation 0#015#012a=candidate:1190865175 1 udp 1686052607 2.2.2.2 56050 typ srflx raddr 192.168.0.101 rport 56050 generation 0#015#012a=candidate:1190865175 2 udp 1686052607 2.2.2.2 56050 typ srflx raddr 192.168.0.101 rport 56050 generation 0#015#012a=ice-ufrag:/BpHYKAAtvbkO+46#015#012a=ice-pwd:JOPq6loMi7vLJMkwoN+06b9M#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256 03:48:83:8A:E5:CF:28:C5:28:43:8B:F2:3A:AF:0A:ED:6D:E7:63:EE:87:14:43:0B:61:C5:4F:BB:9F:96:90:AA#015#012a=setup:actpass#015#012a=mid:audio#015#012a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level#015#012a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time#015#012a=rtpmap:111 opus/48000/2#015#012a=fmtp:111 minptime=10#015#012a=rtpmap:103 ISAC/16000#015#012a=rtpmap:104 ISAC/32000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:106 CN/32000#015#012a=rtpmap:105 CN/16000#015#012a=rtpmap:13 CN/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=maxptime:60#015#012a=
Jul 22 19:36:31 u363id562 rtpengine[16930]: Got valid command from 127.0.0.1:50012: delete - { "call-id": "35hkg7ct44fpndrs0gjt", "received-from": [ "IP4", "2.2.2.2" ], "from-tag": "ulaa4af3s4", "command": "delete" }
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] Deleting full call
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] Final packet stats:
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] --- Tag '', created 0:00 ago, in dialogue with '(none)'
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] ------ Media #1, port 30146 <>    2.2.2.2:56050, 0 p, 0 b, 0 e
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] --- Tag 'ulaa4af3s4', created 0:00 ago, in dialogue with ''
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] ------ Media #1, port 30144 <>            [::]:0    , 0 p, 0 b, 0 e
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] Returning to SIP proxy: d7:createdi1406043391e11:last signali1406043391e4:tagsd10:ulaa4af3s4d3:tag10:ulaa4af3s47:createdi1406043391e16:in dialogue with0:6:mediasld5:indexi1e4:type5:audio8:protocol9:RTP/SAVPF7:streamsld10:local porti30144e8:endpointd6:family4:IPv67:address2:::4:porti0ee19:advertised endpointd6:family4:IPv67:address2:::4:porti0ee11:last packeti1406043391e5:flagsl3:RTP4:RTCPe5:statsd7:packetsi0e5:bytesi0e6:errorsi0eeed10:local porti30145e8:endpointd6:family4:IPv67:address2:::4:porti0ee19:advertised endpointd6:family4:IPv67:address2:::4:porti0ee11:last packeti1406043391e5:flagsl4:RTCP13:fallback RTCPe5:statsd7:packetsi0e5:bytesi0e6:errorsi0eeee5:flagsl8:rtcp-mux11:passthrough3:ICEeeeee6:totalsd3:RTPd7:packetsi0e5:bytesi0e6:errorsi0ee4:RTCPd7:packetsi0e5:bytesi0e6:errorsi0eee6:result2:oke
Jul 22 19:36:31 u363id562 rtpengine[16930]: Got valid command from 127.0.0.1:50012: offer - { "sdp": "v=0#015#012o=- 4878041229845783313 2 IN IP4 127.0.0.1#015#012s=-#015#012t=0 0#015#012a=group:BUNDLE audio#015#012a=msid-semantic: WMS a5c8QXN9lIWNnLI9gyw54a4ea3LY0iil8frd#015#012m=audio 56050 RTP/SAVPF 111 103 104 0 8 106 105 13 126#015#012c=IN IP4 2.2.2.2#015#012a=rtcp:56050 IN IP4 2.2.2.2#015#012a=candidate:3350409123 1 udp 2122260223 192.168.0.101 56050 typ host generation 0#015#012a=candidate:3350409123 2 udp 2122260223 192.168.0.101 56050 typ host generation 0#015#012a=candidate:2999745851 1 udp 2122194687 192.168.56.1 56051 typ host generation 0#015#012a=candidate:2999745851 2 udp 2122194687 192.168.56.1 56051 typ host generation 0#015#012a=candidate:2301678419 1 tcp 1518280447 192.168.0.101 0 typ host generation 0#015#012a=candidate:2301678419 2 tcp 1518280447 192.168.0.101 0 typ host generation 0#015#012a=candidate:4233069003 1 tcp 1518214911 192.168.56.1 0 typ host generation 0#015#012a=candidate:4233069003 2 tcp 1518214911 192.168.56.1 0 typ host generation 0#015#012a=candidate:1190865175 1 udp 1686052607 2.2.2.2 56050 typ srflx raddr 192.168.0.101 rport 56050 generation 0#015#012a=candidate:1190865175 2 udp 1686052607 2.2.2.2 56050 typ srflx raddr 192.168.0.101 rport 56050 generation 0#015#012a=ice-ufrag:/BpHYKAAtvbkO+46#015#012a=ice-pwd:JOPq6loMi7vLJMkwoN+06b9M#015#012a=ice-options:google-ice#015#012a=fingerprint:sha-256 03:48:83:8A:E5:CF:28:C5:28:43:8B:F2:3A:AF:0A:ED:6D:E7:63:EE:87:14:43:0B:61:C5:4F:BB:9F:96:90:AA#015#012a=setup:actpass#015#012a=mid:audio#015#012a=extmap:1 urn:ietf:params:rtp-hdrext:ssrc-audio-level#015#012a=extmap:3 http://www.webrtc.org/experiments/rtp-hdrext/abs-send-time#015#012a=sendrecv#015#012a=rtcp-mux#015#012a=rtpmap:111 opus/48000/2#015#012a=fmtp:111 minptime=10#015#012a=rtpmap:103 ISAC/16000#015#012a=rtpmap:104 ISAC/32000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:106 CN/32000#015#012a=rtpmap:105 CN/16000#015#012a=rtpmap:13 CN/
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] Creating new call
Jul 22 19:36:31 u363id562 rtpengine[16930]: [35hkg7ct44fpndrs0gjt] Returning to SIP proxy: d3:sdp750:v=0#015#012o=- 4878041229845783313 2 IN IP4 1.1.1.1#015#012s=-#015#012t=0 0#015#012a=msid-semantic: WMS a5c8QXN9lIWNnLI9gyw54a4ea3LY0iil8frd#015#012m=audio 30148 RTP/AVP 111 103 104 0 8 106 105 13 126#015#012c=IN IP4 1.1.1.1#015#012a=rtpmap:111 opus/48000/2#015#012a=fmtp:111 minptime=10#015#012a=rtpmap:103 ISAC/16000#015#012a=rtpmap:104 ISAC/32000#015#012a=rtpmap:0 PCMU/8000#015#012a=rtpmap:8 PCMA/8000#015#012a=rtpmap:106 CN/32000#015#012a=rtpmap:105 CN/16000#015#012a=rtpmap:13 CN/8000#015#012a=rtpmap:126 telephone-event/8000#015#012a=maxptime:60#015#012a=ssrc:570927802 cname:dYE3cgVVbH9kDELV#015#012a=ssrc:570927802 msid:a5c8QXN9lIWNnLI9gyw54a4ea3LY0iil8frd ef55e410-eb48-4c6d-a270-de333bfe7ae2#015#012a=ssrc:570927802 mslabel:a5c8QXN9lIWNnLI9gyw54a4ea3LY0iil8frd#015#012a=ssrc:570927802 label:ef55e410-eb48-4c6d-a270-de333bfe7ae2#015#012a=sendrecv#015#012a=rtcp:30149#015#0126:result2:oke


There is something that might be relevant, after I started using dispatcher for sending the requests to Asterisk servers, I couldn't get calls through when routing INVITEs to Asterisk. Had errors with media servers not responding, although my Asterisk was running fine. Not sure why that happened but calls started flowing correctly after I used t_on_failure("MANAGE_FAILURE") of the default config and adding there a call to route("UA_FAILURE"), where I handle the 488 response.

So, my MANAGE_FAILURE route looks like this: 

failure_route[MANAGE_FAILURE] {

        route(NATMANAGE);

        if (t_is_canceled()) {
                exit;
        }

#!ifdef WITH_BLOCK3XX
        # block call redirect based on 3xx replies.
        if (t_check_status("3[0-9][0-9]")) {
                t_reply("404","Not found");
                exit;
        }
#!endif

#!ifdef WITH_VOICEMAIL
        # serial forking
        # - route to voicemail on busy or no answer (timeout)
        if (t_check_status("486|408")) {
                $du = $null;
                route(TOVOICEMAIL);
                exit;
        }
#!endif

#!ifdef WITH_WEBSOCKETS
        route(UA_FAILURE);
#!endif

       # ... dispatcher for 500 reply or local timeout handling
}


cheers,
Olli




2014-07-23 16:32 GMT+03:00 Richard Fuchs <rfuchs@sipwise.com>:
On 07/23/14 05:03, Olli Heiskanen wrote:
>
> Hi,
>
> Thanks very much for this, that solved the double-m-line issue. Now I'm
> calling rtpengine_offer in a branch route.
>
> One issue still remains; the call still gets connected to the called
> zoiper client, but it gets hung up right away. I traced this to be
> caused by a BYE message from Kamailio, which I think may be caused by
> the fact that the SDP returning to the chrome/websocket caller contains
> RTP/AVP profile, which it doesn't support. What I don't know is why this
> happens.
...
> I suspect there is a 200 OK message going from Kamailio to the ws client
> that has the RTP/AVP profile, as the Jssip library gets an error
> stating: "Failed to set remote answer sdp: Failed to push down transport
> description: Answerer must use either active or passive value for setup
> attribute."
>
> Any idea on what's going wrong here?

Yes, I see there's an a=setup:actpass in the answer, which shouldn't be
there. Could you post the entire log for the whole call (just the
rtpengine part) so I can see why it's putting that there?

cheers

_______________________________________________
SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
sr-users@lists.sip-router.org
http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users