Hi, Hope someone knows how I am able to get around this one. Here goes... Did an upgrade to trunk (from a July vintage build) last week and noticed calls out to a provider were now failing after about 30 seconds or so - post answer. Tried latest (15183) - same thing. Analysing, I see that I have multiple UPDATE messages now being sent to the provider, but no response being sent back to FS. So FS times out and eventually kills the call. Interestingly, it only drops the A-leg; the B-leg remains up till the B party hangs up. I cant recall seeing these UPDATE messages before... The intent of the UPDATE seems to be to send the callee name & number to the B-leg. If its the provider's sip stack that's broken w.r.t. handling UPDATE - is there any way to get around it by doing something in my config to ensure these UPDATE's are not 'triggered' ? Some traces below. Any suggestions welcomed... Best Regards Keith Pretoria, South Africa. -------------------------------------------------------------------------------------------------------------------------------------------------- send 1048 bytes to udp/[196.10.11.12]:5060 at 13:24:04.249269: ------------------------------------------------------------------------ INVITE sip:27835551111@196.10.11.12 SIP/2.0 Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bKyQepDXQ5H8g5m Max-Forwards: 67 From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12> Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947386 INVITE Contact: <sip:gw+vprov@10.17.10.10:5060;transport=udp;gw=vprov> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 247 X-Actually-Support: UPDATE Remote-Party-ID: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1256118582 1256118583 IN IP4 10.17.10.10 s=FreeSWITCH c=IN IP4 10.17.10.10 t=0 0 m=audio 12862 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ 2009-10-21 15:24:04.248448 [DEBUG] sofia.c:3493 Channel sofia/vvrf/27835551111 entering state [calling][0] recv 601 bytes from udp/[196.10.11.12]:5060 at 13:24:04.307690: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bKyQepDXQ5H8g5m From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12>;tag=GR52RWG346-34 Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947386 INVITE Contact: "vprov C5CM" <sip:196.10.11.12:5060> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow-Events: talk Allow-Events: refer Content-Disposition: session X-Actually-Support: UPDATE Remote-Party-ID: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;party=calling;screen=yes;privacy=off Content-Length: 0 ------------------------------------------------------------------------ recv 879 bytes from udp/[196.10.11.12]:5060 at 13:24:08.508162: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bKyQepDXQ5H8g5m From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12>;tag=GR52RWG346-34 Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947386 INVITE Contact: "vprov C5CM" <sip:196.10.11.12:5060> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow-Events: talk Allow-Events: refer Content-Disposition: session X-Actually-Support: UPDATE Remote-Party-ID: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;party=calling;screen=yes;privacy=off Content-Type: application/sdp Content-Length: 233 v=0 o=Clarent 152602 152603 IN IP4 196.10.11.15 s=Clarent C5CM c=IN IP4 196.10.11.15 t=0 0 m=audio 5230 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=ptime:20 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ 2009-10-21 15:24:08.507506 [DEBUG] sofia.c:3493 Channel sofia/vvrf/27835551111 entering state [proceeding][183] 2009-10-21 15:24:08.507506 [DEBUG] sofia.c:3500 Remote SDP: v=0 o=Clarent 152602 152603 IN IP4 196.10.11.15 s=Clarent C5CM c=IN IP4 196.10.11.15 t=0 0 m=audio 5230 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=ptime:20 2009-10-21 15:24:08.507506 [DEBUG] sofia_glue.c:3144 Audio Codec Compare [G729:18:8000:20]/[G729:18:8000:20] 2009-10-21 15:24:08.508561 [DEBUG] sofia_glue.c:2102 Set Codec sofia/vvrf/27835551111 G729/8000 20 ms 160 samples 2009-10-21 15:24:08.508561 [DEBUG] sofia_glue.c:3104 Set 2833 dtmf payload to 101 2009-10-21 15:24:08.508561 [DEBUG] sofia_glue.c:2336 AUDIO RTP [sofia/vvrf/27835551111] 10.17.10.10 port 12862 -> 196.10.11.15 port 5230 codec: 18 ms: 20 2009-10-21 15:24:08.508561 [DEBUG] switch_rtp.c:1155 Starting timer [soft] 160 bytes per 20ms 2009-10-21 15:24:08.509616 [NOTICE] sofia_glue.c:2771 Pre-Answer sofia/vvrf/27835551111! 2009-10-21 15:24:08.509616 [DEBUG] switch_channel.c:1992 Send signal sofia/inetticky/27878050000@196.222.3.4 [BREAK] 2009-10-21 15:24:08.510671 [DEBUG] switch_ivr_originate.c:615 Setting codec string on sofia/inetticky/27878050000@196.222.3.4 to G729@8000h@20i 2009-10-21 15:24:08.510671 [DEBUG] switch_ivr_originate.c:2326 sofia/inetticky/27878050000@196.222.3.4 receive message [PROGRESS] 2009-10-21 15:24:08.510671 [INFO] switch_ivr_originate.c:2326 Sending early media 2009-10-21 15:24:08.510671 [DEBUG] sofia_glue.c:3144 Audio Codec Compare [G729:18:8000:0]/[G729:18:8000:20] 2009-10-21 15:24:08.510671 [DEBUG] sofia_glue.c:2102 Set Codec sofia/inetticky/27878050000@196.222.3.4 G729/8000 20 ms 160 samples 2009-10-21 15:24:08.510671 [DEBUG] sofia_glue.c:3104 Set 2833 dtmf payload to 101 2009-10-21 15:24:08.510671 [DEBUG] sofia_glue.c:2336 AUDIO RTP [sofia/inetticky/27878050000@196.222.3.4] 196.222.3.4 port 16840 -> 41.242.6.167 port 62936 codec: 18 ms: 20 2009-10-21 15:24:08.510671 [DEBUG] switch_rtp.c:1155 Starting timer [soft] 160 bytes per 20ms 2009-10-21 15:24:08.511769 [NOTICE] sofia_glue.c:2771 Pre-Answer sofia/inetticky/27878050000@196.222.3.4! 2009-10-21 15:24:08.511769 [INFO] mod_sofia.c:1582 Ring SDP: v=0 o=FreeSWITCH 1256114608 1256114609 IN IP4 196.222.3.4 s=FreeSWITCH c=IN IP4 196.222.3.4 t=0 0 m=audio 16840 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 2009-10-21 15:24:08.511769 [DEBUG] switch_core_session.c:712 Send signal sofia/vvrf/27835551111 [BREAK] 2009-10-21 15:24:08.511769 [DEBUG] switch_core_session.c:653 Send signal sofia/inetticky/27878050000@196.222.3.4 [BREAK] 2009-10-21 15:24:08.511769 [DEBUG] sofia.c:407 sofia/vvrf/27835551111 receive message [DISPLAY] 2009-10-21 15:24:08.511769 [DEBUG] switch_ivr_originate.c:2368 Originate Resulted in Success: [sofia/vvrf/27835551111] 2009-10-21 15:24:08.511769 [DEBUG] switch_channel.c:182 sofia/vvrf/27835551111 receive message [AUDIO_SYNC] 2009-10-21 15:24:08.511769 [DEBUG] switch_channel.c:182 sofia/inetticky/27878050000@196.222.3.4 receive message [AUDIO_SYNC] 2009-10-21 15:24:08.512832 [DEBUG] switch_ivr_bridge.c:975 sofia/vvrf/27835551111 receive message [BRIDGE] 2009-10-21 15:24:08.512832 [DEBUG] switch_core_session.c:653 Send signal sofia/vvrf/27835551111 [BREAK] 2009-10-21 15:24:08.512832 [DEBUG] switch_ivr_bridge.c:982 sofia/inetticky/27878050000@196.222.3.4 receive message [BRIDGE] send 973 bytes to udp/[41.242.6.167]:62928 at 13:24:08.512974: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 41.242.6.167:56675;branch=z9hG4bK2234c0462cdad03fc318bd6c7438282;rport=629282009-10-21 15:24:08.512832 [DEBUG] switch_core_session.c:653 Send signal sofia/inetticky/27878050000@196.222.3.4 [BREAK] 2009-10-21 15:24:08.512832 [DEBUG] switch_ivr_bridge.c:1026 (sofia/vvrf/27835551111) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2009-10-21 15:24:08.512832 [DEBUG] switch_core_session.c:985 Send signal sofia/vvrf/27835551111 [BREAK] 2009-10-21 15:24:08.512832 [DEBUG] switch_core_state_machine.c:306 (sofia/vvrf/27835551111) Running State Change CS_EXCHANGE_MEDIA 2009-10-21 15:24:08.512832 [DEBUG] switch_core_state_machine.c:343 (sofia/vvrf/27835551111) State EXCHANGE_MEDIA 2009-10-21 15:24:08.512832 [DEBUG] mod_sofia.c:436 SOFIA LOOPBACK From: "Keith PhoneADSL" <sip:27878050000@196.222.3.4>;tag=3283506127 To: <sip:0835551111@196.222.3.4>;user=phone;tag=g2DFFpS0QN4cc Call-ID: 4252084844@192_168_1_65 CSeq: 3 INVITE Contact: <sip:0835551111@196.222.3.4:5060;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 249 X-Actually-Support: UPDATE v=0 o=FreeSWITCH 1256114608 1256114609 IN IP4 196.222.3.4 s=FreeSWITCH c=IN IP4 196.222.3.4 t=0 0 m=audio 16840 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ 2009-10-21 15:24:08.512832 [DEBUG] sofia.c:3493 Channel sofia/inetticky/27878050000@196.222.3.4 entering state [early][183] recv 928 bytes from udp/[196.10.11.12]:5060 at 13:24:14.188781: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bKyQepDXQ5H8g5m From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12>;tag=GR52RWG346-34 Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947386 INVITE Contact: "vprov C5CM" <sip:196.10.11.12:5060> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow-Events: talk Allow-Events: refer Content-Disposition: session X-Actually-Support: UPDATE Remote-Party-ID: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;party=calling;screen=yes;privacy=off Allow: ACK, NOTIFY, OPTIONS, REFER, INFO, BYE, CANCEL, INVITE Content-Type: application/sdp Content-Length: 233 v=0 o=Clarent 153288 153288 IN IP4 196.10.11.15 s=Clarent C5CM c=IN IP4 196.10.11.15 t=0 0 m=audio 5230 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=ptime:20 a=fmtp:18 annexb=no a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ send 396 bytes to udp/[196.10.11.12]:5060 at 13:24:14.189032: ------------------------------------------------------------------------ ACK sip:196.10.11.12:5060 SIP/2.0 Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bKZ07eFr88eH7Qg Max-Forwards: 70 From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12>;tag=GR52RWG346-34 Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947386 ACK Contact: <sip:gw+vprov@10.17.10.10:5060;transport=udp;gw=vprov> Content-Length: 0 ------------------------------------------------------------------------ 2009-10-21 15:24:14.188910 [DEBUG] sofia.c:3493 Channel sofia/vvrf/27835551111 entering state [ready][200] 2009-10-21 15:24:14.188910 [DEBUG] switch_channel.c:2105 Send signal sofia/inetticky/27878050000@196.222.3.4 [BREAK] 2009-10-21 15:24:14.188910 [NOTICE] sofia.c:3928 Channel [sofia/vvrf/27835551111] has been answered 2009-10-21 15:24:14.188910 [DEBUG] switch_channel.c:182 sofia/vvrf/27835551111 receive message [AUDIO_SYNC] 2009-10-21 15:24:14.204823 [DEBUG] switch_ivr_bridge.c:378 sofia/inetticky/27878050000@196.222.3.4 receive message [ANSWER] 2009-10-21 15:24:14.204823 [DEBUG] mod_sofia.c:543 Local SDP sofia/inetticky/27878050000@196.222.3.4: v=0 o=FreeSWITCH 1256114608 1256114610 IN IP4 196.222.3.4 s=FreeSWITCH c=IN IP4 196.222.3.4 t=0 0 m=audio 16840 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 a=sendrecv 2009-10-21 15:24:14.204823 [DEBUG] switch_core_session.c:712 Send signal sofia/vvrf/27835551111 [BREAK] 2009-10-21 15:24:14.204823 [DEBUG] switch_core_session.c:653 Send signal sofia/inetticky/27878050000@196.222.3.4 [BREAK] 2009-10-21 15:24:14.204823 [NOTICE] switch_ivr_bridge.c:378 Channel [sofia/inetticky/27878050000@196.222.3.4] has been answered 2009-10-21 15:24:14.204823 [DEBUG] switch_channel.c:182 sofia/inetticky/27878050000@196.222.3.4 receive message [AUDIO_SYNC] send 983 bytes to udp/[41.242.6.167]:62928 at 13:24:14.205173: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 41.242.6.167:56675;branch=z9hG4bK2234c0462cdad03fc318bd6c7438282;rport=62928 From: "Keith PhoneADSL" <sip:27878050000@196.222.3.4>;tag=3283506127 To: <sip:0835551111@196.222.3.4>;user=phone;tag=g2DFFpS0QN4cc Call-ID: 4252084844@192_168_1_65 CSeq: 3 INVITE Contact: <sip:0835551111@196.222.3.4:5060;transport=udp> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, refer Session-Expires: 120;refresher=uas Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 249 X-Actually-Support: UPDATE v=0 o=FreeSWITCH 1256114608 1256114609 IN IP4 196.222.3.4 s=FreeSWITCH c=IN IP4 196.222.3.4 t=0 0 m=audio 16840 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ 2009-10-21 15:24:14.204823 [DEBUG] sofia.c:3493 Channel sofia/inetticky/27878050000@196.222.3.4 entering state [completed][200] 2009-10-21 15:24:14.224959 [DEBUG] sofia.c:407 sofia/vvrf/27835551111 receive message [DISPLAY] send 947 bytes to udp/[196.10.11.12]:5060 at 13:24:14.225162: ------------------------------------------------------------------------ UPDATE sip:196.10.11.12:5060 SIP/2.0 Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bK0907gKScctXac Max-Forwards: 70 From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12>;tag=GR52RWG346-34 Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947387 UPDATE Contact: <sip:gw+vprov@10.17.10.10:5060;transport=udp;gw=vprov> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY Supported: timer, precondition, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 247 P-Asserted-Identity: "unknown" <27835551111> v=0 o=FreeSWITCH 1256118582 1256118583 IN IP4 10.17.10.10 s=FreeSWITCH c=IN IP4 10.17.10.10 t=0 0 m=audio 12862 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ 2009-10-21 15:24:14.224959 [DEBUG] sofia.c:3493 Channel sofia/vvrf/27835551111 entering state [calling][0] recv 733 bytes from udp/[41.242.6.167]:62928 at 13:24:14.533488: ------------------------------------------------------------------------ ACK sip:0835551111@196.222.3.4:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 41.242.6.167:56675;branch=z9hG4bKd2c91e1afbf0880332fbeba5fc9c04a6;rport From: "Keith PhoneADSL" <sip:27878050000@196.222.3.4>;tag=3283506127 To: <sip:0835551111@196.222.3.4>;user=phone;tag=g2DFFpS0QN4cc Call-ID: 4252084844@192_168_1_65 CSeq: 3 ACK Contact: <sip:27878050000@41.242.6.167:56675> Proxy-Authorization: Digest username="27878050000", realm="196.222.3.4", qop=auth, algorithm=MD5, uri="sip:0835551111@196.222.3.4", nonce="0092f192-be45-11de-a2c1-a70e5037c20e", nc=00000001, cnonce="ab2d49987adb74997fc6b236fc16d23", response="445ae74c1f910e45d9aa1043402b4dbb" Max-Forwards: 70 User-Agent: C455 IP021910000000 Content-Length: 0 ------------------------------------------------------------------------ 2009-10-21 15:24:14.533538 [DEBUG] sofia.c:3493 Channel sofia/inetticky/27878050000@196.222.3.4 entering state [ready][200] 2009-10-21 15:24:14.545161 [DEBUG] switch_core_session.c:712 Send signal sofia/vvrf/27835551111 [BREAK] 2009-10-21 15:24:14.545161 [DEBUG] switch_core_session.c:712 Send signal sofia/inetticky/27878050000@196.222.3.4 [BREAK] 2009-10-21 15:24:14.565231 [DEBUG] switch_ivr_bridge.c:122 sofia/vvrf/27835551111 receive message [DISPLAY] 2009-10-21 15:24:14.565231 [DEBUG] switch_ivr_bridge.c:122 sofia/inetticky/27878050000@196.222.3.4 receive message [DISPLAY] send 947 bytes to udp/[196.10.11.12]:5060 at 13:24:14.727678: ------------------------------------------------------------------------ UPDATE sip:196.10.11.12:5060 SIP/2.0 Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bK0907gKScctXac Max-Forwards: 70 From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12>;tag=GR52RWG346-34 Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947387 UPDATE Contact: <sip:gw+vprov@10.17.10.10:5060;transport=udp;gw=vprov> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY Supported: timer, precondition, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 247 P-Asserted-Identity: "unknown" <27835551111> v=0 o=FreeSWITCH 1256118582 1256118583 IN IP4 10.17.10.10 s=FreeSWITCH c=IN IP4 10.17.10.10 t=0 0 m=audio 12862 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ send 947 bytes to udp/[196.10.11.12]:5060 at 13:24:15.728255: ------------------------------------------------------------------------ UPDATE sip:196.10.11.12:5060 SIP/2.0 Via: SIP/2.0/UDP 10.17.10.10;rport;branch=z9hG4bK0907gKScctXac Max-Forwards: 70 From: "Keith PhoneADSL" <sip:878050000@10.17.10.10>;tag=Upa3NvXpBB1eF To: <sip:27835551111@196.10.11.12>;tag=GR52RWG346-34 Call-ID: d821359d-38e7-122d-a38e-002264cc9b93 CSeq: 121947387 UPDATE Contact: <sip:gw+vprov@10.17.10.10:5060;transport=udp;gw=vprov> User-Agent: FreeSWITCH-mod_sofia/1.0.trunk-15183M Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, REGISTER, REFER, UPDATE, NOTIFY Supported: timer, precondition, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 247 P-Asserted-Identity: "unknown" <27835551111> v=0 o=FreeSWITCH 1256118582 1256118583 IN IP4 10.17.10.10 s=FreeSWITCH c=IN IP4 10.17.10.10 t=0 0 m=audio 12862 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 [Many more copies of the UPDATE's continue ... then ] 2009-10-21 15:24:46.228024 [DEBUG] sofia.c:3493 Channel sofia/vvrf/27835551111 entering state [terminated][408] 2009-10-21 15:24:46.228024 [NOTICE] sofia.c:4039 Hangup sofia/vvrf/27835551111 [CS_EXCHANGE_MEDIA] [RECOVERY_ON_TIMER_EXPIRE] 2009-10-21 15:24:46.228024 [DEBUG] switch_channel.c:1896 Send signal sofia/vvrf/27835551111 [KILL]