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)
Change History (28)
comment:1 by , 8 years ago
comment:2 by , 8 years ago
Cc: | added |
---|
comment:4 by , 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 , 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 , 8 years ago
Priority: | critical → blocker |
---|
Вот лог работы 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]
follow-ups: 15 20 comment:7 by , 8 years ago
Процитирую сам себя из ticket:342#comment:6:
я думаю, что надо исследовать данную проблему, выявить причину и, по возможности, устранить. А для исследования надо , как минимум, больше информации: какие команды посылаются коммутатору, что он на них отвечает, какими сообщениями при этом обмениваются SIP юзер-агенты, что коммутатор выводит в лог и т.п.
Лога SIP между FreeSwitch и юзер-агентом пока не было...
Чисто по коду ошибки могу сказать, что, по идее, "INCOMPATIBLE_DESTINATION" возвращается при проблеме с медиа-потоком. Самый простой пример - у юзер-агентов нет общего поддерживаемого кодека. Или, например, коммутатор по каким-то причинам не может использовать указанный адрес/порт для передачи медиа...
И еще просьба к Диме: Когда помещаете в комментарий логи, пожалуйста, помещайте их как блоки кода. Очень неудобно читать "кашу", как например в comment:6. А если лог длинный, лучше вообще аттачить его файлом...
comment:8 by , 8 years ago
В FreeSwitch добавлен вывод в лог во всех местах, где дается ответ с кодом INCOMPATIBLE_DESTINATION. Это, надеюсь, позволит более точно установить место возникновения ошибки.
Заодно версия обновилась до 1.6.10.
comment:9 by , 8 years ago
В r380 не смог повторить, я делал многократное отключение несколько десятков раз.
Возможно такая ситуация перестала появляться после замены текущей FreeSwitch сервера на 1.6.10.
У тебя Александр воспроизводиться такая ситуация?
follow-up: 11 comment:10 by , 8 years ago
В r380 воспроизвёл за 10 отключений, а потом за 5 и даже за 2 отключения(это мой личный рекорд)
comment:11 by , 8 years ago
comment:13 by , 8 years ago
Логи программы есть в теле тикета, сейчас наверное более интересны логи фрисвитча, я их не смотрел, просто ответил на Димин вопрос
У тебя Александр воспроизводиться такая ситуация?
comment:14 by , 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
comment:15 by , 8 years ago
comment:16 by , 8 years ago
Milestone: | Текущее → 1 очередь |
---|
comment:20 by , 8 years ago
К тому, что я написал в comment:7, мне добавить нечего. Логов SIP я до сих пор не видел.
by , 8 years ago
by , 8 years ago
Attachment: | fs_cli_log.txt added |
---|
comment:21 by , 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 , 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 , 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
Что именно по-вашему меня в этой строке должно заинтересовать?
follow-up: 25 comment:24 by , 8 years ago
Ошибка в выражении
[Stop-alarm] ${hash(select/alarms/${destination_number})}() =~ /1/ break=on-false
comment:25 by , 8 years ago
Replying to dimag:
Ошибка в выражении
[Stop-alarm] ${hash(select/alarms/${destination_number})}() =~ /1/ break=on-false
Не нахожу в этом выражении никакой ошибки... В чем по-вашему она состоит?
comment:26 by , 8 years ago
Resolution: | → fixed |
---|---|
Status: | new → closed |
Так как код, отвечающий за подключение нашего диспетчера к конференции, полностью переписан, считаю, что проблема тем самым устранена.
Я пробовал проделать так, нажимал и тут же отбивал, после пару десятов раз, пользователь перестал подключаться к конференции.
НА следующую команду
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?