Opened 8 years ago

Closed 8 years ago

Last modified 6 years ago

#342 closed баг (fixed)

Сломалось "автоматическое" подключения в основную конф.

Reported by: san Owned by: dimag
Priority: blocker Milestone: 2 очередь
Component: ПО MC04-Dispatcher. Пульт диспетчера/техника Keywords: algorithm
Cc: alx

Description

В r358 работает совсем странно: то подключается когда не нужно, то не подключается когда нужно.
Раньше работало правильно, описание как должно работать в #241

Change History (15)

comment:1 by dimag, 8 years ago

Использовались настройки портов и медиа-сервера по умолчанию?

comment:2 by dimag, 8 years ago

Кроме того, при автоматическом добавление, FreeSwitch сервер иногда отказывается добавлять пользователя в диспетчерскую, возвращая на попытку добавить сообщение PRESENCE_IN с параметром Hangup-Cause равным INCOMPATIBLE_DESTINATION.

comment:3 by dimag, 8 years ago

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

comment:4 by dimag, 8 years ago

Если много и часто вызывать и отзывать одного пользователя в конференцию, то FreeSwitch перестанет принимать вызовы от него, возращая INCOMPATIBLE DESTINATION
После окончания периода перерегистрации, пользователь снова может подключаться к конференции.

comment:5 by dimag, 8 years ago

Что Алексей вы думаете об этом?

in reply to:  5 comment:6 by alx, 8 years ago

Replying to dimag:

Что Алексей вы думаете об этом?

Дмитрий, если Вы задаете вопрос мне, добавляйте меня, пожалуйста, в список CC. А то я ведь и не знаю о том, что Вы у меня что-то спрашиваете. Случайно увидел, просматривая хронику...

Сейчас я уже собираюсь уходить, и подробно думать и писать нет времени. Если кратко - я думаю, что надо исследовать данную проблему, выявить причину и, по возможности, устранить. А для исследования надо , как минимум, больше информации: какие команды посылаются коммутатору, что он на них отвечает, какими сообщениями при этом обмениваются SIP юзер-агенты, что коммутатор выводит в лог и т.п.

И еще, поскольку раньше работало правильно, стоит найти коммит, после которого сломалось, и проанализировать, что же там такое изменено, это может навести на мысль о причине бага. Правда этот пункт затруднен из-за того что Вы, Дима, часто объединяете в одном коммите несколько не связанных логически между собой изменений. Вот Вам наглядный пример того, почему это плохо...

comment:7 by alx, 8 years ago

Cc: alx added

comment:8 by dimag, 8 years ago

Такая проблема была раньше, просто ни я, ни Александр не пробовали запускать программу в режиме когда пользователь 20 раз отбивается и тут же подключается,подобное поведение было обнаруженно в #338. Думаю такое поведение было всегда, просто оно не было обнаружено.

comment:9 by dimag, 8 years ago

2016-08-29 11:52:52.449191 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
2016-08-29 11:52:52.449191 [DEBUG] switch_ivr_originate.c:2580 Parsing session specific variables
2016-08-29 11:52:52.449191 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
2016-08-29 11:52:52.449191 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/2@192.168.0.63:5061 [48b49e46-e24e-4c05-98dc-2ac0ab14e795]
2016-08-29 11:52:52.449191 [DEBUG] mod_sofia.c:4757 (sofia/internal/2@192.168.0.63:5061) State Change CS_NEW -> CS_INIT
2016-08-29 11:52:52.449191 [DEBUG] switch_core_session.c:613 sofia/internal/2@192.168.0.63:5061 set UUID=48b49e46-e24e-4c05-98dc-2ac0ab14e795
2016-08-29 11:52:52.449191 [DEBUG] switch_ivr_originate.c:2580 Parsing session specific variables
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2@192.168.0.63:5061) Running State Change CS_INIT
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/2@192.168.0.63:5061) State INIT
2016-08-29 11:52:52.449191 [DEBUG] mod_sofia.c:89 sofia/internal/2@192.168.0.63:5061 SOFIA INIT
2016-08-29 11:52:52.449191 [NOTICE] switch_channel.c:1104 New Channel loopback/2/alarm-a [c09e88bd-f2ab-497f-8d46-336355f8d3fc]
2016-08-29 11:52:52.449191 [DEBUG] mod_loopback.c:158 loopback/2/alarm-a setup codec L16/8000/20
2016-08-29 11:52:52.449191 [NOTICE] switch_channel.c:1102 Rename Channel loopback/2/alarm-a->loopback/2-a [c09e88bd-f2ab-497f-8d46-336355f8d3fc]
2016-08-29 11:52:52.449191 [DEBUG] mod_loopback.c:1171 (loopback/2-a) State Change CS_NEW -> CS_INIT
2016-08-29 11:52:52.449191 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.449191 [DEBUG] sofia_glue.c:1257 sofia/internal/2@192.168.0.63:5061 sending invite version: 1.6.9 git d574870 2016-06-13 18:10:44Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1472443092 1472443093 IN IP4 192.168.0.63
s=FreeSWITCH
c=IN IP4 192.168.0.63
t=0 0
m=audio 17680 RTP/AVP 0 8 18 9 3 101 13
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=rtpmap:9 G722/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtcp-mux
a=rtcp:17680 IN IP4 192.168.0.63
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv

2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:40 sofia/internal/2@192.168.0.63:5061 Standard INIT
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/2@192.168.0.63:5061) State Change CS_INIT -> CS_ROUTING
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:586 (sofia/internal/2@192.168.0.63:5061) State INIT going to sleep
2016-08-29 11:52:52.449191 [DEBUG] switch_ivr_originate.c:2892 loopback/2-a Setting leg delay start to 10
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2@192.168.0.63:5061) Running State Change CS_ROUTING
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/2@192.168.0.63:5061) State ROUTING
2016-08-29 11:52:52.449191 [DEBUG] mod_sofia.c:142 sofia/internal/2@192.168.0.63:5061 SOFIA ROUTING
2016-08-29 11:52:52.449191 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/2@192.168.0.63:5061) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:602 (sofia/internal/2@192.168.0.63:5061) State ROUTING going to sleep
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2@192.168.0.63:5061) Running State Change CS_CONSUME_MEDIA
2016-08-29 11:52:52.449191 [DEBUG] sofia.c:6933 Channel sofia/internal/2@192.168.0.63:5061 entering state [calling][0]
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/2@192.168.0.63:5061) State CONSUME_MEDIA
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:621 (sofia/internal/2@192.168.0.63:5061) State CONSUME_MEDIA going to sleep
2016-08-29 11:52:52.449191 [DEBUG] sofia.c:6933 Channel sofia/internal/2@192.168.0.63:5061 entering state [terminated][488]
2016-08-29 11:52:52.449191 [NOTICE] sofia.c:7961 Hangup sofia/internal/2@192.168.0.63:5061 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
2016-08-29 11:52:52.449191 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2@192.168.0.63:5061) Running State Change CS_HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/2@192.168.0.63:5061) Callstate Change DOWN -> HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/2@192.168.0.63:5061) State HANGUP
2016-08-29 11:52:52.469162 [DEBUG] mod_sofia.c:437 Channel sofia/internal/2@192.168.0.63:5061 hanging up, cause: INCOMPATIBLE_DESTINATION
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2@192.168.0.63:5061 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/2@192.168.0.63:5061) State HANGUP going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_ivr_originate.c:2127 Parsing global variables
2016-08-29 11:52:52.469162 [NOTICE] switch_channel.c:1104 New Channel loopback/2/answered-a [52c95582-b24f-4a1d-bc30-0bd7a8e204fe]
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:158 loopback/2/answered-a setup codec L16/8000/20
2016-08-29 11:52:52.469162 [NOTICE] switch_channel.c:1102 Rename Channel loopback/2/answered-a->loopback/2-a [52c95582-b24f-4a1d-bc30-0bd7a8e204fe]
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:1171 (loopback/2-a) State Change CS_NEW -> CS_INIT
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_INIT
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:586 (loopback/2-a) State INIT
2016-08-29 11:52:52.469162 [NOTICE] switch_channel.c:1104 New Channel loopback/2-b [74abdf4a-de09-4297-b4b4-15dae16f7dd6]
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:158 loopback/2-b setup codec L16/8000/20
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:276 (loopback/2-b) State Change CS_NEW -> CS_INIT
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:362 (loopback/2-a) State Change CS_INIT -> CS_ROUTING
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_INIT
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:586 (loopback/2-a) State INIT going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:586 (loopback/2-b) State INIT
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:362 (loopback/2-b) State Change CS_INIT -> CS_ROUTING
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:586 (loopback/2-b) State INIT going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_ROUTING
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_ROUTING
2016-08-29 11:52:52.469162 [DEBUG] switch_channel.c:2249 (loopback/2-b) Callstate Change DOWN -> RINGING
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:602 (loopback/2-a) State ROUTING
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:394 loopback/2-a CHANNEL ROUTING
2016-08-29 11:52:52.469162 [DEBUG] switch_ivr_originate.c:67 (loopback/2-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:602 (loopback/2-a) State ROUTING going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_CONSUME_MEDIA
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:602 (loopback/2-b) State ROUTING
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:394 loopback/2-b CHANNEL ROUTING
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:236 loopback/2-b Standard ROUTING
2016-08-29 11:52:52.469162 [INFO] mod_dialplan_xml.c:637 Processing <0000000000>->2 in context answered
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:621 (loopback/2-a) State CONSUME_MEDIA
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:661 CHANNEL CONSUME_MEDIA
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:621 (loopback/2-a) State CONSUME_MEDIA going to sleep
Dialplan: loopback/2-b parsing [answered->Stop-alarm] continue=false
Dialplan: loopback/2-b Regex (FAIL) [Stop-alarm] ${hash(select/alarms/${destination_number})}() =~ /1/ break=on-false
2016-08-29 11:52:52.469162 [INFO] switch_core_state_machine.c:311 No Route, Aborting
2016-08-29 11:52:52.469162 [NOTICE] switch_core_state_machine.c:312 Hangup loopback/2-b [CS_ROUTING] [NO_ROUTE_DESTINATION]
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:602 (loopback/2-b) State ROUTING going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:809 (loopback/2-b) Callstate Change RINGING -> HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:811 (loopback/2-b) State HANGUP
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:548 loopback/2-b CHANNEL HANGUP
2016-08-29 11:52:52.469162 [NOTICE] mod_loopback.c:564 Hangup loopback/2-a [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:60 loopback/2-b Standard HANGUP, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:811 (loopback/2-b) State HANGUP going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:578 (loopback/2-b) State Change CS_HANGUP -> CS_REPORTING
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_REPORTING
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:897 (loopback/2-b) State REPORTING
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:809 (loopback/2-a) Callstate Change DOWN -> HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:174 loopback/2-b Standard REPORTING, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:811 (loopback/2-a) State HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:897 (loopback/2-b) State REPORTING going to sleep
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:548 loopback/2-a CHANNEL HANGUP
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:60 loopback/2-a Standard HANGUP, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:811 (loopback/2-a) State HANGUP going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:578 (loopback/2-a) State Change CS_HANGUP -> CS_REPORTING
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_REPORTING
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:569 (loopback/2-b) State Change CS_REPORTING -> CS_DESTROY
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:897 (loopback/2-a) State REPORTING
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_session.c:1647 Session 3147 (loopback/2-b) Locked, Waiting on external entities
2016-08-29 11:52:52.469162 [NOTICE] switch_core_session.c:1665 Session 3147 (loopback/2-b) Ended
2016-08-29 11:52:52.469162 [NOTICE] switch_core_session.c:1669 Close Channel loopback/2-b [CS_DESTROY]
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:174 loopback/2-a Standard REPORTING, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:897 (loopback/2-a) State REPORTING going to sleep
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:700 (loopback/2-b) Running State Change CS_DESTROY
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:569 (loopback/2-a) State Change CS_REPORTING -> CS_DESTROY
2016-08-29 11:52:52.469162 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.469162 [DEBUG] switch_core_session.c:1647 Session 3146 (loopback/2-a) Locked, Waiting on external entities
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:710 (loopback/2-b) State DESTROY
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:181 loopback/2-b Standard DESTROY
2016-08-29 11:52:52.469162 [DEBUG] switch_core_state_machine.c:710 (loopback/2-b) State DESTROY going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_ivr_originate.c:3750 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:742 Hangup Command with no Session originate(loopback/2/answered 0):
-ERR NO_ROUTE_DESTINATION

2016-08-29 11:52:52.489157 [NOTICE] switch_core_session.c:1665 Session 3146 (loopback/2-a) Ended
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/2@192.168.0.63:5061) State Change CS_HANGUP -> CS_REPORTING
2016-08-29 11:52:52.489157 [NOTICE] switch_core_session.c:1669 Close Channel loopback/2-a [CS_DESTROY]
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2@192.168.0.63:5061) Running State Change CS_REPORTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:700 (loopback/2-a) Running State Change CS_DESTROY
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:710 (loopback/2-a) State DESTROY
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/2@192.168.0.63:5061) State REPORTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:181 loopback/2-a Standard DESTROY
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:710 (loopback/2-a) State DESTROY going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2@192.168.0.63:5061 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/2@192.168.0.63:5061) State REPORTING going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/2@192.168.0.63:5061) State Change CS_REPORTING -> CS_DESTROY
2016-08-29 11:52:52.489157 [DEBUG] switch_core_session.c:1647 Session 3144 (sofia/internal/2@192.168.0.63:5061) Locked, Waiting on external entities
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_INIT
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:586 (loopback/2-a) State INIT
2016-08-29 11:52:52.489157 [NOTICE] switch_channel.c:1104 New Channel loopback/2-b [ebd623f9-90a4-421a-a2f8-bba3e5d8b22e]
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:158 loopback/2-b setup codec L16/8000/20
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:276 (loopback/2-b) State Change CS_NEW -> CS_INIT
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:362 (loopback/2-a) State Change CS_INIT -> CS_ROUTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_INIT
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:586 (loopback/2-a) State INIT going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:586 (loopback/2-b) State INIT
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:362 (loopback/2-b) State Change CS_INIT -> CS_ROUTING
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:586 (loopback/2-b) State INIT going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_ROUTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_ROUTING
2016-08-29 11:52:52.489157 [DEBUG] switch_channel.c:2249 (loopback/2-b) Callstate Change DOWN -> RINGING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:602 (loopback/2-a) State ROUTING
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:394 loopback/2-a CHANNEL ROUTING
2016-08-29 11:52:52.489157 [DEBUG] switch_ivr_originate.c:67 (loopback/2-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:602 (loopback/2-a) State ROUTING going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_CONSUME_MEDIA
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:602 (loopback/2-b) State ROUTING
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:394 loopback/2-b CHANNEL ROUTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:236 loopback/2-b Standard ROUTING
2016-08-29 11:52:52.489157 [INFO] mod_dialplan_xml.c:637 Processing Диспетчерская <2>->2 in context alarm
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:621 (loopback/2-a) State CONSUME_MEDIA
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:661 CHANNEL CONSUME_MEDIA
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:621 (loopback/2-a) State CONSUME_MEDIA going to sleep
Dialplan: loopback/2-b parsing [alarm->Alarm] continue=false
Dialplan: loopback/2-b Regex (FAIL) [Alarm] ${db(exists/alarms/${destination_number})}(false) =~ /true$/ break=on-false
2016-08-29 11:52:52.489157 [INFO] switch_core_state_machine.c:311 No Route, Aborting
2016-08-29 11:52:52.489157 [NOTICE] switch_core_state_machine.c:312 Hangup loopback/2-b [CS_ROUTING] [NO_ROUTE_DESTINATION]
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:602 (loopback/2-b) State ROUTING going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_HANGUP
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:809 (loopback/2-b) Callstate Change RINGING -> HANGUP
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:811 (loopback/2-b) State HANGUP
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:548 loopback/2-b CHANNEL HANGUP
2016-08-29 11:52:52.489157 [NOTICE] mod_loopback.c:564 Hangup loopback/2-a [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_HANGUP
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:60 loopback/2-b Standard HANGUP, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:811 (loopback/2-b) State HANGUP going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:578 (loopback/2-b) State Change CS_HANGUP -> CS_REPORTING
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_REPORTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:897 (loopback/2-b) State REPORTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:809 (loopback/2-a) Callstate Change DOWN -> HANGUP
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:174 loopback/2-b Standard REPORTING, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:897 (loopback/2-b) State REPORTING going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:811 (loopback/2-a) State HANGUP
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:548 loopback/2-a CHANNEL HANGUP
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:60 loopback/2-a Standard HANGUP, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:811 (loopback/2-a) State HANGUP going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:578 (loopback/2-a) State Change CS_HANGUP -> CS_REPORTING
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_REPORTING
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:569 (loopback/2-b) State Change CS_REPORTING -> CS_DESTROY
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:897 (loopback/2-a) State REPORTING
2016-08-29 11:52:52.489157 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-29 11:52:52.489157 [DEBUG] switch_core_session.c:1647 Session 3148 (loopback/2-b) Locked, Waiting on external entities
2016-08-29 11:52:52.489157 [NOTICE] switch_core_session.c:1665 Session 3148 (loopback/2-b) Ended
2016-08-29 11:52:52.489157 [NOTICE] switch_core_session.c:1669 Close Channel loopback/2-b [CS_DESTROY]
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:174 loopback/2-a Standard REPORTING, cause: NO_ROUTE_DESTINATION
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:897 (loopback/2-a) State REPORTING going to sleep
2016-08-29 11:52:52.489157 [DEBUG] switch_core_state_machine.c:700 (loopback/2-b) Running State Change CS_DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:710 (loopback/2-b) State DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:569 (loopback/2-a) State Change CS_REPORTING -> CS_DESTROY
2016-08-29 11:52:52.509163 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:181 loopback/2-b Standard DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_session.c:1647 Session 3145 (loopback/2-a) Locked, Waiting on external entities
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:710 (loopback/2-b) State DESTROY going to sleep
2016-08-29 11:52:52.509163 [DEBUG] switch_ivr_originate.c:3750 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
2016-08-29 11:52:52.509163 [NOTICE] switch_core_session.c:1665 Session 3145 (loopback/2-a) Ended
2016-08-29 11:52:52.509163 [NOTICE] switch_core_session.c:1665 Session 3144 (sofia/internal/2@192.168.0.63:5061) Ended
2016-08-29 11:52:52.509163 [NOTICE] switch_core_session.c:1669 Close Channel loopback/2-a [CS_DESTROY]
2016-08-29 11:52:52.509163 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/2@192.168.0.63:5061 [CS_DESTROY]
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:700 (loopback/2-a) Running State Change CS_DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/2@192.168.0.63:5061) Running State Change CS_DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:710 (loopback/2-a) State DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:181 loopback/2-a Standard DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/2@192.168.0.63:5061) State DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:710 (loopback/2-a) State DESTROY going to sleep
2016-08-29 11:52:52.509163 [DEBUG] mod_sofia.c:342 sofia/internal/2@192.168.0.63:5061 SOFIA DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2@192.168.0.63:5061 Standard DESTROY
2016-08-29 11:52:52.509163 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/2@192.168.0.63:5061) State DESTROY going to sleep

comment:10 by dimag, 8 years ago

Вот что выводит FreeSwitch в ответ на команду
bgapi originate {originate_timeout=120,origination_caller_id_name='Диспетчерская',origination_caller_id_number=2}[origination_uuid=37e95890-7fe0-461b-a0b5-38e90aec627b,api_hangup_hook='originate loopback/2/answered 0']user/2,[leg_delay_start=10]loopback/2/alarm 0 XML conference

in reply to:  8 comment:11 by san, 8 years ago

Replying to dimag:

Такая проблема была раньше, просто ни я, ни Александр не пробовали запускать программу в режиме когда пользователь 20 раз отбивается и тут же подключается,подобное поведение было обнаруженно в #338. Думаю такое поведение было всегда, просто оно не было обнаружено.

Не понимаю, Дима, зачем ты смешиваешь всё в кучу?
какие 20 раз?

Вот например в r366 делаем

  1. Настраиваем пользователю ("4") режим "Автоматическое подключение в основную конференцию".
  2. В "Диспетчерской" находится абонент "3"
  3. В Диспетчерскую подключается абонент "201"

По логике "Автоматического" подключения пользователь ("4") должен автоматически подключиться к диспетчерской, но этого не происходит

comment:12 by dimag, 8 years ago

Я понял. Сейчас, если в диспетсерской есть хотя один абонент с правами диспетчер или техник, то подключение не происходит.

comment:13 by san, 8 years ago

Ну вот, оказалось просто исправление предыдущей ошибки вызвало новую.
А фрисвитч и INCOMPATIBLE DESTINATION тут вовсе не замешаны.

comment:14 by dimag, 8 years ago

Keywords: algorithm added
Resolution: → fixed
Status: new → closed

r369
Теперь присутствие в диспетчерской пользователя с правами диспетчер или техник не препятствует подключению пользователя.
Дамп лога FreeSwitch я переношу в тикет 338.

comment:15 by san, 6 years ago

Milestone: Текущее → 2 очередь

Milestone renamed

Note: See TracTickets for help on using tickets.