freeswitch@jalsot> recv 798 bytes from udp/[192.168.1.10]:5060 at 20:46:19.00000 0: ------------------------------------------------------------------------ INVITE sip:667@10.23.45.9 SIP/2.0 Via: SIP/2.0/UDP 192.168.1.10:5060;branch=z9hG4bK4ef554ab;rport From: "Tamas Jalsovszky, CTO" ;tag=as0e981a8f To: Contact: Call-ID: 2b14f898139b664f6f9344b9175ba66c@192.168.1.10 CSeq: 102 INVITE User-Agent: eWorldComOffice Max-Forwards: 70 Date: Wed, 25 Oct 2006 20:46:23 GMT Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Content-Type: application/sdp Content-Length: 290 v=0 o=root 2611 2611 IN IP4 192.168.1.10 s=session c=IN IP4 192.168.1.10 t=0 0 m=audio 17222 RTP/AVP 110 8 0 3 101 a=rtpmap:110 speex/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - ------------------------------------------------------------------------ nta: received INVITE sip:667@10.23.45.9 SIP/2.0 (CSeq 102) nta: canonizing sip:667@10.23.45.9 with contact nta: INVITE (102) going to a default leg nta_leg_create(bogus %p send 315 bytes to udp/[192.168.1.10]:5060 at 20:46:19.000000: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 192.168.1.10:5060;branch=z9hG4bK4ef554ab;rport=5060 From: "Tamas Jalsovszky, CTO" ;tag=as0e981a8f To: Call-ID: 2b14f898139b664f6f9344b9175ba66c@192.168.1.10 CSeq: 102 INVITE User-Agent: FreeSWITCH(mod_sofia) Content-Length: 0 ------------------------------------------------------------------------ nta: sent 100 Trying for INVITE (102) 2006-10-25 22:46:19 [NOTICE] switch_channel.c:383 switch_channel_set_name() New Chan sofia/mytest/202@192.168.1.10 [4141241d-a07b-c04f-bbe9-7cdfe04e2fe6] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2321 sip_i_state() Channel sofia/mytest/ 202@192.168.1.10 entering state [received] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2326 sip_i_state() Remote SDP: v=0 o=root 2611 2611 IN IP4 192.168.1.10 s=session c=IN IP4 192.168.1.10 t=0 0 m=audio 17222 RTP/AVP 110 8 0 3 101 a=rtpmap:110 speex/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[PCMU:0] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[PCMA:8] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[L16:10] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[L16:10] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[L16:10] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[L16:10] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[L16:10] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[L16:10] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [spee x:110]/[L16:10] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [PCMA :8]/[PCMU:0] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2007 negotiate_sdp() Codec Compare [PCMA :8]/[PCMA:8] 2006-10-25 22:46:19 [INFO] mod_sofia.c:1284 tech_set_codec() Set Codec sofia/myt est/202@192.168.1.10 PCMU/8000 20 ms 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2435 sip_i_state() sofia/mytest/202@192. 168.1.10 State Change CS_NEW -> CS_INIT 2006-10-25 22:46:19 [DEBUG] switch_core.c:2762 switch_core_session_run() (sofia/ mytest/202@192.168.1.10) State INIT 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1022 sofia_on_init() SOFIA INIT 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1039 sofia_on_init() sofia/mytest/202@19 2.168.1.10 State Change CS_INIT -> CS_RING 2006-10-25 22:46:19 [DEBUG] switch_core.c:2798 switch_core_session_run() (sofia/ mytest/202@192.168.1.10) State RING 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1055 sofia_on_ring() SOFIA RING 2006-10-25 22:46:19 [DEBUG] switch_core.c:2463 switch_core_standard_on_ring() St andard RING sofia/mytest/202@192.168.1.10 2006-10-25 22:46:19 [INFO] mod_dialplan_xml.c:278 dialplan_hunt() Processing Tam as Jalsovszky, CTO->667! 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(667) =~ /^(18(0{2}|8{2}|7{2}|6{2})\d{7})$/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(667) =~ /^1234$/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(667) =~ /^1000$/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(667) =~ /^2000$/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions source(mod_sofia) =~ /mod_sofia/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(667) =~ /^4(\d+)/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions source(mod_sofia) =~ /mod_sofia/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(667) =~ /^4(\d+)/ 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:201 parse_exten() Regex mismatch 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:199 parse_exten() test conditions destination_number(667) =~ // 2006-10-25 22:46:19 [DEBUG] mod_dialplan_xml.c:326 dialplan_hunt() sofia/mytest/ 202@192.168.1.10 State Change CS_RING -> CS_EXECUTE 2006-10-25 22:46:19 [DEBUG] switch_core.c:2834 switch_core_session_run() (sofia/ mytest/202@192.168.1.10) State EXECUTE 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1073 sofia_on_execute() SOFIA EXECUTE 2006-10-25 22:46:19 [DEBUG] switch_core.c:2497 switch_core_standard_on_execute() Standard EXECUTE 2006-10-25 22:46:19 [NOTICE] switch_core.c:2506 switch_core_standard_on_execute( ) Execute bridge(sofia/mytest/202@192.168.1.10) 2006-10-25 22:46:19 [NOTICE] switch_channel.c:383 switch_channel_set_name() New Chan sofia/mytest/202@192.168.1.10 [7707e1bc-e687-7d4e-8da8-92bf4de22d5e] 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1949 sofia_outgoing_channel() sofia/myte st/202@192.168.1.10 State Change CS_NEW -> CS_INIT 2006-10-25 22:46:19 [DEBUG] switch_core.c:2762 switch_core_session_run() (sofia/ mytest/202@192.168.1.10) State INIT 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1022 sofia_on_init() SOFIA INIT 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1039 sofia_on_init() sofia/mytest/202@19 2.168.1.10 State Change CS_INIT -> CS_RING 2006-10-25 22:46:19 [DEBUG] switch_ivr.c:1963 switch_ivr_originate() Raw Codec A ctivation Success L16@8000hz 1 channel 20ms 2006-10-25 22:46:19 [DEBUG] switch_core.c:2798 switch_core_session_run() (sofia/ mytest/202@192.168.1.10) State RING 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:1055 sofia_on_ring() SOFIA RING 2006-10-25 22:46:19 [DEBUG] switch_ivr.c:1425 audio_bridge_on_ring() CUSTOM RING 2006-10-25 22:46:19 [DEBUG] switch_ivr.c:1428 audio_bridge_on_ring() sofia/mytes t/202@192.168.1.10 State Change CS_RING -> CS_HOLD 2006-10-25 22:46:19 [DEBUG] switch_core.c:2943 switch_core_session_run() (sofia/ mytest/202@192.168.1.10) State HOLD 2006-10-25 22:46:19 [DEBUG] switch_ivr.c:1439 audio_bridge_on_hold() CUSTOM HOLD nta_leg_create(bogus %p nta: selecting scheme sip send 1098 bytes to udp/[192.168.1.10]:5060 at 20:46:19.046875: ------------------------------------------------------------------------ INVITE sip:202@192.168.1.10 SIP/2.0 Via: SIP/2.0/UDP 10.23.45.9;rport;branch=z9hG4bKcvvUDgc797UZB Max-Forwards: 70 From: "Tamas Jalsovszky, CTO" ;tag=D5NmFBDZS6eZK To: Call-ID: b4fe41da-df0c-1229-2880-39a48cb53b8d CSeq: 74786254 INVITE Contact: User-Agent: FreeSWITCH(mod_sofia) Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO Supported: 100rel, precondition Content-Type: application/sdp Content-Disposition: session Content-Length: 411 Remote-Party-ID: "Tamas Jalsovszky, CTO" ;party=calling;s creen=yes;privacy=no v=0 o=FreeSWITCH 1110445663877587962 6324161778166975444 IN IP4 10.23.45.9 s=FreeSWITCH c=IN IP4 10.23.45.9 t=0 0 a=sendrecv m=audio 16384 RTP/AVP 0 8 10 10 10 10 10 10 10 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:10 L16/32000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=ptime:20 a=ptime:120 a=ptime:60 a=ptime:30 a=ptime:20 a=ptime:20 a=ptime:20 a=ptime:20 ------------------------------------------------------------------------ nta: sent INVITE (74786254) to */192.168.1.10:5060 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:4002 event_callback() event [nua_i_state ] status [0][INVITE sent] session: sofia/mytest/202@192.168.1.10 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:2321 sip_i_state() Channel sofia/mytest/ 202@192.168.1.10 entering state [calling] recv 422 bytes from udp/[192.168.1.10]:5060 at 20:46:19.125000: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.23.45.9;rport;branch=z9hG4bKcvvUDgc797UZB;received=10.23. 45.9 From: "Tamas Jalsovszky, CTO" ;tag=D5NmFBDZS6eZK To: Call-ID: b4fe41da-df0c-1229-2880-39a48cb53b8d CSeq: 74786254 INVITE User-Agent: eWorldComOffice Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 ------------------------------------------------------------------------ nta: received 100 Trying for INVITE (74786254) nta: 100 Trying going to a transaction nta_outgoing: RTT is 78.125 ms recv 438 bytes from udp/[192.168.1.10]:5060 at 20:46:19.156250: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 10.23.45.9;rport;branch=z9hG4bKcvvUDgc797UZB;received=10.23. 45.9 From: "Tamas Jalsovszky, CTO" ;tag=D5NmFBDZS6eZK To: ;tag=as38c58a80 Call-ID: b4fe41da-df0c-1229-2880-39a48cb53b8d CSeq: 74786254 INVITE User-Agent: eWorldComOffice Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY Contact: Content-Length: 0 ------------------------------------------------------------------------ nta: received 180 Ringing for INVITE (74786254) nta: 180 Ringing going to a transaction 2006-10-25 22:46:19 [DEBUG] mod_sofia.c:4002 event_callback() event [nua_r_invit e] status [180][Ringing] session: sofia/mytest/202@192.168.1.10