Opened 8 years ago

Closed 8 years ago

#338 closed баг (fixed)

Пользователь не подключается к основной конференции в режимах "принудительное" и "постоянное"

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

Description

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

Логи записаные при воспроизведения бага для r341: ticket:241#comment:21

В r354 также воспроизводилось: ticket:241#comment:26

Attachments (2)

sip.pcap (41.4 KB ) - added by san 8 years ago.
fs_cli_log.txt (24.4 KB ) - added by dimag 8 years ago.

Download all attachments as: .zip

Change History (28)

comment:1 by dimag, 8 years ago

Я пробовал проделать так, нажимал и тут же отбивал, после пару десятов раз, пользователь перестал подключаться к конференции.
НА следующую команду
api originate {originate_timeout=120,origination_caller_id_name='Диспетчерская',origination_caller_id_number=2}[origination_uuid=1672bab3-18a9-46ae-b486-852cb21be2ef,api_hangup_hook='originate loopback/2/answered 0']user/2,[leg_delay_start=11]loopback/2/alarm 0 XML conference
FreeSwitch стал возвращать -ERR INCOMPATIBLE_DESTINATION вместо +OK db5bf4cc-0f3d-432f-9faa-75f49023485e.
Может проблема в сервере FreeSwitch?

comment:2 by san, 8 years ago

Cc: alx added

comment:3 by san, 8 years ago

Last edited 8 years ago by san (previous) (diff)

comment:4 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

comment:5 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

comment:6 by dimag, 8 years ago

Priority: criticalblocker

Вот лог работы FreeSwitch в момент прихода команды.
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


Прошу обратить внимание на строку 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]

comment:7 by alx, 8 years ago

Процитирую сам себя из ticket:342#comment:6:

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

Лога SIP между FreeSwitch и юзер-агентом пока не было...

Чисто по коду ошибки могу сказать, что, по идее, "INCOMPATIBLE_DESTINATION" возвращается при проблеме с медиа-потоком. Самый простой пример - у юзер-агентов нет общего поддерживаемого кодека. Или, например, коммутатор по каким-то причинам не может использовать указанный адрес/порт для передачи медиа...

И еще просьба к Диме: Когда помещаете в комментарий логи, пожалуйста, помещайте их как блоки кода. Очень неудобно читать "кашу", как например в comment:6. А если лог длинный, лучше вообще аттачить его файлом...

Last edited 8 years ago by alx (previous) (diff)

comment:8 by alx, 8 years ago

В FreeSwitch добавлен вывод в лог во всех местах, где дается ответ с кодом INCOMPATIBLE_DESTINATION. Это, надеюсь, позволит более точно установить место возникновения ошибки.

Заодно версия обновилась до 1.6.10.

comment:9 by dimag, 8 years ago

В r380 не смог повторить, я делал многократное отключение несколько десятков раз.
Возможно такая ситуация перестала появляться после замены текущей FreeSwitch сервера на 1.6.10.
У тебя Александр воспроизводиться такая ситуация?

comment:10 by san, 8 years ago

В r380 воспроизвёл за 10 отключений, а потом за 5 и даже за 2 отключения(это мой личный рекорд)

in reply to:  10 comment:11 by alx, 8 years ago

Replying to san:

В r380 воспроизвёл за 10 отключений, а потом за 5 и даже за 2 отключения(это мой личный рекорд)

Логи где? Или мы с Димой должны "с закрытыми глазами" угадать, где ошибка? :)

comment:12 by san, 8 years ago

Дима отказался от логов, и от предложения воспроизвести "на бис"

comment:13 by san, 8 years ago

Логи программы есть в теле тикета, сейчас наверное более интересны логи фрисвитча, я их не смотрел, просто ответил на Димин вопрос

У тебя Александр воспроизводиться такая ситуация?

comment:14 by dimag, 8 years ago

Алексей, Вот что выводиться при ошибке вызова в консоли управления FreeSwitch
Появление подобной ситуации предугадать невозможно
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:609 (sofia/internal/2@192.168.0.81:62571) State EXECUTE going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2@192.168.0.81:62571) Running State Change CS_HANGUP
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:809 (sofia/internal/2@192.168.0.81:62571) Callstate Change ACTIVE -> HANGUP
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/2@192.168.0.81:62571) State HANGUP
2016-08-31 14:21:35.406503 [DEBUG] mod_sofia.c:438 Channel sofia/internal/2@192.168.0.81:62571 hanging up, cause: NORMAL_CLEARING
2016-08-31 14:21:35.406503 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/2@192.168.0.81:62571
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:60 sofia/internal/2@192.168.0.81:62571 Standard HANGUP, cause: NORMAL_CLEARING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:811 (sofia/internal/2@192.168.0.81:62571) State HANGUP going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_ivr_originate.c:2136 Parsing global variables
2016-08-31 14:21:35.406503 [NOTICE] switch_channel.c:1104 New Channel loopback/2/answered-a [756f898a-6b7a-489a-9792-5d5e2df98f1c]
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:158 loopback/2/answered-a setup codec L16/8000/20
2016-08-31 14:21:35.406503 [NOTICE] switch_channel.c:1102 Rename Channel loopback/2/answered-a->loopback/2-a [756f898a-6b7a-489a-9792-5d5e2df98f1c]
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:1174 (loopback/2-a) State Change CS_NEW -> CS_INIT
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_INIT
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:586 (loopback/2-a) State INIT
2016-08-31 14:21:35.406503 [NOTICE] switch_channel.c:1104 New Channel loopback/2-b [4b86d7de-d783-4525-a52d-003ccc1f55c1]
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:158 loopback/2-b setup codec L16/8000/20
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:276 (loopback/2-b) State Change CS_NEW -> CS_INIT
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:362 (loopback/2-a) State Change CS_INIT -> CS_ROUTING
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:586 (loopback/2-a) State INIT going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_INIT
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:586 (loopback/2-b) State INIT
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:362 (loopback/2-b) State Change CS_INIT -> CS_ROUTING
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:586 (loopback/2-b) State INIT going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_ROUTING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_ROUTING
2016-08-31 14:21:35.406503 [DEBUG] switch_channel.c:2249 (loopback/2-b) Callstate Change DOWN -> RINGING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:602 (loopback/2-a) State ROUTING
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:394 loopback/2-a CHANNEL ROUTING
2016-08-31 14:21:35.406503 [DEBUG] switch_ivr_originate.c:67 (loopback/2-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:602 (loopback/2-a) State ROUTING going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_CONSUME_MEDIA
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:602 (loopback/2-b) State ROUTING
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:394 loopback/2-b CHANNEL ROUTING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:236 loopback/2-b Standard ROUTING
2016-08-31 14:21:35.406503 [INFO] mod_dialplan_xml.c:637 Processing <0000000000>->2 in context answered
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:621 (loopback/2-a) State CONSUME_MEDIA
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:661 CHANNEL CONSUME_MEDIA
2016-08-31 14:21:35.406503 [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-31 14:21:35.406503 [INFO] switch_core_state_machine.c:311 No Route, Aborting
2016-08-31 14:21:35.406503 [NOTICE] switch_core_state_machine.c:312 Hangup loopback/2-b [CS_ROUTING] [NO_ROUTE_DESTINATION]
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:602 (loopback/2-b) State ROUTING going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_HANGUP
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:809 (loopback/2-b) Callstate Change RINGING -> HANGUP
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:811 (loopback/2-b) State HANGUP
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:548 loopback/2-b CHANNEL HANGUP
2016-08-31 14:21:35.406503 [NOTICE] mod_loopback.c:564 Hangup loopback/2-a [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:60 loopback/2-b Standard HANGUP, cause: NO_ROUTE_DESTINATION
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_HANGUP
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:811 (loopback/2-b) State HANGUP going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:578 (loopback/2-b) State Change CS_HANGUP -> CS_REPORTING
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-b) Running State Change CS_REPORTING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:897 (loopback/2-b) State REPORTING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:809 (loopback/2-a) Callstate Change DOWN -> HANGUP
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:174 loopback/2-b Standard REPORTING, cause: NO_ROUTE_DESTINATION
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:897 (loopback/2-b) State REPORTING going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:811 (loopback/2-a) State HANGUP
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:548 loopback/2-a CHANNEL HANGUP
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:60 loopback/2-a Standard HANGUP, cause: NO_ROUTE_DESTINATION
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:811 (loopback/2-a) State HANGUP going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:569 (loopback/2-b) State Change CS_REPORTING -> CS_DESTROY
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:578 (loopback/2-a) State Change CS_HANGUP -> CS_REPORTING
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-b CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_session.c:1647 Session 1766 (loopback/2-b) Locked, Waiting on external entities
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-31 14:21:35.406503 [NOTICE] switch_core_session.c:1665 Session 1766 (loopback/2-b) Ended
2016-08-31 14:21:35.406503 [NOTICE] switch_core_session.c:1669 Close Channel loopback/2-b [CS_DESTROY]
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:543 (loopback/2-a) Running State Change CS_REPORTING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:897 (loopback/2-a) State REPORTING
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:700 (loopback/2-b) Running State Change CS_DESTROY
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:174 loopback/2-a Standard REPORTING, cause: NO_ROUTE_DESTINATION
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:710 (loopback/2-b) State DESTROY
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:897 (loopback/2-a) State REPORTING going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:181 loopback/2-b Standard DESTROY
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:710 (loopback/2-b) State DESTROY going to sleep
2016-08-31 14:21:35.406503 [DEBUG] switch_core_state_machine.c:569 (loopback/2-a) State Change CS_REPORTING -> CS_DESTROY
2016-08-31 14:21:35.406503 [DEBUG] mod_loopback.c:601 loopback/2-a CHANNEL KILL
2016-08-31 14:21:35.406503 [DEBUG] switch_core_session.c:1647 Session 1765 (loopback/2-a) Locked, Waiting on external entities
2016-08-31 14:21:35.426496 [DEBUG] switch_ivr_originate.c:3759 Originate Resulted in Error Cause: 3 [NO_ROUTE_DESTINATION]
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:742 Hangup Command with no Session originate(loopback/2/answered 0):
-ERR NO_ROUTE_DESTINATION

2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:578 (sofia/internal/2@192.168.0.81:62571) State Change CS_HANGUP -> CS_REPORTING
2016-08-31 14:21:35.426496 [NOTICE] switch_core_session.c:1665 Session 1765 (loopback/2-a) Ended
2016-08-31 14:21:35.426496 [NOTICE] switch_core_session.c:1669 Close Channel loopback/2-a [CS_DESTROY]
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:543 (sofia/internal/2@192.168.0.81:62571) Running State Change CS_REPORTING
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:700 (loopback/2-a) Running State Change CS_DESTROY
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/2@192.168.0.81:62571) State REPORTING
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:710 (loopback/2-a) State DESTROY
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:181 loopback/2-a Standard DESTROY
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:174 sofia/internal/2@192.168.0.81:62571 Standard REPORTING, cause: NORMAL_CLEARING
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:710 (loopback/2-a) State DESTROY going to sleep
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:897 (sofia/internal/2@192.168.0.81:62571) State REPORTING going to sleep
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:569 (sofia/internal/2@192.168.0.81:62571) State Change CS_REPORTING -> CS_DESTROY
2016-08-31 14:21:35.426496 [DEBUG] switch_core_session.c:1647 Session 1759 (sofia/internal/2@192.168.0.81:62571) Locked, Waiting on external entities
2016-08-31 14:21:35.426496 [NOTICE] switch_core_session.c:1665 Session 1759 (sofia/internal/2@192.168.0.81:62571) Ended
2016-08-31 14:21:35.426496 [NOTICE] switch_core_session.c:1669 Close Channel sofia/internal/2@192.168.0.81:62571 [CS_DESTROY]
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:700 (sofia/internal/2@192.168.0.81:62571) Running State Change CS_DESTROY
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/2@192.168.0.81:62571) State DESTROY
2016-08-31 14:21:35.426496 [DEBUG] mod_sofia.c:343 sofia/internal/2@192.168.0.81:62571 SOFIA DESTROY
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:181 sofia/internal/2@192.168.0.81:62571 Standard DESTROY
2016-08-31 14:21:35.426496 [DEBUG] switch_core_state_machine.c:710 (sofia/internal/2@192.168.0.81:62571) State DESTROY going to sleep

in reply to:  7 comment:15 by san, 8 years ago

alx:

И еще просьба к Диме: Когда помещаете в комментарий логи, пожалуйста, помещайте их как блоки кода. Очень неудобно читать "кашу", как например в comment:6. А если лог длинный, лучше вообще аттачить его файлом...

Last edited 8 years ago by san (previous) (diff)

comment:16 by san, 8 years ago

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

comment:17 by dimag, 8 years ago

Алексей, вы что нибудь выяснили?

in reply to:  17 comment:18 by alx, 8 years ago

Replying to dimag:

Алексей, вы что нибудь выяснили?

По поводу чего?

comment:19 by dimag, 8 years ago

По пободу [INCOMPATIBLE_DESTINATION]

in reply to:  7 comment:20 by alx, 8 years ago

К тому, что я написал в comment:7, мне добавить нечего. Логов SIP я до сих пор не видел.

by san, 8 years ago

Attachment: sip.pcap added

by dimag, 8 years ago

Attachment: fs_cli_log.txt added

comment:21 by dimag, 8 years ago

В ответ на команду добавления нового пользователя:
bgapi originate {originate_timeout=120,origination_caller_id_name='Диспетчерская',origination_caller_id_number=2}[origination_uuid=8220245f-b1ba-4979-9435-99f3daf63919,api_hangup_hook='originate loopback/2/answered 0']user/2,[leg_delay_start=10]loopback/2/alarm 0 XML conference
я получая отказ в установление соединения
с получением следующих сообщений
PRESENCE_IN - outbound-2@192.168.0.63-CS_ROUTING - ringing
CHANNEL_CALLSTATE - CS_HANGUP-HANGUP-outbound-INCOMPATIBLE_DESTINATION
CHANNEL_CALLSTATE - CS_ROUTING|RINGING|
CHANNEL_CALLSTATE - CS_HANGUP-HANGUP-inbound-NO_ROUTE_DESTINATION
CHANNEL_CALLSTATE - CS_HANGUP-HANGUP-outbound-NO_ROUTE_DESTINATION
PRESENCE_IN - outbound-2@192.168.0.63-CS_HANGUP-356e9bd3-05de-4519-b277-1faa785a06e2-INCOMPATIBLE_DESTINATION
CHANNEL_CALLSTATE - CS_ROUTING|RINGING|
CHANNEL_CALLSTATE - CS_HANGUP-HANGUP-inbound-NO_ROUTE_DESTINATION
CHANNEL_CALLSTATE - CS_HANGUP-HANGUP-outbound-NO_ROUTE_DESTINATION
BACKGROUND_JOB - -f7df8605-887e-49fa-8a6a-428086131431
Лог работы сервера FreeSwitch во вложение fs_cli_log.txt.
После первоначального отказа в подключение к конференции и пробовал подключиться несколько раз снова, с аналогичным результатом.
Я считаю что ошибка на строне FreeSwitch сервера.

comment:22 by dimag, 8 years ago

Прошу обратить внимание на строку
2016-09-07 13:43:22.296063 [DEBUG] switch_core_state_machine.c:621 (loopback/2-a) State CONSUME_MEDIA going to sleep
Dialplan: loopback/2-b Regex (FAIL) [Stop-alarm] ${hash(select/alarms/${destination_number})}() =~ /1/ break=on-false
2016-09-07 13:43:22.296063 [INFO] switch_core_state_machine.c:311 No Route, Aborting
в файле лога fs_cli_log.txt

comment:23 by alx, 8 years ago

Дима, все перечисленные в comment:7 данные должны быть получены в одно и то же время! А сейчас получается, что Александр дает лог SIP обмена одного события, а Вы даете логи от совсем другого события. Нет никакой гарантии, того, что в обоих случаях произошло одно и то же.

Replying to comment:22:

Прошу обратить внимание на строку
2016-09-07 13:43:22.296063 [DEBUG] switch_core_state_machine.c:621 (loopback/2-a) State
в файле лога fs_cli_log.txt

Что именно по-вашему меня в этой строке должно заинтересовать?

comment:24 by dimag, 8 years ago

Ошибка в выражении
[Stop-alarm] ${hash(select/alarms/${destination_number})}() =~ /1/ break=on-false

in reply to:  24 comment:25 by alx, 8 years ago

Replying to dimag:

Ошибка в выражении
[Stop-alarm] ${hash(select/alarms/${destination_number})}() =~ /1/ break=on-false

Не нахожу в этом выражении никакой ошибки... В чем по-вашему она состоит?

comment:26 by alx, 8 years ago

Resolution: fixed
Status: newclosed

Так как код, отвечающий за подключение нашего диспетчера к конференции, полностью переписан, считаю, что проблема тем самым устранена.

Note: See TracTickets for help on using tickets.