Call thru external gate released before INVITE (NORMAL_UNSPECIFIED)
Hello! Very strange, some time ago this works, but now not... and I don't know where I was made mistake... From FS CLI do command *originate {ignore_early_media=true}sofia/internal/00294539100% &bridge(sofia/gateway/voip_provider/919262417024@voip_provider)* Call to user 00294539100 established, but second call to external is dropped before send INVITE... (local cause NORMAL_UNSPECIFIED) Could someone give me idea where is a problem? Trace here 2015-07-15 10:09:19.375204 [DEBUG] switch_ivr_originate.c:2100 Parsing global variables 2015-07-15 10:09:19.375204 [DEBUG] switch_event.c:1698 Parsing variable [ignore_early_media]=[true] 2015-07-15 10:09:19.375204 [NOTICE] switch_channel.c:1075 New Channel sofia/internal/00294539100 [680855ef-9973-43c4-bb9d-7ee1dd27d392] 2015-07-15 10:09:19.375204 [DEBUG] mod_sofia.c:4701 (sofia/internal/00294539100) State Change CS_NEW -> CS_INIT 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_INIT 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/00294539100) State INIT 2015-07-15 10:09:19.375204 [DEBUG] mod_sofia.c:87 sofia/internal/00294539100 SOFIA INIT freeswitch@internal> 2015-07-15 10:09:19.375204 [DEBUG] sofia_glue.c:1236 sofia/internal/00294539100 sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436918327 1436918328 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 22232 RTP/AVP 102 9 0 8 3 101 13 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=20; maxptime=20; samplerate=48000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 29716 RTP/AVP 103 a=rtpmap:103 VP8/90000 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:40 sofia/internal/00294539100 Standard INIT 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/00294539100) State Change CS_INIT -> CS_ROUTING 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/00294539100) State INIT going to sleep 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_ROUTING 2015-07-15 10:09:19.375204 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [calling][0] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/00294539100) State ROUTING 2015-07-15 10:09:19.375204 [DEBUG] mod_sofia.c:123 sofia/internal/00294539100 SOFIA ROUTING 2015-07-15 10:09:19.375204 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/00294539100) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/00294539100) State ROUTING going to sleep 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_CONSUME_MEDIA 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/00294539100) State CONSUME_MEDIA 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/00294539100) State CONSUME_MEDIA going to sleep 2015-07-15 10:09:19.455217 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.455217 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.455217 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [proceeding][180] 2015-07-15 10:09:19.455217 [NOTICE] sofia.c:6729 Ring-Ready sofia/internal/00294539100! 2015-07-15 10:09:19.455217 [DEBUG] switch_channel.c:3297 (sofia/internal/00294539100) Callstate Change DOWN -> RINGING 2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [completing][200] 2015-07-15 10:09:22.075203 [DEBUG] sofia.c:6637 Remote SDP: v=0 o=floxent 3645929363 1 IN IP4 192.168.223.220 s=sflphone c=IN IP4 192.168.223.220 t=0 0 m=audio 28878 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtcp:28879 IN IP4 192.168.223.220 m=video 0 RTP/AVP 103 2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [ready][200] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3727 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3588 Set telephone-event payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:2507 Set Codec sofia/internal/00294539100 G722/8000 20 ms 160 samples 64000 bits 1 channels 2015-07-15 10:09:22.075203 [DEBUG] switch_core_codec.c:111 sofia/internal/00294539100 Original read codec set to G722:9 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3926 Set 2833 dtmf send payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5171 AUDIO RTP [sofia/internal/00294539100] 194.87.7.17 port 22232 -> 192.168.223.220 port 28878 codec: 9 ms: 20 2015-07-15 10:09:22.075203 [DEBUG] switch_rtp.c:3569 Starting timer [soft] 160 bytes per 20ms 2015-07-15 10:09:22.075203 [INFO] switch_core_media.c:5388 Activating RTCP PORT 28879 2015-07-15 10:09:22.075203 [DEBUG] switch_rtp.c:3919 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 28879 2015-07-15 10:09:22.075203 [DEBUG] switch_rtp.c:2349 Setting RTCP remote addr to 192.168.223.220:28879 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5469 Set 2833 dtmf send payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5475 Set 2833 dtmf receive payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5497 sofia/internal/00294539100 Set rtp dtmf delay to 40 *2015-07-15 10:09:22.075203 [NOTICE] sofia.c:7488 Channel [sofia/internal/00294539100] has been answered* 2015-07-15 10:09:22.075203 [DEBUG] switch_channel.c:3711 (sofia/internal/00294539100) Callstate Change RINGING -> ACTIVE 2015-07-15 10:09:22.095205 [DEBUG] switch_ivr_originate.c:3577 Originate Resulted in Success: [sofia/internal/00294539100] 2015-07-15 10:09:22.095205 [INFO] switch_channel.c:3082 sofia/internal/00294539100 Flipping CID from "" <0000000000> to "Outbound Call" <00294539100%> 2015-07-15 10:09:22.095205 [DEBUG] mod_commands.c:4469 (sofia/internal/00294539100) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/00294539100) State EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:178 sofia/internal/00294539100 SOFIA EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:258 sofia/internal/00294539100 Standard EXECUTE EXECUTE sofia/internal/00294539100 bridge(sofia/gateway/voip_provider/919262417024@voip_provider) 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:1786 (sofia/internal/00294539100) Callstate Change ACTIVE -> RING_WAIT 2015-07-15 10:09:22.095205 [DEBUG] switch_ivr_originate.c:2100 Parsing global variables 2015-07-15 10:09:22.095205 [NOTICE] switch_channel.c:1075 New Channel sofia/external/919262417024@voip_provider [32799f05-3018-4a48-bea4-1d3cc3700835] 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:4701 (sofia/external/919262417024@voip_provider) State Change CS_NEW -> CS_INIT 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_INIT 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@voip_provider) State INIT 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:87 sofia/external/919262417024@voip_provider SOFIA INIT 2015-07-15 10:09:22.095205 [DEBUG] sofia_glue.c:1236 sofia/external/919262417024@voip_provider sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436921310 1436921311 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 19252 RTP/AVP 9 0 8 3 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 31754 RTP/AVP 103 a=rtpmap:103 VP8/90000 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:40 sofia/external/919262417024@voip_provider Standard INIT 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:48 (sofia/external/919262417024@voip_provider) State Change CS_INIT -> CS_ROUTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@voip_provider) State INIT going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_ROUTING 2015-07-15 10:09:22.095205 [DEBUG] sofia.c:6627 Channel sofia/external/919262417024@voip_provider entering state [terminated][900] *2015-07-15 10:09:22.095205 [NOTICE] sofia.c:7543 Hangup sofia/external/919262417024@voip_provider [CS_ROUTING] [NORMAL_UNSPECIFIED]* 2015-07-15 10:09:22.095205 [DEBUG] switch_ivr_originate.c:3720 Originate Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED] 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:3242 Send signal sofia/external/919262417024@voip_provider [KILL] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:528 (sofia/external/919262417024@voip_provider) State ROUTING 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:123 sofia/external/919262417024@voip_provider SOFIA ROUTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:528 (sofia/external/919262417024@voip_provider) State ROUTING going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_HANGUP 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:735 (sofia/external/919262417024@voip_provider) Callstate Change DOWN -> HANGUP 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:737 (sofia/external/919262417024@voip_provider) State HANGUP 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:413 Channel sofia/external/919262417024@voip_provider hanging up, cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:60 sofia/external/919262417024@voip_provider Standard HANGUP, cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:737 (sofia/external/919262417024@voip_provider) State HANGUP going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:504 (sofia/external/919262417024@voip_provider) State Change CS_HANGUP -> CS_REPORTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_REPORTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:823 (sofia/external/919262417024@voip_provider) State REPORTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:104 sofia/external/919262417024@voip_provider Standard REPORTING, cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:823 (sofia/external/919262417024@voip_provider) State REPORTING going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:1999 (sofia/internal/00294539100) Callstate Change RING_WAIT -> ACTIVE 2015-07-15 10:09:22.095205 [INFO] mod_dptools.c:3268 Originate Failed. Cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [NOTICE] switch_channel.c:4747 Hangup sofia/internal/00294539100 [CS_EXECUTE] [NORMAL_UNSPECIFIED] 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:3242 Send signal sofia/internal/00294539100 [KILL] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:498 (sofia/external/919262417024@voip_provider) State Change CS_REPORTING -> CS_DESTROY 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1623 Session 202 (sofia/external/919262417024@voip_provider) Locked, Waiting on external entities 2015-07-15 10:09:22.095205 [NOTICE] switch_core_session.c:1641 Session 202 (sofia/external/919262417024@voip_provider) Ended 2015-07-15 10:09:22.095205 [NOTICE] switch_core_session.c:1645 Close Channel sofia/external/919262417024@voip_provider [CS_DESTROY]
What is "voip_provider"? Please enable 1) sofia global siptrace on 2) sofia loglevel all 9
Sergey, Peter, *originate {ignore_early_media=true}sofia/gateway/sipnet.ru/**919262417024@sipnet.ru &bridge(sofia/internal/00294539100%)* If i change like this - call established fine, but fail if first call to internal 00294539100 New trace 2015-07-15 11:17:57.115208 [NOTICE] switch_channel.c:1075 New Channel sofia/external/919262417024@sipnet.ru [4c3ee575-4f0f-4ebd-b4ec-0e5357469690] 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:4701 (sofia/external/919262417024@sipnet.ru) State Change CS_NEW -> CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru [BREAK] 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@sipnet.ru) Running State Change CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@sipnet.ru) State INIT 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:87 sofia/external/919262417024@sipnet.ru SOFIA INIT nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia_glue.c:1236 sofia/external/919262417024@sipnet.ru sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436923599 1436923600 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 21078 RTP/AVP 9 0 8 3 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 20298 RTP/AVP 103 a=rtpmap:103 VP8/90000 nua.c:633 nua_invite() nua: nua_invite: entering nua_stack.c:529 nua_signal() nua(0xb6d54b10): sent signal r_invite 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:40 sofia/external/919262417024@sipnet.ru Standard INIT nua_stack.c:569 nua_stack_signal() nua(0xb6d54b10): recv signal r_invite nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:48 (sofia/external/919262417024@sipnet.ru) State Change CS_INIT -> CS_ROUTING soa.c:280 soa_clone() soa_clone(static::0x8a45980, 0x8a547e8, 0xb6d54b10) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru [BREAK] soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@sipnet.ru) State INIT going to sleep soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event r_invite 900 Internal error at nua_client.c:552 nua_session.c:4139 signal_call_state_change() nua(0xb6d54b10): call state changed: init -> terminated nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_state 900 Internal error at nua_client.c:552 nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_terminated 900 Internal error at nua_client.c:552 nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@sipnet.ru) Running State Change CS_ROUTING 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia.c:6627 Channel sofia/external/919262417024@sipnet.ru entering state [terminated][900] 2015-07-15 11:17:57.115208 [NOTICE] sofia.c:7543 Hangup sofia/external/919262417024@sipnet.ru [CS_ROUTING] [NORMAL_UNSPECIFIED] On 07/15/2015 11:14 AM, Sergey Safarov wrote:
What is "voip_provider"? Please enable 1) sofia global siptrace on 2) sofia loglevel all 9
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
Should be originate {ignore_early_media=true}sofia/gateway/sipnet.ru/919262417024 <mailto:919262417024@sipnet.ru> &bridge(sofia/gateway/sipnet.ru/00294539100) You seem to be doubling up the signet.ru by having as a gateway then again @sipnet.ru <http://sipnet.ru/> - either is valid but both at the same time is wrong. After the destination number you had % - not sure why, but wouldn’t have thought it should be there. Additionally you are using 2 different number formats (with and without 00 prefix) - I would check with provider what format you should be sending.
On 15 Jul 2015, at 08:27, Dmitry Mordovin <dm@dwide.com> wrote:
Sergey, Peter,
originate {ignore_early_media=true}sofia/gateway/sipnet.ru/919262417024@sipnet.ru <mailto:919262417024@sipnet.ru> &bridge(sofia/internal/00294539100%)
If i change like this - call established fine, but fail if first call to internal 00294539100
New trace
2015-07-15 11:17:57.115208 [NOTICE] switch_channel.c:1075 New Channel sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> [4c3ee575-4f0f-4ebd-b4ec-0e5357469690] 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:4701 (sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru>) State Change CS_NEW -> CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> [BREAK] 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru>) Running State Change CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru>) State INIT 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:87 sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> SOFIA INIT nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia_glue.c:1236 sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436923599 1436923600 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 21078 RTP/AVP 9 0 8 3 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 20298 RTP/AVP 103 a=rtpmap:103 VP8/90000
nua.c:633 nua_invite() nua: nua_invite: entering nua_stack.c:529 nua_signal() nua(0xb6d54b10): sent signal r_invite 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:40 sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> Standard INIT nua_stack.c:569 nua_stack_signal() nua(0xb6d54b10): recv signal r_invite nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:48 (sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru>) State Change CS_INIT -> CS_ROUTING soa.c:280 soa_clone() soa_clone(static::0x8a45980, 0x8a547e8, 0xb6d54b10) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> [BREAK] soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru>) State INIT going to sleep soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event r_invite 900 Internal error at nua_client.c:552 nua_session.c:4139 signal_call_state_change() nua(0xb6d54b10): call state changed: init -> terminated nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_state 900 Internal error at nua_client.c:552 nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_terminated 900 Internal error at nua_client.c:552 nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru>) Running State Change CS_ROUTING 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> [BREAK] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia.c:6627 Channel sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> entering state [terminated][900] 2015-07-15 11:17:57.115208 [NOTICE] sofia.c:7543 Hangup sofia/external/919262417024@sipnet.ru <mailto:sofia/external/919262417024@sipnet.ru> [CS_ROUTING] [NORMAL_UNSPECIFIED]
On 07/15/2015 11:14 AM, Sergey Safarov wrote:
What is "voip_provider"? Please enable 1) sofia global siptrace on 2) sofia loglevel all 9
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org <mailto:consulting@freeswitch.org> http://www.freeswitchsolutions.com <http://www.freeswitchsolutions.com/>
Official FreeSWITCH Sites http://www.freeswitch.org <http://www.freeswitch.org/> http://confluence.freeswitch.org <http://confluence.freeswitch.org/> http://www.cluecon.com <http://www.cluecon.com/>
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org <mailto:FreeSWITCH-users@lists.freeswitch.org> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users <http://lists.freeswitch.org/mailman/listinfo/freeswitch-users> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users <http://lists.freeswitch.org/mailman/options/freeswitch-users> http://www.freeswitch.org <http://www.freeswitch.org/>
Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
00294539100 - internal user, % used when registered user dialed Second sipnet.ru crop Same problem.... If I dial both external numbers, or first external (second internal user) - they bridged fine. Problem occured when internal user dialed first, and bridge to external number. On 07/15/2015 12:49 PM, Oz Mortimer wrote:
Should be * * *originate {ignore_early_media=true}sofia/gateway/sipnet.ru/ <http://sipnet.ru/>**919262417024 &bridge(sofia/gateway**/sipnet.ru/ <http://sipnet.ru/>**00294539100)*
You seem to be doubling up the signet.ru <http://signet.ru> by having as a gateway then again @*sipnet.ru <http://sipnet.ru> - *either is valid but both at the same time is wrong. After the destination number you had % - not sure why, but wouldn’t have thought it should be there. Additionally you are using 2 different number formats (with and without 00 prefix) - I would check with provider what format you should be sending.
On 15 Jul 2015, at 08:27, Dmitry Mordovin <dm@dwide.com <mailto:dm@dwide.com>> wrote:
Sergey, Peter,
*originate {ignore_early_media=true}sofia/gateway/sipnet.ru/ <http://sipnet.ru/>**919262417024@sipnet.ru &bridge(sofia/internal/00294539100%)*
If i change like this - call established fine, but fail if first call to internal 00294539100
New trace
2015-07-15 11:17:57.115208 [NOTICE] switch_channel.c:1075 New Channel sofia/external/919262417024@sipnet.ru [4c3ee575-4f0f-4ebd-b4ec-0e5357469690] 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:4701 (sofia/external/919262417024@sipnet.ru) State Change CS_NEW -> CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru [BREAK] 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@sipnet.ru) Running State Change CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@sipnet.ru) State INIT 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:87 sofia/external/919262417024@sipnet.ru SOFIA INIT nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia_glue.c:1236 sofia/external/919262417024@sipnet.ru sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436923599 1436923600 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 21078 RTP/AVP 9 0 8 3 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 20298 RTP/AVP 103 a=rtpmap:103 VP8/90000
nua.c:633 nua_invite() nua: nua_invite: entering nua_stack.c:529 nua_signal() nua(0xb6d54b10): sent signal r_invite 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:40 sofia/external/919262417024@sipnet.ru Standard INIT nua_stack.c:569 nua_stack_signal() nua(0xb6d54b10): recv signal r_invite nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:48 (sofia/external/919262417024@sipnet.ru) State Change CS_INIT -> CS_ROUTING soa.c:280 soa_clone() soa_clone(static::0x8a45980, 0x8a547e8, 0xb6d54b10) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru [BREAK] soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@sipnet.ru) State INIT going to sleep soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event r_invite 900 Internal error at nua_client.c:552 nua_session.c:4139 signal_call_state_change() nua(0xb6d54b10): call state changed: init -> terminated nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_state 900 Internal error at nua_client.c:552 nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_terminated 900 Internal error at nua_client.c:552 nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@sipnet.ru) Running State Change CS_ROUTING 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia.c:6627 Channel sofia/external/919262417024@sipnet.ru entering state [terminated][900] 2015-07-15 11:17:57.115208 [NOTICE] sofia.c:7543 Hangup sofia/external/919262417024@sipnet.ru [CS_ROUTING] [NORMAL_UNSPECIFIED]
On 07/15/2015 11:14 AM, Sergey Safarov wrote:
What is "voip_provider"? Please enable 1) sofia global siptrace on 2) sofia loglevel all 9
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org <mailto:consulting@freeswitch.org> http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
Where are you read "% used when registered user dialed"? Try call FS users via originate {ignore_early_media=true}user/00294539100 On Wed, Jul 15, 2015 at 12:47 PM, Dmitry Mordovin <dm@dwide.com> wrote:
00294539100 - internal user, % used when registered user dialed
Second sipnet.ru crop
Same problem.... If I dial both external numbers, or first external (second internal user) - they bridged fine.
Problem occured when internal user dialed first, and bridge to external number.
On 07/15/2015 12:49 PM, Oz Mortimer wrote:
Should be
*originate {ignore_early_media=true}sofia/gateway/sipnet.ru/ <http://sipnet.ru/>**919262417024 <919262417024@sipnet.ru> &bridge(sofia/gateway**/sipnet.ru/ <http://sipnet.ru/>**00294539100)*
You seem to be doubling up the signet.ru by having as a gateway then again @*sipnet.ru <http://sipnet.ru> - *either is valid but both at the same time is wrong. After the destination number you had % - not sure why, but wouldn’t have thought it should be there. Additionally you are using 2 different number formats (with and without 00 prefix) - I would check with provider what format you should be sending.
On 15 Jul 2015, at 08:27, Dmitry Mordovin <dm@dwide.com> wrote:
Sergey, Peter,
*originate {ignore_early_media=true}sofia/gateway/sipnet.ru/ <http://sipnet.ru/>**919262417024@sipnet.ru <919262417024@sipnet.ru> &bridge(sofia/internal/00294539100%)*
If i change like this - call established fine, but fail if first call to internal 00294539100
New trace
2015-07-15 11:17:57.115208 [NOTICE] switch_channel.c:1075 New Channel sofia/external/919262417024@sipnet.ru [4c3ee575-4f0f-4ebd-b4ec-0e5357469690] 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:4701 ( sofia/external/919262417024@sipnet.ru) State Change CS_NEW -> CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru [BREAK] 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 ( sofia/external/919262417024@sipnet.ru) Running State Change CS_INIT 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 ( sofia/external/919262417024@sipnet.ru) State INIT 2015-07-15 11:17:57.115208 [DEBUG] mod_sofia.c:87 sofia/external/919262417024@sipnet.ru SOFIA INIT nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia_glue.c:1236 sofia/external/919262417024@sipnet.ru sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436923599 1436923600 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 21078 RTP/AVP 9 0 8 3 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 20298 RTP/AVP 103 a=rtpmap:103 VP8/90000
nua.c:633 nua_invite() nua: nua_invite: entering nua_stack.c:529 nua_signal() nua(0xb6d54b10): sent signal r_invite 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:40 sofia/external/919262417024@sipnet.ru Standard INIT nua_stack.c:569 nua_stack_signal() nua(0xb6d54b10): recv signal r_invite nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:48 ( sofia/external/919262417024@sipnet.ru) State Change CS_INIT -> CS_ROUTING soa.c:280 soa_clone() soa_clone(static::0x8a45980, 0x8a547e8, 0xb6d54b10) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@sipnet.ru [BREAK] soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called soa.c:403 soa_set_params() soa_set_params(static::0x8a4cbb0, ...) called 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:512 ( sofia/external/919262417024@sipnet.ru) State INIT going to sleep soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x8a4cbb0, (nil), 0xb6d831a4, -1) called nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event r_invite 900 Internal error at nua_client.c:552 nua_session.c:4139 signal_call_state_change() nua(0xb6d54b10): call state changed: init -> terminated nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_state 900 Internal error at nua_client.c:552 nua_stack.c:271 nua_stack_event() nua(0xb6d54b10): event i_terminated 900 Internal error at nua_client.c:552 nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2015-07-15 11:17:57.115208 [DEBUG] switch_core_state_machine.c:472 ( sofia/external/919262417024@sipnet.ru) Running State Change CS_ROUTING 2015-07-15 11:17:57.115208 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@sipnet.ru [BREAK] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2015-07-15 11:17:57.115208 [DEBUG] sofia.c:6627 Channel sofia/external/919262417024@sipnet.ru entering state [terminated][900] 2015-07-15 11:17:57.115208 [NOTICE] sofia.c:7543 Hangup sofia/external/919262417024@sipnet.ru [CS_ROUTING] [NORMAL_UNSPECIFIED]
On 07/15/2015 11:14 AM, Sergey Safarov wrote:
What is "voip_provider"? Please enable 1) sofia global siptrace on 2) sofia loglevel all 9
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.orghttp://www.freeswitchsolutions.com
Official FreeSWITCH Siteshttp://www.freeswitch.orghttp://confluence.freeswitch.orghttp://www.cluecon....
FreeSWITCH-users mailing listFreeSWITCH-users@lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.frees...
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.orghttp://www.freeswitchsolutions.com
Official FreeSWITCH Siteshttp://www.freeswitch.orghttp://confluence.freeswitch.orghttp://www.cluecon....
FreeSWITCH-users mailing listFreeSWITCH-users@lists.freeswitch.orghttp://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-usershttp://www.frees...
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
Hi all, The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce? Thanks -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382
sounds like answer delay is configured On Wednesday, July 15, 2015, Denis Jakovlev <yadenis@seznam.cz> wrote:
Hi all,
The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce?
Thanks
*-- S pozdravem, Ing.Denis Jakovlev mob.tel <http://mob.tel>. 775-415-382 * <http://www.freeswitch.org>
Dobrý den, How can I check it? I do not have in dialplan delay-time -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 středa 15. července 2015, 16:26:47, napsal jste: sounds like answer delay is configured On Wednesday, July 15, 2015, Denis Jakovlev <yadenis@seznam.cz> wrote: Hi all, The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce? Thanks -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382
do you have anything like <X-PRE-PROCESS cmd="set" data="answer_delay=4000"/> in your vars.xml
On Jul 15, 2015, at 10:42 AM, Denis Jakovlev <yadenis@seznam.cz> wrote:
Dobrý den,
How can I check it? I do not have in dialplan delay-time
-- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382
středa 15. července 2015, 16:26:47, napsal jste:
sounds like answer delay is configured
On Wednesday, July 15, 2015, Denis Jakovlev <yadenis@seznam.cz <mailto:yadenis@seznam.cz>> wrote: Hi all,
The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce?
Thanks
-- S pozdravem, Ing.Denis Jakovlev mob.tel <http://mob.tel/>. 775-415-382 _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
Dobrý den, Yes exactly! I did not notice. Reduction of this setting helped me a lot. Thanks. -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 středa 15. července 2015, 18:19:14, napsal jste: do you have anything like <X-PRE-PROCESS cmd="set" data="answer_delay=4000"/> in your vars.xml On Jul 15, 2015, at 10:42 AM, Denis Jakovlev <yadenis@seznam.cz> wrote: Re: [Freeswitch-users] Reduce connection time when video calling Dobrý den, How can I check it? I do not have in dialplan delay-time -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 středa 15. července 2015, 16:26:47, napsal jste: sounds like answer delay is configured On Wednesday, July 15, 2015, Denis Jakovlev <yadenis@seznam.cz> wrote: Hi all, The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce? Thanks -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
This is in the vanilla config, not sure if it's better to reduce the value or just remove it. Thoughts? On Thu, Jul 16, 2015 at 6:22 AM, Denis Jakovlev <yadenis@seznam.cz> wrote:
Dobrý den,
Yes exactly! I did not notice. Reduction of this setting helped me a lot. Thanks.
*-- S pozdravem, Ing.Denis Jakovlev mob.tel <http://mob.tel>. 775-415-382 středa 15. července 2015, 18:19:14, napsal jste: * do you have anything like
<X-PRE-PROCESS cmd="set" data="answer_delay=4000"/>
in your vars.xml
On Jul 15, 2015, at 10:42 AM, Denis Jakovlev <yadenis@seznam.cz> wrote:
Re: [Freeswitch-users] Reduce connection time when video calling Dobrý den,
How can I check it? I do not have in dialplan *delay*-
*time *
*-- S pozdravem, Ing.Denis Jakovlev mob.tel <http://mob.tel>. 775-415-382 středa 15. července 2015, 16:26:47, napsal jste: *
sounds like answer delay is configured
On Wednesday, July 15, 2015, Denis Jakovlev <yadenis@seznam.cz> wrote: Hi all,
The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce?
Thanks
*-- S pozdravem, Ing.Denis Jakovlev *mob.tel*. 775-415-382* _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- Ítalo Rossi
Dobrý den, When I removed this value at all, nothing has changed. By this I reduced to 500 and the connection speed is comparable with the included "bypass_media = true". -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 čtvrtek 16. července 2015, 14:32:12, napsal jste: This is in the vanilla config, not sure if it's better to reduce the value or just remove it. Thoughts? On Thu, Jul 16, 2015 at 6:22 AM, Denis Jakovlev <yadenis@seznam.cz> wrote: Dobrý den, Yes exactly! I did not notice. Reduction of this setting helped me a lot. Thanks. -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 středa 15. července 2015, 18:19:14, napsal jste: do you have anything like <X-PRE-PROCESS cmd="set" data="answer_delay=4000"/> in your vars.xml On Jul 15, 2015, at 10:42 AM, Denis Jakovlev <yadenis@seznam.cz> wrote: Re: [Freeswitch-users] Reduce connection time when video calling Dobrý den, How can I check it? I do not have in dialplan delay-time -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 středa 15. července 2015, 16:26:47, napsal jste: sounds like answer delay is configured On Wednesday, July 15, 2015, Denis Jakovlev <yadenis@seznam.cz> wrote: Hi all, The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce? Thanks -- S pozdravem, Ing.Denis Jakovlev mob.tel. 775-415-382 _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org -- Ítalo Rossi
I think we removed it from default config On Thursday, July 16, 2015, Ítalo Rossi <italorossib@gmail.com> wrote:
This is in the vanilla config, not sure if it's better to reduce the value or just remove it. Thoughts?
On Thu, Jul 16, 2015 at 6:22 AM, Denis Jakovlev <yadenis@seznam.cz <javascript:_e(%7B%7D,'cvml','yadenis@seznam.cz');>> wrote:
Dobrý den,
Yes exactly! I did not notice. Reduction of this setting helped me a lot. Thanks.
*-- S pozdravem, Ing.Denis Jakovlev mob.tel <http://mob.tel>. 775-415-382 středa 15. července 2015, 18:19:14, napsal jste: * do you have anything like
<X-PRE-PROCESS cmd="set" data="answer_delay=4000"/>
in your vars.xml
On Jul 15, 2015, at 10:42 AM, Denis Jakovlev <yadenis@seznam.cz <javascript:_e(%7B%7D,'cvml','yadenis@seznam.cz');>> wrote:
Re: [Freeswitch-users] Reduce connection time when video calling Dobrý den,
How can I check it? I do not have in dialplan *delay*-
*time *
*-- S pozdravem, Ing.Denis Jakovlev mob.tel <http://mob.tel>. 775-415-382 středa 15. července 2015, 16:26:47, napsal jste: *
sounds like answer delay is configured
On Wednesday, July 15, 2015, Denis Jakovlev <yadenis@seznam.cz <javascript:_e(%7B%7D,'cvml','yadenis@seznam.cz');>> wrote: Hi all,
The question in the following. How it is possible to reduce the connection time with the customer video calls? If you do directly with "bypass_media = true", then both callers begin to see each other almost instantaneously. Through FreeSwitch this connection can take from 5 to 10 seconds. It can somehow reduce?
Thanks
*-- S pozdravem, Ing.Denis Jakovlev *mob.tel*. 775-415-382* _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org <javascript:_e(%7B%7D,'cvml','consulting@freeswitch.org');> http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org <javascript:_e(%7B%7D,'cvml','FreeSWITCH-users@lists.freeswitch.org');> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org <javascript:_e(%7B%7D,'cvml','consulting@freeswitch.org');> http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org <javascript:_e(%7B%7D,'cvml','FreeSWITCH-users@lists.freeswitch.org');> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- Ítalo Rossi
Hello Dmitry, just some thoughts: Is your gateway voip_provider definded properly? Can you dial this number voip_to provider manually with originate &park()? Is the DNS lookup to voip_provider successfull? It may be usefull o make a pcap file and investigate with Wireshark. Best regards Peter On 07/15/15 08:24, Dmitry Mordovin wrote:
Hello!
Very strange, some time ago this works, but now not... and I don't know where I was made mistake...
From FS CLI do command
*originate {ignore_early_media=true}sofia/internal/00294539100% &bridge(sofia/gateway/voip_provider/919262417024@voip_provider)*
Call to user 00294539100 established, but second call to external is dropped before send INVITE... (local cause NORMAL_UNSPECIFIED)
Could someone give me idea where is a problem?
Trace here
2015-07-15 10:09:19.375204 [DEBUG] switch_ivr_originate.c:2100 Parsing global variables 2015-07-15 10:09:19.375204 [DEBUG] switch_event.c:1698 Parsing variable [ignore_early_media]=[true] 2015-07-15 10:09:19.375204 [NOTICE] switch_channel.c:1075 New Channel sofia/internal/00294539100 [680855ef-9973-43c4-bb9d-7ee1dd27d392] 2015-07-15 10:09:19.375204 [DEBUG] mod_sofia.c:4701 (sofia/internal/00294539100) State Change CS_NEW -> CS_INIT 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_INIT 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/00294539100) State INIT 2015-07-15 10:09:19.375204 [DEBUG] mod_sofia.c:87 sofia/internal/00294539100 SOFIA INIT freeswitch@internal> 2015-07-15 10:09:19.375204 [DEBUG] sofia_glue.c:1236 sofia/internal/00294539100 sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436918327 1436918328 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 22232 RTP/AVP 102 9 0 8 3 101 13 a=rtpmap:102 opus/48000/2 a=fmtp:102 useinbandfec=1; usedtx=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=20; maxptime=20; samplerate=48000 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 29716 RTP/AVP 103 a=rtpmap:103 VP8/90000
2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:40 sofia/internal/00294539100 Standard INIT 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/00294539100) State Change CS_INIT -> CS_ROUTING 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:512 (sofia/internal/00294539100) State INIT going to sleep 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_ROUTING 2015-07-15 10:09:19.375204 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [calling][0] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/00294539100) State ROUTING 2015-07-15 10:09:19.375204 [DEBUG] mod_sofia.c:123 sofia/internal/00294539100 SOFIA ROUTING 2015-07-15 10:09:19.375204 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/00294539100) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2015-07-15 10:09:19.375204 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:528 (sofia/internal/00294539100) State ROUTING going to sleep 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_CONSUME_MEDIA 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/00294539100) State CONSUME_MEDIA 2015-07-15 10:09:19.375204 [DEBUG] switch_core_state_machine.c:547 (sofia/internal/00294539100) State CONSUME_MEDIA going to sleep 2015-07-15 10:09:19.455217 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.455217 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:19.455217 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [proceeding][180] 2015-07-15 10:09:19.455217 [NOTICE] sofia.c:6729 Ring-Ready sofia/internal/00294539100! 2015-07-15 10:09:19.455217 [DEBUG] switch_channel.c:3297 (sofia/internal/00294539100) Callstate Change DOWN -> RINGING 2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [completing][200] 2015-07-15 10:09:22.075203 [DEBUG] sofia.c:6637 Remote SDP: v=0 o=floxent 3645929363 1 IN IP4 192.168.223.220 s=sflphone c=IN IP4 192.168.223.220 t=0 0 m=audio 28878 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-15 a=rtcp:28879 IN IP4 192.168.223.220 m=video 0 RTP/AVP 103
2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_session.c:1061 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.075203 [DEBUG] sofia.c:6627 Channel sofia/internal/00294539100 entering state [ready][200] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[opus:116:48000:20:0:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3727 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3672 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3588 Set telephone-event payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:2507 Set Codec sofia/internal/00294539100 G722/8000 20 ms 160 samples 64000 bits 1 channels 2015-07-15 10:09:22.075203 [DEBUG] switch_core_codec.c:111 sofia/internal/00294539100 Original read codec set to G722:9 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:3926 Set 2833 dtmf send payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5171 AUDIO RTP [sofia/internal/00294539100] 194.87.7.17 port 22232 -> 192.168.223.220 port 28878 codec: 9 ms: 20 2015-07-15 10:09:22.075203 [DEBUG] switch_rtp.c:3569 Starting timer [soft] 160 bytes per 20ms 2015-07-15 10:09:22.075203 [INFO] switch_core_media.c:5388 Activating RTCP PORT 28879 2015-07-15 10:09:22.075203 [DEBUG] switch_rtp.c:3919 RTCP send rate is: 5000 and packet rate is: 20000 Remote Port: 28879 2015-07-15 10:09:22.075203 [DEBUG] switch_rtp.c:2349 Setting RTCP remote addr to 192.168.223.220:28879 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5469 Set 2833 dtmf send payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5475 Set 2833 dtmf receive payload to 101 2015-07-15 10:09:22.075203 [DEBUG] switch_core_media.c:5497 sofia/internal/00294539100 Set rtp dtmf delay to 40 *2015-07-15 10:09:22.075203 [NOTICE] sofia.c:7488 Channel [sofia/internal/00294539100] has been answered* 2015-07-15 10:09:22.075203 [DEBUG] switch_channel.c:3711 (sofia/internal/00294539100) Callstate Change RINGING -> ACTIVE 2015-07-15 10:09:22.095205 [DEBUG] switch_ivr_originate.c:3577 Originate Resulted in Success: [sofia/internal/00294539100] 2015-07-15 10:09:22.095205 [INFO] switch_channel.c:3082 sofia/internal/00294539100 Flipping CID from "" <0000000000> to "Outbound Call" <00294539100%> 2015-07-15 10:09:22.095205 [DEBUG] mod_commands.c:4469 (sofia/internal/00294539100) State Change CS_CONSUME_MEDIA -> CS_EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/internal/00294539100) Running State Change CS_EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/00294539100) State EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:178 sofia/internal/00294539100 SOFIA EXECUTE 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:258 sofia/internal/00294539100 Standard EXECUTE EXECUTE sofia/internal/00294539100 bridge(sofia/gateway/voip_provider/919262417024@voip_provider) 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:1786 (sofia/internal/00294539100) Callstate Change ACTIVE -> RING_WAIT 2015-07-15 10:09:22.095205 [DEBUG] switch_ivr_originate.c:2100 Parsing global variables 2015-07-15 10:09:22.095205 [NOTICE] switch_channel.c:1075 New Channel sofia/external/919262417024@voip_provider [32799f05-3018-4a48-bea4-1d3cc3700835] 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:4701 (sofia/external/919262417024@voip_provider) State Change CS_NEW -> CS_INIT 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_INIT 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@voip_provider) State INIT 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:87 sofia/external/919262417024@voip_provider SOFIA INIT 2015-07-15 10:09:22.095205 [DEBUG] sofia_glue.c:1236 sofia/external/919262417024@voip_provider sending invite version: 1.5.15b git acdb1ca 2015-05-17 18:45:52Z 32bit Local SDP: v=0 o=FreeSWITCH 1436921310 1436921311 IN IP4 194.87.7.17 s=FreeSWITCH c=IN IP4 194.87.7.17 t=0 0 m=audio 19252 RTP/AVP 9 0 8 3 101 13 a=rtpmap:9 G722/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 a=sendrecv m=video 31754 RTP/AVP 103 a=rtpmap:103 VP8/90000
2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:40 sofia/external/919262417024@voip_provider Standard INIT 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:48 (sofia/external/919262417024@voip_provider) State Change CS_INIT -> CS_ROUTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:512 (sofia/external/919262417024@voip_provider) State INIT going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1061 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_ROUTING 2015-07-15 10:09:22.095205 [DEBUG] sofia.c:6627 Channel sofia/external/919262417024@voip_provider entering state [terminated][900] *2015-07-15 10:09:22.095205 [NOTICE] sofia.c:7543 Hangup sofia/external/919262417024@voip_provider [CS_ROUTING] [NORMAL_UNSPECIFIED]* 2015-07-15 10:09:22.095205 [DEBUG] switch_ivr_originate.c:3720 Originate Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED] 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:3242 Send signal sofia/external/919262417024@voip_provider [KILL] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:528 (sofia/external/919262417024@voip_provider) State ROUTING 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:123 sofia/external/919262417024@voip_provider SOFIA ROUTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:528 (sofia/external/919262417024@voip_provider) State ROUTING going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_HANGUP 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:735 (sofia/external/919262417024@voip_provider) Callstate Change DOWN -> HANGUP 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:737 (sofia/external/919262417024@voip_provider) State HANGUP 2015-07-15 10:09:22.095205 [DEBUG] mod_sofia.c:413 Channel sofia/external/919262417024@voip_provider hanging up, cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:60 sofia/external/919262417024@voip_provider Standard HANGUP, cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:737 (sofia/external/919262417024@voip_provider) State HANGUP going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:504 (sofia/external/919262417024@voip_provider) State Change CS_HANGUP -> CS_REPORTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:472 (sofia/external/919262417024@voip_provider) Running State Change CS_REPORTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:823 (sofia/external/919262417024@voip_provider) State REPORTING 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:104 sofia/external/919262417024@voip_provider Standard REPORTING, cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:823 (sofia/external/919262417024@voip_provider) State REPORTING going to sleep 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:1999 (sofia/internal/00294539100) Callstate Change RING_WAIT -> ACTIVE 2015-07-15 10:09:22.095205 [INFO] mod_dptools.c:3268 Originate Failed. Cause: NORMAL_UNSPECIFIED 2015-07-15 10:09:22.095205 [NOTICE] switch_channel.c:4747 Hangup sofia/internal/00294539100 [CS_EXECUTE] [NORMAL_UNSPECIFIED] 2015-07-15 10:09:22.095205 [DEBUG] switch_channel.c:3242 Send signal sofia/internal/00294539100 [KILL] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/internal/00294539100 [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_state_machine.c:498 (sofia/external/919262417024@voip_provider) State Change CS_REPORTING -> CS_DESTROY 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1396 Send signal sofia/external/919262417024@voip_provider [BREAK] 2015-07-15 10:09:22.095205 [DEBUG] switch_core_session.c:1623 Session 202 (sofia/external/919262417024@voip_provider) Locked, Waiting on external entities 2015-07-15 10:09:22.095205 [NOTICE] switch_core_session.c:1641 Session 202 (sofia/external/919262417024@voip_provider) Ended 2015-07-15 10:09:22.095205 [NOTICE] switch_core_session.c:1645 Close Channel sofia/external/919262417024@voip_provider [CS_DESTROY]
_________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org http://www.freeswitchsolutions.com
Official FreeSWITCH Sites http://www.freeswitch.org http://confluence.freeswitch.org http://www.cluecon.com
FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org
-- With kind regards Peter Steinbach Telefaks Services GmbH mailto:lists (att) telefaks.de Internet: www.telefaks.de
participants (7)
-
Denis Jakovlev
-
Dmitry Mordovin
-
Michael Jerris
-
Oz Mortimer
-
Peter Steinbach
-
Sergey Safarov
-
Ítalo Rossi