hello guys, <br><br><br>i configured FS HA and looks like its trying to recover the call .. but the re-INVITE fails due to "wrong/missed" codec capability.<br><br><br><br><br>freeswitch@internal> <br>freeswitch@internal> sofia profile external siptrace on<br>
Enabled sip debugging on external<br>freeswitch@internal> sofia profile internal siptrace on<br>Enabled sip debugging on internal<br>freeswitch@internal> <br>freeswitch@internal> <br>freeswitch@internal> <br>freeswitch@internal> sofia recover<br>
Recovered 1 call(s)<br><br>2011-01-24 21:32:31.667404 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error;<br>FATAL: terminating connection due to administrator command<br>
][176]<br>freeswitch@internal> 2011-01-24 21:32:31.667404 [INFO] switch_odbc.c:210 The connection has been re-established<br>2011-01-24 21:32:32.726402 [NOTICE] switch_channel.c:669 New Channel sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [808b0a98-8928-4ca2-a320-8e026ba94018]<br>
<b style="color: rgb(0, 0, 153);">2011-01-24 21:32:32.726402 [DEBUG] sofia_glue.c:2354 Set Codec sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> PCMA/8000 20 ms 160 samples</b><br>
2011-01-24 21:32:32.726402 [DEBUG] sofia_glue.c:2594 AUDIO RTP [sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>] 195.88.212.30 port 20092 -> 195.88.212.39 port 17750 codec: 8 ms: 20<br>
2011-01-24 21:32:32.726402 [DEBUG] switch_rtp.c:1182 Starting timer [soft] 160 bytes per 20ms<br>2011-01-24 21:32:32.728288 [DEBUG] sofia_glue.c:2774 Set 2833 dtmf send payload to 101<br>2011-01-24 21:32:32.728288 [DEBUG] sofia_glue.c:2779 Set 2833 dtmf receive payload to 101<br>
2011-01-24 21:32:32.728288 [DEBUG] sofia_glue.c:2790 Set comfort noise payload to 13<br>2011-01-24 21:32:32.779414 [DEBUG] sofia_glue.c:4246 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State Change CS_NEW -> CS_INIT<br>
2011-01-24 21:32:32.779414 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [BREAK]<br>2011-01-24 21:32:32.779414 [NOTICE] sofia_glue.c:4249 Resurrecting fallen channel sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a><br>
2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:314 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) Running State Change CS_INIT<br>2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:338 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State INIT<br>
2011-01-24 21:32:32.780381 [DEBUG] mod_sofia.c:83 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> SOFIA INIT<br>2011-01-24 21:32:32.780381 [DEBUG] mod_sofia.c:114 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State Change CS_INIT -> CS_EXECUTE<br>
2011-01-24 21:32:32.780381 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [BREAK]<br>2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:338 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State INIT going to sleep<br>
2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:314 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) Running State Change CS_EXECUTE<br>2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:348 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State EXECUTE<br>
2011-01-24 21:32:32.780381 [DEBUG] mod_sofia.c:226 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> SOFIA EXECUTE<br>2011-01-24 21:32:32.780381 [DEBUG] switch_core_state_machine.c:157 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> Standard EXECUTE<br>
EXECUTE sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> set(playback_delimiter=!)<br>send 1029 bytes to udp/[195.88.212.39]:62342 at 20:32:32.781241:<br> ------------------------------------------------------------------------<br>
INVITE <a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a> SIP/2.0<br> Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j<br> Route: <sip:385914392122@195.88.212.39:62342;lr><br>
Max-Forwards: 69<br> From: <<a href="mailto:sip%3A38518880050@195.88.212.30">sip:38518880050@195.88.212.30</a>>;tag=DFejDg1B59y9r<br> To: <<a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a>>;tag=C4B0BE6C-1B8<br>
Call-ID: <a href="mailto:1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39">1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39</a><br> CSeq: 7614512 INVITE<br> Contact: <<a href="http://sip:385914392122@195.88.212.30:5080">sip:385914392122@195.88.212.30:5080</a>><br>
User-Agent: FreeSWITCH-mod_sofia/1.0.6-svn-exported<br> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY<br> Supported: timer, precondition, path, replaces<br> Allow-Events: talk, refer<br>
Content-Type: application/sdp<br> Content-Disposition: session<br> Content-Length: 168<br> X-FS-Support: update_display<br> Remote-Party-ID: "38518880050" <<a href="mailto:sip%3A38518880050@195.88.212.30">sip:38518880050@195.88.212.30</a>>;party=calling;screen=yes;privacy=off<br>
<br> v=0<br> o=FreeSWITCH 1295881060 1295881062 IN IP4 195.88.212.30<br> s=FreeSWITCH<br> c=IN IP4 195.88.212.30<br> t=0 0<b><br> <span style="color: rgb(255, 0, 0);"> m=audio 20092 RTP/AVP 0 13</span></b><br>
<b style="color: rgb(255, 0, 0);"> a=rtpmap:13 CN/8000</b><br> a=ptime:20<br> ------------------------------------------------------------------------<br>2011-01-24 21:32:32.781430 [DEBUG] sofia.c:4153 Channel sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> entering state [calling][0]<br>
recv 496 bytes from udp/[195.88.212.39]:5060 at 20:32:32.784077:<br> ------------------------------------------------------------------------<br> SIP/2.0 100 Trying<br> Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j<br>
From: <<a href="mailto:sip%3A38518880050@195.88.212.30">sip:38518880050@195.88.212.30</a>>;tag=DFejDg1B59y9r<br> To: <<a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a>>;tag=C4B0BE6C-1B8<br>
Date: Mon, 24 Jan 2011 19:22:34 GMT<br> Call-ID: <a href="mailto:1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39">1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39</a><br> Server: Cisco-SIPGateway/IOS-12.x<br> CSeq: 7614512 INVITE<br>
Allow-Events: telephone-event<br> Remote-Party-ID: <<a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a>>;party=called;screen=yes;privacy=off<br> Content-Length: 0<br> <br> ------------------------------------------------------------------------<br>
recv 376 bytes from udp/[195.88.212.39]:5060 at 20:32:32.784195:<br> ------------------------------------------------------------------------<br> SIP/2.0 488 Not Acceptable Media<br> Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j<br>
From: <<a href="mailto:sip%3A38518880050@195.88.212.30">sip:38518880050@195.88.212.30</a>>;tag=DFejDg1B59y9r<br> To: <<a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a>>;tag=C4B0BE6C-1B8<br>
Call-ID: <a href="mailto:1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39">1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39</a><br> Warning: 304 195.88.212.39 "Media Type(s) Unavailable"<br> CSeq: 7614512 INVITE<br>
Content-Length: 0<br> <br> ------------------------------------------------------------------------<br>send 395 bytes to udp/[195.88.212.39]:62342 at 20:32:32.784303:<br> ------------------------------------------------------------------------<br>
ACK <a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a> SIP/2.0<br> Via: SIP/2.0/UDP 195.88.212.30:5080;rport;branch=z9hG4bKKDX31Z2Q71B3j<br> Route: <sip:385914392122@195.88.212.39:62342;lr><br>
Max-Forwards: 69<br> From: <<a href="mailto:sip%3A38518880050@195.88.212.30">sip:38518880050@195.88.212.30</a>>;tag=DFejDg1B59y9r<br> To: <<a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a>>;tag=C4B0BE6C-1B8<br>
Call-ID: <a href="mailto:1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39">1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39</a><br> CSeq: 7614512 ACK<br> Content-Length: 0<br> <br> ------------------------------------------------------------------------<br>
2011-01-24 21:32:32.784397 [DEBUG] sofia.c:4153 Channel sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> entering state [terminated][488]<br>2011-01-24 21:32:32.784397 [NOTICE] sofia.c:4789 Hangup sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]<br>
2011-01-24 21:32:32.784397 [DEBUG] switch_channel.c:2102 Send signal sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [KILL]<br>2011-01-24 21:32:32.784397 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [BREAK]<br>
2011-01-24 21:32:32.852411 [DEBUG] mod_dptools.c:816 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> SET [playback_delimiter]=[!]<br>2011-01-24 21:32:32.853505 [DEBUG] switch_core_state_machine.c:348 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State EXECUTE going to sleep<br>
2011-01-24 21:32:32.853505 [DEBUG] switch_core_state_machine.c:314 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) Running State Change CS_HANGUP<br>2011-01-24 21:32:32.853505 [DEBUG] switch_core_state_machine.c:499 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State HANGUP<br>
2011-01-24 21:32:32.861414 [DEBUG] mod_sofia.c:408 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> Overriding SIP cause 488 with 488 from the other leg<br>2011-01-24 21:32:32.861414 [DEBUG] mod_sofia.c:414 Channel sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> hanging up, cause: INCOMPATIBLE_DESTINATION<br>
2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:46 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> Standard HANGUP, cause: INCOMPATIBLE_DESTINATION<br>2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:499 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State HANGUP going to sleep<br>
2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:333 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State Change CS_HANGUP -> CS_REPORTING<br>2011-01-24 21:32:32.863454 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [BREAK]<br>
2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:314 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) Running State Change CS_REPORTING<br>2011-01-24 21:32:32.863454 [DEBUG] switch_core_state_machine.c:590 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State REPORTING<br>
2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:53 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> Standard REPORTING, cause: INCOMPATIBLE_DESTINATION<br>2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:590 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State REPORTING going to sleep<br>
2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:327 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State Change CS_REPORTING -> CS_DESTROY<br>2011-01-24 21:32:32.864328 [DEBUG] switch_core_session.c:1021 Send signal sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [BREAK]<br>
2011-01-24 21:32:32.864328 [DEBUG] switch_core_session.c:1164 Session 1 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) Locked, Waiting on external entities<br>2011-01-24 21:32:32.864328 [NOTICE] switch_core_session.c:1182 Session 1 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) Ended<br>
2011-01-24 21:32:32.864328 [NOTICE] switch_core_session.c:1184 Close Channel sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> [CS_DESTROY]<br>2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:428 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) Running State Change CS_DESTROY<br>
2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:439 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State DESTROY<br>2011-01-24 21:32:32.864328 [DEBUG] mod_sofia.c:341 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> SOFIA DESTROY<br>
2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:60 sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> Standard DESTROY<br>2011-01-24 21:32:32.864328 [DEBUG] switch_core_state_machine.c:439 (sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a>) State DESTROY going to sleep<br>
<br>freeswitch@internal> <br>freeswitch@internal> <br>freeswitch@internal> 2011-01-24 21:32:51.813359 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error;<br>
FATAL: terminating connection due to administrator command<br>][176]<br>2011-01-24 21:32:51.813359 [INFO] switch_odbc.c:210 The connection has been re-established<br>2011-01-24 21:32:52.810408 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error;<br>
FATAL: terminating connection due to administrator command<br>][176]<br>2011-01-24 21:32:52.810408 [INFO] switch_odbc.c:210 The connection has been re-established<br>2011-01-24 21:32:53.817405 [CRIT] switch_odbc.c:205 The sql server is not responding for DSN COREFSdrv [STATE: HY000 CODE 7 ERROR: [unixODBC]Unknown error;<br>
FATAL: terminating connection due to administrator command<br>][176]<br>2011-01-24 21:32:53.817405 [INFO] switch_odbc.c:210 The connection has been re-established<br>recv 432 bytes from udp/[195.88.212.39]:62342 at 20:33:06.045642:<br>
------------------------------------------------------------------------<br> BYE <a href="http://sip:385914392122@195.88.212.30:5080">sip:385914392122@195.88.212.30:5080</a> SIP/2.0<br> Via: SIP/2.0/UDP 195.88.212.39:5060;branch=z9hG4bK240FA<br>
From: <<a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a>>;tag=C4B0BE6C-1B8<br> To: <<a href="mailto:sip%3A38518880050@195.88.212.30">sip:38518880050@195.88.212.30</a>>;tag=DFejDg1B59y9r<br>
Date: Mon, 24 Jan 2011 19:22:34 GMT<br> Call-ID: <a href="mailto:1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39">1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39</a><br> User-Agent: Cisco-SIPGateway/IOS-12.x<br>
Max-Forwards: 70<br> Timestamp: 1295896987<br> CSeq: 102 BYE<br> Content-Length: 0<br> <br> ------------------------------------------------------------------------<br>send 522 bytes to udp/[195.88.212.39]:5060 at 20:33:06.045858:<br>
------------------------------------------------------------------------<br> SIP/2.0 481 Call Does Not Exist<br> Via: SIP/2.0/UDP 195.88.212.39:5060;branch=z9hG4bK240FA<br> From: <<a href="mailto:sip%3A385914392122@195.88.212.39">sip:385914392122@195.88.212.39</a>>;tag=C4B0BE6C-1B8<br>
To: <<a href="mailto:sip%3A38518880050@195.88.212.30">sip:38518880050@195.88.212.30</a>>;tag=DFejDg1B59y9r<br> Call-ID: <a href="mailto:1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39">1785B58B-272611E0-8909D88B-B59C2BD1@195.88.212.39</a><br>
CSeq: 102 BYE<br> Timestamp: 1295896987 0.000191<br> User-Agent: FreeSWITCH-mod_sofia/1.0.6-svn-exported<br> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY<br> Supported: timer, precondition, path, replaces<br>
Content-Length: 0<br> <br> ------------------------------------------------------------------------<br><br>freeswitch@internal> <br>freeswitch@internal> <br><br><br><br><br><br><br>my codec settings in vars.conf<br>
<X-PRE-PROCESS cmd="set" data="global_codec_prefs=PCMA,GSM"/><br> <X-PRE-PROCESS cmd="set" data="outbound_codec_prefs=PCMA,GSM"/><br><br><br>my codec settings in sip_profiles/*<br>
<param name="inbound-codec-prefs" value="$${global_codec_prefs}"/><br> <param name="outbound-codec-prefs" value="$${global_codec_prefs}"/><br><br><br><br>now, where the is FS picking up the codec list for the re-INVITE after "sofia recover"<br>
<br><br><br><br>from the debug:<br><br>2011-01-24 21:32:32.726402 [DEBUG] sofia_glue.c:2354 Set Codec sofia/external/<a href="mailto:385914392122@195.88.212.39">385914392122@195.88.212.39</a> PCMA/8000 20 ms 160 samples<br>
<br>i can see sofia knows what codec is being used, but somehow its not sending it in re-INVITE.<br><br><br><br>BTW: yea, i know, i know .. the version is a bit outdated but we can still make it working .. :)<br><br><br><br>
<br><br><br><br><br><br>