Проблема со звонками между абонентами

samael

unread,

Dec 5, 2011, 12:18:01 PM12/5/11

to freesw...@googlegroups.com

Имеется чистая инсталляция FreeSWITCH 1.0.head-git.master.20110530.1-1 на Ubuntu 11.10 64 bit

2 сетевых интерфейса: внешний 193..... и внутренний 10.10.10.4

Домен для подключения номеров из локалки: 10.10.10.4

Подключаю 2 телефона: Yealink T26P и Zoipper

Каждый из телефонов может позвонить на стандартные номера (5000, конференция и т.д.). Все работает отлично! (Dialplan - стандартный, ничего пока не менял)

Но, если позвонить с одного номера на другой - вызов идет... Нажимаю ответить и слышу тишины, а у вызывающего абонента включается голосовая почта (контекст "Local_Extension")

В логах наблюдаю:

Dialplan: sofia/internal/10...@10.10.10.4 Regex (PASS) [public_extensions] destination_number(1004) =~ /^(10[01][0-9])$/ break=on-false

Dialplan: sofia/internal/10...@10.10.10.4 Action transfer(1004 XML default)

2011-12-05 19:01:39.503389 [DEBUG] switch_core_state_machine.c:154 (sofia/internal/10...@10.10.10.4) State Change CS_ROUTING -> CS_EXECUTE

2011-12-05 19:01:39.503389 [DEBUG] switch_core_session.c:1177 Send signal sofia/internal/10...@10.10.10.4 [BREAK]

2011-12-05 19:01:39.503389 [DEBUG] switch_core_state_machine.c:410 (sofia/internal/10...@10.10.10.4) State ROUTING going to sleep

2011-12-05 19:01:39.503389 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/10...@10.10.10.4) Running State Change CS_EXECUTE

2011-12-05 19:01:39.503389 [DEBUG] switch_core_state_machine.c:417 (sofia/internal/10...@10.10.10.4) State EXECUTE

2011-12-05 19:01:39.503389 [DEBUG] mod_sofia.c:241 sofia/internal/10...@10.10.10.4 SOFIA EXECUTE

2011-12-05 19:01:39.503389 [DEBUG] switch_core_state_machine.c:192 sofia/internal/10...@10.10.10.4 Standard EXECUTE

2011-12-05 19:01:39.983410 [INFO] switch_ivr_originate.c:1115 Sending early media

2011-12-05 19:01:39.983410 [DEBUG] sofia_glue.c:3140 AUDIO RTP [sofia/internal/10...@10.10.10.4] 10.10.10.4 port 26742 -> 10.10.10.117 port 11782 codec: 9

ms: 20

2011-12-05 19:01:39.983410 [DEBUG] switch_rtp.c:1642 Starting timer [soft] 160 bytes per 20ms

2011-12-05 19:01:39.983410 [DEBUG] sofia_glue.c:3404 Set 2833 dtmf send payload to 101

2011-12-05 19:01:39.983410 [DEBUG] sofia_glue.c:3410 Set 2833 dtmf receive payload to 101

2011-12-05 19:01:39.983410 [DEBUG] mod_sofia.c:2503 Ring SDP:

v=0

o=FreeSWITCH 1323077757 1323077758 IN IP4 10.10.10.4

s=FreeSWITCH

c=IN IP4 10.10.10.4

t=0 0

m=audio 26742 RTP/AVP 9 101

a=rtpmap:9 G722/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv

2011-12-05 19:01:39.983410 [NOTICE] mod_sofia.c:2506 Pre-Answer sofia/internal/10...@10.10.10.4!

2011-12-05 19:01:39.983410 [DEBUG] switch_channel.c:2917 (sofia/internal/10...@10.10.10.4) Callstate Change RINGING -> EARLY

2011-12-05 19:01:39.983410 [DEBUG] switch_core_session.c:726 Send signal sofia/internal/10...@10.10.10.4 [BREAK]

2011-12-05 19:01:39.983410 [DEBUG] switch_ivr_originate.c:1164 Raw Codec Activation Success L16@16000hz 1 channel 20ms

2011-12-05 19:01:39.983410 [DEBUG] switch_core_codec.c:116 sofia/internal/10...@10.10.10.4 Push codec L16:70

2011-12-05 19:01:39.983410 [DEBUG] switch_ivr_originate.c:1227 Play Ringback Tone [%(2000,4000,440,480)]

2011-12-05 19:01:39.983410 [DEBUG] switch_core_session.c:872 Send signal sofia/internal/10...@10.10.10.4 [BREAK]

2011-12-05 19:01:40.003396 [DEBUG] sofia.c:5361 Channel sofia/internal/10...@10.10.10.4 skipping state [early][183]

2011-12-05 19:01:40.203389 [DEBUG] switch_rtp.c:3187 Correct ip/port confirmed.

2011-12-05 19:01:46.203392 [DEBUG] switch_core_session.c:872 Send signal sofia/internal/sip:10...@10.10.10.125:5060 [BREAK]

2011-12-05 19:01:46.203392 [DEBUG] sofia.c:5368 Channel sofia/internal/sip:10...@10.10.10.125:5060 entering state [completing][200]

2011-12-05 19:01:46.203392 [DEBUG] sofia.c:5379 Remote SDP:

v=0

o=MobileZoiper_user 0 2 IN IP4 10.10.10.125

s=MobileZoiper_session

c=IN IP4 10.10.10.125

t=0 0

m=audio 8000 RTP/AVP 0 3 110 97 8 101

a=rtpmap:0 PCMU/8000

a=rtpmap:3 GSM/8000

a=rtpmap:110 speex/8000

a=rtpmap:97 iLBC/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

2011-12-05 19:01:46.203392 [DEBUG] switch_core_session.c:872 Send signal sofia/internal/sip:10...@10.10.10.125:5060 [BREAK]

2011-12-05 19:01:46.203392 [DEBUG] sofia.c:5368 Channel sofia/internal/sip:10...@10.10.10.125:5060 entering state [ready][200]

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:115:32000:20:48000]

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [PCMU:0:8000:20:64000]/[G7221:107:16000:20:32000]

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [PCMU:0:8000:20:64000]/[G722:9:8000:20:64000]

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:2888 Set Codec sofia/internal/sip:10...@10.10.10.125:5060 PCMU/8000 20 ms 160 samples 64000 bits

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:4881 Set 2833 dtmf send payload to 101

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:3140 AUDIO RTP [sofia/internal/sip:10...@10.10.10.125:5060] 10.10.10.4 port 21136 -> 10.10.10.125 port 800

0 codec: 0 ms: 20

2011-12-05 19:01:46.203392 [DEBUG] switch_rtp.c:1642 Starting timer [soft] 160 bytes per 20ms

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:3404 Set 2833 dtmf send payload to 101

2011-12-05 19:01:46.203392 [DEBUG] sofia_glue.c:3410 Set 2833 dtmf receive payload to 101

2011-12-05 19:01:46.203392 [DEBUG] switch_channel.c:3175 (sofia/internal/sip:10...@10.10.10.125:5060) Callstate Change RINGING -> ACTIVE

2011-12-05 19:01:46.203392 [DEBUG] switch_channel.c:3187 Send signal sofia/internal/10...@10.10.10.4 [BREAK]

2011-12-05 19:01:46.203392 [NOTICE] sofia.c:6077 Channel [sofia/internal/sip:10...@10.10.10.125:5060] has been answered

2011-12-05 19:01:46.223408 [DEBUG] switch_core_codec.c:141 sofia/internal/10...@10.10.10.4 Restore previous codec G722:9.

2011-12-05 19:01:46.223408 [DEBUG] mod_sofia.c:746 Local SDP sofia/internal/10...@10.10.10.4:

v=0

o=FreeSWITCH 1323077757 1323077759 IN IP4 10.10.10.4

s=FreeSWITCH

c=IN IP4 10.10.10.4

t=0 0

m=audio 26742 RTP/AVP 9 101

a=rtpmap:9 G722/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv

2011-12-05 19:01:46.223408 [DEBUG] switch_core_session.c:726 Send signal sofia/internal/10...@10.10.10.4 [BREAK]

2011-12-05 19:01:46.223408 [DEBUG] switch_channel.c:3175 (sofia/internal/10...@10.10.10.4) Callstate Change EARLY -> ACTIVE

2011-12-05 19:01:46.223408 [NOTICE] switch_ivr_originate.c:3209 Channel [sofia/internal/10...@10.10.10.4] has been answered

2011-12-05 19:01:46.223408 [DEBUG] switch_core_session.c:872 Send signal sofia/internal/10...@10.10.10.4 [BREAK]

2011-12-05 19:01:46.223408 [DEBUG] sofia.c:5368 Channel sofia/internal/10...@10.10.10.4 entering state [completed][200]

2011-12-05 19:01:46.223408 [DEBUG] switch_ivr_originate.c:3269 Originate Resulted in Success: [sofia/internal/sip:10...@10.10.10.125:5060]

2011-12-05 19:01:46.223408 [DEBUG] switch_ivr_originate.c:3514 (sofia/internal/sip:10...@10.10.10.125:5060) State Change CS_CONSUME_MEDIA -> CS_RESET

2011-12-05 19:01:46.223408 [DEBUG] switch_core_session.c:1177 Send signal sofia/internal/sip:10...@10.10.10.125:5060 [BREAK]

2011-12-05 19:01:46.223408 [DEBUG] switch_core_state_machine.c:362 (sofia/internal/sip:10...@10.10.10.125:5060) Running State Change CS_RESET

2011-12-05 19:01:46.223408 [DEBUG] switch_core_state_machine.c:413 (sofia/internal/sip:10...@10.10.10.125:5060) State RESET

2011-12-05 19:01:46.223408 [DEBUG] mod_sofia.c:166 sofia/internal/sip:10...@10.10.10.125:5060 SOFIA RESET

2011-12-05 19:01:46.223408 [DEBUG] switch_core_state_machine.c:93 sofia/internal/sip:10...@10.10.10.125:5060 Standard RESET

2011-12-05 19:01:46.223408 [DEBUG] switch_core_state_machine.c:413 (sofia/internal/sip:10...@10.10.10.125:5060) State RESET going to sleep

2011-12-05 19:01:46.243430 [DEBUG] switch_ivr_originate.c:3367 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]

2011-12-05 19:01:46.243430 [INFO] mod_dptools.c:2897 Originate Failed. Cause: ORIGINATOR_CANCEL

EXECUTE sofia/internal/10...@10.10.10.4 answer()

EXECUTE sofia/internal/10...@10.10.10.4 sleep(1000)

2011-12-05 19:01:46.243430 [DEBUG] switch_core_session.c:872 Send signal sofia/internal/10...@10.10.10.4 [BREAK]

2011-12-05 19:01:46.263407 [DEBUG] sofia.c:5368 Channel sofia/internal/10...@10.10.10.4 entering state [ready][200]

EXECUTE sofia/internal/10...@10.10.10.4 bridge(loopback/app=voicemail:default 193.ХХ.ХХ.ХХ 1004)

2011-12-05 19:01:47.263391 [DEBUG] switch_channel.c:1041 sofia/internal/10...@10.10.10.4 EXPORTING[export_vars] [dialed_extension]=[1004] to event

2011-12-05 19:01:47.263391 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables

2011-12-05 19:01:47.263391 [NOTICE] switch_channel.c:920 New Channel loopback/app=voicemail:default 193.ХХ.ХХ.ХХ 1004-a [f6f1a65d-f9bd-45a2-b9a2-fc131b5b

a56e]

2011-12-05 19:01:47.263391 [DEBUG] mod_loopback.c:145 loopback/app=voicemail:default 193.ХХ.ХХ.ХХ 1004-a setup codec G722/8000/20

2011-12-05 19:01:47.263391 [NOTICE] switch_channel.c:918 Rename Channel loopback/app=voicemail:default 193.ХХ.ХХ.ХХ 1004-a->loopback/voicemail-a [f6f1a65

d-f9bd-45a2-b9a2-fc131b5ba56e]

Пошла голосовая почта

Подскажите, в чем могу быть грабли?? Не как не могу понять :(

Спасибо!

slonik

unread,

Dec 5, 2011, 8:42:30 PM12/5/11

to freesw...@googlegroups.com

Честно говоря, хз.
Попробовать: добавить на T26P кодек PCMU или использовать другой клиент вместо Zoipper...

Віталій Ковалишин

unread,

Dec 6, 2011, 2:09:42 AM12/6/11

to freesw...@googlegroups.com

Пробовал - не помогло :(

Сегодня попробую завести на минимальной конфигурации, посмотрю какой будет результат...

--
Vitaly Kovalyshyn, IT-SFERA
http://vk.it-sfera.com.ua/

2011/12/6 slonik <valery....@gmail.com>

Честно говоря, хз.
Попробовать: добавить на T26P кодек PCMU или использовать другой клиент вместо Zoipper...

--
Вы получили это сообщение, поскольку подписаны на группу freeswitch-ru.
Просмотреть это обсуждение в Сети можно по адресу https://groups.google.com/d/msg/freeswitch-ru/-/Z41YBg-k81QJ.

Чтобы добавлять сообщения в эту группу, отправьте письмо по адресу freesw...@googlegroups.com.
Чтобы отменить подписку на эту группу, отправьте сообщение по адресу freeswitch-r...@googlegroups.com.
О дополнительных функциях можно узнать в группе по адресу http://groups.google.com/group/freeswitch-ru?hl=ru.

Віталій Ковалишин

unread,

Dec 6, 2011, 2:55:44 AM12/6/11

to freesw...@googlegroups.com

С минимальной конфигурацией та же фигня...

2011-12-06 09:16:11.743388 [INFO] mod_dialplan_xml.c:481 Processing 101 <101>->102 in context local

Dialplan: sofia/local/1...@10.10.10.4 parsing [local->local-account-hello] continue=false

Dialplan: sofia/local/1...@10.10.10.4 Regex (FAIL) [local-account-hello] destination_number(102) =~ /^100$/ break=on-false

Dialplan: sofia/local/1...@10.10.10.4 parsing [local->local-accounts] continue=false

Dialplan: sofia/local/1...@10.10.10.4 Regex (PASS) [local-accounts] destination_number(102) =~ /^(10[1-3])$/ break=on-false

Dialplan: sofia/local/1...@10.10.10.4 Action bridge(user/1...@10.10.10.4)

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:154 (sofia/local/1...@10.10.10.4) State Change CS_ROUTING -> CS_EXECUTE

2011-12-06 09:16:11.743388 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:410 (sofia/local/1...@10.10.10.4) State ROUTING going to sleep

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:362 (sofia/local/1...@10.10.10.4) Running State Change CS_EXECUTE

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:417 (sofia/local/1...@10.10.10.4) State EXECUTE

2011-12-06 09:16:11.743388 [DEBUG] mod_sofia.c:241 sofia/local/1...@10.10.10.4 SOFIA EXECUTE

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:192 sofia/local/1...@10.10.10.4 Standard EXECUTE

EXECUTE sofia/local/1...@10.10.10.4 bridge(user/1...@10.10.10.4)

2011-12-06 09:16:11.743388 [DEBUG] switch_ivr_originate.c:1884 Parsing global variables

2011-12-06 09:16:11.743388 [DEBUG] switch_event.c:1521 Parsing variable [presence_id]=[1...@10.10.10.4]

2011-12-06 09:16:11.743388 [NOTICE] switch_channel.c:920 New Channel sofia/local/sip:1...@10.10.10.125:5060 [33a77c2c-d088-4366-8d57-b61fc0d226b8]

2011-12-06 09:16:11.743388 [DEBUG] mod_sofia.c:4542 (sofia/local/sip:1...@10.10.10.125:5060) State Change CS_NEW -> CS_INIT

2011-12-06 09:16:11.743388 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:362 (sofia/local/sip:1...@10.10.10.125:5060) Running State Change CS_INIT

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:401 (sofia/local/sip:1...@10.10.10.125:5060) State INIT

2011-12-06 09:16:11.743388 [DEBUG] mod_sofia.c:85 sofia/local/sip:1...@10.10.10.125:5060 SOFIA INIT

2011-12-06 09:16:11.743388 [DEBUG] mod_sofia.c:125 (sofia/local/sip:1...@10.10.10.125:5060) State Change CS_INIT -> CS_ROUTING

2011-12-06 09:16:11.743388 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:401 (sofia/local/sip:1...@10.10.10.125:5060) State INIT going to sleep

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:362 (sofia/local/sip:1...@10.10.10.125:5060) Running State Change CS_ROUTING

2011-12-06 09:16:11.743388 [DEBUG] switch_channel.c:1871 (sofia/local/sip:1...@10.10.10.125:5060) Callstate Change DOWN -> RINGING

2011-12-06 09:16:11.743388 [DEBUG] switch_core_session.c:872 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:410 (sofia/local/sip:1...@10.10.10.125:5060) State ROUTING

2011-12-06 09:16:11.743388 [DEBUG] mod_sofia.c:148 sofia/local/sip:1...@10.10.10.125:5060 SOFIA ROUTING

2011-12-06 09:16:11.743388 [DEBUG] switch_ivr_originate.c:66 (sofia/local/sip:1...@10.10.10.125:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA

2011-12-06 09:16:11.743388 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:410 (sofia/local/sip:1...@10.10.10.125:5060) State ROUTING going to sleep

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:362 (sofia/local/sip:1...@10.10.10.125:5060) Running State Change CS_CONSUME_MEDIA

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:429 (sofia/local/sip:1...@10.10.10.125:5060) State CONSUME_MEDIA

2011-12-06 09:16:11.743388 [DEBUG] switch_core_state_machine.c:429 (sofia/local/sip:1...@10.10.10.125:5060) State CONSUME_MEDIA going to sleep

2011-12-06 09:16:11.743388 [DEBUG] sofia.c:5368 Channel sofia/local/sip:1...@10.10.10.125:5060 entering state [calling][0]

2011-12-06 09:16:12.563386 [DEBUG] switch_core_session.c:872 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:12.563386 [DEBUG] sofia.c:5368 Channel sofia/local/sip:1...@10.10.10.125:5060 entering state [proceeding][180]

2011-12-06 09:16:12.563386 [NOTICE] sofia.c:5460 Ring-Ready sofia/local/sip:1...@10.10.10.125:5060!

2011-12-06 09:16:12.563386 [NOTICE] mod_sofia.c:2433 Ring-Ready sofia/local/1...@10.10.10.4!

2011-12-06 09:16:12.563386 [DEBUG] switch_core_session.c:872 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:12.563386 [DEBUG] sofia.c:5368 Channel sofia/local/1...@10.10.10.4 entering state [early][180]

2011-12-06 09:16:12.563386 [DEBUG] switch_core_session.c:726 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:12.563386 [NOTICE] switch_ivr_originate.c:483 Ring Ready sofia/local/1...@10.10.10.4!

2011-12-06 09:16:15.203390 [DEBUG] switch_core_session.c:872 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:15.203390 [DEBUG] sofia.c:5368 Channel sofia/local/sip:1...@10.10.10.125:5060 entering state [completing][200]

2011-12-06 09:16:15.203390 [DEBUG] sofia.c:5379 Remote SDP:

v=0

o=MobileZoiper_user 0 2 IN IP4 10.10.10.125

s=MobileZoiper_session

c=IN IP4 10.10.10.125

t=0 0

m=audio 8000 RTP/AVP 0 8 101

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

2011-12-06 09:16:15.203390 [DEBUG] switch_core_session.c:872 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:15.203390 [DEBUG] sofia.c:5368 Channel sofia/local/sip:1...@10.10.10.125:5060 entering state [ready][200]

2011-12-06 09:16:15.203390 [DEBUG] sofia_glue.c:4767 Audio Codec Compare [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]

2011-12-06 09:16:15.203390 [DEBUG] sofia_glue.c:2888 Set Codec sofia/local/sip:1...@10.10.10.125:5060 PCMU/8000 20 ms 160 samples 64000 bits

2011-12-06 09:16:15.203390 [DEBUG] sofia_glue.c:4881 Set 2833 dtmf send payload to 101

2011-12-06 09:16:15.203390 [DEBUG] sofia_glue.c:3140 AUDIO RTP [sofia/local/sip:1...@10.10.10.125:5060] 10.10.10.4 port 27604 -> 10.10.10.125 port 8000 codec: 0 ms: 20

2011-12-06 09:16:15.203390 [DEBUG] switch_rtp.c:1650 Not using a timer

2011-12-06 09:16:15.203390 [DEBUG] sofia_glue.c:3404 Set 2833 dtmf send payload to 101

2011-12-06 09:16:15.203390 [DEBUG] sofia_glue.c:3410 Set 2833 dtmf receive payload to 101

2011-12-06 09:16:15.223411 [DEBUG] switch_channel.c:3175 (sofia/local/sip:1...@10.10.10.125:5060) Callstate Change RINGING -> ACTIVE

2011-12-06 09:16:15.223411 [DEBUG] switch_channel.c:3187 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:15.223411 [NOTICE] sofia.c:6077 Channel [sofia/local/sip:1...@10.10.10.125:5060] has been answered

2011-12-06 09:16:15.223411 [DEBUG] sofia_glue.c:3140 AUDIO RTP [sofia/local/1...@10.10.10.4] 10.10.10.4 port 28260 -> 10.10.10.117 port 11780 codec: 0 ms: 20

2011-12-06 09:16:15.223411 [DEBUG] switch_rtp.c:1650 Not using a timer

2011-12-06 09:16:15.223411 [DEBUG] sofia_glue.c:3404 Set 2833 dtmf send payload to 101

2011-12-06 09:16:15.223411 [DEBUG] sofia_glue.c:3410 Set 2833 dtmf receive payload to 101

2011-12-06 09:16:15.223411 [DEBUG] mod_sofia.c:746 Local SDP sofia/local/1...@10.10.10.4:

v=0

o=FreeSWITCH 1323127515 1323127516 IN IP4 10.10.10.4

s=FreeSWITCH

c=IN IP4 10.10.10.4

t=0 0

m=audio 28260 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv

2011-12-06 09:16:15.223411 [DEBUG] switch_core_session.c:726 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:15.223411 [DEBUG] switch_channel.c:3175 (sofia/local/1...@10.10.10.4) Callstate Change RINGING -> ACTIVE

2011-12-06 09:16:15.223411 [NOTICE] switch_ivr_originate.c:3209 Channel [sofia/local/1...@10.10.10.4] has been answered

2011-12-06 09:16:15.223411 [DEBUG] switch_core_session.c:872 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:15.223411 [DEBUG] sofia.c:5368 Channel sofia/local/1...@10.10.10.4 entering state [completed][200]

2011-12-06 09:16:15.223411 [DEBUG] switch_ivr_originate.c:3269 Originate Resulted in Success: [sofia/local/sip:1...@10.10.10.125:5060]

2011-12-06 09:16:15.223411 [DEBUG] switch_ivr_originate.c:3514 (sofia/local/sip:1...@10.10.10.125:5060) State Change CS_CONSUME_MEDIA -> CS_RESET

2011-12-06 09:16:15.223411 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/sip:1...@10.10.10.125:5060 [BREAK]

2011-12-06 09:16:15.223411 [DEBUG] switch_core_state_machine.c:362 (sofia/local/sip:1...@10.10.10.125:5060) Running State Change CS_RESET

2011-12-06 09:16:15.223411 [DEBUG] switch_core_state_machine.c:413 (sofia/local/sip:1...@10.10.10.125:5060) State RESET

2011-12-06 09:16:15.223411 [DEBUG] mod_sofia.c:166 sofia/local/sip:1...@10.10.10.125:5060 SOFIA RESET

2011-12-06 09:16:15.223411 [DEBUG] switch_core_state_machine.c:93 sofia/local/sip:1...@10.10.10.125:5060 Standard RESET

2011-12-06 09:16:15.223411 [DEBUG] switch_core_state_machine.c:413 (sofia/local/sip:1...@10.10.10.125:5060) State RESET going to sleep

2011-12-06 09:16:15.243395 [DEBUG] switch_ivr_originate.c:3367 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL]

2011-12-06 09:16:15.243395 [INFO] mod_dptools.c:2897 Originate Failed. Cause: ORIGINATOR_CANCEL

2011-12-06 09:16:15.243395 [NOTICE] switch_core_state_machine.c:226 sofia/local/1...@10.10.10.4 has executed the last dialplan instruction, hanging up.

2011-12-06 09:16:15.243395 [DEBUG] switch_channel.c:2833 (sofia/local/1...@10.10.10.4) Callstate Change ACTIVE -> HANGUP

2011-12-06 09:16:15.243395 [NOTICE] switch_core_state_machine.c:228 Hangup sofia/local/1...@10.10.10.4 [CS_EXECUTE] [NORMAL_CLEARING]

2011-12-06 09:16:15.243395 [DEBUG] switch_channel.c:2856 Send signal sofia/local/1...@10.10.10.4 [KILL]

2011-12-06 09:16:15.243395 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:417 (sofia/local/1...@10.10.10.4) State EXECUTE going to sleep

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:362 (sofia/local/1...@10.10.10.4) Running State Change CS_HANGUP

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:602 (sofia/local/1...@10.10.10.4) State HANGUP

2011-12-06 09:16:15.243395 [DEBUG] mod_sofia.c:465 Channel sofia/local/1...@10.10.10.4 hanging up, cause: NORMAL_CLEARING

2011-12-06 09:16:15.243395 [DEBUG] mod_sofia.c:509 Sending BYE to sofia/local/1...@10.10.10.4

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:47 sofia/local/1...@10.10.10.4 Standard HANGUP, cause: NORMAL_CLEARING

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:602 (sofia/local/1...@10.10.10.4) State HANGUP going to sleep

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:393 (sofia/local/1...@10.10.10.4) State Change CS_HANGUP -> CS_REPORTING

2011-12-06 09:16:15.243395 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:362 (sofia/local/1...@10.10.10.4) Running State Change CS_REPORTING

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:662 (sofia/local/1...@10.10.10.4) State REPORTING

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:79 sofia/local/1...@10.10.10.4 Standard REPORTING, cause: NORMAL_CLEARING

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:662 (sofia/local/1...@10.10.10.4) State REPORTING going to sleep

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:387 (sofia/local/1...@10.10.10.4) State Change CS_REPORTING -> CS_DESTROY

2011-12-06 09:16:15.243395 [DEBUG] switch_core_session.c:1177 Send signal sofia/local/1...@10.10.10.4 [BREAK]

2011-12-06 09:16:15.243395 [DEBUG] switch_core_session.c:1377 Session 1 (sofia/local/1...@10.10.10.4) Locked, Waiting on external entities

2011-12-06 09:16:15.243395 [NOTICE] switch_core_session.c:1395 Session 1 (sofia/local/1...@10.10.10.4) Ended

2011-12-06 09:16:15.243395 [NOTICE] switch_core_session.c:1397 Close Channel sofia/local/1...@10.10.10.4 [CS_DESTROY]

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:491 (sofia/local/1...@10.10.10.4) Callstate Change HANGUP -> DOWN

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:494 (sofia/local/1...@10.10.10.4) Running State Change CS_DESTROY

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:504 (sofia/local/1...@10.10.10.4) State DESTROY

2011-12-06 09:16:15.243395 [DEBUG] mod_sofia.c:370 sofia/local/1...@10.10.10.4 SOFIA DESTROY

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:86 sofia/local/1...@10.10.10.4 Standard DESTROY

2011-12-06 09:16:15.243395 [DEBUG] switch_core_state_machine.c:504 (sofia/local/1...@10.10.10.4) State DESTROY going to sleep

Віталій Ковалишин

unread,

Dec 6, 2011, 3:31:01 AM12/6/11

to freesw...@googlegroups.com

Мда-аа, бредня какая-то!

Установил FreeSWITCH у себя на ноуте под Windows - звонки между телефонами отлично проходят!

Пробую полностью пересобрать deb пакеты на ubuntu 11.10 и переустановить...

Не может быть проблемы в 2-х сетевухах или 64-битах ?

Alexei Volkov

unread,

Dec 6, 2011, 3:42:31 AM12/6/11

to freesw...@googlegroups.com

64 бита скорее всего не при чем, а вот две сетевухи очень даже может
быть. В настройках FS в профиле явно указаны IP и порты для сигнализации
и RTP?

--
Best regards.
Alexei Volkov.

06.12.2011 12:31, Віталій Ковалишин пишет:

> --
> Вы получили это сообщение, поскольку подписаны на группу freeswitch-ru.

Віталій Ковалишин

unread,

Dec 6, 2011, 3:46:48 AM12/6/11

to freesw...@googlegroups.com

Сигнализация проходит нормально (звонок идет), а вот в момент коммутации - обрыв связи... Значит проблема с прохождением RTP.. Я указывал для RTP адрес... Хотя... уже столько вариантов пробовал - не помню.... Сейчас пересоберу пакеты, установлю - попробую опять на минимальной конфигурации.

Тогда посмотрим....

Алексей Волков

unread,

Dec 6, 2011, 3:53:59 AM12/6/11

to freesw...@googlegroups.com

Вот тут ситуация явно с кодеками:

В одной сессии

a=rtpmap:9 G722/8000

А в другой

a=rtpmap:0 PCMU/8000

a=rtpmap:3 GSM/8000

a=rtpmap:110 speex/8000

a=rtpmap:97 iLBC/8000

(одинаковых нет) и FS должен делать транскодинг, и видимо не может так как кодек только в passthough, по этому в телефон прилетает несовместимый содек и он делает CANCEL, отсюда в логах Originate Failed. Cause: ORIGINATOR_CANCEL

Алексей Волков

unread,

Dec 6, 2011, 3:57:44 AM12/6/11

to freesw...@googlegroups.com

Последний лог выглядит нормально hanging up, cause: NORMAL_CLEARING это как поговорили и потом повесили трубку. Какие были внешние симптомы по этому логу?

Віталій Ковалишин

unread,

Dec 6, 2011, 4:43:28 AM12/6/11

to freesw...@googlegroups.com

Перекомпилировал пакеты - все теперь нормально проходит...

Странно... В любом случае, всем спасибо за помощь!

Reply all

Reply to author

Forward