freeswitch@pbx-dev.cc.huji.ac.il> freeswitch@pbx-dev.cc.huji.ac.il> recv 563 bytes from udp/[132.64.4.231]:5060 at 13:44:28.483267: ------------------------------------------------------------------------ REGISTER sip:pbx-dev.cc.huji.ac.il SIP/2.0 Via: SIP/2.0/UDP 132.64.4.231;branch=z9hG4bKac2016427958 Max-Forwards: 70 From: ;tag=1c2016424806 To: Call-ID: 43236590159200964529@132.64.4.231 CSeq: 795 REGISTER Contact: ;expires=180 Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Expires: 180 User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.5.00A.024 Content-Length: 0 ------------------------------------------------------------------------ send 627 bytes to udp/[132.64.4.231]:5060 at 13:44:28.536809: ------------------------------------------------------------------------ SIP/2.0 401 Unauthorized Via: SIP/2.0/UDP 132.64.4.231;branch=z9hG4bKac2016427958 From: ;tag=1c2016424806 To: ;tag=S6a7rQvKHHFKN Call-ID: 43236590159200964529@132.64.4.231 CSeq: 795 REGISTER User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces WWW-Authenticate: Digest realm="pbx-dev.cc.huji.ac.il", nonce="8c0a8857-222a-4e85-b3fb-b619729b5098", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ recv 813 bytes from udp/[132.64.4.231]:5060 at 13:44:28.565536: ------------------------------------------------------------------------ REGISTER sip:pbx-dev.cc.huji.ac.il SIP/2.0 Via: SIP/2.0/UDP 132.64.4.231;branch=z9hG4bKac2016529457 Max-Forwards: 70 From: ;tag=1c2016424806 To: Call-ID: 43236590159200964529@132.64.4.231 CSeq: 796 REGISTER Authorization: Digest username="80677",realm="pbx-dev.cc.huji.ac.il",nc=00000001,nonce="8c0a8857-222a-4e85-b3fb-b619729b5098",Cnonce="Default_Cnonce",uri="sip:pbx-dev.cc.huji.ac.il",qop=auth,algorithm=MD5,response="29b33a9453d83e86717f3a0ace3adfdb" Contact: ;expires=180 Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Expires: 180 User-Agent: Audiocodes-Sip-Gateway-MP-124 FXS/v.5.00A.024 Content-Length: 0 ------------------------------------------------------------------------ send 572 bytes to udp/[132.64.4.231]:5060 at 13:44:28.917749: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.4.231;branch=z9hG4bKac2016529457 From: ;tag=1c2016424806 To: ;tag=tF4ZtjDQet55g Call-ID: 43236590159200964529@132.64.4.231 CSeq: 796 REGISTER Contact: ;expires=180 Date: Tue, 15 Sep 2009 13:44:28 GMT User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Content-Length: 0 ------------------------------------------------------------------------ recv 1138 bytes from udp/[132.64.4.137]:2048 at 13:44:29.020398: ------------------------------------------------------------------------ INVITE sip:80675@pbx-dev.cc.huji.ac.il;user=phone SIP/2.0 Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-t02m8bdltt3k;rport From: "Test Yehavi SNOM" ;tag=eptt27imyh To: Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 1 INVITE Max-Forwards: 70 Contact: ;reg-id=1 P-Key-Flags: keys="3" User-Agent: snom320/7.3.14 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change, Remote-Aprty-ID Session-Expires: 3600;refresher=uas Min-SE: 90 Content-Type: application/sdp Content-Length: 386 v=0 o=root 753629314 753629314 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.4.137 t=0 0 m=audio 51634 RTP/AVP 8 0 9 99 3 18 4 101 a=direction:both a=rtpmap:8 pcma/8000 a=rtpmap:0 pcmu/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv ------------------------------------------------------------------------ send 342 bytes to udp/[132.64.4.137]:2048 at 13:44:29.031734: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-t02m8bdltt3k;rport=2048 From: "Test Yehavi SNOM" ;tag=eptt27imyh To: Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:29.39204 [DEBUG] sofia.c:4628 IP 132.64.4.137 Rejected by acl "domains". Falling back to Digest auth. send 827 bytes to udp/[132.64.4.137]:2048 at 13:44:29.082774: ------------------------------------------------------------------------ SIP/2.0 407 Proxy Authentication Required Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-t02m8bdltt3k;rport=2048 From: "Test Yehavi SNOM" ;tag=eptt27imyh To: ;tag=UrXrvDytB3Urc Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Accept: application/sdp 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 Proxy-Authenticate: Digest realm="pbx-dev.cc.huji.ac.il", nonce="274966e3-22cf-4b4f-909a-2e143cca0afa", algorithm=MD5, qop="auth" Content-Length: 0 ------------------------------------------------------------------------ recv 406 bytes from udp/[132.64.4.137]:2048 at 13:44:29.209702: ------------------------------------------------------------------------ ACK sip:80675@pbx-dev.cc.huji.ac.il;user=phone SIP/2.0 Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-t02m8bdltt3k;rport From: "Test Yehavi SNOM" ;tag=eptt27imyh To: ;tag=UrXrvDytB3Urc Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 1 ACK Max-Forwards: 70 Contact: ;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ recv 1405 bytes from udp/[132.64.4.137]:2048 at 13:44:29.219576: ------------------------------------------------------------------------ INVITE sip:80675@pbx-dev.cc.huji.ac.il;user=phone SIP/2.0 Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-vn18xozd6hjr;rport From: "Test Yehavi SNOM" ;tag=eptt27imyh To: Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 2 INVITE Max-Forwards: 70 Contact: ;reg-id=1 P-Key-Flags: keys="3" User-Agent: snom320/7.3.14 Accept: application/sdp Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change, Remote-Aprty-ID Session-Expires: 3600;refresher=uas Min-SE: 90 Proxy-Authorization: Digest username="80678",realm="pbx-dev.cc.huji.ac.il",nonce="274966e3-22cf-4b4f-909a-2e143cca0afa",uri="sip:80675@pbx-dev.cc.huji.ac.il;user=phone",qop=auth,nc=00000001,cnonce="2493484c",response="519b6bba7b0cf8b00cc0788ea568590b",algorithm=MD5 Content-Type: application/sdp Content-Length: 386 v=0 o=root 753629314 753629314 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.4.137 t=0 0 m=audio 51634 RTP/AVP 8 0 9 99 3 18 4 101 a=direction:both a=rtpmap:8 pcma/8000 a=rtpmap:0 pcmu/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv ------------------------------------------------------------------------ send 342 bytes to udp/[132.64.4.137]:2048 at 13:44:29.234378: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-vn18xozd6hjr;rport=2048 From: "Test Yehavi SNOM" ;tag=eptt27imyh To: Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 2 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:29.237195 [DEBUG] sofia.c:4628 IP 132.64.4.137 Rejected by acl "domains". Falling back to Digest auth. send 862 bytes to udp/[132.64.4.231]:5060 at 13:44:29.347764: ------------------------------------------------------------------------ NOTIFY sip:80677@132.64.4.231 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKQ8BHFmv0gKXjj Max-Forwards: 70 From: ;tag=Xaga03Z15m8XK To: Call-ID: bb793d51-1ca0-122d-46ba-0002b35fc481 CSeq: 120392798 NOTIFY Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Event: message-summary Allow-Events: talk, presence, dialog, call-info, sla, include-session-description, presence.winfo, message-summary, refer Subscription-State: terminated;reason=timeout Content-Type: application/simple-message-summary Content-Length: 65 Messages-Waiting: no Message-Account: sip:80677@132.64.9.164 ------------------------------------------------------------------------ recv 411 bytes from udp/[132.64.4.231]:5060 at 13:44:29.369293: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKQ8BHFmv0gKXjj From: ;tag=Xaga03Z15m8XK To: ;tag=1c2017532041 Call-ID: bb793d51-1ca0-122d-46ba-0002b35fc481 CSeq: 120392798 NOTIFY Supported: em,timer,replaces,path,resource-priority Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE Content-Length: 0 ------------------------------------------------------------------------ ** invite **80678 80675 2009-09-15 16:44:29.520207 [NOTICE] switch_channel.c:602 New Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il [7b9f9e91-e45c-4178-8ef4-2c93aa8099e3] 2009-09-15 16:44:29.542176 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_NEW 2009-09-15 16:44:29.543257 [DEBUG] switch_core_state_machine.c:404 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State NEW 2009-09-15 16:44:29.544284 [DEBUG] sofia.c:3289 Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il entering state [received][100] 2009-09-15 16:44:29.548793 [DEBUG] sofia.c:3296 Remote SDP: v=0 o=root 753629314 753629314 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.4.137 t=0 0 m=audio 51634 RTP/AVP 8 0 9 99 3 18 4 101 a=rtpmap:8 pcma/8000 a=rtpmap:0 pcmu/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=direction:both a=ptime:20 2009-09-15 16:44:29.552239 [DEBUG] sofia_glue.c:3071 Audio Codec Compare [pcma:8:8000:20]/[G7221:115:32000:20] 2009-09-15 16:44:29.554194 [DEBUG] sofia_glue.c:3071 Audio Codec Compare [pcma:8:8000:20]/[G7221:107:16000:20] 2009-09-15 16:44:29.554194 [DEBUG] sofia_glue.c:3071 Audio Codec Compare [pcma:8:8000:20]/[G722:9:8000:20] 2009-09-15 16:44:29.555245 [DEBUG] sofia_glue.c:3071 Audio Codec Compare [pcma:8:8000:20]/[PCMU:0:8000:20] 2009-09-15 16:44:29.555245 [DEBUG] sofia_glue.c:3071 Audio Codec Compare [pcma:8:8000:20]/[PCMA:8:8000:20] 2009-09-15 16:44:29.560329 [DEBUG] sofia_glue.c:2029 Set Codec sofia/internal/80678@pbx-dev.cc.huji.ac.il PCMA/8000 20 ms 160 samples 2009-09-15 16:44:29.564279 [DEBUG] sofia_glue.c:3031 Set 2833 dtmf payload to 101 2009-09-15 16:44:29.564279 [DEBUG] sofia.c:3455 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_NEW -> CS_INIT 2009-09-15 16:44:29.566611 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:29.568258 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_INIT 2009-09-15 16:44:29.568258 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State INIT 2009-09-15 16:44:29.569289 [DEBUG] mod_sofia.c:83 sofia/internal/80678@pbx-dev.cc.huji.ac.il SOFIA INIT 2009-09-15 16:44:29.569289 [DEBUG] mod_sofia.c:111 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_INIT -> CS_ROUTING 2009-09-15 16:44:29.569289 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:29.569289 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State INIT going to sleep 2009-09-15 16:44:29.569289 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_ROUTING 2009-09-15 16:44:29.571271 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State ROUTING 2009-09-15 16:44:29.571271 [DEBUG] mod_sofia.c:130 sofia/internal/80678@pbx-dev.cc.huji.ac.il SOFIA ROUTING 2009-09-15 16:44:29.571271 [DEBUG] switch_core_state_machine.c:78 sofia/internal/80678@pbx-dev.cc.huji.ac.il Standard ROUTING 2009-09-15 16:44:29.571271 [INFO] mod_dialplan_xml.c:315 Processing Test Yehavi SNOM->80675 in context huji Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->unloop] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->intercept-ext] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [intercept-ext] destination_number(80675) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->DefaultAreacode] continue=true Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (PASS) [DefaultAreacode] ${user_data(${caller_id_number}@${domain_name} var areacode)}() =~ /^$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(areacode=02) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->global] continue=true Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Absolute Condition [global] Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action lua(DisplayMe.lua ${areacode} ${user_data(${caller_id_number}@${domain_name} var toll_allow)}) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [global] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->SpecialNumbers] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [SpecialNumbers] destination_number(80675) =~ /^106$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->Conf] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [Conf] destination_number(80675) =~ /^Conf(.*)$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->conftest] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [conftest] destination_number(80675) =~ /^\*55(.*)$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->StarNumbers] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [StarNumbers] destination_number(80675) =~ /^\*(\d{4})$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->StarNumbers] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [StarNumbers] destination_number(80675) =~ /^(0\d1222\d{4})$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->redial] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [redial] destination_number(80675) =~ /^\*41$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->cancel_redial] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [cancel_redial] destination_number(80675) =~ /^\#41$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->call_return] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [call_return] destination_number(80675) =~ /^\*42$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->SetFollowme] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [SetFollowme] destination_number(80675) =~ /^\*43(\d*)$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->vmain] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [vmain] destination_number(80675) =~ /^vmain$|^80666$|^\*98$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->VoiceMail] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (FAIL) [VoiceMail] destination_number(80675) =~ /^VoiceMail_(.*)$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->Huji-InsideNumber-DP] continue=true Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (PASS) [Huji-InsideNumber-DP] ${user_data(${caller_id_number}@${domain_name} attr id)}(80678) =~ /^80678$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(bypass_media=true) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(call_timeout=45) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(inside_caller=true) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(api_hangup_hook=jsrun OnHangup.js ${domain_name} ${context} ${caller_id_number} ${destination_number}) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->Huji-InsideNumber-DP] continue=true Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (PASS) [Huji-InsideNumber-DP] ${user_data(${destination_number}@${domain_name} attr id)}(80675) =~ /^80675$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(call_timeout=45) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action javascript(OnBusy.js) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(api_hangup_hook=jsrun OnHangup.js ${domain_name} ${context} ${destination_number} ${caller_id_number}) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(inside_dest=true) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->Huji-InsideNumber-DP] continue=true Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Absolute Condition [Huji-InsideNumber-DP] Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action lua(SetNames.lua) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->Huji-InsideNumber-DP] continue=true Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (PASS) [Huji-InsideNumber-DP] ${user_data(${destination_number}@${domain_name} attr id)}(80675) =~ /^80675$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (PASS) [Huji-InsideNumber-DP] destination_number(80675) =~ /^(80\d{3})$|^97207222(80\d{3})/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action hash(insert/${domain_name}-call_return/${destination_number}/${caller_id_number}) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(call_timeout=45) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action lua(SetDialStr.lua) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(hangup_after_bridge=true) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(continue_on_fail=true) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action bridge(sofia/internal/80675%${domain_name}${RingAt}) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action answer() Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action voicemail(default 132.64.9.164 80675) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il parsing [huji->specialnum] continue=false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Regex (PASS) [specialnum] destination_number(80675) =~ /^(8\d{4})$|^([26]\d{4})$|^97207222(8{\d{4})$/ break=on-false Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action set(execute_on_answer=start_dtmf_generate true) Dialplan: sofia/internal/80678@pbx-dev.cc.huji.ac.il Action bridge(sofia/internal/${destination_number}@${outside_domain}) 2009-09-15 16:44:30.179291 [DEBUG] switch_core_state_machine.c:114 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_ROUTING -> CS_EXECUTE 2009-09-15 16:44:30.180220 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:30.180220 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State ROUTING going to sleep 2009-09-15 16:44:30.181238 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_EXECUTE 2009-09-15 16:44:30.188246 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State EXECUTE 2009-09-15 16:44:30.189267 [DEBUG] mod_sofia.c:173 sofia/internal/80678@pbx-dev.cc.huji.ac.il SOFIA EXECUTE 2009-09-15 16:44:30.190294 [DEBUG] switch_core_state_machine.c:151 sofia/internal/80678@pbx-dev.cc.huji.ac.il Standard EXECUTE EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(areacode=02) 2009-09-15 16:44:30.193259 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [areacode]=[02] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il lua(DisplayMe.lua 02 ) ++02 2009-09-15 16:44:30.405475 [DEBUG] switch_cpp.cpp:923 destroy/unlink session from object EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(bypass_media=true) 2009-09-15 16:44:30.409265 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [bypass_media]=[true] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(call_timeout=45) 2009-09-15 16:44:30.411273 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [call_timeout]=[45] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(inside_caller=true) 2009-09-15 16:44:30.413264 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [inside_caller]=[true] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il hash(insert/132.64.9.164-last_dial/80678/80675) EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(api_hangup_hook=jsrun OnHangup.js 132.64.9.164 huji 80678 80675) 2009-09-15 16:44:30.417285 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [api_hangup_hook]=[jsrun OnHangup.js 132.64.9.164 huji 80678 80675] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(call_timeout=45) 2009-09-15 16:44:30.418351 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [call_timeout]=[45] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il javascript(OnBusy.js) 2009-09-15 16:44:30.549409 [DEBUG] mod_spidermonkey.c:3390 Loading CoreDB EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(api_hangup_hook=jsrun OnHangup.js 132.64.9.164 huji 80675 80678) 2009-09-15 16:44:31.213286 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [api_hangup_hook]=[jsrun OnHangup.js 132.64.9.164 huji 80675 80678] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(inside_dest=true) 2009-09-15 16:44:31.235231 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [inside_dest]=[true] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il lua(SetNames.lua) 2009-09-15 16:44:31.396179 [DEBUG] switch_cpp.cpp:923 destroy/unlink session from object EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il hash(insert/132.64.9.164-call_return/80675/80678) EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(call_timeout=45) 2009-09-15 16:44:31.414193 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [call_timeout]=[45] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il lua(SetDialStr.lua) 2009-09-15 16:44:31.599272 [DEBUG] switch_cpp.cpp:923 destroy/unlink session from object EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(hangup_after_bridge=true) 2009-09-15 16:44:31.613253 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [hangup_after_bridge]=[true] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il set(continue_on_fail=true) 2009-09-15 16:44:31.621376 [DEBUG] mod_dptools.c:748 sofia/internal/80678@pbx-dev.cc.huji.ac.il SET [continue_on_fail]=[true] EXECUTE sofia/internal/80678@pbx-dev.cc.huji.ac.il bridge(sofia/internal/80675%132.64.9.164 ) 2009-09-15 16:44:31.680190 [NOTICE] switch_channel.c:602 New Channel sofia/internal/80675 [0a0d3b1a-f5ef-4b3e-a4c1-61ebcf4fa336] 2009-09-15 16:44:31.685247 [DEBUG] mod_sofia.c:2814 (sofia/internal/80675) State Change CS_NEW -> CS_INIT 2009-09-15 16:44:31.686264 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:31.708253 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_INIT 2009-09-15 16:44:31.709320 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/80675) State INIT 2009-09-15 16:44:31.709320 [DEBUG] mod_sofia.c:83 sofia/internal/80675 SOFIA INIT send 1241 bytes to udp/[132.64.4.235]:5060 at 13:44:31.714930: ------------------------------------------------------------------------ INVITE sip:80675@132.64.4.235:5060 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKrH59gFD4DvK5D Max-Forwards: 69 From: "n8 l8" ;tag=yK921yg52XygF To: Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392799 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported 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 Content-Type: application/sdp Content-Disposition: session Content-Length: 374 P-Key-Flags: keys="3" Remote-Party-ID: "n8 l8" ;party=calling;screen=yes;privacy=off v=0 o=root 753629314 753629314 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.4.137 t=0 0 m=audio 51634 RTP/AVP 8 0 9 99 3 18 4 101 a=rtpmap:8 pcma/8000 a=rtpmap:0 pcmu/8000 a=rtpmap:9 g722/8000 a=rtpmap:99 g726-32/8000 a=rtpmap:3 gsm/8000 a=rtpmap:18 g729/8000 a=rtpmap:4 g723/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=direction:both a=ptime:20 ------------------------------------------------------------------------ 2009-09-15 16:44:31.720275 [DEBUG] mod_sofia.c:111 (sofia/internal/80675) State Change CS_INIT -> CS_ROUTING 2009-09-15 16:44:31.721303 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:31.721303 [DEBUG] sofia.c:3289 Channel sofia/internal/80675 entering state [calling][0] 2009-09-15 16:44:31.722222 [DEBUG] switch_core_state_machine.c:481 (sofia/internal/80675) State INIT going to sleep 2009-09-15 16:44:31.722222 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_ROUTING 2009-09-15 16:44:31.724196 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/80675) State ROUTING 2009-09-15 16:44:31.724196 [DEBUG] mod_sofia.c:130 sofia/internal/80675 SOFIA ROUTING 2009-09-15 16:44:31.724196 [DEBUG] switch_ivr_originate.c:63 (sofia/internal/80675) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2009-09-15 16:44:31.739002 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:31.739002 [DEBUG] switch_core_state_machine.c:484 (sofia/internal/80675) State ROUTING going to sleep 2009-09-15 16:44:31.739002 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_CONSUME_MEDIA 2009-09-15 16:44:31.741215 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80675) State CONSUME_MEDIA 2009-09-15 16:44:31.741215 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80675) State CONSUME_MEDIA going to sleep recv 357 bytes from udp/[132.64.4.235]:5060 at 13:44:32.005304: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKrH59gFD4DvK5D From: "n8 l8" ;tag=yK921yg52XygF To: Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392799 INVITE Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Length: 0 ------------------------------------------------------------------------ recv 437 bytes from udp/[132.64.4.235]:5060 at 13:44:32.008288: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKrH59gFD4DvK5D From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392799 INVITE Contact: Server: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:32.6334 [DEBUG] sofia.c:3289 Channel sofia/internal/80675 entering state [proceeding][180] 2009-09-15 16:44:32.6334 [NOTICE] sofia.c:3353 Ring-Ready sofia/internal/80675! 2009-09-15 16:44:32.6334 [DEBUG] sofia.c:3361 sofia/internal/80678@pbx-dev.cc.huji.ac.il receive message [RINGING] 2009-09-15 16:44:32.6334 [NOTICE] mod_sofia.c:1452 Ring-Ready sofia/internal/80678@pbx-dev.cc.huji.ac.il! 2009-09-15 16:44:32.6334 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] send 812 bytes to udp/[132.64.4.137]:2048 at 13:44:32.012552: ------------------------------------------------------------------------ SIP/2.0 180 Ringing Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-vn18xozd6hjr;rport=2048 From: "Test Yehavi SNOM" ;tag=eptt27imyh To: ;tag=v1pHy8ey8BjBr Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Accept: application/sdp 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 Content-Length: 0 Remote-Party-ID: "n5 l5" ;party=called;screen=yes;privacy=off ------------------------------------------------------------------------ 2009-09-15 16:44:32.6334 [DEBUG] sofia.c:3289 Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il entering state [early][180] 2009-09-15 16:44:32.6334 [DEBUG] switch_ivr_originate.c:1805 sofia/internal/80678@pbx-dev.cc.huji.ac.il receive message [RINGING] 2009-09-15 16:44:32.6334 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:32.6334 [NOTICE] switch_ivr_originate.c:1805 Ring Ready sofia/internal/80678@pbx-dev.cc.huji.ac.il! recv 727 bytes from udp/[132.64.4.235]:5060 at 13:44:34.329466: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKrH59gFD4DvK5D From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392799 INVITE Contact: Supported: 100rel, replaces, timer Server: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 226 v=0 o=80675 14463169 11753565 IN IP4 132.64.4.235 s=A conversation c=IN IP4 132.64.4.235 t=0 0 m=audio 5018 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ send 365 bytes to udp/[132.64.4.235]:5060 at 13:44:34.343097: ------------------------------------------------------------------------ ACK sip:80675@132.64.4.235:5060 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKSty2jay7a59QS Max-Forwards: 70 From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392799 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:34.345446 [DEBUG] sofia.c:3289 Channel sofia/internal/80675 entering state [ready][200] 2009-09-15 16:44:34.345446 [DEBUG] sofia.c:3296 Remote SDP: v=0 o=80675 14463169 11753565 IN IP4 132.64.4.235 s=A conversation c=IN IP4 132.64.4.235 t=0 0 m=audio 5018 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 2009-09-15 16:44:34.345446 [DEBUG] switch_channel.c:1891 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:34.345446 [NOTICE] sofia.c:3759 Channel [sofia/internal/80675] has been answered 2009-09-15 16:44:34.345446 [DEBUG] sofia.c:3772 sofia/internal/80678@pbx-dev.cc.huji.ac.il receive message [ANSWER] 2009-09-15 16:44:34.345446 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:34.345446 [NOTICE] sofia.c:3772 Channel [sofia/internal/80678@pbx-dev.cc.huji.ac.il] has been answered 2009-09-15 16:44:34.353045 [DEBUG] switch_ivr_originate.c:2061 Originate Resulted in Success: [sofia/internal/80675] 2009-09-15 16:44:34.356404 [DEBUG] switch_ivr_bridge.c:789 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_EXECUTE -> CS_HIBERNATE 2009-09-15 16:44:34.356404 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:34.356404 [DEBUG] switch_ivr_bridge.c:790 (sofia/internal/80675) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE 2009-09-15 16:44:34.356404 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:34.356404 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_HIBERNATE 2009-09-15 16:44:34.356404 [DEBUG] switch_core_state_machine.c:506 (sofia/internal/80675) State HIBERNATE 2009-09-15 16:44:34.356404 [DEBUG] mod_sofia.c:160 sofia/internal/80675 SOFIA HIBERNATE 2009-09-15 16:44:34.356404 [DEBUG] switch_core_state_machine.c:212 sofia/internal/80675 Standard HIBERNATE 2009-09-15 16:44:34.356404 [DEBUG] switch_core_state_machine.c:506 (sofia/internal/80675) State HIBERNATE going to sleep 2009-09-15 16:44:34.366108 [DEBUG] switch_core_state_machine.c:491 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State EXECUTE going to sleep 2009-09-15 16:44:34.366108 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_HIBERNATE send 1097 bytes to udp/[132.64.4.137]:2048 at 13:44:34.376113: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-vn18xozd6hjr;rport=2048 From: "Test Yehavi SNOM" ;tag=eptt27imyh To: ;tag=v1pHy8ey8BjBr Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 2 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported 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 Session-Expires: 3600;refresher=uas Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 202 Remote-Party-ID: "n5 l5" ;party=called;screen=yes;privacy=off v=0 o=80675 14463169 11753565 IN IP4 132.64.4.235 s=A conversation c=IN IP4 132.64.4.235 t=0 0 m=audio 5018 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 ------------------------------------------------------------------------ 2009-09-15 16:44:34.373995 [DEBUG] sofia.c:3289 Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il entering state [completed][200] 2009-09-15 16:44:34.379019 [DEBUG] switch_core_state_machine.c:506 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State HIBERNATE 2009-09-15 16:44:34.379019 [DEBUG] mod_sofia.c:160 sofia/internal/80678@pbx-dev.cc.huji.ac.il SOFIA HIBERNATE 2009-09-15 16:44:34.379019 [DEBUG] switch_core_state_machine.c:212 sofia/internal/80678@pbx-dev.cc.huji.ac.il Standard HIBERNATE 2009-09-15 16:44:34.379019 [DEBUG] switch_core_state_machine.c:506 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State HIBERNATE going to sleep recv 405 bytes from udp/[132.64.4.137]:2048 at 13:44:34.411801: ------------------------------------------------------------------------ ACK sip:80675@132.64.9.164:5060;transport=udp SIP/2.0 Via: SIP/2.0/UDP 132.64.4.137:2048;branch=z9hG4bK-7ihp1e3119tw;rport From: "Test Yehavi SNOM" ;tag=eptt27imyh To: ;tag=v1pHy8ey8BjBr Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 2 ACK Max-Forwards: 70 Contact: ;reg-id=1 Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:34.415451 [DEBUG] sofia.c:3289 Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il entering state [ready][200] ===== HERE HOLD IS PRESSED AND THE CALL DISCONNECTS ==== freeswitch@pbx-dev.cc.huji.ac.il> freeswitch@pbx-dev.cc.huji.ac.il> freeswitch@pbx-dev.cc.huji.ac.il> freeswitch@pbx-dev.cc.huji.ac.il> freeswitch@pbx-dev.cc.huji.ac.il> freeswitch@pbx-dev.cc.huji.ac.il> freeswitch@pbx-dev.cc.huji.ac.il> recv 797 bytes from udp/[132.64.4.235]:5060 at 13:44:39.097991: ------------------------------------------------------------------------ INVITE sip:mod_sofia@132.64.9.164:5060 SIP/2.0 Via: SIP/2.0/UDP 132.64.4.235:5060;branch=z9hG4bK30333165823250427563;rport From: ;tag=2706516354 To: "n8 l8" ;tag=yK921yg52XygF Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 1 INVITE Contact: Max-Forwards: 70 Supported: replaces, join, path, 100rel User-Agent: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 233 v=0 o=80675 14463169 11753566 IN IP4 132.64.4.235 s=A conversation c=IN IP4 0.0.0.0 t=0 0 m=audio 5018 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendonly ------------------------------------------------------------------------ send 343 bytes to udp/[132.64.4.235]:5060 at 13:44:39.107820: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 132.64.4.235:5060;branch=z9hG4bK30333165823250427563;rport=5060 From: ;tag=2706516354 To: "n8 l8" ;tag=yK921yg52XygF Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 1 INVITE User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:39.108880 [DEBUG] sofia.c:3289 Channel sofia/internal/80675 entering state [received][100] 2009-09-15 16:44:39.108880 [DEBUG] sofia.c:3296 Remote SDP: v=0 o=80675 14463169 11753566 IN IP4 132.64.4.235 s=A conversation c=IN IP4 0.0.0.0 t=0 0 m=audio 5018 RTP/AVP 8 0 101 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendonly 2009-09-15 16:44:39.108880 [DEBUG] switch_ivr.c:1145 sofia/internal/80678@pbx-dev.cc.huji.ac.il receive message [MEDIA] 2009-09-15 16:44:39.108880 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] send 878 bytes to udp/[132.64.4.235]:5060 at 13:44:39.118239: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.4.235:5060;branch=z9hG4bK30333165823250427563;rport=5060 From: ;tag=2706516354 To: "n8 l8" ;tag=yK921yg52XygF Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 1 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Accept: application/sdp Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 242 v=0 o=root 753629314 753629315 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.9.164 t=0 0 m=audio 22780 RTP/AVP 8 101 13 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=recvonly a=ptime:20 ------------------------------------------------------------------------ send 997 bytes to udp/[132.64.4.137]:2048 at 13:44:39.120737: ------------------------------------------------------------------------ INVITE sip:80678@132.64.4.137:2048 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKt3QUm5eB8D0aN Max-Forwards: 70 From: ;tag=v1pHy8ey8BjBr To: "Test Yehavi SNOM" ;tag=eptt27imyh Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 120392803 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Session-Expires: 3600;refresher=uac Min-SE: 120 Content-Type: application/sdp Content-Disposition: session Content-Length: 242 P-Key-Flags: keys="3" v=0 2009-09-15 16:44:39.121773 [DEBUG] sofia.c:3289 Channel sofia/internal/80675 entering state [completed][200] o=80675 14463169 11753566 IN IP4 132.64.4.235 s=A conversation c=IN IP4 132.64.9.164 t=0 0 m=audio 26066 RTP/AVP 8 101 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ 2009-09-15 16:44:39.157324 [DEBUG] sofia.c:3289 Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il entering state [calling][0] recv 349 bytes from udp/[132.64.4.235]:5060 at 13:44:39.158746: ------------------------------------------------------------------------ ACK sip:mod_sofia@132.64.9.164:5060 SIP/2.0 Via: SIP/2.0/UDP 132.64.4.235:5060;branch=z9hG4bK315285472968715627 From: ;tag=2706516354 To: "n8 l8" ;tag=yK921yg52XygF Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 1 ACK Max-Forwards: 70 User-Agent: Voip Phone 1.0 Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:39.176037 [DEBUG] sofia.c:3289 Channel sofia/internal/80675 entering state [ready][200] recv 909 bytes from udp/[132.64.4.137]:2048 at 13:44:39.217886: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 132.64.9.164;rport=5060;branch=z9hG4bKt3QUm5eB8D0aN From: ;tag=v1pHy8ey8BjBr To: "Test Yehavi SNOM" ;tag=eptt27imyh Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 120392803 INVITE Contact: ;reg-id=1 Require: timer Session-Expires: 3600;refresher=uac User-Agent: snom320/7.3.14 Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO Allow-Events: talk, hold, refer, call-info Supported: timer, 100rel, replaces, from-change, Remote-Aprty-ID Content-Type: application/sdp Content-Length: 236 v=0 o=root 753629314 753629315 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.4.137 t=0 0 m=audio 51634 RTP/AVP 8 101 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=direction:both a=sendrecv ------------------------------------------------------------------------ send 345 bytes to udp/[132.64.4.137]:2048 at 13:44:39.232384: ------------------------------------------------------------------------ ACK sip:80678@132.64.4.137:2048 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKUcHmp0Ze5ppXg Max-Forwards: 70 From: ;tag=v1pHy8ey8BjBr To: "Test Yehavi SNOM" ;tag=eptt27imyh Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 120392803 ACK Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:39.241385 [DEBUG] sofia.c:3289 Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il entering state [ready][200] 2009-09-15 16:44:39.241385 [DEBUG] sofia.c:3296 Remote SDP: v=0 o=root 753629314 753629315 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.4.137 t=0 0 m=audio 51634 RTP/AVP 8 101 a=rtpmap:8 pcma/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=direction:both 2009-09-15 16:44:39.241385 [DEBUG] sofia_glue.c:3005 Our existing sdp is still good [pcma 132.64.4.137:51634], let's keep it. 2009-09-15 16:44:39.241385 [DEBUG] sofia_glue.c:3031 Set 2833 dtmf payload to 101 2009-09-15 16:44:39.241385 [DEBUG] sofia_glue.c:2263 AUDIO RTP [sofia/internal/80678@pbx-dev.cc.huji.ac.il] 132.64.9.164 port 26066 -> 132.64.4.137 port 51634 codec: 8 ms: 20 2009-09-15 16:44:39.241385 [DEBUG] switch_rtp.c:1138 Starting timer [soft] 160 bytes per 20ms 2009-09-15 16:44:39.257357 [DEBUG] switch_ivr.c:1160 sofia/internal/80675 receive message [MEDIA] 2009-09-15 16:44:39.257357 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80675 [BREAK] send 992 bytes to udp/[132.64.4.235]:5060 at 13:44:39.260723: ------------------------------------------------------------------------ INVITE sip:80675@132.64.4.235:5060 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKvNaDrUgj2Zcgc Max-Forwards: 69 From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392800 INVITE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Content-Type: application/sdp Content-Disposition: session Content-Length: 233 P-Key-Flags: keys="3" Remote-Party-ID: "n8 l8" ;party=calling;screen=yes;privacy=off v=0 o=root 753629314 753629316 IN IP4 132.64.4.137 s=call c=IN IP4 132.64.9.164 t=0 0 m=audio 22780 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=silenceSupp:off - - - - a=ptime:20 ------------------------------------------------------------------------ 2009-09-15 16:44:39.257357 [DEBUG] sofia.c:3289 Channel sofia/internal/80675 entering state [calling][0] recv 710 bytes from udp/[132.64.4.235]:5060 at 13:44:39.287777: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKvNaDrUgj2Zcgc From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392800 INVITE Contact: Supported: 100rel, replaces, timer Server: Voip Phone 1.0 Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, REFER, NOTIFY, INFO, PRACK, UPDATE, MESSAGE Content-Type: application/sdp Content-Length: 209 v=0 o=80675 16010197 13731316 IN IP4 132.64.4.235 s=A conversation c=IN IP4 0.0.0.0 t=0 0 m=audio 5018 RTP/AVP 8 101 a=rtpmap:8 PCMA/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=sendonly ------------------------------------------------------------------------ 2009-09-15 16:44:39.309284 [DEBUG] switch_ivr_bridge.c:1173 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_HIBERNATE -> CS_CONSUME_MEDIA 2009-09-15 16:44:39.312244 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_CONSUME_MEDIA 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State CONSUME_MEDIA 2009-09-15 16:44:39.312244 [DEBUG] switch_ivr_bridge.c:578 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_CONSUME_MEDIA -> CS_RESET 2009-09-15 16:44:39.312244 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State CONSUME_MEDIA going to sleep 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_RESET 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:487 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State RESET 2009-09-15 16:44:39.312244 [DEBUG] mod_sofia.c:145 sofia/internal/80678@pbx-dev.cc.huji.ac.il SOFIA RESET 2009-09-15 16:44:39.312244 [DEBUG] switch_ivr_bridge.c:565 sofia/internal/80678@pbx-dev.cc.huji.ac.il CUSTOM RESET 2009-09-15 16:44:39.312244 [DEBUG] switch_ivr_bridge.c:570 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_RESET -> CS_SOFT_EXECUTE 2009-09-15 16:44:39.312244 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:487 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State RESET going to sleep 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_SOFT_EXECUTE 2009-09-15 16:44:39.312244 [DEBUG] switch_core_state_machine.c:497 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State SOFT_EXECUTE 2009-09-15 16:44:39.312244 [DEBUG] mod_sofia.c:457 SOFIA TRANSMIT 2009-09-15 16:44:39.312244 [DEBUG] switch_ivr_bridge.c:588 sofia/internal/80678@pbx-dev.cc.huji.ac.il CUSTOM SOFT_EXECUTE 2009-09-15 16:44:39.315950 [DEBUG] switch_ivr_bridge.c:1175 (sofia/internal/80675) State Change CS_HIBERNATE -> CS_CONSUME_MEDIA 2009-09-15 16:44:39.315950 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.315950 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_CONSUME_MEDIA 2009-09-15 16:44:39.315950 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80675) State CONSUME_MEDIA 2009-09-15 16:44:39.315950 [DEBUG] switch_ivr_bridge.c:578 (sofia/internal/80675) State Change CS_CONSUME_MEDIA -> CS_RESET 2009-09-15 16:44:39.315950 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.315950 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80675) State CONSUME_MEDIA going to sleep 2009-09-15 16:44:39.315950 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_RESET 2009-09-15 16:44:39.315950 [DEBUG] switch_core_state_machine.c:487 (sofia/internal/80675) State RESET 2009-09-15 16:44:39.315950 [DEBUG] mod_sofia.c:145 sofia/internal/80675 SOFIA RESET 2009-09-15 16:44:39.315950 [DEBUG] switch_ivr_bridge.c:565 sofia/internal/80675 CUSTOM RESET 2009-09-15 16:44:39.315950 [DEBUG] switch_core_state_machine.c:67 sofia/internal/80675 Standard RESET 2009-09-15 16:44:39.315950 [DEBUG] switch_core_state_machine.c:487 (sofia/internal/80675) State RESET going to sleep 2009-09-15 16:44:39.317574 [DEBUG] switch_ivr_bridge.c:614 (sofia/internal/80675) State Change CS_RESET -> CS_SOFT_EXECUTE 2009-09-15 16:44:39.317574 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.317574 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_SOFT_EXECUTE 2009-09-15 16:44:39.317574 [DEBUG] switch_core_state_machine.c:497 (sofia/internal/80675) State SOFT_EXECUTE 2009-09-15 16:44:39.317574 [DEBUG] mod_sofia.c:457 SOFIA TRANSMIT 2009-09-15 16:44:39.317574 [DEBUG] switch_ivr_bridge.c:588 sofia/internal/80675 CUSTOM SOFT_EXECUTE 2009-09-15 16:44:39.317574 [DEBUG] switch_core_state_machine.c:192 sofia/internal/80675 Standard SOFT_EXECUTE 2009-09-15 16:44:39.317574 [DEBUG] switch_core_state_machine.c:497 (sofia/internal/80675) State SOFT_EXECUTE going to sleep 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:845 (sofia/internal/80675) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_CONSUME_MEDIA 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80675) State CONSUME_MEDIA 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:529 sofia/internal/80675 CUSTOM HOLD 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:503 (sofia/internal/80675) State CONSUME_MEDIA going to sleep 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:889 sofia/internal/80675 receive message [BRIDGE] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:896 sofia/internal/80678@pbx-dev.cc.huji.ac.il receive message [BRIDGE] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:940 (sofia/internal/80675) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_EXCHANGE_MEDIA 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/80675) State EXCHANGE_MEDIA 2009-09-15 16:44:39.319338 [DEBUG] mod_sofia.c:451 SOFIA LOOPBACK 2009-09-15 16:44:39.319338 [ERR] switch_core_io.c:118 sofia/internal/80675 has no read codec. 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:377 sofia/internal/80675 ending bridge by request from read function 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:426 sofia/internal/80675 receive message [UNBRIDGE] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:452 BRIDGE THREAD DONE [sofia/internal/80675] 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:454 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.319338 [NOTICE] switch_ivr_bridge.c:503 Hangup sofia/internal/80675 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-09-15 16:44:39.319338 [DEBUG] switch_channel.c:1683 Send signal sofia/internal/80675 [KILL] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:494 (sofia/internal/80675) State EXCHANGE_MEDIA going to sleep 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_HANGUP 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/80675) State HANGUP 2009-09-15 16:44:39.319338 [DEBUG] mod_sofia.c:339 Channel sofia/internal/80675 hanging up, cause: NORMAL_CLEARING 2009-09-15 16:44:39.319338 [DEBUG] mod_sofia.c:397 Sending BYE to sofia/internal/80675 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:46 sofia/internal/80675 Standard HANGUP, cause: NORMAL_CLEARING 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/80675) State HANGUP going to sleep 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/80675) State Change CS_HANGUP -> CS_REPORTING 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80675) Running State Change CS_REPORTING 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/80675) State REPORTING 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:53 sofia/internal/80675 Standard REPORTING, cause: NORMAL_CLEARING 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/80675) State REPORTING going to sleep 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:411 (sofia/internal/80675) State Change CS_REPORTING -> CS_DESTROY 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:1068 Session 2 (sofia/internal/80675) Locked, Waiting on external entities 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:426 sofia/internal/80678@pbx-dev.cc.huji.ac.il receive message [UNBRIDGE] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:630 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:452 BRIDGE THREAD DONE [sofia/internal/80678@pbx-dev.cc.huji.ac.il] 2009-09-15 16:44:39.319338 [DEBUG] switch_ivr_bridge.c:454 Send signal sofia/internal/80675 [BREAK] 2009-09-15 16:44:39.319338 [NOTICE] switch_ivr_bridge.c:1016 Hangup sofia/internal/80678@pbx-dev.cc.huji.ac.il [CS_SOFT_EXECUTE] [NORMAL_CLEARING] 2009-09-15 16:44:39.319338 [DEBUG] switch_channel.c:1683 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [KILL] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:497 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State SOFT_EXECUTE going to sleep 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_HANGUP 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State HANGUP 2009-09-15 16:44:39.319338 [DEBUG] mod_sofia.c:339 Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il hanging up, cause: NORMAL_CLEARING 2009-09-15 16:44:39.319338 [DEBUG] mod_sofia.c:397 Sending BYE to sofia/internal/80678@pbx-dev.cc.huji.ac.il 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:46 sofia/internal/80678@pbx-dev.cc.huji.ac.il Standard HANGUP, cause: NORMAL_CLEARING 2009-09-15 16:44:39.319338 [DEBUG] switch_core_state_machine.c:434 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State HANGUP going to sleep 2009-09-15 16:44:39.362315 [DEBUG] switch_core_state_machine.c:461 Hangup Command jsrun(OnHangup.js 132.64.9.164 huji 80675 80678): OK 2009-09-15 16:44:39.366231 [DEBUG] switch_core_state_machine.c:476 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_HANGUP -> CS_REPORTING 2009-09-15 16:44:39.367214 [DEBUG] switch_core_session.c:932 Send signal sofia/internal/80678@pbx-dev.cc.huji.ac.il [BREAK] 2009-09-15 16:44:39.367214 [DEBUG] switch_core_state_machine.c:398 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Running State Change CS_REPORTING 2009-09-15 16:44:39.367214 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State REPORTING 2009-09-15 16:44:39.385735 [DEBUG] switch_core_state_machine.c:53 sofia/internal/80678@pbx-dev.cc.huji.ac.il Standard REPORTING, cause: NORMAL_CLEARING 2009-09-15 16:44:39.385735 [DEBUG] switch_core_state_machine.c:612 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State REPORTING going to sleep 2009-09-15 16:44:39.385735 [DEBUG] switch_core_state_machine.c:411 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State Change CS_REPORTING -> CS_DESTROY 2009-09-15 16:44:39.385735 [DEBUG] switch_core_session.c:1068 Session 1 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Locked, Waiting on external entities send 365 bytes to udp/[132.64.4.235]:5060 at 13:44:39.385034: ------------------------------------------------------------------------ ACK sip:80675@132.64.4.235:5060 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKXy35Sp1NZ822Q Max-Forwards: 70 From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392800 ACK Contact: Content-Length: 0 ------------------------------------------------------------------------ send 626 bytes to udp/[132.64.4.235]:5060 at 13:44:39.408453: ------------------------------------------------------------------------ BYE sip:80675@132.64.4.235:5060 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKy7vyUHjSvHSNK Max-Forwards: 70 From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392801 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ send 660 bytes to udp/[132.64.4.137]:2048 at 13:44:39.421921: ------------------------------------------------------------------------ BYE sip:80678@132.64.4.137:2048 SIP/2.0 Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKZgpQXc3vStF8e Max-Forwards: 70 From: ;tag=v1pHy8ey8BjBr To: "Test Yehavi SNOM" ;tag=eptt27imyh Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 120392804 BYE Contact: User-Agent: FreeSWITCH-mod_sofia/1.0.4-exported Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, MESSAGE, SUBSCRIBE, NOTIFY, REFER, UPDATE, REGISTER, INFO, PUBLISH Supported: timer, precondition, path, replaces Reason: Q.850;cause=16;text="NORMAL_CLEARING" Content-Length: 0 ------------------------------------------------------------------------ recv 525 bytes from udp/[132.64.4.137]:2048 at 13:44:39.447695: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.9.164;rport=5060;branch=z9hG4bKZgpQXc3vStF8e From: ;tag=v1pHy8ey8BjBr To: "Test Yehavi SNOM" ;tag=eptt27imyh Call-ID: 3c26ba770ec9-wyrar3xdqmrg CSeq: 120392804 BYE Contact: ;reg-id=1 User-Agent: snom320/7.3.14 RTP-RxStat: Total_Rx_Pkts=214,Rx_Pkts=214,Rx_Pkts_Lost=0,Remote_Rx_Pkts_Lost=0 RTP-TxStat: Total_Tx_Pkts=370,Tx_Pkts=370,Remote_Tx_Pkts=85 Content-Length: 0 ------------------------------------------------------------------------ 2009-09-15 16:44:39.477192 [DEBUG] mod_spidermonkey.c:3390 Loading CoreDB 2009-09-15 16:44:39.615318 [NOTICE] switch_core_session.c:1086 Session 1 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) Ended 2009-09-15 16:44:39.615318 [NOTICE] switch_core_session.c:1088 Close Channel sofia/internal/80678@pbx-dev.cc.huji.ac.il [CS_DESTROY] 2009-09-15 16:44:39.615318 [DEBUG] switch_core_state_machine.c:564 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State DESTROY 2009-09-15 16:44:39.615318 [DEBUG] mod_sofia.c:256 sofia/internal/80678@pbx-dev.cc.huji.ac.il SOFIA DESTROY 2009-09-15 16:44:39.615318 [DEBUG] switch_core_state_machine.c:60 sofia/internal/80678@pbx-dev.cc.huji.ac.il Standard DESTROY 2009-09-15 16:44:39.615318 [DEBUG] switch_core_state_machine.c:564 (sofia/internal/80678@pbx-dev.cc.huji.ac.il) State DESTROY going to sleep 2009-09-15 16:44:39.615318 [NOTICE] switch_core_session.c:1086 Session 2 (sofia/internal/80675) Ended 2009-09-15 16:44:39.615318 [NOTICE] switch_core_session.c:1088 Close Channel sofia/internal/80675 [CS_DESTROY] 2009-09-15 16:44:39.615318 [DEBUG] switch_core_state_machine.c:564 (sofia/internal/80675) State DESTROY 2009-09-15 16:44:39.615318 [DEBUG] mod_sofia.c:256 sofia/internal/80675 SOFIA DESTROY 2009-09-15 16:44:39.615318 [DEBUG] switch_core_state_machine.c:60 sofia/internal/80675 Standard DESTROY 2009-09-15 16:44:39.615318 [DEBUG] switch_core_state_machine.c:564 (sofia/internal/80675) State DESTROY going to sleep recv 302 bytes from udp/[132.64.4.235]:5060 at 13:44:39.620663: ------------------------------------------------------------------------ SIP/2.0 200 OK Via: SIP/2.0/UDP 132.64.9.164;rport;branch=z9hG4bKy7vyUHjSvHSNK From: "n8 l8" ;tag=yK921yg52XygF To: ;tag=2706516354 Call-ID: bce26447-1ca0-122d-46ba-0002b35fc481 CSeq: 120392801 BYE Server: Voip Phone 1.0 Content-Length: 0 ------------------------------------------------------------------------ freeswitch@pbx-dev.cc.huji.ac.il>