July 2011 - FreeSWITCH-users - FreeSWITCH Mailing Lists

Leg-A is automatically disconnected on Leg-B orginate failure
by dma 19 Jul '11

19 Jul '11

I am creating a call-back solution. After leg-A answers, I originate leg-B call. After receiving SIP 183 from Leg-B, I bridge the 2 legs. However, in some cases, leg-A is automatically disconnected by FreeSwitch on leg-B failure, for example, DESTINATION_OUT_OF_ORDER. The application is not given a chance to handle leg-B failure event. This should not be a correct scenario because I never set "hangup-after-bridge", which is false by default. The right way should be, FreeSwitch doesn't hang up leg-A automatically, but give a chance for the application to decide what to do. Please see the logs below: ================================================= 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 0 = [origination_caller_id_number=03996563750914] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 1 = [originate_timeout=30] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 2 = [ccd_session_id=20110610105829676824] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 3 = [sip_cid_type=pid] 2011-06-10 11:09:55.370506 [DEBUG] switch_ivr_originate.c:1971 variable string 4 = [privacy=yes] 2011-06-10 11:09:55.370506 [NOTICE] switch_channel.c:808 New Channel sofia/external/03996590031055(a)203.208.207.212 [ea57b74b-a8c2-4fea-9683-98054dc03a79] 2011-06-10 11:09:55.370506 [DEBUG] mod_sofia.c:4129 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_NEW -> CS_INIT 2011-06-10 11:09:55.370506 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:55.370506 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_INIT 2011-06-10 11:09:55.370506 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996590031055(a)203.208.207.212) State INIT 2011-06-10 11:09:55.370506 [DEBUG] mod_sofia.c:84 sofia/external/03996590031055(a)203.208.207.212 SOFIA INIT send 984 bytes to udp/[203.208.207.212]:5060 at 03:09:55.477997: ------------------------------------------------------------------------ INVITE sip:03996590031055@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKXjQ7eFpKypy5D Max-Forwards: 70 From: "" <sip:03996563750914@202.73.56.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212> Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Contact: <sip:mod_sofia@202.73.56.46:5080> User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110603T012235Z Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, refer Privacy: none Content-Type: application/sdp Content-Disposition: session Content-Length: 204 X-FS-Support: update_display P-Asserted-Identity: "" <sip:03996563750914@202.73.56.46> v=0 o=FreeSWITCH 1307645395 1307645396 IN IP4 202.73.56.46 s=FreeSWITCH c=IN IP4 202.73.56.46 t=0 0 m=audio 30000 RTP/AVP 18 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2011-06-10 11:09:55.371674 [DEBUG] mod_sofia.c:124 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_INIT -> CS_ROUTING 2011-06-10 11:09:55.371674 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:55.371674 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [calling][0] 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996590031055(a)203.208.207.212) State INIT going to sleep 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_ROUTING 2011-06-10 11:09:55.373478 [DEBUG] switch_channel.c:1657 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change DOWN -> RINGING 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996590031055(a)203.208.207.212) State ROUTING 2011-06-10 11:09:55.373478 [DEBUG] mod_sofia.c:147 sofia/external/03996590031055(a)203.208.207.212 SOFIA ROUTING 2011-06-10 11:09:55.373478 [DEBUG] switch_ivr_originate.c:66 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-06-10 11:09:55.373478 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996590031055(a)203.208.207.212) State ROUTING going to sleep 2011-06-10 11:09:55.373478 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_CONSUME_MEDIA 2011-06-10 11:09:55.374485 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996590031055(a)203.208.207.212) State CONSUME_MEDIA 2011-06-10 11:09:55.374485 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996590031055(a)203.208.207.212) State CONSUME_MEDIA going to sleep recv 307 bytes from udp/[203.208.207.212]:5060 at 03:09:55.485130: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212> Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Content-Length: 0 ------------------------------------------------------------------------ recv 669 bytes from udp/[203.208.207.212]:5060 at 03:09:56.677788: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 189 v=0 o=- 421265648 1 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:09:56.571839 [INFO] sofia.c:729 sofia/external/03996590031055(a)203.208.207.212 Update Callee ID to "Outbound Call" <03996590031055> 2011-06-10 11:09:56.571839 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [proceeding][183] 2011-06-10 11:09:56.571839 [DEBUG] sofia.c:4657 Remote SDP: v=0 o=- 421265648 1 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:4467 Audio Codec Compare [G729:18:8000:20:8000]/[G729:18:8000:20:8000] 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:2757 Set Codec sofia/external/03996590031055(a)203.208.207.212 G729/8000 20 ms 160 samples 8000 bits 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:4565 Set 2833 dtmf send payload to 101 2011-06-10 11:09:56.571839 [DEBUG] sofia_glue.c:2987 AUDIO RTP [sofia/external/03996590031055(a)203.208.207.212] 10.1.1.46 port 30000 -> 203.208.207.196 port 30792 codec: 18 ms: 20 2011-06-10 11:09:56.571839 [DEBUG] switch_rtp.c:1607 Starting timer [soft] 160 bytes per 20ms 2011-06-10 11:09:56.573731 [DEBUG] sofia_glue.c:3228 Set 2833 dtmf send payload to 101 2011-06-10 11:09:56.573731 [DEBUG] sofia_glue.c:3233 Set 2833 dtmf receive payload to 101 2011-06-10 11:09:56.573731 [NOTICE] sofia_glue.c:3680 Pre-Answer sofia/external/03996590031055(a)203.208.207.212! 2011-06-10 11:09:56.573731 [DEBUG] switch_channel.c:2627 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change RINGING -> EARLY 2011-06-10 11:09:56.573731 [DEBUG] switch_ivr_originate.c:3408 Originate Resulted in Success: [sofia/external/03996590031055(a)203.208.207.212] 2011-06-10 11:09:56.573731 [DEBUG] mod_commands.c:3205 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2011-06-10 11:09:56.573731 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:09:56.575262 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_EXECUTE 2011-06-10 11:09:56.575262 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996590031055(a)203.208.207.212) State EXECUTE 2011-06-10 11:09:56.575262 [DEBUG] mod_sofia.c:240 sofia/external/03996590031055(a)203.208.207.212 SOFIA EXECUTE 2011-06-10 11:09:56.575262 [DEBUG] switch_core_state_machine.c:157 sofia/external/03996590031055(a)203.208.207.212 Standard EXECUTE EXECUTE sofia/external/03996590031055(a)203.208.207.212 park() 2011-06-10 11:09:56.618770 [DEBUG] switch_rtp.c:2933 Correct ip/port confirmed. recv 669 bytes from udp/[203.208.207.212]:5060 at 03:10:01.562021: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 189 v=0 o=- 421265648 2 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:01.455944 [INFO] sofia.c:729 sofia/external/03996590031055(a)203.208.207.212 Update Callee ID to "03996590031055" <03996590031055> 2011-06-10 11:10:01.455944 [DEBUG] sofia.c:4641 Channel sofia/external/03996590031055(a)203.208.207.212 skipping state [proceeding][183] recv 669 bytes from udp/[203.208.207.212]:5060 at 03:10:01.563178: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 189 v=0 o=- 421265648 3 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:01.457169 [DEBUG] sofia.c:4641 Channel sofia/external/03996590031055(a)203.208.207.212 skipping state [proceeding][183] recv 749 bytes from udp/[203.208.207.212]:5060 at 03:10:27.365284: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKXjQ7eFpKypy5D;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 INVITE Contact: <sip:203.208.207.212:5060> Allow-Events: refer Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Supported: 100rel, timer, replaces Content-Length: 189 v=0 o=- 421265648 4 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:27.258092 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [completing][200] 2011-06-10 11:10:27.258092 [DEBUG] sofia.c:4657 Remote SDP: v=0 o=- 421265648 4 IN IP4 203.208.207.219 s=session c=IN IP4 203.208.207.196 t=0 0 m=audio 30792 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 send 405 bytes to udp/[203.208.207.212]:5060 at 03:10:27.366562: ------------------------------------------------------------------------ ACK sip:203.208.207.212:5060 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKyUg0ga7pUZmrS Max-Forwards: 70 From: "" <sip:03996563750914@202.73.56.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501633 ACK Contact: <sip:mod_sofia@202.73.56.46:5080> Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:27.260267 [DEBUG] sofia.c:4646 Channel sofia/external/03996590031055(a)203.208.207.212 entering state [ready][200] 2011-06-10 11:10:27.260267 [DEBUG] switch_channel.c:2782 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change EARLY -> ACTIVE 2011-06-10 11:10:27.260267 [NOTICE] sofia.c:5175 Channel [sofia/external/03996590031055(a)203.208.207.212] has been answered 2011-06-10 11:10:27.264178 [DEBUG] switch_scheduler.c:214 Added task 23 switch_ivr_schedule_hangup (ea57b74b-a8c2-4fea-9683-98054dc03a79) to run at 1307676927 2011-06-10 11:10:27.265202 [DEBUG] switch_core_session.c:954 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 0 = [origination_caller_id_number=03996590031055] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 1 = [ccd_session_id=20110610105829676824] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 2 = [sip_cid_type=pid] 2011-06-10 11:10:27.266218 [DEBUG] switch_ivr_originate.c:1971 variable string 3 = [privacy=yes] 2011-06-10 11:10:27.266218 [NOTICE] switch_channel.c:808 New Channel sofia/external/03996563750914(a)203.208.207.212 [30228d2b-756a-4a98-871d-db63a2955b52] 2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:4129 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_NEW -> CS_INIT 2011-06-10 11:10:27.266218 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_INIT 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996563750914(a)203.208.207.212) State INIT 2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:84 sofia/external/03996563750914(a)203.208.207.212 SOFIA INIT 2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:124 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_INIT -> CS_ROUTING 2011-06-10 11:10:27.266218 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:356 (sofia/external/03996563750914(a)203.208.207.212) State INIT going to sleep 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_ROUTING 2011-06-10 11:10:27.266218 [DEBUG] switch_channel.c:1657 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change DOWN -> RINGING 2011-06-10 11:10:27.266218 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996563750914(a)203.208.207.212) State ROUTING send 984 bytes to udp/[203.208.207.212]:5060 at 03:10:27.373220: ------------------------------------------------------------------------ INVITE sip:03996563750914@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKZ49rj5Qtr8aBN2011-06-10 11:10:27.266218 [DEBUG] mod_sofia.c:147 sofia/external/03996563750914(a)203.208.207.212 SOFIA ROUTING Max-Forwards: 70 From: "" <sip:03996590031055@202.73.56.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212> Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Contact: <sip:mod_sofia@202.73.56.46:5080> User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110603T012235Z Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Allow-Events: talk, hold, refer Privacy: none Content-Type: application/sdp Content-Disposition: session Content-Length: 204 X-FS-Support: update_display P-Asserted-Identity: "" <sip:03996590031055@202.73.56.46> v=0 o=FreeSWITCH 1307646863 1307646864 IN IP4 202.73.56.46 s=FreeSWITCH c=IN IP4 202.73.56.46 t=0 0 m=audio 28564 RTP/AVP 18 3 101 13 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2011-06-10 11:10:27.267630 [DEBUG] sofia.c:4646 Channel sofia/external/03996563750914(a)203.208.207.212 entering state [calling][0] 2011-06-10 11:10:27.267630 [DEBUG] switch_ivr_originate.c:66 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2011-06-10 11:10:27.267630 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:359 (sofia/external/03996563750914(a)203.208.207.212) State ROUTING going to sleep 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_CONSUME_MEDIA 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996563750914(a)203.208.207.212) State CONSUME_MEDIA 2011-06-10 11:10:27.267630 [DEBUG] switch_core_state_machine.c:378 (sofia/external/03996563750914(a)203.208.207.212) State CONSUME_MEDIA going to sleep recv 307 bytes from udp/[203.208.207.212]:5060 at 03:10:27.378710: ------------------------------------------------------------------------ SIP/2.0 100 Trying Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212> Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:27.278484 [DEBUG] switch_ivr.c:563 sofia/external/03996590031055(a)203.208.207.212 Command Execute playback(/usr/local/freeswitch/sounds/clearhub/MusicForCalls_v1.wav) EXECUTE sofia/external/03996590031055(a)203.208.207.212 playback(/usr/local/freeswitch/sounds/clearhub/MusicForCalls_v1.wav) 2011-06-10 11:10:27.278484 [DEBUG] switch_core_file.c:176 File /usr/local/freeswitch/sounds/clearhub/MusicForCalls_v1.wav sample rate 11025 doesn't match requested rate 8000 2011-06-10 11:10:27.278484 [WARNING] switch_core_file.c:189 File has 2 channels, muxing to mono will occur. 2011-06-10 11:10:27.278484 [DEBUG] switch_ivr_play_say.c:1244 Codec Activated L16@8000hz 2 channels 20ms 2011-06-10 11:10:27.298764 [INFO] mod_com_g729.c:119 ENCODER CREATE - 0x2aaab00310c0 0x2aaab00b20c0 recv 666 bytes from udp/[203.208.207.212]:5060 at 03:10:28.618472: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 186 v=0 o=- 131082 1 IN IP4 203.208.207.218 s=session c=IN IP4 203.208.207.195 t=0 0 m=audio 45002 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:28.513195 [INFO] sofia.c:729 sofia/external/03996563750914(a)203.208.207.212 Update Callee ID to "Outbound Call" <03996563750914> 2011-06-10 11:10:28.513195 [DEBUG] sofia.c:4646 Channel sofia/external/03996563750914(a)203.208.207.212 entering state [proceeding][183] 2011-06-10 11:10:28.513195 [DEBUG] sofia.c:4657 Remote SDP: v=0 o=- 131082 1 IN IP4 203.208.207.218 s=session c=IN IP4 203.208.207.195 t=0 0 m=audio 45002 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:4467 Audio Codec Compare [G729:18:8000:20:8000]/[G729:18:8000:20:8000] 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:2757 Set Codec sofia/external/03996563750914(a)203.208.207.212 G729/8000 20 ms 160 samples 8000 bits 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:4565 Set 2833 dtmf send payload to 101 2011-06-10 11:10:28.513195 [DEBUG] sofia_glue.c:2987 AUDIO RTP [sofia/external/03996563750914(a)203.208.207.212] 10.1.1.46 port 28564 -> 203.208.207.195 port 45002 codec: 18 ms: 20 2011-06-10 11:10:28.513195 [DEBUG] switch_rtp.c:1607 Starting timer [soft] 160 bytes per 20ms 2011-06-10 11:10:28.514938 [DEBUG] sofia_glue.c:3228 Set 2833 dtmf send payload to 101 2011-06-10 11:10:28.514938 [DEBUG] sofia_glue.c:3233 Set 2833 dtmf receive payload to 101 2011-06-10 11:10:28.514938 [NOTICE] sofia_glue.c:3680 Pre-Answer sofia/external/03996563750914(a)203.208.207.212! 2011-06-10 11:10:28.514938 [DEBUG] switch_channel.c:2627 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change RINGING -> EARLY 2011-06-10 11:10:28.514938 [DEBUG] switch_ivr_originate.c:3408 Originate Resulted in Success: [sofia/external/03996563750914(a)203.208.207.212] 2011-06-10 11:10:28.514938 [DEBUG] mod_commands.c:3205 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2011-06-10 11:10:28.514938 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.515884 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_EXECUTE 2011-06-10 11:10:28.515884 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996563750914(a)203.208.207.212) State EXECUTE 2011-06-10 11:10:28.515884 [DEBUG] mod_sofia.c:240 sofia/external/03996563750914(a)203.208.207.212 SOFIA EXECUTE 2011-06-10 11:10:28.515884 [DEBUG] switch_core_state_machine.c:157 sofia/external/03996563750914(a)203.208.207.212 Standard EXECUTE EXECUTE sofia/external/03996563750914(a)203.208.207.212 park() 2011-06-10 11:10:28.516887 [DEBUG] switch_core_session.c:954 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_ivr_bridge.c:1480 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_EXECUTE -> CS_HIBERNATE 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_ivr_bridge.c:1482 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_EXECUTE -> CS_HIBERNATE 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:771 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.517896 [DEBUG] switch_core_session.c:771 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr_play_say.c:1581 done playing file 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr.c:563 sofia/external/03996590031055(a)203.208.207.212 Command Execute playback(tone_stream://%(2000,4000,440,480);loops=10) 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996563750914(a)203.208.207.212) State EXECUTE going to sleep 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_HIBERNATE 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996563750914(a)203.208.207.212) State HIBERNATE 2011-06-10 11:10:28.519223 [DEBUG] mod_sofia.c:221 sofia/external/03996563750914(a)203.208.207.212 SOFIA HIBERNATE 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr_bridge.c:731 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_HIBERNATE -> CS_RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996563750914(a)203.208.207.212) State HIBERNATE going to sleep 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996563750914(a)203.208.207.212) State RESET 2011-06-10 11:10:28.519223 [DEBUG] mod_sofia.c:165 sofia/external/03996563750914(a)203.208.207.212 SOFIA RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_ivr_bridge.c:716 sofia/external/03996563750914(a)203.208.207.212 CUSTOM RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:66 sofia/external/03996563750914(a)203.208.207.212 Standard RESET 2011-06-10 11:10:28.519223 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996563750914(a)203.208.207.212) State RESET going to sleep 2011-06-10 11:10:28.519223 [DEBUG] switch_core_session.c:709 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] recv 666 bytes from udp/[203.208.207.212]:5060 at 03:10:30.540814: ------------------------------------------------------------------------ SIP/2.0 183 Session Progress Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, PRACK, INFO, REFER, NOTIFY, SUBSCRIBE, UPDATE Content-Type: application/sdp Content-Length: 186 v=0 o=- 131082 2 IN IP4 203.208.207.218 s=session c=IN IP4 203.208.207.195 t=0 0 m=audio 45002 RTP/AVP 18 101 a=rtpmap:18 G729/8000 a=rtpmap:101 telephone-event/8000 a=sendrecv ------------------------------------------------------------------------ 2011-06-10 11:10:30.435309 [INFO] sofia.c:729 sofia/external/03996563750914(a)203.208.207.212 Update Callee ID to "03996563750914" <03996563750914> 2011-06-10 11:10:30.435309 [DEBUG] sofia.c:4641 Channel sofia/external/03996563750914(a)203.208.207.212 skipping state [proceeding][183] 2011-06-10 11:10:30.691404 [WARNING] switch_core_session.c:1940 Cannot execute app 'playback' media required on an outbound channel that does not have media established 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:366 (sofia/external/03996590031055(a)203.208.207.212) State EXECUTE going to sleep 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_HIBERNATE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996590031055(a)203.208.207.212) State HIBERNATE 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:221 sofia/external/03996590031055(a)203.208.207.212 SOFIA HIBERNATE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:731 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_HIBERNATE -> CS_RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:381 (sofia/external/03996590031055(a)203.208.207.212) State HIBERNATE going to sleep 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996590031055(a)203.208.207.212) State RESET 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:165 sofia/external/03996590031055(a)203.208.207.212 SOFIA RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:716 sofia/external/03996590031055(a)203.208.207.212 CUSTOM RESET 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:723 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_RESET -> CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:362 (sofia/external/03996590031055(a)203.208.207.212) State RESET going to sleep 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996590031055(a)203.208.207.212) State SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:558 SOFIA SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:741 sofia/external/03996590031055(a)203.208.207.212 CUSTOM SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:761 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_RESET -> CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996563750914(a)203.208.207.212) State SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] mod_sofia.c:558 SOFIA SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_ivr_bridge.c:741 sofia/external/03996563750914(a)203.208.207.212 CUSTOM SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:204 sofia/external/03996563750914(a)203.208.207.212 Standard SOFT_EXECUTE 2011-06-10 11:10:30.691404 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996563750914(a)203.208.207.212) State SOFT_EXECUTE going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2535 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change EARLY -> HANGUP 2011-06-10 11:10:30.711798 [NOTICE] switch_ivr_originate.c:1045 Hangup sofia/external/03996563750914(a)203.208.207.212 [CS_SOFT_EXECUTE] [DESTINATION_OUT_OF_ORDER] 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2551 Send signal sofia/external/03996563750914(a)203.208.207.212 [KILL] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_HANGUP 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2535 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change ACTIVE -> HANGUP 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996563750914(a)203.208.207.212) State HANGUP 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:457 Channel sofia/external/03996563750914(a)203.208.207.212 hanging up, cause: DESTINATION_OUT_OF_ORDER 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:510 Sending CANCEL to sofia/external/03996563750914(a)203.208.207.212 send 390 bytes to udp/[203.208.207.212]:5060 at 03:10:30.818391: ------------------------------------------------------------------------ CANCEL sip:03996563750914@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKZ49rj5Qtr8aBN Max-Forwards: 70 From: "" <sip:03996590031055@202.73.56.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212> Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 CANCEL Reason: Q.850;cause=27;text="DESTINATION_OUT_OF_ORDER" Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:30.711798 [NOTICE] switch_ivr_bridge.c:772 Hangup sofia/external/03996590031055(a)203.208.207.212 [CS_SOFT_EXECUTE] [ORIGINATOR_CANCEL] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:46 sofia/external/03996563750914(a)203.208.207.212 Standard HANGUP, cause: DESTINATION_OUT_OF_ORDER 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996563750914(a)203.208.207.212) State HANGUP going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:351 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_HANGUP -> CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996563750914(a)203.208.207.212) State REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:53 sofia/external/03996563750914(a)203.208.207.212 Standard REPORTING, cause: DESTINATION_OUT_OF_ORDER 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996563750914(a)203.208.207.212) State REPORTING going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_channel.c:2551 Send signal sofia/external/03996590031055(a)203.208.207.212 [KILL] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:372 (sofia/external/03996590031055(a)203.208.207.212) State SOFT_EXECUTE going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_HANGUP 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:345 (sofia/external/03996563750914(a)203.208.207.212) State Change CS_REPORTING -> CS_DESTROY 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996563750914(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1288 Session 40 (sofia/external/03996563750914(a)203.208.207.212) Locked, Waiting on external entities 2011-06-10 11:10:30.711798 [NOTICE] switch_core_session.c:1306 Session 40 (sofia/external/03996563750914(a)203.208.207.212) Ended 2011-06-10 11:10:30.711798 [NOTICE] switch_core_session.c:1308 Close Channel sofia/external/03996563750914(a)203.208.207.212 [CS_DESTROY] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:449 (sofia/external/03996563750914(a)203.208.207.212) Callstate Change HANGUP -> DOWN 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:452 (sofia/external/03996563750914(a)203.208.207.212) Running State Change CS_DESTROY 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996563750914(a)203.208.207.212) State DESTROY 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:362 sofia/external/03996563750914(a)203.208.207.212 SOFIA DESTROY 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaaac013028 (nil) 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaaac013028 (nil) 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaaac013088 (nil) 2011-06-10 11:10:30.711798 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaaac013088 (nil) 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:60 sofia/external/03996563750914(a)203.208.207.212 Standard DESTROY 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996563750914(a)203.208.207.212) State DESTROY going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996590031055(a)203.208.207.212) State HANGUP 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:457 Channel sofia/external/03996590031055(a)203.208.207.212 hanging up, cause: ORIGINATOR_CANCEL 2011-06-10 11:10:30.711798 [DEBUG] mod_sofia.c:500 Sending BYE to sofia/external/03996590031055(a)203.208.207.212 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:46 sofia/external/03996590031055(a)203.208.207.212 Standard HANGUP, cause: ORIGINATOR_CANCEL 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:557 (sofia/external/03996590031055(a)203.208.207.212) State HANGUP going to sleep 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:351 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_HANGUP -> CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:320 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_REPORTING 2011-06-10 11:10:30.711798 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996590031055(a)203.208.207.212) State REPORTING 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:53 sofia/external/03996590031055(a)203.208.207.212 Standard REPORTING, cause: ORIGINATOR_CANCEL 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:617 (sofia/external/03996590031055(a)203.208.207.212) State REPORTING going to sleep 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:345 (sofia/external/03996590031055(a)203.208.207.212) State Change CS_REPORTING -> CS_DESTROY 2011-06-10 11:10:30.714001 [DEBUG] switch_core_session.c:1116 Send signal sofia/external/03996590031055(a)203.208.207.212 [BREAK] 2011-06-10 11:10:30.714001 [DEBUG] switch_core_session.c:1288 Session 39 (sofia/external/03996590031055(a)203.208.207.212) Locked, Waiting on external entities 2011-06-10 11:10:30.714001 [NOTICE] switch_core_session.c:1306 Session 39 (sofia/external/03996590031055(a)203.208.207.212) Ended 2011-06-10 11:10:30.714001 [NOTICE] switch_core_session.c:1308 Close Channel sofia/external/03996590031055(a)203.208.207.212 [CS_DESTROY] 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:449 (sofia/external/03996590031055(a)203.208.207.212) Callstate Change HANGUP -> DOWN 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:452 (sofia/external/03996590031055(a)203.208.207.212) Running State Change CS_DESTROY 2011-06-10 11:10:30.714001 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996590031055(a)203.208.207.212) State DESTROY 2011-06-10 11:10:30.714001 [DEBUG] mod_sofia.c:362 sofia/external/03996590031055(a)203.208.207.212 SOFIA DESTROY 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaab0031060 (nil) 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaab0031060 (nil) 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:78 ENCODER DESTROYX - 0x2aaab00310c0 0x2aaab00b20c0 2011-06-10 11:10:30.714001 [INFO] mod_com_g729.c:79 DECODER DESTROYX - 0x2aaab00310c0 (nil) send 662 bytes to udp/[203.208.207.212]:5060 at 03:10:30.820530: ------------------------------------------------------------------------ BYE sip:203.208.207.212:5060 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bK0D3Hm08XNH1Xg Max-Forwards: 70 From: "" <sip:03996563750914@202.73.56.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501634 BYE Contact: <sip:mod_sofia@202.73.56.46:5080> User-Agent: FreeSWITCH-mod_sofia/1.0.7-hacked-20110603T012235Z Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY Supported: timer, precondition, path, replaces Reason: FreeSWITCH;cause=487;text="ORIGINATOR_CANCEL" Content-Length: 0 ------------------------------------------------------------------------ 2011-06-10 11:10:30.715878 [INFO] mod_com_g729.c:83 ENCODER DESTROY - 0x2aaab00310c0 0x2aaab00b20c0 2011-06-10 11:10:30.715878 [DEBUG] switch_core_state_machine.c:60 sofia/external/03996590031055(a)203.208.207.212 Standard DESTROY 2011-06-10 11:10:30.715878 [DEBUG] switch_core_state_machine.c:462 (sofia/external/03996590031055(a)203.208.207.212) State DESTROY going to sleep recv 383 bytes from udp/[203.208.207.212]:5060 at 03:10:30.823302: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D00000u00000001QXU3LU Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 CANCEL Contact: <sip:203.208.207.212:5060> Content-Length: 0 ------------------------------------------------------------------------ recv 411 bytes from udp/[203.208.207.212]:5060 at 03:10:30.824765: ------------------------------------------------------------------------ SIP/2.0 487 Request Terminated Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bKZ49rj5Qtr8aBN;received=10.1.1.46 From: "" <sip:03996590031055@10.1.1.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 INVITE Reason: SIP;cause=487;text="Request Terminated" Content-Length: 0 ------------------------------------------------------------------------ send 371 bytes to udp/[203.208.207.212]:5060 at 03:10:30.824891: ------------------------------------------------------------------------ ACK sip:03996563750914@203.208.207.212 SIP/2.0 Via: SIP/2.0/UDP 202.73.56.46:5080;rport;branch=z9hG4bKZ49rj5Qtr8aBN Max-Forwards: 70 From: "" <sip:03996590031055@202.73.56.46>;tag=jDSe6ycN62Zar To: <sip:03996563750914@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ410OWC5F Call-ID: 082c8244-0db2-122f-c4ab-0015c5fc7ad9 CSeq: 13501649 ACK Content-Length: 0 ------------------------------------------------------------------------ recv 380 bytes from udp/[203.208.207.212]:5060 at 03:10:30.826375: ------------------------------------------------------------------------ SIP/2.0 200 Ok Via: SIP/2.0/UDP 10.1.1.46:5080;rport=5080;branch=z9hG4bK0D3Hm08XNH1Xg;received=10.1.1.46 From: "" <sip:03996563750914@10.1.1.46>;tag=H4ZN43UH9S9Qc To: <sip:03996590031055@203.208.207.212>;tag=2QGB951HCR30000E1D0001Ll0008FJ21EI9PBW Call-ID: f529ae7e-0db1-122f-c4ab-0015c5fc7ad9 CSeq: 13501634 BYE -- View this message in context: http://freeswitch-users.2379917.n2.nabble.com/Leg-A-is-automatically-discon… Sent from the freeswitch-users mailing list archive at Nabble.com.

4 14

Re: [Freeswitch-users] please help!!! how to set flag 'endconf' in bridging conference
by ran zhang 18 Jul '11

18 Jul '11

I think most people don't understand my problem, let me just clarify: I want a way so only when either one of the first 2 members of the bridging conference leaves the conference, the conference will be terminated even if there are 2 or more members in it. i'm trying to set 'endconf' flag in a bridging conference using 'bridge:confname+flag{endconf}:user/10', so the first member entering the conference will invite user extension 10, but its giving me config error while running. I only want to use 'bridging conference' since i want at least 2 people in the conference to start with. On 7/1/2011 9:46 AM, Michael Collins wrote: > > > On Fri, Jul 1, 2011 at 9:45 AM, ran zhang <rzhang(a)gosilverplus.com > <mailto:rzhang@gosilverplus.com>> wrote: > > do u understand my problem with the bridging conference? pl > suggest on what to do. > > Honestly, no. But I want this discussion on the public mailing list so > that we all can talk about it. > -MC

3 2

What does "delete interp" do?
by Yungwei Chen 18 Jul '11

18 Jul '11

Hi, I came across the following statement in SpeechTools.jm, and I'm wondering what it does. Thanks. delete interp;

1 0

BLF (on Snom)
by Frank Rosengart 18 Jul '11

18 Jul '11

Hi, I'm tearing my hair about getting FS & BLF to work with Snom 360s. I think it should work, but I'm stuck with debugging. Maybe someone can help. What I know: The whole setup was working fine on Asterisk 1.4, including BLF. The phone is configured with a function key => BLF, <to_be_monitored>@<mydomain> The phone sends SUBSCRIBE to FS and receives a '202 Accepted' The sqlite db lists this subscription with SELECT * FROM sip_subscriptions. How can I trigger FS to send the NOTIFY dialog-info-XML? * I tried on the cli to send presence in to_be_monitored@mydomain inuse inuse and <action application="set" data="result=${presence(in to_be_monitored@mydomain inuse inuse)}"/> as well as the <param name="dial-string" value="{presence_id=${dialed_user}@${dialed_domain}}${sofia_contact(${dialed_user}@${dialed_domain})}"/> or setting the presence_id variable from the dialplan, like suggested in the "persistant BLF' ML thread a while ago. There is no NOTIFY packet leaving FS, or any reaction of LED on the phone. Thanks for any hints. Frank

3 3

rfc 4662
by Alex Crow 18 Jul '11

18 Jul '11

Hi, Does freeswitch support RFC4662 SIP resource lists notification (on phones it seems to be called Eventlist BLF)? It would seem a good thing to reduce traffic for monitoring presence/status. There seems to be at least one post on Trixbox forums indicating it does, however I can find nothing in the Wiki. Thanks, Alex

1 0

domain-Element in SIP-Profiles
by David Spautz 18 Jul '11

18 Jul '11

Hi FreeSWITCH users, currently, i am write my bachelor thesis about FreeSWITCH and the communication with external application servers. In this regard, I am faced with the question of what function the <domains>-element of sofia profiles should have. What is the concept behind it? And what are the differences to the domain attribute in the <profile name='...' domain='...'>-element of sofia.conf.xml file? It would be helpful if someone could describe one or more scenarios to use this domains-element. Is it a way to register the SIP Profile to all user specified gateways in addition? <domains>     <domain name="all" alias="true" parse="false"/> </domains> Thanks for all help David Anhören Umschrift

1 0

How to creat an outgoing media channal for non-SIP?
by max 18 Jul '11

18 Jul '11

Hello, I want to write a quick and dirty extension as a prove of concept for Freeswitch translating SIP into another protocol(e.g. proprietary RUF XML-RTP for calls). For that I chose to use mod_erlang_event to handle the handshake and the other standards server. The media should be handled completely by Freeswitch. Please see the attached picture. The focus on this project is to get used to Erlang as well as to Freeswitch. To the problem: While creating an outgoing media channel via Erlang to the non SIP I got stuck. Can I use originate in that context and if yes, how? If no, is there any other command? Thank you, Max

2 2

Looking for US based providers offering t.38 that work with freeswitch
by Tony Graziano 18 Jul '11

18 Jul '11

So far we have only been able to find a provider using sonus switches for this. Is anyone successfully using a trunk provider to receive faxes via t.38 in freeswitch they are willing to share? Thanks, Tony

2 1

Hanged up callers doubt
by Leonardo P. Bidinoto 18 Jul '11

18 Jul '11

Hi everyone! I having some troubles with my FS box(CentOS 5.3 64-bit, Xeon(R) CPU 2.66GHz with 8 cores , 8GbRAM, (git-bc19d28 2011-04-25 15-53-54 -0400) ). Sometimes, when a user calls to my box and hangs up, the user's channel remains on FS when i execute a "show channels" command. with "uuid_exists" command, im receiving "false". looking at freeswitch log, the last message for the channels is: 0eeb975f-2ae9-4f7d-9888-1478faf87d2d 2011-05-11 00:05:57.555142 [DEBUG] switch_core_session.c:1286 Session 38 (sofia/external/2562687580(a)10.0.70.6) Locked, Waiting on external entities Im not receiving "Ended" State after the previous message. did someone have seen this issue? or know a way to remove from freeswitch? Thanks all. -- Leonardo Pires Bidinoto Voice Technology www.voicetechnology.com.br

4 14

How to creat an outgoing media channal for non-SIP?
by max 18 Jul '11

18 Jul '11

Hello, First of all I am sorry if you receive this mail double. I sent it but I didn't get a copy on the mailing list. I want to write a quick and dirty extension as a prove of concept for Freeswitch translating SIP into another protocol(e.g. proprietary RUF XML-RTP for calls). For that I chose to use mod_erlang_event to handle the handshake and the other standards server. The media should be handled completely by Freeswitch. The focus on this project is to get used to Erlang as well as to Freeswitch so solutions should contain this two parts. To the problem: While creating an outgoing media channel via Erlang to the non SIP I got stuck. Can I use originate in that context and if yes, how? If no, is there any other command to realise that? Also I would need to know how to control/close this media channel. Thank you, Max

1 0