cisco послыает астеру много запросов BYE
Добавлено: 04 фев 2014, 10:18
Всем хорошего настроения!
Ситуация такая. Есть городская линия FXO, которая подключена к голосовому шлюзу cisco 1750. Входящие звонки, циска через sip отправляет на астериск, а он уже разруливает звонки. Стандартный cptone RU линию не отбивал, поэтому сделал свою настройку custom-cptone. Линия после звонка стала нормально отбиваться, но вот проблема. Если позвонить на линию, завершить звонок и сразу еще раз позвонить, то звонок сбрасывается. На циске в debug ccsip all заметил что cisco отправляет очень много сообщений BYE. Т.е., как я понимаю, после завершения первого звонка циска продолжает отсылать астеру запрос BYE. Второй звонок сбрасывается астером потому-что от циски приходит команда BYE и астер думает что это уже текущий звонок завешен. Не уверен что все внятно объяснил или все правильно понял. Из-за чего это может быть? Есть какой-то параметр на лимит запросов? Менял значение max-forwards, но изменений не заметил. Гуглил по этому поводу, ничего толком не нашел =(
Ситуация такая. Есть городская линия FXO, которая подключена к голосовому шлюзу cisco 1750. Входящие звонки, циска через sip отправляет на астериск, а он уже разруливает звонки. Стандартный cptone RU линию не отбивал, поэтому сделал свою настройку custom-cptone. Линия после звонка стала нормально отбиваться, но вот проблема. Если позвонить на линию, завершить звонок и сразу еще раз позвонить, то звонок сбрасывается. На циске в debug ccsip all заметил что cisco отправляет очень много сообщений BYE. Т.е., как я понимаю, после завершения первого звонка циска продолжает отсылать астеру запрос BYE. Второй звонок сбрасывается астером потому-что от циски приходит команда BYE и астер думает что это уже текущий звонок завешен. Не уверен что все внятно объяснил или все правильно понял. Из-за чего это может быть? Есть какой-то параметр на лимит запросов? Менял значение max-forwards, но изменений не заметил. Гуглил по этому поводу, ничего толком не нашел =(
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER: конфиг cisco
Current configuration : 2444 bytes
!
! Last configuration change at 14:23:58 IRKT Tue Feb 4 2014
! NVRAM config last updated at 14:24:05 IRKT Tue Feb 4 2014
!
version 12.3
service timestamps debug uptime
service timestamps log uptime
no service password-encryption
!
hostname WGate
!
boot-start-marker
boot-end-marker
!
no logging cns-events
!
memory-size iomem 25
clock timezone IRKT 9
mmi polling-interval 60
no mmi auto-configure
no mmi pvc
mmi snmp-timeout 180
aaa new-model
!
!
aaa session-id common
ip subnet-zero
ip cef
!
!
!
ip name-server 91.219.136.4
ip name-server 91.219.137.4
no ftp-server write-enable
!
voice echo-canceller extended
voice rtp send-recv
!
voice service pots
supported-language ru
!
voice service voip
sip
bind all source-interface FastEthernet0
!
voice class codec 1
codec preference 1 g711alaw
codec preference 2 g711ulaw
codec preference 3 g729br8
!
!
!
!
!
voice class dualtone-detect-params 1
cadence-variation 50
!
!
voice class custom-cptone SIB
dualtone busy
frequency 450
cadence 300 300
!
!
voice class custom-cptone DIS
dualtone busy
frequency 425
cadence 350 350
!
!
!
!
!
!
!
gw-accounting aaa
!
!
!
!
interface FastEthernet0
ip address 192.168.128.73 255.255.255.0
speed auto
!
interface Serial0
no ip address
shutdown
!
interface Serial1
no ip address
shutdown
!
ip default-gateway 192.168.128.1
ip classless
ip route 0.0.0.0 0.0.0.0 192.168.128.1
no ip http server
!
!
voice-port 1/0
input gain 5
timeouts call-disconnect 5
timeouts wait-release 5
connection plar opx 5001
supervisory disconnect dualtone mid-call
supervisory custom-cptone SIB
supervisory dualtone-detect-params 1
no battery-reversal
dial-type pulse
!
voice-port 1/1
input gain 5
timeouts call-disconnect 5
timeouts wait-release 5
connection plar opx 5002
supervisory disconnect dualtone mid-call
supervisory custom-cptone DIS
supervisory dualtone-detect-params 1
no battery-reversal
dial-type pulse
!
mgcp
!
!
dial-peer voice 6002 pots
preference 7
destination-pattern .T
port 1/1
!
dial-peer voice 1 voip
preference 2
destination-pattern 500[12]
voice-class codec 1
session protocol sipv2
session target sip-server
session transport udp
dtmf-relay rtp-nte
no vad
!
sip-ua
calling-info sip-to-pstn name set SIPTOPSTN
max-forwards 15
sip-server ipv4:91.219.137.26:5060
!
!
line con 0
line aux 0
line vty 0 4
password password
!
ntp clock-period 17180042
ntp master 3
ntp server 192.168.128.6 source FastEthernet0
!
end
!
! Last configuration change at 14:23:58 IRKT Tue Feb 4 2014
! NVRAM config last updated at 14:24:05 IRKT Tue Feb 4 2014
!
version 12.3
service timestamps debug uptime
service timestamps log uptime
no service password-encryption
!
hostname WGate
!
boot-start-marker
boot-end-marker
!
no logging cns-events
!
memory-size iomem 25
clock timezone IRKT 9
mmi polling-interval 60
no mmi auto-configure
no mmi pvc
mmi snmp-timeout 180
aaa new-model
!
!
aaa session-id common
ip subnet-zero
ip cef
!
!
!
ip name-server 91.219.136.4
ip name-server 91.219.137.4
no ftp-server write-enable
!
voice echo-canceller extended
voice rtp send-recv
!
voice service pots
supported-language ru
!
voice service voip
sip
bind all source-interface FastEthernet0
!
voice class codec 1
codec preference 1 g711alaw
codec preference 2 g711ulaw
codec preference 3 g729br8
!
!
!
!
!
voice class dualtone-detect-params 1
cadence-variation 50
!
!
voice class custom-cptone SIB
dualtone busy
frequency 450
cadence 300 300
!
!
voice class custom-cptone DIS
dualtone busy
frequency 425
cadence 350 350
!
!
!
!
!
!
!
gw-accounting aaa
!
!
!
!
interface FastEthernet0
ip address 192.168.128.73 255.255.255.0
speed auto
!
interface Serial0
no ip address
shutdown
!
interface Serial1
no ip address
shutdown
!
ip default-gateway 192.168.128.1
ip classless
ip route 0.0.0.0 0.0.0.0 192.168.128.1
no ip http server
!
!
voice-port 1/0
input gain 5
timeouts call-disconnect 5
timeouts wait-release 5
connection plar opx 5001
supervisory disconnect dualtone mid-call
supervisory custom-cptone SIB
supervisory dualtone-detect-params 1
no battery-reversal
dial-type pulse
!
voice-port 1/1
input gain 5
timeouts call-disconnect 5
timeouts wait-release 5
connection plar opx 5002
supervisory disconnect dualtone mid-call
supervisory custom-cptone DIS
supervisory dualtone-detect-params 1
no battery-reversal
dial-type pulse
!
mgcp
!
!
dial-peer voice 6002 pots
preference 7
destination-pattern .T
port 1/1
!
dial-peer voice 1 voip
preference 2
destination-pattern 500[12]
voice-class codec 1
session protocol sipv2
session target sip-server
session transport udp
dtmf-relay rtp-nte
no vad
!
sip-ua
calling-info sip-to-pstn name set SIPTOPSTN
max-forwards 15
sip-server ipv4:91.219.137.26:5060
!
!
line con 0
line aux 0
line vty 0 4
password password
!
ntp clock-period 17180042
ntp master 3
ntp server 192.168.128.6 source FastEthernet0
!
end
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER: кусок debug ccsip all
00:40:31: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
00:40:31: CCSIP-SPI-CONTROL: act_active_disconnect
00:40:31: sipSPIValidateGtd: No rawMsg from CCAPI
00:40:31: RequestCloseConnection: Closing connid 4 Local Port 52827
00:40:31: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:40:31: Queued event from SIP SPI : SIPSPI_EV_CREATE_CONNECTION
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:40:31: udpsock_close_connect: Socket fd: 4 closed for connid 4 with remote port: 5060
00:40:31: CCSIP-SPI-CONTROL: sipSPICheckSocketConnection: Connid(1) created to
91.219.137.26:5060, local_port 54466
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_NONE)
00:40:31: sipSPIStopHoldTimer: Stopping hold timer
00:40:31: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:31: sip_stats_method
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
00:40:31: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493349
CSeq: 102 BYE
Content-Length: 0
00:40:32: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:32: sipSPIStopHoldTimer: Stopping hold timer
00:40:32: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:32: sip_stats_method
00:40:32: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493349
CSeq: 102 BYE
Content-Length: 0
00:40:33: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:33: sipSPIStopHoldTimer: Stopping hold timer
00:40:33: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:33: sip_stats_method
00:40:33: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493350
CSeq: 102 BYE
Content-Length: 0
00:40:35: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:35: sipSPIStopHoldTimer: Stopping hold timer
00:40:35: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:35: sip_stats_method
00:40:35: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493352
CSeq: 102 BYE
Content-Length: 0
00:40:39: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:39: sipSPIStopHoldTimer: Stopping hold timer
00:40:39: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:39: sip_stats_method
00:40:39: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493356
CSeq: 102 BYE
Content-Length: 0
00:40:43: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:43: sipSPIStopHoldTimer: Stopping hold timer
00:40:43: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:43: sip_stats_method
00:40:43: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493360
CSeq: 102 BYE
Content-Length: 0
00:40:47: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:47: sipSPIStopHoldTimer: Stopping hold timer
00:40:47: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:47: sip_stats_method
00:40:47: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493364
CSeq: 102 BYE
Content-Length: 0
00:40:51: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:51: sipSPIStopHoldTimer: Stopping hold timer
00:40:51: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:51: sip_stats_method
00:40:51: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493368
CSeq: 102 BYE
Content-Length: 0
00:40:55: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:55: sipSPIStopHoldTimer: Stopping hold timer
00:40:55: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:55: sip_stats_method
00:40:55: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493372
CSeq: 102 BYE
Content-Length: 0
00:40:59: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:59: sipSPIStopHoldTimer: Stopping hold timer
00:40:59: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:59: sip_stats_method
00:40:59: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493376
CSeq: 102 BYE
Content-Length: 0
00:41:03: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:41:03: sipSPIStopHoldTimer: Stopping hold timer
00:41:03: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:41:03: sip_stats_method
00:41:03: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493380
CSeq: 102 BYE
Content-Length: 0
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
00:41:05: CCSIP-SPI-CONTROL: act_active_disconnect
00:41:05: sipSPIValidateGtd: No rawMsg from CCAPI
00:41:05: RequestCloseConnection: Closing connid 2 Local Port 49725
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CREATE_CONNECTION
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:41:05: udpsock_close_connect: Socket fd: 2 closed for connid 2 with remote port: 5060
00:41:05: CCSIP-SPI-CONTROL: sipSPICheckSocketConnection: Connid(2) created to 91.219.137.26:5060, local_port 55471
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_NONE)
00:41:05: sipSPIStopHoldTimer: Stopping hold timer
00:41:05: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:41:05: sip_stats_method
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
00:41:05: Sent:
BYE sip:5001@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=12C6B8-2550
To: <sip:5001@91.219.137.26>;tag=as39d2e162
Date: Tue, 04 Feb 2014 05:35:48 GMT
Call-ID: 90C362D-8C9511E3-8009F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493383
CSeq: 102 BYE
Content-Length: 0
00:41:05: Received:
SIP/2.0 481 Call leg/transaction does not exist
Via: SIP/2.0/UDP 192.168.128.73:5060;received=192.168.128.73;rport=55471
From: <sip:192.168.128.73>;tag=12C6B8-2550
To: <sip:5001@91.219.137.26>;tag=as39d2e162
Call-ID: 90C362D-8C9511E3-8009F7FB-8CB4AB1A@192.168.128.73
CSeq: 102 BYE
Server: Asterisk PBX 11.5.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0
00:41:05: HandleUdpSocketReads :Msg enqueued for SPI with IPaddr: 91.219.137.26:5060
00:41:05: CCSIP-SPI-CONTROL: act_disconnecting_new_message
00:41:05: CCSIP-SPI-CONTROL: sact_disconnecting_new_message_response
00:41:05: CCSIP-SPI-CONTROL: sipSPICheckResponse
00:41:05: sip_stats_status_code
00:41:05: Roundtrip delay 16 milliseconds for method BYE
00:41:05: CCSIP-SPI-CONTROL: sipSPICallCleanup
00:41:05: sipSPIIcpifUpdate :CallState: 4 Playout: 40880 DiscTime:246546 ConnTime 123059
00:41:05: RequestCloseConnection: Closing connid 2 Local Port 55471
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:41:05: sipSPIFlushEventBufferQueue: There are 0 events on the internal queuethat are going to be free'd
00:41:05: freeing ccb 826B1D08
00:41:05: udpsock_close_connect: Socket fd: 2 closed for connid 2 with remote port: 5060
00:41:07: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:41:07: CCSIP-SPI-CONTROL: act_disconnecting_wait_200 : Out of retries
00:41:07: RequestCloseConnection: Closing connid 1 Local Port 54466
00:41:07: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:41:07: CCSIP-SPI-CONTROL: sipSPICallCleanup
00:41:07: sipSPIIcpifUpdate :CallState: 4 Playout: 66340 DiscTime:246719 ConnTime 236224
00:41:07: 0x826B4B4C : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
00:41:07: The Call Setup Information is :
Call Control Block (CCB) : 0x826B4B4C
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number :
Called Number : 5002
Number of Media Streams : 1
00:41:07: Media Stream 1
Negotiated Codec : g711alaw
Negotiated Codec Bytes : 160
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101
Source IP Address (Media): 192.168.128.73
Source IP Port (Media): 17404
Destn IP Address (Media): 91.219.137.26
Destn IP Port (Media): 13032
00:41:07: Orig Destn IP Address:Port (Media): 0.0.0.0:0
00:41:07:
Source IP Address (Sig ): 192.168.128.73
Destn SIP Req Addr:Port : 91.219.137.26:5060
Destn SIP Resp Addr:Port : 0.0.0.0:0
Destination Name : 91.219.137.26
00:41:07:
Disconnect Cause (CC) : 17
Disconnect Cause (SIP) : 486
00:41:07: ****Deleting from UAC table. ccb=0x826B4B4C key=AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
00:41:07: Removing call id A
00:40:31: CCSIP-SPI-CONTROL: act_active_disconnect
00:40:31: sipSPIValidateGtd: No rawMsg from CCAPI
00:40:31: RequestCloseConnection: Closing connid 4 Local Port 52827
00:40:31: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:40:31: Queued event from SIP SPI : SIPSPI_EV_CREATE_CONNECTION
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:40:31: udpsock_close_connect: Socket fd: 4 closed for connid 4 with remote port: 5060
00:40:31: CCSIP-SPI-CONTROL: sipSPICheckSocketConnection: Connid(1) created to
91.219.137.26:5060, local_port 54466
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_NONE)
00:40:31: sipSPIStopHoldTimer: Stopping hold timer
00:40:31: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:31: sip_stats_method
00:40:31: 0x826B4B4C : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
00:40:31: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493349
CSeq: 102 BYE
Content-Length: 0
00:40:32: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:32: sipSPIStopHoldTimer: Stopping hold timer
00:40:32: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:32: sip_stats_method
00:40:32: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493349
CSeq: 102 BYE
Content-Length: 0
00:40:33: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:33: sipSPIStopHoldTimer: Stopping hold timer
00:40:33: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:33: sip_stats_method
00:40:33: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493350
CSeq: 102 BYE
Content-Length: 0
00:40:35: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:35: sipSPIStopHoldTimer: Stopping hold timer
00:40:35: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:35: sip_stats_method
00:40:35: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493352
CSeq: 102 BYE
Content-Length: 0
00:40:39: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:39: sipSPIStopHoldTimer: Stopping hold timer
00:40:39: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:39: sip_stats_method
00:40:39: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493356
CSeq: 102 BYE
Content-Length: 0
00:40:43: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:43: sipSPIStopHoldTimer: Stopping hold timer
00:40:43: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:43: sip_stats_method
00:40:43: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493360
CSeq: 102 BYE
Content-Length: 0
00:40:47: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:47: sipSPIStopHoldTimer: Stopping hold timer
00:40:47: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:47: sip_stats_method
00:40:47: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493364
CSeq: 102 BYE
Content-Length: 0
00:40:51: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:51: sipSPIStopHoldTimer: Stopping hold timer
00:40:51: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:51: sip_stats_method
00:40:51: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493368
CSeq: 102 BYE
Content-Length: 0
00:40:55: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:55: sipSPIStopHoldTimer: Stopping hold timer
00:40:55: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:55: sip_stats_method
00:40:55: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493372
CSeq: 102 BYE
Content-Length: 0
00:40:59: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:40:59: sipSPIStopHoldTimer: Stopping hold timer
00:40:59: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:40:59: sip_stats_method
00:40:59: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493376
CSeq: 102 BYE
Content-Length: 0
00:41:03: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:41:03: sipSPIStopHoldTimer: Stopping hold timer
00:41:03: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:41:03: sip_stats_method
00:41:03: Sent:
BYE sip:5002@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=240B38-1DD
To: <sip:5002@91.219.137.26>;tag=as2df10f9b
Date: Tue, 04 Feb 2014 05:54:39 GMT
Call-ID: AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493380
CSeq: 102 BYE
Content-Length: 0
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CC_CALL_DISCONNECT
00:41:05: CCSIP-SPI-CONTROL: act_active_disconnect
00:41:05: sipSPIValidateGtd: No rawMsg from CCAPI
00:41:05: RequestCloseConnection: Closing connid 2 Local Port 49725
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CREATE_CONNECTION
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_CONNECTING)
00:41:05: udpsock_close_connect: Socket fd: 2 closed for connid 2 with remote port: 5060
00:41:05: CCSIP-SPI-CONTROL: sipSPICheckSocketConnection: Connid(2) created to 91.219.137.26:5060, local_port 55471
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_CONNECTING) to (STATE_ACTIVE, SUBSTATE_NONE)
00:41:05: sipSPIStopHoldTimer: Stopping hold timer
00:41:05: Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
00:41:05: sip_stats_method
00:41:05: 0x826B1D08 : State change from (STATE_ACTIVE, SUBSTATE_NONE) to (STATE_DISCONNECTING, SUBSTATE_NONE)
00:41:05: Sent:
BYE sip:5001@91.219.137.26:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.128.73:5060
From: <sip:192.168.128.73>;tag=12C6B8-2550
To: <sip:5001@91.219.137.26>;tag=as39d2e162
Date: Tue, 04 Feb 2014 05:35:48 GMT
Call-ID: 90C362D-8C9511E3-8009F7FB-8CB4AB1A@192.168.128.73
User-Agent: Cisco-SIPGateway/IOS-12.x
Max-Forwards: 15
Timestamp: 1391493383
CSeq: 102 BYE
Content-Length: 0
00:41:05: Received:
SIP/2.0 481 Call leg/transaction does not exist
Via: SIP/2.0/UDP 192.168.128.73:5060;received=192.168.128.73;rport=55471
From: <sip:192.168.128.73>;tag=12C6B8-2550
To: <sip:5001@91.219.137.26>;tag=as39d2e162
Call-ID: 90C362D-8C9511E3-8009F7FB-8CB4AB1A@192.168.128.73
CSeq: 102 BYE
Server: Asterisk PBX 11.5.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0
00:41:05: HandleUdpSocketReads :Msg enqueued for SPI with IPaddr: 91.219.137.26:5060
00:41:05: CCSIP-SPI-CONTROL: act_disconnecting_new_message
00:41:05: CCSIP-SPI-CONTROL: sact_disconnecting_new_message_response
00:41:05: CCSIP-SPI-CONTROL: sipSPICheckResponse
00:41:05: sip_stats_status_code
00:41:05: Roundtrip delay 16 milliseconds for method BYE
00:41:05: CCSIP-SPI-CONTROL: sipSPICallCleanup
00:41:05: sipSPIIcpifUpdate :CallState: 4 Playout: 40880 DiscTime:246546 ConnTime 123059
00:41:05: RequestCloseConnection: Closing connid 2 Local Port 55471
00:41:05: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:41:05: sipSPIFlushEventBufferQueue: There are 0 events on the internal queuethat are going to be free'd
00:41:05: freeing ccb 826B1D08
00:41:05: udpsock_close_connect: Socket fd: 2 closed for connid 2 with remote port: 5060
00:41:07: CCSIP-SPI-CONTROL: act_disconnecting_wait_200
00:41:07: CCSIP-SPI-CONTROL: act_disconnecting_wait_200 : Out of retries
00:41:07: RequestCloseConnection: Closing connid 1 Local Port 54466
00:41:07: Queued event from SIP SPI : SIPSPI_EV_CLOSE_CONNECTION
00:41:07: CCSIP-SPI-CONTROL: sipSPICallCleanup
00:41:07: sipSPIIcpifUpdate :CallState: 4 Playout: 66340 DiscTime:246719 ConnTime 236224
00:41:07: 0x826B4B4C : State change from (STATE_DISCONNECTING, SUBSTATE_NONE) to (STATE_DEAD, SUBSTATE_NONE)
00:41:07: The Call Setup Information is :
Call Control Block (CCB) : 0x826B4B4C
State of The Call : STATE_DEAD
TCP Sockets Used : NO
Calling Number :
Called Number : 5002
Number of Media Streams : 1
00:41:07: Media Stream 1
Negotiated Codec : g711alaw
Negotiated Codec Bytes : 160
Negotiated Dtmf-relay : 6
Dtmf-relay Payload : 101
Source IP Address (Media): 192.168.128.73
Source IP Port (Media): 17404
Destn IP Address (Media): 91.219.137.26
Destn IP Port (Media): 13032
00:41:07: Orig Destn IP Address:Port (Media): 0.0.0.0:0
00:41:07:
Source IP Address (Sig ): 192.168.128.73
Destn SIP Req Addr:Port : 91.219.137.26:5060
Destn SIP Resp Addr:Port : 0.0.0.0:0
Destination Name : 91.219.137.26
00:41:07:
Disconnect Cause (CC) : 17
Disconnect Cause (SIP) : 486
00:41:07: ****Deleting from UAC table. ccb=0x826B4B4C key=AB91B9E5-8C9711E3-8015F7FB-8CB4AB1A@192.168.128.73
00:41:07: Removing call id A
В общем у циски в sip-ua конфиге нашел такой параметр retry bye. По дефолту он 10. Т.е. 1 запрос и если ответа нет, то еще 10 повторений. Поставил значение в 1 и все наладилось