[Freeswitch-users] origainate through sofia gateway
Jacek Sokulski
jsokulski at dotsystems.pl
Thu Feb 5 05:09:15 PST 2009
the result of
apiExecute("bgapi", "originate
{effective_caller_id_number=fixed0248b,origination_caller_id_number=1000,ignore_early_media=true}user/1008 at 192.168.1.122 bridge:sofia/gateway/halonet/0225490317 inline");
> /:
> 2009-02-05 14:04:48 [DEBUG] switch_ivr_originate.c:777 switch_ivr_originate() variable string 0 = [effective_caller_id_number=fixed0248b]
> 2009-02-05 14:04:48 [DEBUG] switch_ivr_originate.c:777 switch_ivr_originate() variable string 1 = [origination_caller_id_number=1000]
> 2009-02-05 14:04:48 [DEBUG] switch_ivr_originate.c:777 switch_ivr_originate() variable string 2 = [ignore_early_media=true]
> 2009-02-05 14:04:48 [DEBUG] switch_ivr_originate.c:777 switch_ivr_originate() variable string 0 = [presence_id=1008 at 192.168.1.122]
> 2009-02-05 14:04:48 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel sofia/internal/sip:1008 at 192.168.1.126:5070 [f9b18b2e-b0a9-4e24-8452-40e1cce047bd]
> 2009-02-05 14:04:48 [DEBUG] mod_sofia.c:2518 sofia_outgoing_channel() (sofia/internal/sip:1008 at 192.168.1.126:5070) State Change CS_NEW -> CS_INIT
> 2009-02-05 14:04:48 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [BREAK]
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) Running State Change CS_INIT
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State INIT
> 2009-02-05 14:04:48 [DEBUG] mod_sofia.c:83 sofia_on_init() sofia/internal/sip:1008 at 192.168.1.126:5070 SOFIA INIT
> send 1196 bytes to udp/[192.168.1.126]:5070 at 13:04:48.127004:
> ------------------------------------------------------------------------
> INVITE sip:1008 at 192.168.1.126:5070 SIP/2.0
> Via: SIP/2.0/UDP 192.168.1.122;rport;branch=z9hG4bKFpQjUmp1vNHZB
> Max-Forwards: 70
> From: "FreeSWITCH" <sip:1000 at 192.168.1.122>;tag=9vpFm3tBc8gSe
> To: <sip:1008 at 192.168.1.126:5070>
> Call-ID: 68736fc7-6e28-122c-8396-000c7680c408
> CSeq: 110801208 INVITE
> Contact: <sip:mod_sofia at 192.168.1.122:5060>
> User-Agent: FreeSWITCH-mod_sofia/1.0.2-wyeksportowane
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
> Supported: timer, precondition, path, replaces
> Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer
> Min-SE: 120
> Content-Type: application/sdp
> Content-Disposition: session
> Content-Length: 335
> Remote-Party-ID: "FreeSWITCH" <sip:1000 at 192.168.1.122>;screen=yes;privacy=off
>
> v=0
> o=FreeSWITCH 1927164999227225404 3276738624485570081 IN IP4 192.168.1.122
> s=FreeSWITCH
> c=IN IP4 192.168.1.122
> t=0 0
> m=audio 27044 RTP/AVP 9 0 8 3 101 13
> a=rtpmap:9 G722/8000
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:3 GSM/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=rtpmap:13 CN/8000
> a=ptime:20
> ------------------------------------------------------------------------
> 2009-02-05 14:04:48 [DEBUG] mod_sofia.c:111 sofia_on_init() (sofia/internal/sip:1008 at 192.168.1.126:5070) State Change CS_INIT -> CS_ROUTING
> 2009-02-05 14:04:48 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [BREAK]
> 2009-02-05 14:04:48 [DEBUG] sofia.c:2542 sofia_handle_sip_i_state() Channel sofia/internal/sip:1008 at 192.168.1.126:5070 entering state [calling]
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State INIT going to sleep
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) Running State Change CS_ROUTING
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State ROUTING
> 2009-02-05 14:04:48 [DEBUG] mod_sofia.c:130 sofia_on_routing() sofia/internal/sip:1008 at 192.168.1.126:5070 SOFIA ROUTING
> 2009-02-05 14:04:48 [DEBUG] switch_ivr_originate.c:58 originate_on_routing() (sofia/internal/sip:1008 at 192.168.1.126:5070) State Change CS_ROUTING -> CS_CONSUME_MEDIA
> 2009-02-05 14:04:48 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [BREAK]
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State ROUTING going to sleep
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) Running State Change CS_CONSUME_MEDIA
> 2009-02-05 14:04:48 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State CONSUME_MEDIA
> recv 361 bytes from udp/[192.168.1.126]:5070 at 13:04:48.130745:
> ------------------------------------------------------------------------
> SIP/2.0 100 Trying
> Via: SIP/2.0/UDP 192.168.1.122;rport;branch=z9hG4bKFpQjUmp1vNHZB
> From: "FreeSWITCH" <sip:1000 at 192.168.1.122>;tag=9vpFm3tBc8gSe
> To: <sip:1008 at 192.168.1.126:5070>;tag=2111843199
> Contact: <sip:1008 at 192.168.1.126:5070>
> Call-ID: 68736fc7-6e28-122c-8396-000c7680c408
> CSeq: 110801208 INVITE
> Server: X-Lite release 1105d
> Content-Length: 0
>
> ------------------------------------------------------------------------
> recv 362 bytes from udp/[192.168.1.126]:5070 at 13:04:48.181571:
> ------------------------------------------------------------------------
> SIP/2.0 180 Ringing
> Via: SIP/2.0/UDP 192.168.1.122;rport;branch=z9hG4bKFpQjUmp1vNHZB
> From: "FreeSWITCH" <sip:1000 at 192.168.1.122>;tag=9vpFm3tBc8gSe
> To: <sip:1008 at 192.168.1.126:5070>;tag=2111843199
> Contact: <sip:1008 at 192.168.1.126:5070>
> Call-ID: 68736fc7-6e28-122c-8396-000c7680c408
> CSeq: 110801208 INVITE
> Server: X-Lite release 1105d
> Content-Length: 0
>
> ------------------------------------------------------------------------
> 2009-02-05 14:04:48 [DEBUG] sofia.c:2542 sofia_handle_sip_i_state() Channel sofia/internal/sip:1008 at 192.168.1.126:5070 entering state [proceeding]
> 2009-02-05 14:04:48 [NOTICE] sofia.c:2596 sofia_handle_sip_i_state() Ring-Ready sofia/internal/sip:1008 at 192.168.1.126:5070!
> recv 678 bytes from udp/[192.168.1.126]:5070 at 13:04:51.914714:
> ------------------------------------------------------------------------
> SIP/2.0 200 Ok
> Via: SIP/2.0/UDP 192.168.1.122;rport;branch=z9hG4bKFpQjUmp1vNHZB
> From: "FreeSWITCH" <sip:1000 at 192.168.1.122>;tag=9vpFm3tBc8gSe
> To: <sip:1008 at 192.168.1.126:5070>;tag=2111843199
> Contact: <sip:1008 at 192.168.1.126:5070>
> Call-ID: 68736fc7-6e28-122c-8396-000c7680c408
> CSeq: 110801208 INVITE
> Content-Type: application/sdp
> Server: X-Lite release 1105d
> Content-Length: 288
>
> v=0
> o=1008 1183460117 1183463903 IN IP4 192.168.1.126
> s=X-Lite
> c=IN IP4 192.168.1.126
> t=0 0
> m=audio 9000 RTP/AVP 0 8 98 97 101
> a=rtpmap:0 pcmu/8000
> a=rtpmap:8 pcma/8000
> a=rtpmap:98 iLBC/8000
> a=rtpmap:97 speex/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
> a=sendrecv
> ------------------------------------------------------------------------
> send 372 bytes to udp/[192.168.1.126]:5070 at 13:04:51.915303:
> ------------------------------------------------------------------------
> ACK sip:1008 at 192.168.1.126:5070 SIP/2.0
> Via: SIP/2.0/UDP 192.168.1.122;rport;branch=z9hG4bKgZgBXF74Sy7HQ
> Max-Forwards: 70
> From: "FreeSWITCH" <sip:1000 at 192.168.1.122>;tag=9vpFm3tBc8gSe
> To: <sip:1008 at 192.168.1.126:5070>;tag=2111843199
> Call-ID: 68736fc7-6e28-122c-8396-000c7680c408
> CSeq: 110801208 ACK
> Contact: <sip:mod_sofia at 192.168.1.122:5060>
> Content-Length: 0
>
> ------------------------------------------------------------------------
> 2009-02-05 14:04:51 [DEBUG] sofia.c:2542 sofia_handle_sip_i_state() Channel sofia/internal/sip:1008 at 192.168.1.126:5070 entering state [ready]
> 2009-02-05 14:04:51 [DEBUG] sofia.c:2546 sofia_handle_sip_i_state() Remote SDP:
> v=0
> o=1008 1183460117 1183463903 IN IP4 192.168.1.126
> s=X-Lite
> c=IN IP4 192.168.1.126
> t=0 0
> m=audio 9000 RTP/AVP 0 8 98 97 101
> a=rtpmap:0 pcmu/8000
> a=rtpmap:8 pcma/8000
> a=rtpmap:98 iLBC/8000
> a=rtpmap:97 speex/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-15
>
> 2009-02-05 14:04:51 [DEBUG] sofia_glue.c:2509 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[G722:9:8000]
> 2009-02-05 14:04:51 [DEBUG] sofia_glue.c:2509 sofia_glue_negotiate_sdp() Audio Codec Compare [pcmu:0:8000]/[PCMU:0:8000]
> 2009-02-05 14:04:51 [DEBUG] sofia_glue.c:1670 sofia_glue_tech_set_codec() Set Codec sofia/internal/sip:1008 at 192.168.1.126:5070 PCMU/8000 20 ms 160 samples
> 2009-02-05 14:04:51 [DEBUG] sofia_glue.c:2473 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101
> 2009-02-05 14:04:51 [DEBUG] sofia_glue.c:1890 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/sip:1008 at 192.168.1.126:5070] 192.168.1.122 port 27044 -> 192.168.1.126 port 9000 codec: 0 ms: 20
> 2009-02-05 14:04:51 [DEBUG] switch_rtp.c:865 switch_rtp_create() Starting timer [soft] 160 bytes per 20000ms
> 2009-02-05 14:04:51 [NOTICE] sofia.c:3031 sofia_handle_sip_i_state() Channel [sofia/internal/sip:1008 at 192.168.1.126:5070] has been answered
> 2009-02-05 14:04:51 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/sip:1008 at 192.168.1.126:5070 receive message [AUDIO_SYNC]
> 2009-02-05 14:04:51 [DEBUG] switch_ivr_originate.c:1627 switch_ivr_originate() Originate Resulted in Success: [sofia/internal/sip:1008 at 192.168.1.126:5070]
> 2009-02-05 14:04:51 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/sip:1008 at 192.168.1.126:5070 receive message [AUDIO_SYNC]
> 2009-02-05 14:04:51 [DEBUG] switch_ivr_originate.c:1627 switch_ivr_originate() Originate Resulted in Success: [sofia/internal/sip:1008 at 192.168.1.126:5070]
> 2009-02-05 14:04:51 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/sip:1008 at 192.168.1.126:5070 receive message [AUDIO_SYNC]
> 2009-02-05 14:04:51 [DEBUG] switch_ivr.c:1245 switch_ivr_session_transfer() (sofia/internal/sip:1008 at 192.168.1.126:5070) State Change CS_CONSUME_MEDIA -> CS_ROUTING
> 2009-02-05 14:04:51 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [BREAK]
> 2009-02-05 14:04:51 [DEBUG] switch_ivr.c:1249 switch_ivr_session_transfer() sofia/internal/sip:1008 at 192.168.1.126:5070 receive message [TRANSFER]
> 2009-02-05 14:04:51 [DEBUG] switch_core_session.c:511 switch_core_session_perform_receive_message() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [BREAK]
> 2009-02-05 14:04:51 [NOTICE] switch_ivr.c:1251 switch_ivr_session_transfer() Transfer sofia/internal/sip:1008 at 192.168.1.126:5070 to inline[bridge:sofia/gateway/halonet/0225490317 at default]
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State CONSUME_MEDIA going to sleep
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) Running State Change CS_ROUTING
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State ROUTING
> 2009-02-05 14:04:51 [DEBUG] mod_sofia.c:130 sofia_on_routing() sofia/internal/sip:1008 at 192.168.1.126:5070 SOFIA ROUTING
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:64 switch_core_standard_on_routing() sofia/internal/sip:1008 at 192.168.1.126:5070 Standard ROUTING
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:100 switch_core_standard_on_routing() (sofia/internal/sip:1008 at 192.168.1.126:5070) State Change CS_ROUTING -> CS_EXECUTE
> 2009-02-05 14:04:51 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [BREAK]
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State ROUTING going to sleep
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) Running State Change CS_EXECUTE
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:454 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State EXECUTE
> 2009-02-05 14:04:51 [DEBUG] mod_sofia.c:173 sofia_on_execute() sofia/internal/sip:1008 at 192.168.1.126:5070 SOFIA EXECUTE
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:137 switch_core_standard_on_execute() sofia/internal/sip:1008 at 192.168.1.126:5070 Standard EXECUTE
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:152 switch_core_standard_on_execute() sofia/internal/sip:1008 at 192.168.1.126:5070 Execute bridge(sofia/gateway/halonet/0225490317)
> 2009-02-05 14:04:51 [NOTICE] switch_channel.c:565 switch_channel_set_name() New Channel sofia/external/0225490317 [bf4fece9-38fc-40fa-8e9e-91f836f05e55]
> 2009-02-05 14:04:51 [DEBUG] mod_sofia.c:2518 sofia_outgoing_channel() (sofia/external/0225490317) State Change CS_NEW -> CS_INIT
> 2009-02-05 14:04:51 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/external/0225490317 [BREAK]
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/external/0225490317) Running State Change CS_INIT
> 2009-02-05 14:04:51 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (sofia/external/0225490317) State INIT
> 2009-02-05 14:04:51 [DEBUG] mod_sofia.c:83 sofia_on_init() sofia/external/0225490317 SOFIA INIT
> 2009-02-05 14:04:52 [DEBUG] sofia_glue.c:566 sofia_glue_ext_address_lookup() STUN Success [89.77.89.244]:[50863]
> 2009-02-05 14:04:52 [DEBUG] mod_sofia.c:111 sofia_on_init() (sofia/external/0225490317) State Change CS_INIT -> CS_ROUTING
> 2009-02-05 14:04:52 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/external/0225490317 [BREAK]
> 2009-02-05 14:04:52 [DEBUG] switch_core_state_machine.c:444 switch_core_session_run() (sofia/external/0225490317) State INIT going to sleep
> 2009-02-05 14:04:52 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/external/0225490317) Running State Change CS_ROUTING
> 2009-02-05 14:04:52 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (sofia/external/0225490317) State ROUTING
> 2009-02-05 14:04:52 [DEBUG] mod_sofia.c:130 sofia_on_routing() sofia/external/0225490317 SOFIA ROUTING
> 2009-02-05 14:04:52 [DEBUG] switch_ivr_originate.c:58 originate_on_routing() (sofia/external/0225490317) State Change CS_ROUTING -> CS_CONSUME_MEDIA
> 2009-02-05 14:04:52 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/external/0225490317 [BREAK]
> 2009-02-05 14:04:52 [DEBUG] switch_core_state_machine.c:447 switch_core_session_run() (sofia/external/0225490317) State ROUTING going to sleep
> 2009-02-05 14:04:52 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/external/0225490317) Running State Change CS_CONSUME_MEDIA
> 2009-02-05 14:04:52 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (sofia/external/0225490317) State CONSUME_MEDIA
> 2009-02-05 14:04:52 [DEBUG] sofia.c:2542 sofia_handle_sip_i_state() Channel sofia/external/0225490317 entering state [calling]
> send 1117 bytes to udp/[194.9.25.21]:5060 at 13:04:52.572008:
> ------------------------------------------------------------------------
> INVITE sip:0225490317 at sip.halonet.pl SIP/2.0
> Via: SIP/2.0/UDP 83.13.98.165:5080;rport;branch=z9hG4bKyvKBDBpvBQe2p
> Max-Forwards: 69
> From: "Extension 1008" <sip:fixed0248b at sip.halonet.pl;transport=udp>;tag=FZp353yettFNN
> To: <sip:0225490317 at sip.halonet.pl>
> Call-ID: 6b05cee4-6e28-122c-8396-000c7680c408
> CSeq: 110801210 INVITE
> Contact: <sip:fixed0248b at 83.13.98.165:5080;transport=udp>
> User-Agent: FreeSWITCH-mod_sofia/1.0.2-wyeksportowane
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO
> Supported: timer, precondition, path, replaces
> Allow-Events: talk, refer
> Min-SE: 120
> Content-Type: application/sdp
> Content-Disposition: session
> Content-Length: 309
> Remote-Party-ID: "Extension 1008" <sip:1000 at sip.halonet.pl>;screen=yes;privacy=off
>
> v=0
> o=FreeSWITCH 3996368035436745512 7588776177330183331 IN IP4 89.77.89.244
> s=FreeSWITCH
> c=IN IP4 89.77.89.244
> t=0 0
> m=audio 50863 RTP/AVP 0 8 3 101 13
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:3 GSM/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=rtpmap:13 CN/8000
> a=ptime:20
> ------------------------------------------------------------------------
> recv 760 bytes from udp/[194.9.25.21]:5060 at 13:04:52.993246:
> ------------------------------------------------------------------------
> SIP/2.0 407 Proxy Authentication Required
> Via: SIP/2.0/UDP 83.13.98.165:5080;rport=5080;branch=z9hG4bKyvKBDBpvBQe2p;received=89.77.89.244
> From: "Extension 1008" <sip:fixed0248b at sip.halonet.pl;transport=udp>;tag=FZp353yettFNN
> To: <sip:0225490317 at sip.halonet.pl>;tag=03bd8e75ec97c9ee65c772e401792a5a.5fa3
> Call-ID: 6b05cee4-6e28-122c-8396-000c7680c408
> CSeq: 110801210 INVITE
> Proxy-Authenticate: Digest realm="sip.halonet.pl", nonce="498ae51439454939d240f51ab9455e2c8505c7aa", qop="auth"
> Server: Sip EXpress router (2.0.0-rc1 (i386/linux))
> Content-Length: 0
> Warning: 392 194.9.25.21:5060 "Noisy feedback tells: pid=22400 req_src_ip=89.77.89.244 req_src_port=50866 in_uri=sip:0225490317 at sip.halonet.pl out_uri=sip:0225490317 at sip.halonet.pl via_cnt==1"
>
> ------------------------------------------------------------------------
> send 387 bytes to udp/[194.9.25.21]:5060 at 13:04:52.993616:
> ------------------------------------------------------------------------
> ACK sip:0225490317 at sip.halonet.pl SIP/2.0
> Via: SIP/2.0/UDP 83.13.98.165:5080;rport;branch=z9hG4bKyvKBDBpvBQe2p
> Max-Forwards: 69
> From: "Extension 1008" <sip:fixed0248b at sip.halonet.pl;transport=udp>;tag=FZp353yettFNN
> To: <sip:0225490317 at sip.halonet.pl>;tag=03bd8e75ec97c9ee65c772e401792a5a.5fa3
> Call-ID: 6b05cee4-6e28-122c-8396-000c7680c408
> CSeq: 110801210 ACK
> Content-Length: 0
>
> ------------------------------------------------------------------------
> send 1409 bytes to udp/[194.9.25.21]:5060 at 13:04:52.994530:
> ------------------------------------------------------------------------
> INVITE sip:0225490317 at sip.halonet.pl SIP/2.0
> Via: SIP/2.0/UDP 83.13.98.165:5080;rport;branch=z9hG4bKZ5c4e66Z8Z4mj
> Max-Forwards: 69
> From: "Extension 1008" <sip:fixed0248b at sip.halonet.pl;transport=udp>;tag=FZp353yettFNN
> To: <sip:0225490317 at sip.halonet.pl>
> Call-ID: 6b05cee4-6e28-122c-8396-000c7680c408
> CSeq: 110801211 INVITE
> Contact: <sip:fixed0248b at 83.13.98.165:5080;transport=udp>
> Expires: 600
> User-Agent: FreeSWITCH-mod_sofia/1.0.2-wyeksportowane
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO
> Supported: timer, precondition, path, replaces
> Allow-Events: talk, refer
> Proxy-Authorization: Digest username="fixed0248b", realm="sip.halonet.pl", nonce="498ae51439454939d240f51ab9455e2c8505c7aa", cnonce="a1oY524oEiyWgwAMdoDECA", algorithm=MD5, uri="sip:0225490317 at sip.halonet.pl", response="f05fad749d4ff6d5a75d61e3283c3afa", qop=auth, nc=00000001
> Min-SE: 120
> Content-Type: application/sdp
> Content-Disposition: session
> Content-Length: 309
> Remote-Party-ID: "Extension 1008" <sip:1000 at sip.halonet.pl>;screen=yes;privacy=off
>
> v=0
> o=FreeSWITCH 3996368035436745512 7588776177330183331 IN IP4 89.77.89.244
> s=FreeSWITCH
> c=IN IP4 89.77.89.244
> t=0 0
> m=audio 50863 RTP/AVP 0 8 3 101 13
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:3 GSM/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=rtpmap:13 CN/8000
> a=ptime:20
> ------------------------------------------------------------------------
> 2009-02-05 14:04:52 [DEBUG] sofia.c:2542 sofia_handle_sip_i_state() Channel sofia/external/0225490317 entering state [calling]
> recv 618 bytes from udp/[194.9.25.21]:5060 at 13:04:53.349700:
> ------------------------------------------------------------------------
> SIP/2.0 100 trying -- your call is important to us
> Via: SIP/2.0/UDP 83.13.98.165:5080;rport=5080;branch=z9hG4bKZ5c4e66Z8Z4mj;received=89.77.89.244
> From: "Extension 1008" <sip:fixed0248b at sip.halonet.pl;transport=udp>;tag=FZp353yettFNN
> To: <sip:0225490317 at sip.halonet.pl>
> Call-ID: 6b05cee4-6e28-122c-8396-000c7680c408
> CSeq: 110801211 INVITE
> Server: Sip EXpress router (2.0.0-rc1 (i386/linux))
> Content-Length: 0
> Warning: 392 194.9.25.21:5060 "Noisy feedback tells: pid=22332 req_src_ip=89.77.89.244 req_src_port=50866 in_uri=sip:0225490317 at sip.halonet.pl out_uri=sip:0225490317 at 217.11.128.50:5060 via_cnt==1"
>
> ------------------------------------------------------------------------
> recv 469 bytes from udp/[194.9.25.21]:5060 at 13:04:53.861812:
> ------------------------------------------------------------------------
> SIP/2.0 500 Internal Server Error
> Via: SIP/2.0/UDP 83.13.98.165:5080;received=89.77.89.244;rport=5080;branch=z9hG4bKZ5c4e66Z8Z4mj
> From: "Extension 1008" <sip:fixed0248b at sip.halonet.pl;transport=udp>;tag=FZp353yettFNN
> To: <sip:0225490317 at sip.halonet.pl>;tag=4961D2C4-DA7
> Date: Thu, 05 Feb 2009 13:04:39 GMT
> Call-ID: 6b05cee4-6e28-122c-8396-000c7680c408
> Server: Cisco-SIPGateway/IOS-12.x
> CSeq: 110801211 INVITE
> Allow-Events: telephone-event
> Content-Length: 0
>
> ------------------------------------------------------------------------
> send 362 bytes to udp/[194.9.25.21]:5060 at 13:04:53.862199:
> ------------------------------------------------------------------------
> ACK sip:0225490317 at sip.halonet.pl SIP/2.0
> Via: SIP/2.0/UDP 83.13.98.165:5080;rport;branch=z9hG4bKZ5c4e66Z8Z4mj
> Max-Forwards: 69
> From: "Extension 1008" <sip:fixed0248b at sip.halonet.pl;transport=udp>;tag=FZp353yettFNN
> To: <sip:0225490317 at sip.halonet.pl>;tag=4961D2C4-DA7
> Call-ID: 6b05cee4-6e28-122c-8396-000c7680c408
> CSeq: 110801211 ACK
> Content-Length: 0
>
> ------------------------------------------------------------------------
> 2009-02-05 14:04:53 [DEBUG] sofia.c:2542 sofia_handle_sip_i_state() Channel sofia/external/0225490317 entering state [terminated]
> 2009-02-05 14:04:53 [NOTICE] sofia.c:3090 sofia_handle_sip_i_state() Hangup sofia/external/0225490317 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE]
> 2009-02-05 14:04:53 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal sofia/external/0225490317 [KILL]
> 2009-02-05 14:04:53 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/external/0225490317 [BREAK]
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:466 switch_core_session_run() (sofia/external/0225490317) State CONSUME_MEDIA going to sleep
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/external/0225490317) Running State Change CS_HANGUP
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (sofia/external/0225490317) State HANGUP
> 2009-02-05 14:04:53 [DEBUG] mod_sofia.c:253 sofia_on_hangup() sofia/external/0225490317 Overriding SIP cause 503 with 500 from the other leg
> 2009-02-05 14:04:53 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel sofia/external/0225490317 hanging up, cause: NORMAL_TEMPORARY_FAILURE
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() sofia/external/0225490317 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (sofia/external/0225490317) State HANGUP going to sleep
> 2009-02-05 14:04:53 [DEBUG] switch_core_session.c:939 switch_core_session_thread() Session 16 (sofia/external/0225490317) Locked, Waiting on external entities
> 2009-02-05 14:04:53 [DEBUG] switch_ivr_originate.c:1695 switch_ivr_originate() Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE]
> 2009-02-05 14:04:53 [DEBUG] switch_channel.c:177 switch_channel_audio_sync() sofia/internal/sip:1008 at 192.168.1.126:5070 receive message [AUDIO_SYNC]
> 2009-02-05 14:04:53 [NOTICE] switch_core_session.c:957 switch_core_session_thread() Session 16 (sofia/external/0225490317) Ended
> 2009-02-05 14:04:53 [INFO] mod_dptools.c:1909 audio_bridge_function() Originate Failed. Cause: NORMAL_TEMPORARY_FAILURE
> 2009-02-05 14:04:53 [NOTICE] mod_dptools.c:1936 audio_bridge_function() Hangup sofia/internal/sip:1008 at 192.168.1.126:5070 [CS_EXECUTE] [NORMAL_TEMPORARY_FAILURE]
> 2009-02-05 14:04:53 [NOTICE] switch_core_session.c:959 switch_core_session_thread() Close Channel sofia/external/0225490317 [CS_HANGUP]
> 2009-02-05 14:04:53 [DEBUG] switch_channel.c:1494 switch_channel_perform_hangup() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [KILL]
> 2009-02-05 14:04:53 [DEBUG] switch_core_session.c:807 switch_core_session_signal_state_change() Send signal sofia/internal/sip:1008 at 192.168.1.126:5070 [BREAK]
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:454 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State EXECUTE going to sleep
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:379 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) Running State Change CS_HANGUP
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State HANGUP
> 2009-02-05 14:04:53 [DEBUG] mod_sofia.c:253 sofia_on_hangup() sofia/internal/sip:1008 at 192.168.1.126:5070 Overriding SIP cause 503 with 500 from the other leg
> 2009-02-05 14:04:53 [DEBUG] mod_sofia.c:287 sofia_on_hangup() Channel sofia/internal/sip:1008 at 192.168.1.126:5070 hanging up, cause: NORMAL_TEMPORARY_FAILURE
> 2009-02-05 14:04:53 [DEBUG] mod_sofia.c:344 sofia_on_hangup() Sending BYE to sofia/internal/sip:1008 at 192.168.1.126:5070
> send 648 bytes to udp/[192.168.1.126]:5070 at 13:04:53.868506:
> ------------------------------------------------------------------------
> BYE sip:1008 at 192.168.1.126:5070 SIP/2.0
> Via: SIP/2.0/UDP 192.168.1.122;rport;branch=z9hG4bKH893yar8p7X4j
> Max-Forwards: 70
> From: "FreeSWITCH" <sip:1000 at 192.168.1.122>;tag=9vpFm3tBc8gSe
> To: <sip:1008 at 192.168.1.126:5070>;tag=2111843199
> Call-ID: 68736fc7-6e28-122c-8396-000c7680c408
> CSeq: 110801209 BYE
> Contact: <sip:mod_sofia at 192.168.1.122:5060>
> User-Agent: FreeSWITCH-mod_sofia/1.0.2-wyeksportowane
> Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH
> Supported: timer, precondition, path, replaces
> Reason: Q.850;cause=41;text="NORMAL_TEMPORARY_FAILURE"
> Content-Length: 0
>
> ------------------------------------------------------------------------
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:46 switch_core_standard_on_hangup() sofia/internal/sip:1008 at 192.168.1.126:5070 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE
> 2009-02-05 14:04:53 [DEBUG] switch_core_state_machine.c:410 switch_core_session_run() (sofia/internal/sip:1008 at 192.168.1.126:5070) State HANGUP going to sleep
> 2009-02-05 14:04:53 [DEBUG] switch_core_session.c:939 switch_core_session_thread() Session 15 (sofia/internal/sip:1008 at 192.168.1.126:5070) Locked, Waiting on external entities
> 2009-02-05 14:04:53 [NOTICE] switch_core_session.c:957 switch_core_session_thread() Session 15 (sofia/internal/sip:1008 at 192.168.1.126:5070) Ended
> 2009-02-05 14:04:53 [NOTICE] switch_core_session.c:959 switch_core_session_thread() Close Channel sofia/internal/sip:1008 at 192.168.1.126:5070 [CS_HANGUP]
> recv 354 bytes from udp/[192.168.1.126]:5070 at 13:04:53.882344:
> ------------------------------------------------------------------------
> SIP/2.0 200 Ok
> Via: SIP/2.0/UDP 192.168.1.122;rport;branch=z9hG4bKH893yar8p7X4j
> From: "FreeSWITCH" <sip:1000 at 192.168.1.122>;tag=9vpFm3tBc8gSe
> To: <sip:1008 at 192.168.1.126:5070>;tag=2111843199
> Contact: <sip:1008 at 192.168.1.126:5070>
> Call-ID: 68736fc7-6e28-122c-8396-000c7680c408
> CSeq: 110801209 BYE
> Server: X-Lite release 1105d
> Content-Length: 0
>
> ------------------------------------------------------------------------
Dnia 04-02-2009, śro o godzinie 10:22 -0600, Anthony Minessale pisze:
> can you press f8 for debug and try that apiExecute and post the
> results?
>
> On Wed, Feb 4, 2009 at 8:46 AM, Jacek Sokulski
> <jsokulski at dotsystems.pl> wrote:
> Thanks Anthony,
> the js snippets are very instructive.
> A couple of points:
> 1. The code with apiExecute does not work (local phone is
> connected, but
> after picking up it hungs up immediately), other examples are
> working
> fine.
>
> 2. It does not show how initiate external call without
> existing session.
>
> 3. How can one pass the call through dialplan?
>
> Jacek
>
> PS.
> we got the code probable from wiki or from this mialing list.
>
> Dnia 04-02-2009, śro o godzinie 08:09 -0600, Anthony Minessale
> pisze:
>
> > Where did you learn how to use js this way?
> > session.originate is being misused here and is depricated
> and may be
> > removed.
> >
> > the first arg to session.originate is either undefined or a
> > *different* session (the a leg)
> >
> > session1 = new Session();
> > session1.originate(undefined,
> > "{ignore_early_media=true}user/1008 at 192.168.1.122");
> >
> >
> session1.setVariable("effective_caller_id_number","fixed0248b");
> >
> > //once you have session1 when you originate session2 you
> pass session1
> > as the arg
> > // the effective_caller_id is taken from session1
> >
> > session2 = new Session();
> > session2.originate(session1,
> "sofia/gateway/halonet/0225490317");
> >
> > Anyway this whole code is depricated in favor of this:
> >
> > session1 = new
> > Session("{ignore_early_media=true}user/1008 at 192.168.1.122");
> >
> > if (session1.ready()) {
> >
> session1.setVariable("effective_caller_id_number","fixed0248b");
> > session2 = new Session("sofia/gateway/halonet/0225490317",
> > session1);
> > }
> >
> > and could be further refactored down to this:
> >
> > session1 = new
> > Session("{ignore_early_media=true}user/1008 at 192.168.1.122");
> > if (session1.ready()) {
> >
> session1.setVariable("effective_caller_id_number","fixed0248b");
> > session1.execute("bridge",
> "sofia/gateway/halonet/0225490317");
> > }
> >
> > or down to this one line of code that will setup the call
> detached
> > from the script and exit.
> >
> > var result = apiExecute("originate",
> >
> "{effective_caller_id_number=fixed0248b,origination_caller_id_number=1000,ignore_early_media=true}user/1008 at 192.168.1.122 bridge:sofia/gateway/halonet/0225490317 inline");
> >
> > if you dont care about the result and want to exit even
> before the
> > call is completed.
> >
> > var result = apiExecute("bgapi", "originate
> >
> {effective_caller_id_number=fixed0248b,origination_caller_id_number=1000,ignore_early_media=true}user/1008 at 192.168.1.122 bridge:sofia/gateway/halonet/0225490317 inline");
> >
> >
> >
> > On Wed, Feb 4, 2009 at 2:51 AM, Jacek Sokulski
> > <jsokulski at dotsystems.pl> wrote:
> >
> > We have tried setting both
> effective_caller_id_number and
> > origination_caller_id_number:
> >
> >
> session1.originate(session1,"{origination_caller_id_number=fixed0248b}sofia/gateway/halonet/0225490317",15);
> > but the problem still exists. The solution we have
> found for
> > the case
> > when we originate two calls, local and external, is
> as follow:
> >
> > session1 = new Session();
> >
> session1.originate(session1,"user/1003 at 192.168.1.122",15);//local
> > if(session1.ready()) {
> >
> session1.execute("execute_extension","00930691688627 XML
> > default");//external
> > }
> >
> > so the external call goes through the dialplan.
> > It does not work if both calls are external. One
> possible
> > solution could be
> > to pass the originating call through dialplan
> (loopback?) but
> > we have not managed
> > to figure out how to do it.
> >
> > Thanks
> > Jacek
> >
> > Dnia 03-02-2009, wto o godzinie 14:31 -0300, Nicolas
> Brenner
> > pisze:
> >
> > > Oops! Well, fortunately I don't use that voip
> provider
> > anymore (nor the script).
> > >
> > > Thanks Brian.
> > >
> > > Nicolas
> > >
> > > On Tue, Feb 3, 2009 at 2:25 PM, Brian West
> > <brian at freeswitch.org> wrote:
> > > > YOU should NEVER use this method or call
> setCallerData at
> > all you
> > > > should use the correct methods to override the
> callerid.
> > > >
> > > > If its a B-Leg born from an A-Leg you use these
> on the on
> > the A-Leg:
> > > >
> > > >
> >
> http://wiki.freeswitch.org/wiki/Channel_Variables#effective_caller_id_name
> > > >
> >
> http://wiki.freeswitch.org/wiki/Channel_Variables#effective_caller_id_number
> > > >
> > > > If you're originating you use this:
> > > >
> > > >
> >
> http://wiki.freeswitch.org/wiki/Channel_Variables#origination_caller_id_name
> > > >
> >
> http://wiki.freeswitch.org/wiki/Channel_Variables#origination_caller_id_number
> > > >
> > > > /b
> > >
> > > _______________________________________________
> > > Freeswitch-users mailing list
> > > Freeswitch-users at lists.freeswitch.org
> > >
> >
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> > >
> >
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> > > http://www.freeswitch.org
> >
> >
> > _______________________________________________
> > Freeswitch-users mailing list
> > Freeswitch-users at lists.freeswitch.org
> >
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> >
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> > http://www.freeswitch.org
> >
> >
> >
> >
> >
> > --
> > Anthony Minessale II
> >
> > FreeSWITCH http://www.freeswitch.org/
> > ClueCon http://www.cluecon.com/
> >
> > AIM: anthm
> > MSN:anthony_minessale at hotmail.com
> > GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com
> > IRC: irc.freenode.net #freeswitch
> >
> > FreeSWITCH Developer Conference
> > sip:888 at conference.freeswitch.org
> > iax:guest at conference.freeswitch.org/888
> > googletalk:conf+888 at conference.freeswitch.org
> > pstn:213-799-1400
> > _______________________________________________
> > Freeswitch-users mailing list
> > Freeswitch-users at lists.freeswitch.org
> >
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> >
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> > http://www.freeswitch.org
>
>
> _______________________________________________
> Freeswitch-users mailing list
> Freeswitch-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
>
>
> --
> Anthony Minessale II
>
> FreeSWITCH http://www.freeswitch.org/
> ClueCon http://www.cluecon.com/
>
> AIM: anthm
> MSN:anthony_minessale at hotmail.com
> GTALK/JABBER/PAYPAL:anthony.minessale at gmail.com
> IRC: irc.freenode.net #freeswitch
>
> FreeSWITCH Developer Conference
> sip:888 at conference.freeswitch.org
> iax:guest at conference.freeswitch.org/888
> googletalk:conf+888 at conference.freeswitch.org
> pstn:213-799-1400
> _______________________________________________
> Freeswitch-users mailing list
> Freeswitch-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
More information about the FreeSWITCH-users
mailing list