-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 Hi, here it is: (I dialed 2850 on softphone)
This is how it should work but that totally depends on config. can you provide that also?
sofia config: <configuration name="sofia.conf" description="sofia Endpoint"> <profiles> <profile name="sip"> <!-- EDIT: You will need to uncomment one of the following SIP provider --> <!-- registrations and configure your authentication data as supplied --> <!-- by your provider. The following are examples only. --> <!-- Sample configuration for Asterlink. www.asterlink.com --> <registrations> <registration name="EWE TEL"> <param name="register-scheme" value="Digest"/> <param name="register-realm" value="ewetel.de"/> <param name="register-username" value="032223930016"/> <param name="register-password" value="xxxxxx"/> <param name="register-from" value="sip:032223930016@sip01.voice.ewetel.de"/> <param name="register-to" value="sip:032223930016@sip01.voice.ewetel.de"/> <param name="register-proxy" value="sip:sip01.voice.ewetel.de:5060"/> <param name="register-frequency" value="600"/> </registration> </registrations> <settings> <param name="debug" value="1"/> <param name="rfc2833-pt" value="101"/> <param name="sip-port" value="5060"/> <param name="dialplan" value="XML"/> <param name="dtmf-duration" value="100"/> <param name="codec-prefs" value="PCMU,speex"/> <param name="codec-ms" value="20"/> <param name="use-rtp-timer" value="true"/> <param name="rtp-timer-name" value="soft"/> <param name="manage-presence" value="true"/> <!-- EDIT: If you want the destination address to be the full sip URI --> <!-- dialed (123@abc.org) instead of just 123, uncomment the following line --> param name="full-id-in-dialplan" value="true"/> <!-- EDIT: The settings below need to be set to your IP address --> <!-- If you are using a NAT these must be the internal IP on your server --> <param name="rtp-ip" value="sip0.en.ewetel.de"/> <param name="sip-ip" value="sip0.en.ewetel.de"/> <!-- EDIT: The setting below lets any phone register with your server. --> <!-- This may be convenient for testing but may pose a security risk if --> <!-- your server is accessable from the public internet --> <!-- comment the next line and uncomment one or both of the other 2 lines for call authentication --> <param name="accept-blind-reg" value="true"/> <param name="auth-calls" value="false"/> <!-- on authed calls, authenticate *all* the packets not just invite --> <!--<param name="auth-all-packets" value="true"/>--> <!-- optional ; --> <!-- <param name="ext-rtp-ip" value="stun:stun.server.com"/>--> <!-- <param name="ext-rtp-ip" value="100.101.102.103"/> --> <!-- VAD choose one (out is a good choice); --> <!-- <param name="vad" value="in"/> --> <!-- <param name="vad" value="out"/> --> <!-- <param name="vad" value="both"/> --> <!--<param name="alias" value="sip:10.0.1.251:5555"/>--> </settings> </profile> </profiles> </configuration> - ------------------------------------------------------------------------------------------------ Dialplan extract: <!-- Dial any 7 digit number (2850) as 10 digit dialing but pass to a local itsp--> <extension name="Local Dial"> <!-- <condition field="destination_number" expression="^([0-9]{7})$">--> <condition field="destination_number" expression="^2850$"> <!-- Set your outgoing caller ID name here --> <action application="set" data="effective_caller_id_name=032223930016"/> <!-- Your SIP provider probably expects a phone number or username for this setting. It is used in the from URL phonenumber@mysipprovider.com --> <action application="set" data="effective_caller_id_number=101"/> <!-- If your provider does not provide ringback (180 or 183) you may simulateringback by uncommenting the following line. --> <!--action application="ringback"/>--> <!-- Call the bridge app. Set data to endpoint/profile/[Areacode]$1@mysipprovider.com --> <!-- Note: the area code is getting prepended to the dialstring variable $1 --> <!-- EDIT: Change the area code and domain of your SIP provider. --> <action application="bridge" data="sofia/sip/044180002850@sip01.voice.ewetel.de"/> </condition> </extension> - ---------------------------------------------------------------------------------
Can you provide log output so we can see what is going on.
LOG: - ------------------------------------------------------- SecRECV[ ] tport_wakeup_pri(0x80abbd0): events IN tport_recv_event(0x80abbd0) tport_recv_iovec(0x80abbd0) msg 0x80ae178 from (udp/212.6.123.118:5060) has 997 bytes, veclen = 1 recv 997 bytes from udp/[212.6.95.198]:9330 at 10:09:39.985008: ------------------------------------------------------------------------ PUBLISH sip:101@sip0.en.ewetel.de SIP/2.0 Via: SIP/2.0/UDP 212.6.95.198:9330;branch=z9hG4bK-d87543-62137a1b22287041-1--d87543-;rport Max-Forwards: 70 Contact: <sip:101@212.6.95.198:9330> To: "101"<sip:101@sip0.en.ewetel.de> From: "101"<sip:101@sip0.en.ewetel.de>;tag=6163fc3b Call-ID: NzEwZTExYzBkMDJmZjE5ZDgxNzhlNTJjMjYzMDc0M2Q. CSeq: 1 PUBLISH Expires: 60 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/pidf+xml User-Agent: X-Lite release 1006e stamp 34025 Event: presence Content-Length: 425 <?xml version='1.0' encoding='UTF-8'?><presence xmlns='urn:ietf:params:xml:ns:pidf' xmlns:dm='urn:ietf:params:xml:ns:pidf:data-model' xmlns:rpid='urn:ietf:params:xml:ns:pidf:rpid' xmlns:c='urn:ietf:params:xml:ns:pidf:cipid' entity='pres:101@sip0.en.ewetel.de'><tuple id='t84368671'><status><basic>open</basic></status></tuple><dm:person id='p670b1859'><rpid:activities><rpid:unknown/></rpid:activities></dm:person></presence> ------------------------------------------------------------------------ tport_deliver(0x80abbd0): msg 0x80ae178 (997 bytes) from udp/212.6.95.198:5060/sip next=(nil) nta: received PUBLISH sip:101@sip0.en.ewetel.de SIP/2.0 (CSeq 1) nta: canonizing sip:101@sip0.en.ewetel.de with contact nta: PUBLISH (1) going to a default leg nua: nua_stack_process_request: entering nua: nh_create: entering nua: nh_create_handle: entering nua: nua_stack_set_params: entering soa_clone(static::0x809d4d0, 0x809b690, 0x80d1160) called soa_set_params(static::0x80af000, ...) called soa_set_params(static::0x80af000, ...) called nua: nua_stack_event: entering nua(0x80d1160): i_publish 100 Trying nua: nua_event: entering nua: nua_respond: entering nua(0x80d1160): signal r_respond 200 OK tport_tsend(0x80abbd0) tpn = UDP/212.6.95.198:9330 tport_resolve addrinfo = 212.6.95.198:9330 tport_by_addrinfo(0x80abbd0): not found by name UDP/212.6.95.198:9330 tport_vsend(0x80abbd0): 541 bytes of 541 to UDP/212.6.95.198:9330 tport_vsend returned 541 send 541 bytes to udp/[212.6.95.198]:9330 at 10:09:39.988395: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 212.6.95.198:9330;branch=z9hG4bK-d87543-62137a1b22287041-1--d87543-;rport=9330 From: "101"<sip:101@sip0.en.ewetel.de>;tag=6163fc3b To: "101" <sip:101@sip0.en.ewetel.de>;tag=raFDate1Qa2Zj Call-ID: NzEwZTExYzBkMDJmZjE5ZDgxNzhlNTJjMjYzMDc0M2Q. CSeq: 1 PUBLISH User-Agent: FreeSWITCH(mod_sofia) Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: 100rel, precondition Allow-Events: presence, presence.winfo Content-Length: 0 ------------------------------------------------------------------------ nta: sent 200 OK for PUBLISH (1) tport_wakeup_pri(0x80abbd0): events IN tport_recv_event(0x80abbd0) tport_recv_iovec(0x80abbd0) msg 0x80afd20 from (udp/212.6.123.118:5060) has 813 bytes, veclen = 1 recv 813 bytes from udp/[212.6.95.198]:9330 at 10:09:40.090389: ------------------------------------------------------------------------ INVITE sip:2850@sip0.en.ewetel.de SIP/2.0 Via: SIP/2.0/UDP 212.6.95.198:9330;branch=z9hG4bK-d87543-a87fcd2a9168d35c-1--d87543-;rport Max-Forwards: 70 Contact: <sip:101@212.6.95.198:9330> To: "2850"<sip:2850@sip0.en.ewetel.de> From: "101"<sip:101@sip0.en.ewetel.de>;tag=a6275625 Call-ID: YmI1MGY0ODQ1ZjYzZWU4ODI5YWNhOGViY2VmM2RiYTg. CSeq: 1 INVITE Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO Content-Type: application/sdp User-Agent: X-Lite release 1006e stamp 34025 Content-Length: 275 v=0 o=- 6 2 IN IP4 212.6.95.198 s=CounterPath X-Lite 3.0 c=IN IP4 212.6.95.198 t=0 0 m=audio 54092 RTP/AVP 107 119 0 98 8 3 101 a=fmtp:101 0-15 a=rtpmap:107 BV32/16000 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ tport_deliver(0x80abbd0): msg 0x80afd20 (813 bytes) from udp/212.6.95.198:5060/sip next=(nil) nta: received INVITE sip:2850@sip0.en.ewetel.de SIP/2.0 (CSeq 1) nta: canonizing sip:2850@sip0.en.ewetel.de with contact nta: INVITE (1) going to a default leg nua: nua_stack_process_request: entering nua: nh_create: entering nua: nh_create_handle: entering nua: nua_stack_set_params: entering soa_clone(static::0x809d4d0, 0x809b690, 0x80a6ba8) called soa_set_params(static::0x80b0088, ...) called soa_set_params(static::0x80b0088, ...) called nta_leg_create(0x80b0298) soa_init_offer_answer(static::0x80b0088) called soa_set_remote_sdp(static::0x80b0088, (nil), 0x80a2aea, 275) called nua(0x80a6ba8): adding session usage tport_tsend(0x80abbd0) tpn = UDP/212.6.95.198:9330 tport_resolve addrinfo = 212.6.95.198:9330 tport_by_addrinfo(0x80abbd0): not found by name UDP/212.6.95.198:9330 tport_vsend(0x80abbd0): 337 bytes of 337 to UDP/212.6.95.198:9330 tport_vsend returned 337 send 337 bytes to udp/[212.6.95.198]:9330 at 10:09:40.092277: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 212.6.95.198:9330;branch=z9hG4bK-d87543-a87fcd2a9168d35c-1--d87543-;rport=9330 From: "101"<sip:101@sip0.en.ewetel.de>;tag=a6275625 To: "2850"<sip:2850@sip0.en.ewetel.de> Call-ID: YmI1MGY0ODQ1ZjYzZWU4ODI5YWNhOGViY2VmM2RiYTg. CSeq: 1 INVITE User-Agent: FreeSWITCH(mod_sofia) Content-Length: 0 ------------------------------------------------------------------------ nta: sent 100 Trying for INVITE (1) nua: nua_stack_event: entering nua(0x80a6ba8): i_invite 100 Trying nua(0x80a6ba8): call state changed: init -> received, received offer soa_get_remote_sdp(static::0x80b0088, [0x40983c08], [0x40983c04], [(nil)]) called nua: nua_stack_event: entering nua(0x80a6ba8): i_state 100 Trying nua: nua_event: entering nua: nua_handle_bind: entering nua: nua_event: entering 2007-02-28 11:09:40 [NOTICE] switch_channel.c:347 switch_channel_set_name() New Chan sofia/sip/101@sip0.en.ewetel.de:5060 [cd5789d0-c713-11db-8be0-6116055f8452] 2007-02-28 11:09:40 [WARNING] mod_sofia.c:680 find_reg_url() Cannot locate registered user 101@sip0.en.ewetel.de 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2815 sip_i_state() Channel sofia/sip/101@sip0.en.ewetel.de:5060 entering state [received] 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2820 sip_i_state() Remote SDP: v=0 o=- 6 2 IN IP4 212.6.95.198 s=CounterPath X-Lite 3.0 c=IN IP4 212.6.95.198 t=0 0 m=audio 54092 RTP/AVP 107 119 0 98 8 3 101 a=rtpmap:107 BV32/16000 a=rtpmap:119 BV32-FEC/16000 a=rtpmap:98 iLBC/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2420 negotiate_sdp() Codec Compare [BV32:107]/[PCMU:0] 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2420 negotiate_sdp() Codec Compare [BV32-FEC:119]/[PCMU:0] 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2420 negotiate_sdp() Codec Compare [PCMU:0]/[PCMU:0] 2007-02-28 11:09:40 [INFO] mod_sofia.c:1483 tech_set_codec() Set Codec sofia/sip/101@sip0.en.ewetel.de:5060 PCMU/8000 20 ms 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2924 sip_i_state() sofia/sip/101@sip0.en.ewetel.de:5060 State Change CS_NEW -> CS_INIT 2007-02-28 11:09:40 [DEBUG] switch_core.c:3225 switch_core_session_run() (sofia/sip/101@sip0.en.ewetel.de:5060) State INIT 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1226 sofia_on_init() SOFIA INIT 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1237 sofia_on_init() sofia/sip/101@sip0.en.ewetel.de:5060 State Change CS_INIT -> CS_RING 2007-02-28 11:09:40 [DEBUG] switch_core.c:3261 switch_core_session_run() (sofia/sip/101@sip0.en.ewetel.de:5060) State RING 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1252 sofia_on_ring() SOFIA RING 2007-02-28 11:09:40 [DEBUG] switch_core.c:2890 switch_core_standard_on_ring() Standard RING sofia/sip/101@sip0.en.ewetel.de:5060 2007-02-28 11:09:40 [INFO] mod_dialplan_xml.c:219 dialplan_hunt() Processing 101->2850! 2007-02-28 11:09:40 [DEBUG] mod_dialplan_xml.c:120 parse_exten() test conditions destination_number(2850) =~ /(.*)/ 2007-02-28 11:09:40 [DEBUG] mod_dialplan_xml.c:120 parse_exten() test conditions destination_number(2850) =~ /^(1[0-9]{2})$/ 2007-02-28 11:09:40 [DEBUG] mod_dialplan_xml.c:122 parse_exten() Regex mismatch 2007-02-28 11:09:40 [DEBUG] mod_dialplan_xml.c:120 parse_exten() test conditions destination_number(2850) =~ /^2850$/ 2007-02-28 11:09:40 [DEBUG] mod_dialplan_xml.c:355 dialplan_hunt() sofia/sip/101@sip0.en.ewetel.de:5060 State Change CS_RING -> CS_EXECUTE 2007-02-28 11:09:40 [DEBUG] switch_core.c:3297 switch_core_session_run() (sofia/sip/101@sip0.en.ewetel.de:5060) State EXECUTE 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1269 sofia_on_execute() SOFIA EXECUTE 2007-02-28 11:09:40 [DEBUG] switch_core.c:2949 switch_core_standard_on_execute() Standard EXECUTE 2007-02-28 11:09:40 [NOTICE] switch_core.c:2959 switch_core_standard_on_execute() Execute info() 2007-02-28 11:09:40 [CRIT] mod_dptools.c:302 info_function() CHANNEL_DATA: Channel-State: CS_EXECUTE Channel-State-Number: 4 Channel-Name: sofia/sip/101%40sip0.en.ewetel.de%3A5060 Unique-ID: cd5789d0-c713-11db-8be0-6116055f8452 Channel-Read-Codec-Name: PCMU Channel-Read-Codec-Rate: 8000 Channel-Write-Codec-Name: PCMU Channel-Write-Codec-Rate: 8000 Caller-Username: 101 Caller-Dialplan: XML Caller-Caller-ID-Name: 101 Caller-Caller-ID-Number: 101 Caller-Network-Addr: 212.6.95.198 Caller-Destination-Number: 2850 Caller-Unique-ID: cd5789d0-c713-11db-8be0-6116055f8452 Caller-Source: mod_sofia Caller-Context: default Caller-Channel-Name: sofia/sip/101%40sip0.en.ewetel.de%3A5060 Caller-Screen-Bit: yes Caller-Privacy-Hide-Name: no Caller-Privacy-Hide-Number: no variable_"sip_from"_host: sip0.en.ewetel.de variable_"sip_to"_uri: 2850%40sip0.en.ewetel.de%3A5060 variable_"sip_from"_user: 101 variable_sip_via_rport: 9330 variable_remote_media_ip: 212.6.95.198 variable_"sip_req"_uri: 2850%40sip0.en.ewetel.de%3A5060 variable_sip_from_user_stripped: 101 variable_"sip_from"_port: 5060 variable_sip_via_host: 212.6.95.198 variable_switch_r_sdp: v%3D0%0D%0Ao%3D-%206%202%20IN%20IP4%20212.6.95.198%0D%0As%3DCounterPath%20X-Lite%203.0%0D%0Ac%3DIN%20IP4%20212.6.95.198%0D%0At%3D0%200%0D%0Am%3Daudio%2054092%20RTP/AVP%20107%20119%200%2098%208%203%20101%0D%0Aa%3Drtpmap%3A107%20BV32/16000%0D%0Aa%3Drtpmap%3A119%20BV32-FEC/16000%0D%0Aa%3Drtpmap%3A98%20iLBC/8000%0D%0Aa%3Drtpmap%3A101%20telephone-event/8000%0D%0Aa%3Dfmtp%3A101%200-15%0D%0A variable_"sip_contact"_uri: 101%40212.6.95.198%3A9330 variable_max_forwards: 70 variable_"sip_to"_host: sip0.en.ewetel.de variable_sip_via_port: 9330 variable_remote_media_port: 54092 variable_"sip_to"_user: 2850 variable_"sip_from"_uri: 101%40sip0.en.ewetel.de%3A5060 variable_sip_call_id: YmI1MGY0ODQ1ZjYzZWU4ODI5YWNhOGViY2VmM2RiYTg. variable_"sip_req"_host: sip0.en.ewetel.de variable_"sip_req"_user: 2850 variable_endpoint_disposition: RECEIVED variable_"sip_to"_port: 5060 variable_"sip_contact"_host: 212.6.95.198 variable_"sip_contact"_user: 101 variable_"sip_req"_port: 5060 variable_"sip_contact"_port: 9330 2007-02-28 11:09:40 [NOTICE] switch_core.c:2959 switch_core_standard_on_execute() Execute set(effective_caller_id_name=032223930016) 2007-02-28 11:09:40 [DEBUG] mod_dptools.c:208 set_function() SET [effective_caller_id_name]=[032223930016] 2007-02-28 11:09:40 [NOTICE] switch_core.c:2959 switch_core_standard_on_execute() Execute set(effective_caller_id_number=101) 2007-02-28 11:09:40 [DEBUG] mod_dptools.c:208 set_function() SET [effective_caller_id_number]=[101] 2007-02-28 11:09:40 [NOTICE] switch_core.c:2959 switch_core_standard_on_execute() Execute bridge(sofia/sip/044180002850@sip01.voice.ewetel.de) 2007-02-28 11:09:40 [NOTICE] switch_channel.c:347 switch_channel_set_name() New Chan sofia/sip/044180002850@sip01.voice.ewetel.de [cd59a13e-c713-11db-8be0-6116055f8452] 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2323 sofia_outgoing_channel() sofia/sip/044180002850@sip01.voice.ewetel.de State Change CS_NEW -> CS_INIT 2007-02-28 11:09:40 [DEBUG] switch_core.c:3225 switch_core_session_run() (sofia/sip/044180002850@sip01.voice.ewetel.de) State INIT 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1226 sofia_on_init() SOFIA INIT nua: nh_create_handle: entering nua: nua_handle_bind: entering nua: nua_invite: entering nua(0x80dbc10): signal r_invite nua: nua_stack_set_params: entering soa_clone(static::0x809d4d0, 0x809b690, 0x80dbc10) called soa_set_params(static::0x80da7a8, ...) called soa_set_user_sdp(static::0x80da7a8, (nil), 0x80d4d50, -1) called soa_set_capability_sdp(static::0x80da7a8, (nil), 0x80d4d50, -1) called soa_set_params(static::0x80da7a8, ...) called soa_set_user_sdp(static::0x80da7a8, (nil), 0x80d4d50, -1) called nua(0x80dbc10): adding session usage nta_leg_create(0x80df188) soa_init_offer_answer(static::0x80da7a8) called soa_generate_offer(static::0x80da7a8, 0, (nil)) called soa_static_offer_answer_action(0x80da7a8, soa_generate_offer): called soa_static(0x80da7a8, soa_generate_offer): generating local description soa_static(0x80da7a8, soa_generate_offer): upgrade with local description soa_sdp_mode_set_is_needed(0x4098410c, (nil), ""): called soa_static(0x80da7a8, soa_generate_offer): storing local description nua(): refresh session after 66 seconds (in [60..90]) soa_get_local_sdp(static::0x80da7a8, [(nil)], [0x409841ac], [0x409841a8]) called nta: selecting scheme sip sres_cache_get(0x809c6d8, NAPTR, "sip01.voice.ewetel.de.") called nta: for "sip01.voice.ewetel.de" query "sip01.voice.ewetel.de" NAPTR sres_query(0x809d420, 0x40b68bb4, 0x80e99b0, NAPTR, "sip01.voice.ewetel.de") called sres_send_dns_query(0x809d420, 0x80e9a68) called sres_send_dns_query(0x809d420, 0x80e9a68) id=30815 NAPTR sip01.voice.ewetel.de (to [192.168.8.193]:53) nua(0x80dbc10): call state changed: init -> calling, sent offer soa_get_local_sdp(static::0x80da7a8, [0x409841e0], [0x409841dc], [(nil)]) called nua: nua_stack_event: entering nua(0x80dbc10): i_state INVITE sent nua: nua_event: entering sres_resolver_receive(0x809d420, 7) called RR received ewetel.de. SOA IN 5113 rdlen=39 sres(q=0x80e9a68): reporting errors for 35 sip01.voice.ewetel.de sres_cache_get(0x809c6d8, SRV, "_sip._udp.sip01.voice.ewetel.de.") called nta: for "sip01.voice.ewetel.de" query "_sip._udp.sip01.voice.ewetel.de" SRV sres_query(0x809d420, 0x40b69194, 0x80e99b0, SRV, "_sip._udp.sip01.voice.ewetel.de") called sres_send_dns_query(0x809d420, 0x80e8930) called sres_send_dns_query(0x809d420, 0x80e8930) id=30816 SRV _sip._udp.sip01.voice.ewetel.de (to [192.168.8.193]:53) 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:4715 event_callback() event [nua_i_state] status [0][INVITE sent] session: sofia/sip/044180002850@sip01.voice.ewetel.de sres_resolver_receive(0x809d420, 7) called RR received ewetel.de. SOA IN 5113 rdlen=39 sres(q=0x80e8930): reporting errors for 33 _sip._udp.sip01.voice.ewetel.de sres_cache_get(0x809c6d8, SRV, "_sip._tcp.sip01.voice.ewetel.de.") called nta: for "sip01.voice.ewetel.de" query "_sip._tcp.sip01.voice.ewetel.de" SRV sres_query(0x809d420, 0x40b69194, 0x80e99b0, SRV, "_sip._tcp.sip01.voice.ewetel.de") called sres_send_dns_query(0x809d420, 0x80d4e48) called sres_send_dns_query(0x809d420, 0x80d4e48) id=30817 SRV _sip._tcp.sip01.voice.ewetel.de (to [192.168.8.193]:53) 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2815 sip_i_state() Channel sofia/sip/044180002850@sip01.voice.ewetel.de entering state [calling] sres_resolver_receive(0x809d420, 7) called RR received ewetel.de. SOA IN 5113 rdlen=39 sres(q=0x80d4e48): reporting errors for 33 _sip._tcp.sip01.voice.ewetel.de sres_cache_get(0x809c6d8, A, "sip01.voice.ewetel.de.") called rr found in cache: sip01.voice.ewetel.de. 01 sres_cache_get(0x809c6d8, A, "sip01.voice.ewetel.de.") returned 1 entries nta: for "sip01.voice.ewetel.de" query "sip01.voice.ewetel.de" A (cached) nta: sip01.voice.ewetel.de. IN A 213.168.198.84 tport(0x80abbd0): EXPENSIVE unresolved */213.168.198.84: tport_tsend(0x80abbd0) tpn = */213.168.198.84:5060 tport_resolve addrinfo = 213.168.198.84:5060 tport_by_addrinfo(0x80abbd0): not found by name */213.168.198.84:5060 tport_vsend(0x80abbd0): 1001 bytes of 1001 to */213.168.198.84:5060 tport_vsend returned 1001 send 1001 bytes to udp/[213.168.198.84]:5060 at 10:09:40.113177: ------------------------------------------------------------------------ INVITE sip:044180002850@sip01.voice.ewetel.de SIP/2.0 Via: SIP/2.0/UDP sip0.en.ewetel.de;rport;branch=z9hG4bKSg09vUrc0XjNK Max-Forwards: 69 From: "032223930016" <sip:101@sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S To: <sip:044180002850@sip01.voice.ewetel.de> Call-ID: a4b859ca-41b6-122a-5dbb-005056947ed9 CSeq: 80210354 INVITE Contact: <sip:mod_sofia@sip0.en.ewetel.de:5060> User-Agent: FreeSWITCH(mod_sofia) Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: 100rel, precondition Content-Type: application/sdp Content-Disposition: session Content-Length: 258 Remote-Party-ID: "032223930016" <sip:101@sip0.en.ewetel.de>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 9158490845426534834 2884113177161000930 IN IP4 212.6.123.118 s=FreeSWITCH c=IN IP4 sip0.en.ewetel.de t=0 0 a=sendrecv m=audio 16384 RTP/AVP 0 0 101 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ nta: sent INVITE (80210354) to */213.168.198.84:5060 tport_pend(0x80abbd0): pending 0x80e8740 for udp/212.6.123.118:5060 (already 0) 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1237 sofia_on_init() sofia/sip/044180002850@sip01.voice.ewetel.de State Change CS_INIT -> CS_RING tport_wakeup_pri(0x80abbd0): events IN tport_recv_event(0x80abbd0) tport_recv_iovec(0x80abbd0) msg 0x80ea7e0 from (udp/212.6.123.118:5060) has 304 bytes, veclen = 1 recv 304 bytes from udp/[213.168.198.84]:5060 at 10:09:40.116247: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP sip0.en.ewetel.de;received=212.6.123.118;branch=z9hG4bKSg09vUrc0XjNK;rport=5060 From: "032223930016" <sip:101@sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S To: <sip:044180002850@sip01.voice.ewetel.de> Call-ID: a4b859ca-41b6-122a-5dbb-005056947ed9 CSeq: 80210354 INVITE ------------------------------------------------------------------------ tport_deliver(0x80abbd0): msg 0x80ea7e0 (304 bytes) from udp/213.168.198.84:5060/sip next=(nil) nta: received 100 Trying for INVITE (80210354) nta: 100 Trying going to a transaction nta_outgoing: RTT is 3.632 ms tport_release(0x80abbd0): 0x80e8740 by 0x80e99b0 with 0x80ea7e0 (preliminary) 2007-02-28 11:09:40 [DEBUG] switch_core.c:3261 switch_core_session_run() (sofia/sip/044180002850@sip01.voice.ewetel.de) State RING 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1252 sofia_on_ring() SOFIA RING 2007-02-28 11:09:40 [DEBUG] switch_ivr.c:2189 originate_on_ring() sofia/sip/044180002850@sip01.voice.ewetel.de State Change CS_RING -> CS_HOLD tport_wakeup_pri(0x80abbd0): events IN tport_recv_event(0x80abbd0) tport_recv_iovec(0x80abbd0) msg 0x80eab88 from (udp/212.6.123.118:5060) has 432 bytes, veclen = 1 recv 432 bytes from udp/[213.168.198.84]:5060 at 10:09:40.118239: ------------------------------------------------------------------------ SIP/2.0 488 Invalid Session Description Via: SIP/2.0/UDP sip0.en.ewetel.de;received=212.6.123.118;branch=z9hG4bKSg09vUrc0XjNK;rport=5060 From: "032223930016" <sip:101@sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S To: <sip:044180002850@sip01.voice.ewetel.de>;tag=aprqngfrt-iei473g07eq5b Call-ID: a4b859ca-41b6-122a-5dbb-005056947ed9 CSeq: 80210354 INVITE Warning: 301 213.168.198.84 "invalid transport IP address" Content-Length: 0 ------------------------------------------------------------------------ tport_deliver(0x80abbd0): msg 0x80eab88 (432 bytes) from udp/213.168.198.84:5060/sip next=(nil) nta: received 488 Invalid Session Description for INVITE (80210354) nta: 488 Invalid Session Description going to a transaction tport_release(0x80abbd0): 0x80e8740 by 0x80e99b0 with 0x80eab88 tport(0x80abbd0): EXPENSIVE unresolved UDP/213.168.198.84:5060 tport_tsend(0x80abbd0) tpn = UDP/213.168.198.84:5060 tport_resolve addrinfo = 213.168.198.84:5060 tport_by_addrinfo(0x80abbd0): not found by name UDP/213.168.198.84:5060 tport_vsend(0x80abbd0): 370 bytes of 370 to UDP/213.168.198.84:5060 tport_vsend returned 370 send 370 bytes to udp/[213.168.198.84]:5060 at 10:09:40.118997: ------------------------------------------------------------------------ ACK sip:044180002850@sip01.voice.ewetel.de SIP/2.0 Via: SIP/2.0/UDP sip0.en.ewetel.de;rport;branch=z9hG4bKSg09vUrc0XjNK Max-Forwards: 70 From: "032223930016" <sip:101@sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S To: <sip:044180002850@sip01.voice.ewetel.de>;tag=aprqngfrt-iei473g07eq5b Call-ID: a4b859ca-41b6-122a-5dbb-005056947ed9 CSeq: 80210354 ACK Content-Length: 0 ------------------------------------------------------------------------ nta: sent ACK (80210354) to UDP/213.168.198.84:5060 nta: outgoing_free(0x80ebe80) nua: nua_stack_event: entering nua(0x80dbc10): r_invite 488 Invalid Session Description nua(0x80dbc10): call state changed: calling -> terminated nua: nua_stack_event: entering nua(0x80dbc10): i_state 488 Invalid Session Description nua: nua_stack_event: entering nua(0x80dbc10): i_terminated 488 Invalid Session Description nua(0x80dbc10): removing session usage nta_leg_destroy(0x80df188) nua: nua_event: entering nua: nua_event: entering 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:4715 event_callback() event [nua_r_invite] status [488][Invalid Session Description] session: sofia/sip/044180002850@sip01.voice.ewetel.de 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:4715 event_callback() event [nua_i_state] status [488][Invalid Session Description] session: sofia/sip/044180002850@sip01.voice.ewetel.de 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2815 sip_i_state() Channel sofia/sip/044180002850@sip01.voice.ewetel.de entering state [terminated] 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:883 terminate_session() Term called from line: 3084 nua: nua_handle_bind: entering nua: nua_handle_destroy: entering nua: nua_event: entering nua(0x80dbc10): signal r_destroy nua nh_destroy(0x80dbc10): entering soa_destroy(static::0x80da7a8) called 2007-02-28 11:09:40 [NOTICE] mod_sofia.c:893 terminate_session() Hangup sofia/sip/044180002850@sip01.voice.ewetel.de [CS_HOLD] [UNKNOWN] 2007-02-28 11:09:40 [INFO] switch_channel.c:992 switch_channel_perform_hangup() Kill sofia/sip/044180002850@sip01.voice.ewetel.de [1] 2007-02-28 11:09:40 [DEBUG] switch_core.c:3188 switch_core_session_run() (sofia/sip/044180002850@sip01.voice.ewetel.de) State HANGUP 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1349 sofia_on_hangup() Channel sofia/sip/044180002850@sip01.voice.ewetel.de hanging up, cause: UNKNOWN 2007-02-28 11:09:40 [DEBUG] switch_core.c:2880 switch_core_standard_on_hangup() Standard HANGUP sofia/sip/044180002850@sip01.voice.ewetel.de, cause: UNKNOWN 2007-02-28 11:09:40 [DEBUG] switch_core.c:3631 switch_core_session_thread() Session 2 (sofia/sip/044180002850@sip01.voice.ewetel.de) Locked, Waiting on external entities 2007-02-28 11:09:40 [DEBUG] switch_ivr.c:2841 switch_ivr_originate() Raw Codec Activation Success L16@8000hz 1 channel 20ms 2007-02-28 11:09:40 [DEBUG] switch_ivr.c:3108 switch_ivr_originate() Originate Resulted in Error Cause: 65 [UNKNOWN] 2007-02-28 11:09:40 [INFO] mod_bridgecall.c:74 audio_bridge_function() Originate Failed. Cause: UNKNOWN 2007-02-28 11:09:40 [NOTICE] mod_bridgecall.c:78 audio_bridge_function() Hangup sofia/sip/101@sip0.en.ewetel.de:5060 [CS_EXECUTE] [UNKNOWN] 2007-02-28 11:09:40 [INFO] switch_channel.c:992 switch_channel_perform_hangup() Kill sofia/sip/101@sip0.en.ewetel.de:5060 [1] 2007-02-28 11:09:40 [DEBUG] switch_core.c:3188 switch_core_session_run() (sofia/sip/101@sip0.en.ewetel.de:5060) State HANGUP 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1349 sofia_on_hangup() Channel sofia/sip/101@sip0.en.ewetel.de:5060 hanging up, cause: UNKNOWN 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1369 sofia_on_hangup() Responding to INVITE with: 488 nua: nua_respond: entering nua(0x80a6ba8): signal r_respond 488 nua: respond_to_invite: entering nua: nua_stack_set_params: entering soa_set_params(static::0x80b0088, ...) called soa_clear_remote_sdp(static::0x80b0088) called tport_tsend(0x80abbd0) tpn = UDP/212.6.95.198:9330 tport_resolve addrinfo = 212.6.95.198:9330 tport_by_addrinfo(0x80abbd0): not found by name UDP/212.6.95.198:9330 tport_vsend(0x80abbd0): 519 bytes of 519 to UDP/212.6.95.198:9330 tport_vsend returned 519 send 519 bytes to udp/[212.6.95.198]:9330 at 10:09:40.137641: ------------------------------------------------------------------------ SIP/2.0 488 Not Acceptable Here Via: SIP/2.0/UDP 212.6.95.198:9330;branch=z9hG4bK-d87543-a87fcd2a9168d35c-1--d87543-;rport=9330 From: "101"<sip:101@sip0.en.ewetel.de>;tag=a6275625 To: "2850" <sip:2850@sip0.en.ewetel.de>;tag=SK85BNZ4mKrje Call-ID: YmI1MGY0ODQ1ZjYzZWU4ODI5YWNhOGViY2VmM2RiYTg. CSeq: 1 INVITE User-Agent: FreeSWITCH(mod_sofia) Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: 100rel, precondition Content-Length: 0 ------------------------------------------------------------------------ nta: sent 488 Not Acceptable Here for INVITE (1) nua(0x80a6ba8): call state changed: received -> init nua: nua_stack_event: entering nua(0x80a6ba8): i_state 488 Call state nua: nua_stack_event: entering nua(0x80a6ba8): i_terminated 488 Call state soa_init_offer_answer(static::0x80b0088) called soa_destroy(static::0x80b0088) called nua(0x80a6ba8): removing session usage nta_leg_destroy(0x80b0298) nua: terminated session 0x80a6ba8 nua: nua_event: entering nua: nua_handle_bind: entering nua: nua_handle_destroy: entering nua: nua_event: entering nua(0x80a6ba8): signal r_destroy nua nh_destroy(0x80a6ba8): entering 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:4715 event_callback() event [nua_i_state] status [488][Call state] session: sofia/sip/101@sip0.en.ewetel.de:5060 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2815 sip_i_state() Channel sofia/sip/101@sip0.en.ewetel.de:5060 entering state [terminated] 2007-02-28 11:09:40 [DEBUG] mod_sofia.c:883 terminate_session() Term called from line: 3084 tport_wakeup_pri(0x80abbd0): events IN tport_recv_event(0x80abbd0) tport_recv_iovec(0x80abbd0) msg 0x80b0088 from (udp/212.6.123.118:5060) has 333 bytes, veclen = 1 recv 333 bytes from udp/[212.6.95.198]:9330 at 10:09:40.140328: ------------------------------------------------------------------------ ACK sip:2850@sip0.en.ewetel.de SIP/2.0 Via: SIP/2.0/UDP 212.6.95.198:9330;branch=z9hG4bK-d87543-a87fcd2a9168d35c-1--d87543-;rport To: "2850" <sip:2850@sip0.en.ewetel.de>;tag=SK85BNZ4mKrje From: "101"<sip:101@sip0.en.ewetel.de>;tag=a6275625 Call-ID: YmI1MGY0ODQ1ZjYzZWU4ODI5YWNhOGViY2VmM2RiYTg. CSeq: 1 ACK Content-Length: 0 ------------------------------------------------------------------------ tport_deliver(0x80abbd0): msg 0x80b0088 (333 bytes) from udp/212.6.95.198:5060/sip next=(nil) nta: received ACK sip:2850@sip0.en.ewetel.de SIP/2.0 (CSeq 1) nta: ACK (1) is going to INVITE (1) 2007-02-28 11:09:40 [DEBUG] switch_core.c:2880 switch_core_standard_on_hangup() Standard HANGUP sofia/sip/101@sip0.en.ewetel.de:5060, cause: UNKNOWN 2007-02-28 11:09:40 [DEBUG] switch_core.c:3631 switch_core_session_thread() Session 1 (sofia/sip/101@sip0.en.ewetel.de:5060) Locked, Waiting on external entities 2007-02-28 11:09:40 [INFO] switch_core.c:3636 switch_core_session_thread() Session 1 (sofia/sip/101@sip0.en.ewetel.de:5060) Ended 2007-02-28 11:09:40 [NOTICE] switch_core.c:3512 switch_core_session_destroy() Close Channel sofia/sip/101@sip0.en.ewetel.de:5060 2007-02-28 11:09:40 [INFO] switch_core.c:3636 switch_core_session_thread() Session 2 (sofia/sip/044180002850@sip01.voice.ewetel.de) Ended 2007-02-28 11:09:40 [NOTICE] switch_core.c:3512 switch_core_session_destroy() Close Channel sofia/sip/044180002850@sip01.voice.ewetel.de - ---------------------------------------------------------------------------------------------------------
It already does what you want where its totally in the middle.
Nice to know! regards helmut -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.5 (MingW32) Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org iD8DBQFF5Va64tZeNddg3dwRAqzEAJ4yojq7DQC2WrBljZ8S0lsJuW5IKQCdEN/e grchlyd3k1lGsN17YPkDK3U= =Yl31 -----END PGP SIGNATURE-----