[Freeswitch-users] Freeswitch as b2bua

Helmut Kuper helmut.kuper at ewetel.de
Wed Feb 28 02:17:33 PST 2007


-----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 at sip01.voice.ewetel.de"/>
                <param name="register-to"
value="sip:032223930016 at 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 at 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 at 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 at 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 at 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 at 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 at 212.6.95.198:9330>
   To: "101"<sip:101 at sip0.en.ewetel.de>
   From: "101"<sip:101 at 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 at 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 at sip0.en.ewetel.de SIP/2.0 (CSeq 1)
nta: canonizing sip:101 at 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 at sip0.en.ewetel.de>;tag=6163fc3b
   To: "101" <sip:101 at 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 at 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 at 212.6.95.198:9330>
   To: "2850"<sip:2850 at sip0.en.ewetel.de>
   From: "101"<sip:101 at 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 at sip0.en.ewetel.de SIP/2.0 (CSeq 1)
nta: canonizing sip:2850 at 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 at sip0.en.ewetel.de>;tag=a6275625
   To: "2850"<sip:2850 at 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 at 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 at sip0.en.ewetel.de
2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2815 sip_i_state() Channel
sofia/sip/101 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at sip01.voice.ewetel.de)
2007-02-28 11:09:40 [NOTICE] switch_channel.c:347
switch_channel_set_name() New Chan
sofia/sip/044180002850 at 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 at 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 at 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 at 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 at 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 at 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 at sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S
   To: <sip:044180002850 at sip01.voice.ewetel.de>
   Call-ID: a4b859ca-41b6-122a-5dbb-005056947ed9
   CSeq: 80210354 INVITE
   Contact: <sip:mod_sofia at 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 at 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 at 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 at sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S
   To: <sip:044180002850 at 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 at 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 at 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 at sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S
   To: <sip:044180002850 at 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 at 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 at sip0.en.ewetel.de>;tag=tv1yDgg8Hve5S
   To: <sip:044180002850 at 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 at 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 at sip01.voice.ewetel.de
2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2815 sip_i_state() Channel
sofia/sip/044180002850 at 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 at 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 at sip01.voice.ewetel.de [1]
2007-02-28 11:09:40 [DEBUG] switch_core.c:3188 switch_core_session_run()
(sofia/sip/044180002850 at sip01.voice.ewetel.de) State HANGUP
2007-02-28 11:09:40 [DEBUG] mod_sofia.c:1349 sofia_on_hangup() Channel
sofia/sip/044180002850 at 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 at 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 at 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 at 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 at 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 at sip0.en.ewetel.de:5060 [1]
2007-02-28 11:09:40 [DEBUG] switch_core.c:3188 switch_core_session_run()
(sofia/sip/101 at 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 at 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 at sip0.en.ewetel.de>;tag=a6275625
   To: "2850" <sip:2850 at 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 at sip0.en.ewetel.de:5060
2007-02-28 11:09:40 [DEBUG] mod_sofia.c:2815 sip_i_state() Channel
sofia/sip/101 at 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 at 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 at sip0.en.ewetel.de>;tag=SK85BNZ4mKrje
   From: "101"<sip:101 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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-----




More information about the FreeSWITCH-users mailing list