Jump to content
Калькуляторы

Проблемы с Cisco 1751 Voice Bundle не отсылает RTP-пакеты

Как посоветовали вот в этой теме, приобрел у NAGCisco 1751 VoIP E1 Bundle с тем, чтобы использовать ее в качестве конвертера SIP <-> E1, установленного между офисной АТС "Panasonic TDA-200" и Asterisk 1.4. Подключил, настроил. Сигнализация проходит, звонки проходят, но... Cisco принципиально НЕ отсылает на Asterisk RTP-пакеты. То есть если SIP-абонент звонит Panasonic-абоненту, либо наоборот, то сеанс связи устанавливается; только SIP-абонента слышно, а вот со стороны Panasonic-а тишина.

 

Мой коллега из организации-партнера высказал следующую гипотезу:

я такое видел только когда PVDM процессоры были вставлены в шасси циски, а не в плату E1\T1. В результате они как бы были, но платой не почему то не использовались. Возможно у Вас таже ситуация.

Но у меня другая циска. Я не уверен, что можно вставить эти процессоры напрямую в мою плату. По-моему, нельзя.

 

В какую сторону копать?

 

Далее привожу конфиг астериска и конфиг циски. Со стороны Panasonic-а используется поток E1/PRI с QSIG.

 

Астериск, фрагмент sip.conf:

[cisco]; Cisco 1751
canreinvite=no
allow=alaw
type=peer
fromdomain=192.168.12.40
host=192.168.12.40
nat=no
context=inbound-pri
insecure=port
t38pt_udptl=yes

 

Cisco, "show run":

Current configuration : 2035 bytes
!
version 12.3
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname sneak
!
boot-start-marker
boot-end-marker
!
enable secret 5 хеш_пароля
enable password какой_то_пароль
!
memory-size iomem 15
tdm clock E1 0/0 both export line
mmi polling-interval 60
no mmi auto-configure
no mmi pvc
mmi snmp-timeout 180
voice-card 0
!
no aaa new-model
ip subnet-zero
no ip routing
no ip cef
!
!
!
ip name-server 192.168.0.225
ip name-server 192.168.0.122
isdn switch-type primary-qsig
!
!
voice call disc-pi-off
voice call carrier capacity active
voice rtp send-recv
!
voice service pots
!
voice service voip
fax protocol t38 ls-redundancy 5 hs-redundancy 2 fallback pass-through g711alaw
sip
!
voice class codec 1
codec preference 1 g711alaw
!
username sust privilege 15 secret 5 хеш_пароля
!
controller E1 0/0
framing NO-CRC4
pri-group timeslots 1-31
!
interface FastEthernet0/0
ip address 192.168.12.40 255.255.255.0
no ip route-cache
speed auto
full-duplex
!
interface Serial0/0:15
no ip address
encapsulation hdlc
no logging event link-status
isdn switch-type primary-qsig
isdn overlap-receiving
isdn incoming-voice voice
isdn send-alerting
isdn sending-complete
no cdp enable
!
ip default-gateway 192.168.12.1
ip classless
no ip http server
!
!
voice-port 0/0:15
cptone RU
!
!
!
dial-peer voice 1 voip
application session
answer-address .T
destination-pattern 7..
rtp payload-type nte 99
voice-class codec 1
session protocol sipv2
session target dns:mypbx.myorg.ru
dtmf-relay rtp-nte
fax protocol t38 ls-redundancy 0 hs-redundancy 0 fallback cisco
!
dial-peer voice 2 pots
answer-address .T
destination-pattern [1-6]..
direct-inward-dial
port 0/0:15
forward-digits all
!
gateway
!
sip-ua
max-forwards 12
sip-server dns:pbx.myorg.ru
!
!
line con 0
line aux 0
line vty 0 4
privilege level 15
password хеш_какого-то_пароля
login local
transport input telnet
!
end

 

show voice dsp:

DSP  DSP             DSPWARE CURR  BOOT                         PAK     TX/RX
TYPE NUM CH CODEC    VERSION STATE STATE   RST AI VOICEPORT TS ABORT  PACK COUNT
==== === == ======== ======= ===== ======= === == ========= == ===== ============

Active Voice Call details


Current total analog signalling channels: 0
Current max allowed digital timeslot for voice: 36
Current number of DSP group: 1
Group 0:
Current allocated analog signalling channels: 0
Current free analog signalling channels: 0
Current allocated digital signalling channels: 30
Current free digital signalling channels: 6
Port(s) served: 0/0
Current Available MIPS: 600
SPMM  DSPRM  State   Image     D-sig  D-sig     A-sig  A-sig  Mips Voice/Xcode
 Dsp   Dsp                  allocate   free  allocate   free  Free     Chan
 0/0     0      UP   FLEX6         6      0         0      0   100        0
 0/1     1      UP   FLEX6         6      0         0      0   100        0
 0/2     2      UP   FLEX6         6      0         0      0   100        0
 1/0     3      UP   FLEX6         6      0         0      0   100        0
 1/1     4      UP   FLEX6         6      0         0      0   100        0
 1/2     5      UP   FLEX6         0      6         0      0   100        0

 

show ver:

Cisco Internetwork Operating System Software
IOS  C1700 Software (C1700-IPVOICE-M), Version 12.3(26), RELEASE SOFTWARE (fc2)
Technical Support: http://www.cisco.com/techsupport
Copyright © 1986-2008 by cisco Systems, Inc.
Compiled Mon 17-Mar-08 14:24 by dchih

ROM: System Bootstrap, Version 12.2(1r)XE1, RELEASE SOFTWARE (fc1)

sneak uptime is 23 hours, 15 minutes
System returned to ROM by power-on
System image file is "flash:c1700-ipvoice-mz.123-26.bin"

cisco 1751 (MPC860P) processor (revision 0x600) with 83559K/14745K bytes of memory.
Processor board ID JAD06210UEY (4233501858), with hardware revision 0000
MPC860P processor: part number 5, mask 2
Channelized E1, Version 1.0.
Bridging software.
X.25 software, Version 3.0.0.
Primary Rate ISDN software, Version 1.1.
1 FastEthernet/IEEE 802.3 interface(s)
31 Serial network interface(s)
1 Channelized E1/PRI port(s)
32K bytes of non-volatile configuration memory.
16384K bytes of processor board System flash (Read/Write)

Configuration register is 0x2102

Edited by SuSt

Share this post


Link to post
Share on other sites

> НЕ отсылает на Asterisk RTP-пакеты.

 

А с какой радости она ДОЛЖНА их отсылать ?

У Вас же Asterisk не RTP proxy, а раз так то при установке соединения пакеты бегают напрямую cisco <> IAD.

Вам необходмо:

1) Или использовать Astersk как RTP proxy (ИМХО - не лучший вариант)

2) Или разобраться со связнсотью в сети.

Share this post


Link to post
Share on other sites

Хорошо, выражусь по-другому.

 

Cisco вообще никому никакие RTP-пакеты не шлет. Ни астериску, ни кому-либо еще. Смотрел WireShark-ом, запущенном на роутере.

Share this post


Link to post
Share on other sites
> НЕ отсылает на Asterisk RTP-пакеты.

 

А с какой радости она ДОЛЖНА их отсылать ?

Должна.

 

[Asterisk] <--> {SIP, RTP} <--> [Cisco 1751] <--> {E1, TDM} <--> [Panasonic]

 

 

.

 

 

Share this post


Link to post
Share on other sites

2Voicemaster:

Вы упустили SIP client.

Если у нас Asterisk не есть RTP proxy, то RTP будет бегать напрямую мо маршруту:

SIP Client <> Cisco.

 

2SuSt:

Вы пробовали запусть debug на подсистемы SIP ?

( debug ccsip all )

Что интересного/подозрительного ?

 

Share this post


Link to post
Share on other sites
2Voicemaster:

Вы упустили SIP client.

Если у нас Asterisk не есть RTP proxy, то RTP будет бегать напрямую мо маршруту:

SIP Client <> Cisco.

Трансферы звонков в этом случае скорее всего полягут.. Из этого и исходил в предположениях.

 

.

Share this post


Link to post
Share on other sites
sh diag покажите
Slot 0:
       C1751 1FE VE DV Mainboard Port adapter, 2 ports
       Port adapter is analyzed
       Port adapter insertion time unknown
       EEPROM contents at hardware discovery:
       Hardware Revision        : 6.0
       PCB Serial Number        : JAD06210UEY
       Part Number              : 73-5128-06
       Board Revision           : B0
       Fab Version              : 04
       Product (FRU) Number     : CISCO1751
       EEPROM format version 4
       EEPROM contents (hex):
         0x00: 04 FF 40 02 4D 41 06 00 C1 8B 4A 41 44 30 36 32
         0x10: 31 30 55 45 59 82 49 14 08 06 42 42 30 02 04 FF
         0x20: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF
         0x30: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF
         0x40: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF
         0x50: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF
         0x60: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF
         0x70: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

       Packet Voice DSP Module Slot 0:
       Hardware Revision        : 3.2
       Part Number              : 73-3691-02
       Board Revision           : A0
       Deviation Number         : 0-0
       Fab Version              : 02
       PCB Serial Number        : VIC1042028L
       RMA Test History         : 00
       RMA Number               : 0-0-0-0
       RMA History              : 00
       Processor type           : 02
       Number of DSP's          : 3
       DSP memory size(in kwords): 192
       Type of DSP              : TMS320C549
       Product (FRU) Number     : PVDM-12=
       EEPROM format version 4
       EEPROM contents (hex):
       0x00:   04 FF 40 01 5C 41 03 02 82 49 0E 6B 02 42 41 30
       0x10:   80 00 00 00 00 02 02 C1 8B 56 49 43 31 30 34 32
       0x20:   30 32 38 4C 03 00 81 00 00 00 00 04 00 09 02 FF

       Packet Voice DSP Module Slot 1:
       Hardware Revision        : 3.2
       Part Number              : 73-3691-02
       Board Revision           : A0
       Deviation Number         : 0-0
       Fab Version              : 02
       PCB Serial Number        : VIC104402FE
       RMA Test History         : 00
       RMA Number               : 0-0-0-0
       RMA History              : 00
       Processor type           : 02
       Number of DSP's          : 3
       DSP memory size(in kwords): 192
       Type of DSP              : TMS320C549
       Product (FRU) Number     : PVDM-12=
       EEPROM format version 4
       EEPROM contents (hex):
       0x00:   04 FF 40 01 5C 41 03 02 82 49 0E 6B 02 42 41 30
       0x10:   80 00 00 00 00 02 02 C1 8B 56 49 43 31 30 34 34
       0x20:   30 32 46 45 03 00 81 00 00 00 00 04 00 09 02 FF

       WIC/VIC Slot 0:
       E1 (1 port) Multi-Flex Trunk WAN daughter card
       Hardware revision 1.0   Board revision B0
       Serial number     0033504710    Part number    800-04475-03
       FRU Part Number   WIC/VIC-E1=

       Test history      0x00          RMA number     00-00-00
       Connector type    PCI
       EEPROM format version 1
       EEPROM contents (hex):
       0x20:   01 21 01 00 01 FF 3D C6 50 11 7B 03 00 00 00 00
       0x30:   58 00 00 00 02 12 23 00 FF FF FF FF FF FF FF FF

 

 

Вы упустили SIP client.
Если в Asterisk явно указать "canreinvite=no", то весь RTP-трафик он (астериск) в любом случае будет пропускать через себя.

 

Вы пробовали запусть debug на подсистемы SIP ?
Сегодня вечером буду пробовать.

Share this post


Link to post
Share on other sites

запустите debug isdq q931. Что бы убедиться что по isdn всё впорядке. С PVDM проблемы врядли так как иначе вы бы не слышали друг друга и звонки с потока в сип вообще не проходили. Ну и debug isdn q931 эту проблему покажет :)

поставьте insecure=port,invite

canreinvite=yes - до фени если сип клиент не поддерживает спрямление rtp потока.

проверьте фаервол на всех точках.

У меня такая схема работет. ISDN PRI - Cisco AS5300-Sip-Asterisk 1.6-SIP-Cisco1751-E1-TDA200 (только я использую euroisdn а не qsig)

и с canreinvite=no и с canreinvite=yes всё работает на ура.

если необходимо конфиг могу скинуть в личку.

Share this post


Link to post
Share on other sites

Отдебагил. Ничего не понял :(

 

Дебаг ccsip (звоню с SIP-телефона на аналоговый)

All SIP Call tracing is enabled
sneak#
Jul  3 14:52:09.549: Received: 
INVITE sip:404@192.168.12.40 SIP/2.0

Via: SIP/2.0/UDP 111.222.111.222:5060;branch=z9hG4bK4905746b;rport

From: "Stanislav Klinkov" <sip:701@192.168.12.40>;tag=as4e329c39

To: <sip:404@192.168.12.40>

Contact: <sip:701@111.222.111.222>

Call-ID: 12ea6d55313664b4181904445d8e6370@192.168.12.40

CSeq: 102 INVITE

User-Agent: Asterisk PBX

Max-Forwards: 70

Date: Fri, 03 Jul 2009 14:51:39 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY

Supported: replaces

Content-Type: application/sdp

Content-Length: 244



v=0

o=root 25695 25695 IN IP4 111.222.111.222

s=session

c=IN IP4 111.222.111.222

t=0 0

m=audio 19974 RTP/AVP 8 101

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=silenceSupp:off - - - -

a=ptime:20

a=sendrecv


Jul  3 14:52:09.553: HandleUdpSocketReads :Msg enqueued for SPI with IPaddr: 111.222.111.222:5060
Jul  3 14:52:09.557: CCSIP-SPI-CONTROL:  sipSPISipIncomingMsg
Jul  3 14:52:09.557: 0x8299C940 : State change from (STATE_NONE, SUBSTATE_NONE)  to (STATE_IDLE, SUBSTATE_NONE)
Jul  3 14:52:09.557: CCSIP-SPI-CONTROL:  act_idle_new_message
Jul  3 14:52:09.561: sip_stats_method
Jul  3 14:52:09.561: CCSIP-SPI-CONTROL:  sact_idle_new_message_invite
Jul  3 14:52:09.561: CCSIP-SPI-CONTROL:  sipSPIUASSessionTimer
Jul  3 14:52:09.565: ****Adding to UAS Request table. ccb=0x8299C940 key=12ea6d55313664b4181904445d8e6370@192.168.12.40404
Jul  3 14:52:09.565: CCSIP-SPI-CONTROL:  sipSPIMatchSrcIpGroup
Jul  3 14:52:09.565: CCSIP-SPI-CONTROL:  sipSPIMatchSrcIpGroup: Match not found on carrier id
Jul  3 14:52:09.565: CCSIP-SPI-CONTROL:  sipSPIMatchSrcIpGroup: Match not found on Incoming called number: 404
Jul  3 14:52:09.565: CCSIP-SPI-CONTROL:  sipSPIMatchSrcIpGroup: Match not found on destination pattern: 701
Jul  3 14:52:09.565: CCSIP-SPI-CONTROL:  sipSPIContinueNewMsgInvite
Jul  3 14:52:09.569: sipSPIGetGtdBody: No valid GTD body found.
Jul  3 14:52:09.569: UpdateSIPQ931Params: Calling Oct3:  0x00
Jul  3 14:52:09.569: UpdateSIPQ931Params: Calling Oct3a: 0x80
Jul  3 14:52:09.569: UpdateSIPQ931Params: Called Oct3:   0x80
Jul  3 14:52:09.569: Received ;screen= ;privacy= -> Setting Octet3A=0x80
Jul  3 14:52:09.569:  sipSPIContinueNewMsgInvite:Not Using Voice Class Codec

Jul  3 14:52:09.569: sipSPICopyPeerDataToCCB: From CLI: Modem NSE payload = 100, Passthrough = 0,Modem relay = 0, Gw-Xid = 1
SPRT latency 200, SPRT Retries = 12, Dict Size = 1024
String Len = 32, Compress dir = 3
Jul  3 14:52:09.569: sipSPIHandleInviteMedia
Jul  3 14:52:09.569: sipSPIDoMediaNegotiation: number of m lines is 1
Jul  3 14:52:09.573: sipSPIDoAudioNegotiation: Codec (g711alaw) Negotiation Successful on Static Payload for m-line 1

Jul  3 14:52:09.573:  sipSPIDoPtimeNegotiation: One ptime attribute found - value:20
Jul  3 14:52:09.573:  convert_ptime_to_codec_bytes: Values :Codec: g711alaw ptime :20, codecbytes: 160

Jul  3 14:52:09.573:  convert_codec_bytes_to_ptime: Values :Codec: g711alaw codecbytes :160, ptime: 20

Jul  3 14:52:09.573: sipSPIDoDTMFRelayNegotiation: m-line index 1
Jul  3 14:52:09.573: sipSPICheckDynPayloadUse: Dynamic payload(101) reserved.
Jul  3 14:52:09.573: sipSPIDoDTMFRelayNegotiation: Reserved the new NTE payload type 101
Jul  3 14:52:09.573: sipSPIDoDTMFRelayNegotiation: Case of full named event(NE) match in fmtp list of events.
Jul  3 14:52:09.573: sip_sdp_get_modem_relay_cap_params:
Jul  3 14:52:09.573: sip_sdp_get_modem_relay_cap_params: NSE payload from X-cap = 0
Jul  3 14:52:09.577: sip_select_modem_relay_params: X-tmr not present in SDP. Disable modem relay
Jul  3 14:52:09.577: sipSPIGetSDPDirectionAttribute: No direction attribute present or multiple direction attributes that can't be handled for m-line:1 and num-a-lines:0

Jul  3 14:52:09.577: sipSPIDoAudioNegotiation: Codec negotiation successful for media line 1
payload_type=8, codec_bytes=160, codec=g711alaw, dtmf_relay=rtp-nte
stream_type=voice+dtmf (1), dest_ip_address=111.222.111.222, dest_port=19974
Jul  3 14:52:09.577: sipSPIUpdCallWithSdpInfo:
Preferred Codec        : g711alaw, bytes :160
Preferred  DTMF relay  : rtp-nte
Preferred NTE payload  : 99
Early Media            : No
Delayed Media          : No
Bridge Done            : No
New Media              : No
DSP DNLD Reqd          : No

Jul  3 14:52:09.577: sipSPISetMediaSrcAddr:  media src addr for stream 1 = 192.168.12.40
Jul  3 14:52:09.577: sipSPIUpdCallWithSdpInfo:Stream Type:1
M-line Index             : 1
 State                  : STREAM_ADDING (2)
 Callid                 : -1
 Negotiated Codec       : g711alaw, bytes :160
 Negotiated DTMF relay  : rtp-nte
 Negotiated NTE payload : 101
 Negotiated CN payload :  0
 Media Srce Addr/Port   : 192.168.12.40:0
 Media Dest Addr/Port   : 111.222.111.222:19974

Jul  3 14:52:09.577: sipSPIHandleInviteMedia:
Negotiated Codec        : g711alaw, bytes :160
Preferred Codec         : g711alaw, bytes :160
Preferred  DTMF relay 1 : 6
Preferred  DTMF relay 2 : 0
Negotiated DTMF relay   : 6
Preferred and Negotiated NTE payloads: 99 0
Preferred and Negotiated NSE payloads: 100 0
Preferred and Negotiated Modem Relay: 0 0
Preferred and Negotiated Modem Relay GwXid: 1 0

Jul  3 14:52:09.581: sipSPIDoQoSNegotiation - SDP body with media description
Jul  3 14:52:09.581: sipSPICanSetFallbackFlag - Local Fallback is not active
Jul  3 14:52:09.581: sipSPIAddBillingInfoToCcb: sipCallId for billing records = 12ea6d55313664b4181904445d8e6370@192.168.12.40
Jul  3 14:52:09.581: sipSPICreateRawMsg: No GTD passed.
Jul  3 14:52:09.585: adding call id 6F to table

Jul  3 14:52:09.585: CCSIP-SPI-CONTROL:  sipSPISendInviteResponse
Jul  3 14:52:09.585:  Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
Jul  3 14:52:09.589: sip_stats_status_code
Jul  3 14:52:09.589: ****Adding to UAS Response table. ccb=0x8299C940 key=12ea6d55313664b4181904445d8e6370@192.168.12.40B2F7D40-1556
Jul  3 14:52:09.589: Previous Hop 111.222.111.222:5060

Jul  3 14:52:09.589: 0x8299C940 : State change from (STATE_IDLE, SUBSTATE_NONE)  to (STATE_RECD_INVITE, SUBSTATE_NONE)
Jul  3 14:52:09.593:  Queued event from SIP SPI : SIPSPI_EV_CC_CALL_PROCEEDING
Jul  3 14:52:09.597: CCSIP-SPI-CONTROL:  act_recdinvite_proceeding
Jul  3 14:52:09.605: Sent: 
SIP/2.0 100 Trying

Via: SIP/2.0/UDP 111.222.111.222:5060;branch=z9hG4bK4905746b;rport

From: "Stanislav Klinkov" <sip:701@192.168.12.40>;tag=as4e329c39

To: <sip:404@192.168.12.40>;tag=B2F7D40-1556

Date: Fri, 03 Jul 2009 14:52:09 GMT

Call-ID: 12ea6d55313664b4181904445d8e6370@192.168.12.40

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 102 INVITE

Allow-Events: telephone-event

Content-Length: 0





Jul  3 14:52:09.741:  Queued event from SIP SPI : SIPSPI_EV_CC_CALL_ALERTING
Jul  3 14:52:09.745: CCSIP-SPI-CONTROL:  ccsip_bridge: confID = 44, srcCallID = 111, dstCallID = 112
Jul  3 14:52:09.745: sipSPIUupdateCcCallIds: old src/dest ccCallids: -1/-1, new src/dest ccCallids: 111/112
Jul  3 14:52:09.745: sipSPIUupdateCcCallIds: old streamcallid=-1, new streamcallid=111
Jul  3 14:52:09.745: CCSIP-SPI-CONTROL:  sipSPIIncomingCallSDP
Jul  3 14:52:09.745: sipSPIUpdateSrcSdpFixedPart
Jul  3 14:52:09.745: sipSPIReserveRtpPort: reserved port 19536 for stream 1
Jul  3 14:52:09.745: sipSPIUpdateSrcSdpFixedPart: reserving rtp port for stream 1, src_port=19536
Jul  3 14:52:09.745: sipSPIUpdateSrcSdpVariablePart
Jul  3 14:52:09.745: sipSPIUpdateSrcSdpVariablePart: setting stream 1 portnum to 19536
Jul  3 14:52:09.749:  Negotiated method of dtmf relay and pyld : 6 101

Jul  3 14:52:09.749: sipSPIProcessRtpSessions
Jul  3 14:52:09.749: sipSPIAddStream: Adding stream 1 (callid 111) to the VOIP RTP library
Jul  3 14:52:09.749: sipSPISetMediaSrcAddr:  media src addr for stream 1 = 192.168.12.40
Jul  3 14:52:09.749: sipSPIUpdateRtcpSession: for m-line 1
Jul  3 14:52:09.749: sipSPIUpdateRtcpSession: rtcp_session info
laddr = 192.168.12.40, lport = 19536, raddr = 111.222.111.222, rport=19974, do_rtcp=TRUE
src_callid = 111, dest_callid = 112, stream type = 1

Jul  3 14:52:09.749: sipSPIUpdateRtcpSession: No rtp session, creating a new one

Jul  3 14:52:09.753: CCSIP-SPI-CONTROL:  ccsip_caps_ind
Jul  3 14:52:09.753: ccsip_get_rtcp_session_parameters: CURRENT VALUES: stream_callid=111, current_seq_num=0x17AF
Jul  3 14:52:09.753: ccsip_get_rtcp_session_parameters: NEW VALUES: stream_callid=111, current_seq_num=0xF8B
Jul  3 14:52:09.753: ccsip_caps_ind: Load DSP with negotiated codec : g711alaw, Bytes=160
Jul  3 14:52:09.753: ccsip_caps_ind: set forking flag to 0x0
Jul  3 14:52:09.753: sipSPISetDTMFRelayMode: set DSP for dtmf-relay = CC_CAP_DTMF_RELAY_NTE_AND_OOB
Jul  3 14:52:09.753: sip_set_modem_caps: Preferred (or the one that came from DSM) modem relay=0, from CLI config=0
Jul  3 14:52:09.753: sip_set_modem_caps: Disabling Modem Relay...
Jul  3 14:52:09.757: sip_set_modem_caps: Negotiation already Done. Set negotiated Modem caps
Jul  3 14:52:09.757: sip_set_modem_caps: Modem Relay & Passthru both disabled
Jul  3 14:52:09.757: sip_set_modem_caps: nse payload = 0, ptru mode = 0, ptru-codec=0, redundancy=0, xid=0, relay=0, sprt-retry=12, latecncy=200, compres-dir=3, dict=1024, strnlen=32
Jul  3 14:52:09.757: sipSPISetStreamInfo: num_streams = 1
Jul  3 14:52:09.757: sipSPISetStreamInfo: adding stream type 1 from mline 1
Jul  3 14:52:09.757: sipSPISetStreamInfo: caps.stream_count=1, caps.stream[0].stream_type=0x2, caps.stream_list.xmitFunc=voip_rtp_xmit, caps.stream_list.context=0x81DD7BD8 (gccb)
Jul  3 14:52:09.757: ccsip_caps_ind: Load DSP with codec : g711alaw, Bytes=160
Jul  3 14:52:09.757: CCSIP-SPI-CONTROL:  ccsip_caps_ack
Jul  3 14:52:09.757: ccsip_caps_ack: set forking flag to 0x0
Jul  3 14:52:09.761: CCSIP-SPI-CONTROL:  act_recdinvite_alerting
Jul  3 14:52:09.761: sipSPIValidateGtd: Signal Forward disabled
Jul  3 14:52:09.761: Session Type is Media/Qos/Security/RTR
                     SDP body is attached
Jul  3 14:52:09.761: CCSIP-SPI-CONTROL:  sipSPIIncomingCallSDP
Jul  3 14:52:09.761:  SDP already there use old sdp and updatemedia if needed

Jul  3 14:52:09.761: sipSPIUpdateSrcSdpVariablePart
Jul  3 14:52:09.761: sipSPIUpdateSrcSdpVariablePart: setting stream 1 portnum to 19536
Jul  3 14:52:09.765:  Negotiated method of dtmf relay and pyld : 6 101

Jul  3 14:52:09.765: CCSIP-SPI-CONTROL:  sipSPISendInviteResponse
Jul  3 14:52:09.765: sipSPIAddLocalContact
Jul  3 14:52:09.765: sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
Jul  3 14:52:09.769:  Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
Jul  3 14:52:09.769: sip_stats_status_code
Jul  3 14:52:09.769: 0x8299C940 : State change from (STATE_RECD_INVITE, SUBSTATE_NONE)  to (STATE_RECD_INVITE, SUBSTATE_RECD_INVITE_RECD_PROGRESS)
Jul  3 14:52:09.773: Sent: 
SIP/2.0 183 Session Progress

Via: SIP/2.0/UDP 111.222.111.222:5060;branch=z9hG4bK4905746b;rport

From: "Stanislav Klinkov" <sip:701@192.168.12.40>;tag=as4e329c39

To: <sip:404@192.168.12.40>;tag=B2F7D40-1556

Date: Fri, 03 Jul 2009 14:52:09 GMT

Call-ID: 12ea6d55313664b4181904445d8e6370@192.168.12.40

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 102 INVITE

Allow-Events: telephone-event

Contact: <sip:404@192.168.12.40:5060>

Content-Disposition: session;handling=required

Content-Type: application/sdp

Content-Length: 248



v=0

o=CiscoSystemsSIP-GW-UserAgent 72 1528 IN IP4 192.168.12.40

s=SIP Call

c=IN IP4 192.168.12.40

t=0 0

m=audio 19536 RTP/AVP 8 101

c=IN IP4 192.168.12.40

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20


Jul  3 14:52:13.213:  Queued event from SIP SPI : SIPSPI_EV_CC_CALL_CONNECT
Jul  3 14:52:13.217: CCSIP-SPI-CONTROL:  act_sentalert_connect
Jul  3 14:52:13.217: sipSPIUpdCallWithSdpInfo:
Preferred Codec        : g711alaw, bytes :160
Preferred  DTMF relay  : rtp-nte
Preferred NTE payload  : 99
Early Media            : Yes
Delayed Media          : No
Bridge Done            : Yes
New Media              : No
DSP DNLD Reqd          : No

Jul  3 14:52:13.221: sipSPISetMediaSrcAddr:  media src addr for stream 1 = 192.168.12.40
Jul  3 14:52:13.221: sipSPIUpdCallWithSdpInfo:Stream Type:1
M-line Index             : 1
 State                  : STREAM_ACTIVE (5)
 Callid                 : 111
 Negotiated Codec       : g711alaw, bytes :160
 Negotiated DTMF relay  : rtp-nte
 Negotiated NTE payload : 101
 Negotiated CN payload :  0
 Media Srce Addr/Port   : 192.168.12.40:19536
 Media Dest Addr/Port   : 111.222.111.222:19974

Jul  3 14:52:13.221: sipSPIProcessMediaChanges
Jul  3 14:52:13.221: sipSPIValidateGtd: Signal Forward disabled
Jul  3 14:52:13.221: CCSIP-SPI-CONTROL:  sipSPIIncomingCallSDP
Jul  3 14:52:13.221:  SDP already there use old sdp and updatemedia if needed

Jul  3 14:52:13.221: sipSPIUpdateSrcSdpVariablePart
Jul  3 14:52:13.221: sipSPIUpdateSrcSdpVariablePart: setting stream 1 portnum to 19536
Jul  3 14:52:13.225:  Negotiated method of dtmf relay and pyld : 6 101

Jul  3 14:52:13.225: CCSIP-SPI-CONTROL:  sipSPISendInviteResponse
Jul  3 14:52:13.225: sipSPIAddLocalContact
Jul  3 14:52:13.225: sip_generate_sdp_xcaps_list: Modem Relay and T38 disabled. X-cap not needed
Jul  3 14:52:13.229:  Queued event from SIP SPI : SIPSPI_EV_SEND_MESSAGE
Jul  3 14:52:13.229: sip_stats_status_code
Jul  3 14:52:13.229: act_sentalert_connect: Transaction active. Facilities will be queued.
Jul  3 14:52:13.229: 0x8299C940 : State change from (STATE_RECD_INVITE, SUBSTATE_RECD_INVITE_RECD_PROGRESS)  to (STATE_SENT_SUCCESS, SUBSTATE_NONE)
Jul  3 14:52:13.229: sipSPIFlushEventBufferQueue: There are 0 events on the internal queue that are going to be free'd
Jul  3 14:52:13.233: Sent: 
SIP/2.0 200 OK

Via: SIP/2.0/UDP 111.222.111.222:5060;branch=z9hG4bK4905746b;rport

From: "Stanislav Klinkov" <sip:701@192.168.12.40>;tag=as4e329c39

To: <sip:404@192.168.12.40>;tag=B2F7D40-1556

Date: Fri, 03 Jul 2009 14:52:09 GMT

Call-ID: 12ea6d55313664b4181904445d8e6370@192.168.12.40

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 102 INVITE

Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO

Allow-Events: telephone-event

Contact: <sip:404@192.168.12.40:5060>

Content-Type: application/sdp

Content-Length: 248



v=0

o=CiscoSystemsSIP-GW-UserAgent 72 1528 IN IP4 192.168.12.40

s=SIP Call

c=IN IP4 192.168.12.40

t=0 0

m=audio 19536 RTP/AVP 8 101

c=IN IP4 192.168.12.40

a=rtpmap:8 PCMA/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-16

a=ptime:20


Jul  3 14:52:13.249: Received: 
ACK sip:404@192.168.12.40:5060 SIP/2.0

Via: SIP/2.0/UDP 111.222.111.222:5060;branch=z9hG4bK0a761e1e;rport

From: "Stanislav Klinkov" <sip:701@192.168.12.40>;tag=as4e329c39

To: <sip:404@192.168.12.40>;tag=B2F7D40-1556

Contact: <sip:701@111.222.111.222>

Call-ID: 12ea6d55313664b4181904445d8e6370@192.168.12.40

CSeq: 102 ACK

User-Agent: Asterisk PBX

Max-Forwards: 70

Content-Length: 0





Jul  3 14:52:13.249: HandleUdpSocketReads :Msg enqueued for SPI with IPaddr: 111.222.111.222:5060
Jul  3 14:52:13.253: *****CCB found in UAS Request table. ccb=0x8299C940
Jul  3 14:52:13.253: CCSIP-SPI-CONTROL:  act_sentsucc_new_message
Jul  3 14:52:13.253: CCSIP-SPI-CONTROL:  act_sentsucc_new_message_request
Jul  3 14:52:13.253: sip_stats_method
Jul  3 14:52:13.257: 0x8299C940 : State change from (STATE_SENT_SUCCESS, SUBSTATE_NONE)  to (STATE_ACTIVE, SUBSTATE_NONE)
Jul  3 14:52:13.257: The Call Setup Information is :
Call Control Block (CCB) : 0x8299C940
State of The Call        : STATE_ACTIVE
TCP Sockets Used         : NO
Calling Number           : 701
Called Number            : 404
Number of Media Streams  : 1

Jul  3 14:52:13.257: Media Stream 1
 Negotiated Codec         : g711alaw
 Negotiated Codec Bytes   : 160
 Negotiated Dtmf-relay    : 6
 Dtmf-relay Payload       : 101
 Source IP Address (Media): 192.168.12.40
 Source IP Port    (Media): 19536
 Destn  IP Address (Media): 111.222.111.222
 Destn  IP Port    (Media): 19974

Jul  3 14:52:13.257: Orig Destn IP Address:Port (Media): 0.0.0.0:0

Jul  3 14:52:13.257: 
Source IP Address (Sig  ): 192.168.12.40
Destn SIP Req Addr:Port  : 111.222.111.222:5060
Destn SIP Resp Addr:Port : 111.222.111.222:5060
Destination Name         : 111.222.111.222

Jul  3 14:52:13.257: act_sentsucc_new_message_request: Transaction Complete. Lock on Facilities released.
sneak#undebug all
All possible debugging has been turned off
sneak#

 

Дебаг ISDN-а полный:

sneak#debug isdn all

This may severely impact network performance. Continue? (yes/[no]): yes
sneak#
Jul  3 14:49:06.601: ISDN Se0/0:15 EVENTd: process_pri_call: call id 0x8002, number 404, speed 0, call type VOICE, redial No, CSM call No, pdata Yes
Jul  3 14:49:06.601: ISDN Se0/0:15 TGRM: process_pri_call BChannel Number -1, NFAS BChannel dsl 255
Jul  3 14:49:06.601: ISDN Se0/0:15 EVENTd: fill_cid_table_voice: Created entry call_id 0x8002, speed 64, remote 404, calling 701
Jul  3 14:49:06.601: ISDN Se0/0:15 EVENTd: pak_private_number: caller type/plan overridden by call_decode
Jul  3 14:49:06.605: ISDN Se0/0:15 EVENTd: pak_private_number: copied oct3a [0x80] for CALLER_NUMBER_IE
Jul  3 14:49:06.605: ISDN Se0/0:15 EVENTd: pak_private_number: called type/plan overridden by call_decode
Jul  3 14:49:06.605: ISDN Se0/0:15 CC: CCPRI_Go: source id 0x500, call id 0x0, event 0x4D (pre-ccb recovery)
Jul  3 14:49:06.605: ISDN Se0/0:15 CC: CCPRI_Go: call_id 0x8002 cref 0x0 event 0x4D Source->HOST
Jul  3 14:49:06.605: ISDN Se0/0:15 CC: CCPQSIG_CallIdle: cref 0x0 event 0x4D
Jul  3 14:49:06.609: ISDN Se0/0:15 TGRM: get_tgrm_avail_state: idb 0x826D936C bchan 31 service_state 0 call_state 1 false busy 0x0 dsl 0
Jul  3 14:49:06.609: ISDN Se0/0:15 TGRM: update_tgrm_call_status: idb 0x826D936C bchan 31 availability state 1 call state(prev,new) (0,1), dsl 0
Jul  3 14:49:06.609: ISDN Se0/0:15 TGRM: update_tgrm_call_status: Calling TGRM with tgrm_call_isdn_update: idb 0x826D936C bchan 31 call state 2 call type 2 call dir 2
Jul  3 14:49:06.609: ISDN Se0/0:15 CC: CCPMSG_OutCall: building outgoing channel id for call nfas_int is 0 len is 0
Jul  3 14:49:06.609: ISDN Se0/0:15 Q931d: CCIE_BuildChanId_IE: int_ident_len 0
Jul  3 14:49:06.613: ISDN Se0/0:15 EVENTd: calltrkr_setup_received: isdn_info=2190838632l, call_id=0x8002 ORIGINATE
Jul  3 14:49:06.613: ISDN Se0/0:15 EVENTd: calltrkr_setup_received: calltracker disabled
Jul  3 14:49:06.613: ISDN Se0/0:15 Q931d: L3_Go: source = 0x400, event = 0x340, call id = 0x8002, int id = 0x0
Jul  3 14:49:06.613: ISDN Se0/0:15 Q931d: L3_Go: call_id 0x8002 cr 0x0 state 0 event 0x80 ces 1
Jul  3 14:49:06.617: ISDN Se0/0:15 Q931d: L3_ProcessEvent: callref = 0x0000 CC_SETUP_REQ:U0_SetupReq(nlcb)
Jul  3 14:49:06.617: ISDN Se0/0:15 Q931d: L3_TxSarMsg: InfoLen = 29 octets 0x0802008805A104038090A31803A9839F
Jul  3 14:49:06.617: 6C050080373031700480343034
Jul  3 14:49:06.617: ISDN Se0/0:15 Q931d: srl_send_l3_pak:
source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ
priv_len = 4 int_id = 0x826D936C datasize = 45
Jul  3 14:49:06.617: ISDN Se0/0:15 Q931d: data =  0x826D936C000003000240044B00010800
Jul  3 14:49:06.617: 0802008805A104038090A31803A9839F
Jul  3 14:49:06.617: 6C050080373031700480343034
Jul  3 14:49:06.621: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82882654), ticks (6000), event (0x1313)
Jul  3 14:49:06.621: ISDN Se0/0:15 Q931d: L3_state_change: callref 0x0088 old NULL_STATE, new CALL_INIT
Jul  3 14:49:06.621: ISDN Se0/0:15 PACKET: handle_mail: received:
dest_id = 0, source_id = 768, prim = 240
priv_ln = 4, int_id = -2106748052, datasize = 45
Jul  3 14:49:06.621: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519A1D8), size (45) 0x0802008805A104038090A31803A9839F
Jul  3 14:49:06.621: 6C050080373031700480343034044772
Jul  3 14:49:06.621: 5018FED18A0700002A00000000
Jul  3 14:49:06.625: ISDN Se0/0:15 PACKET: handle_mail: received data =  0x0802008805A104038090A31803A9839F
Jul  3 14:49:06.625: 6C050080373031700480343034
Jul  3 14:49:06.625: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x0), pkt (0x82874580), msecs (0x0), priority (0xA)
Jul  3 14:49:06.625: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x0),
pkt (0x82474D60), msecs (0x0), priority (0xA)
Jul  3 14:49:06.629: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531BD98), size (33) 0x000174500802008805A104038090A318
Jul  3 14:49:06.629: 03A9839F6C0500803730317004803430
Jul  3 14:49:06.629: 34
Jul  3 14:49:06.629: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052
 datastart = 531BD88, netstart = 531BD98, datasize = 49
Jul  3 14:49:06.629: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531BD88), size (49) 0x826D936C0000020A0140044B00010100
Jul  3 14:49:06.629: 000174500802008805A104038090A318
Jul  3 14:49:06.629: 03A9839F6C0500803730317004803430
Jul  3 14:49:06.629: 34
Jul  3 14:49:06.633: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82A50204), ticks (1000), event (0x1240)
Jul  3 14:49:06.633: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
Jul  3 14:49:06.633: ISDN Se0/0:15 PACKET: handle_l2d_srq_mail: received:
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052, datasize = 33
line_status[0] = IF_ACTIVE
Jul  3 14:49:06.633: ISDN Se0/0:15 Q921f: User TX -> 0x000174500802008805A104038090A318
Jul  3 14:49:06.633: 03A9839F6C0500803730317004803430
Jul  3 14:49:06.633: 34
Jul  3 14:49:06.633: ISDN Se0/0:15 Q921: User TX -> INFO sapi=0 tei=0, ns=58 nr=40
Jul  3 14:49:06.637: ISDN Se0/0:15 Q931: SETUP pd = 8  callref = 0x0088 
Sending Complete 
Bearer Capability i = 0x8090A3 
Standard = CCITT 
Transfer Capability = Speech  
Transfer Mode = Circuit 
Transfer Rate = 64 kbit/s 
Channel ID i = 0xA9839F 
Exclusive, Channel 31 
Calling Party Number i = 0x0080, '701' 
Plan:Unknown, Type:Unknown 
Called Party Number i = 0x80, '404' 
Plan:Unknown, Type:Unknown
Jul  3 14:49:06.637: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
Jul  3 14:49:06.657: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
Jul  3 14:49:06.657: ISDN Se0/0:15 Q921f: User RX <- 0x00010176
Jul  3 14:49:06.657: ISDN Se0/0:15 Q921: User RX <- RR sapi=0 tei=0 nr=59
Jul  3 14:49:06.657: ISDN Se0/0:15 Q921d: process_rxdata: Frame sent to L2
Jul  3 14:49:06.657: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x200), state (0x0),
dest_id (0x20A), event (0x141), 
sapi (0x0), ces (0x0), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x82473580), msecs (0x0), priority (0xA)
Jul  3 14:49:06.657: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531B398), size (4) 0x00010176
Jul  3 14:49:06.661: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052
 datastart = 531B388, netstart = 531B398, datasize = 20
Jul  3 14:49:06.661: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531B388), size (20) 0x826D936C000002000141044B00000100
Jul  3 14:49:06.661: 00010176
Jul  3 14:49:06.661: ISDN  Q921d: isdn_from_driver_process: event_count 1
Jul  3 14:49:06.661: ISDN Se0/0:15 PACKET: handle_mail: received:
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052, datasize = 20
Jul  3 14:49:06.661: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531B398), size (20) 0x00010176793040007E06F5E9C0A8003D
Jul  3 14:49:06.665: C0A80C28
Jul  3 14:49:06.665: ISDN Se0/0:15 PACKET: handle_mail: received data =  0x00010176
Jul  3 14:49:06.665: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82386B58), ticks (10000), event (0x1250)
Jul  3 14:49:06.757: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
Jul  3 14:49:06.757: ISDN Se0/0:15 Q921f: User RX <- 0x0201507608028088021803A9839F
Jul  3 14:49:06.757: ISDN Se0/0:15 Q921: User RX <- INFO sapi=0 tei=0, ns=40 nr=59
Jul  3 14:49:06.757: ISDN Se0/0:15 Q931: CALL_PROC pd = 8  callref = 0x8088 
Channel ID i = 0xA9839F 
Exclusive, Channel 31
Jul  3 14:49:06.761: ISDN Se0/0:15 Q921d: process_rxdata: Frame sent to L2
Jul  3 14:49:06.761: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x200), state (0x0),
dest_id (0x20A), event (0x141), 
sapi (0x0), ces (0x0), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x8221EEFC), msecs (0x0), priority (0xA)
Jul  3 14:49:06.761: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519B718), size (14) 0x0201507608028088021803A9839F
Jul  3 14:49:06.761: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052
 datastart = 519B708, netstart = 519B718, datasize = 30
Jul  3 14:49:06.765: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519B708), size (30) 0x826D936C000002000141044B00000100
Jul  3 14:49:06.765: 0201507608028088021803A9839F
Jul  3 14:49:06.765: ISDN  Q921d: isdn_from_driver_process: event_count 1
Jul  3 14:49:06.765: ISDN Se0/0:15 PACKET: handle_mail: received:
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052, datasize = 30
Jul  3 14:49:06.765: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519B718), size (30) 0x0201507608028088021803A9839F003D
Jul  3 14:49:06.765: C0A80C28122600170581A0087004
Jul  3 14:49:06.765: ISDN Se0/0:15 PACKET: handle_mail: received data =  0x0201507608028088021803A9839F
Jul  3 14:49:06.769: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x300), event (0x241), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x1), pkt (0x82874580), msecs (0x0), priority (0xA)
Jul  3 14:49:06.769: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x300), event (0x241), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x8221CB2C), msecs (0x0), priority (0xA)
Jul  3 14:49:06.773: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519A818), size (10) 0x08028088021803A9839F
Jul  3 14:49:06.773: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 241
priv_ln = 4, int_id = -2106748052
 datastart = 519A808, netstart = 519A818, datasize = 26
Jul  3 14:49:06.773: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519A808), size (26) 0x826D936C0000020A0241044B00010100
Jul  3 14:49:06.773: 08028088021803A9839F
Jul  3 14:49:06.773: ISDN Se0/0:15 Q921d: srl_send_l2_pak:
source_id = Q.921, dest_id = Q.931, prim = DL_DATA_IND
priv_len = 4 int_id = 0x826D936C datasize = 26
Jul  3 14:49:06.773: ISDN Se0/0:15 Q921d: data =  0x826D936C000000000241044B00010100
Jul  3 14:49:06.773: 08028088021803A9839F
Jul  3 14:49:06.777: ISDN Se0/0:15 BACKHAUL: L3IF_rx_L2_pak: received data 0x08028088021803A9839F
Jul  3 14:49:06.777: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82386B58), ticks (10000), event (0x1250)
Jul  3 14:49:06.777: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x1), pkt (0x8268B698), msecs (0x0), priority (0xA)
Jul  3 14:49:06.781: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x8221B34C), msecs (0x0), priority (0xA)
Jul  3 14:49:06.781: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(5199E18), size (4) 0x02010152
Jul  3 14:49:06.781: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052
 datastart = 5199E08, netstart = 5199E18, datasize = 20
Jul  3 14:49:06.781: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(5199E08), size (20) 0x826D936C0000020A0140044B00010100
Jul  3 14:49:06.781: 02010152
Jul  3 14:49:06.781: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
Jul  3 14:49:06.785: ISDN Se0/0:15 PACKET: handle_l2d_srq_mail: received:
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052, datasize = 4
line_status[0] = IF_ACTIVE
Jul  3 14:49:06.785: ISDN Se0/0:15 Q921f: User TX -> 0x02010152
Jul  3 14:49:06.785: ISDN Se0/0:15 Q921: User TX -> RR sapi=0 tei=0 nr=41
Jul  3 14:49:06.785: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
Jul  3 14:49:06.785: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
Jul  3 14:49:06.785: ISDN Se0/0:15 Q921f: User RX <- 0x0201527608028088011E0281881E0281
Jul  3 14:49:06.785: 88
Jul  3 14:49:06.789: ISDN Se0/0:15 Q921: User RX <- INFO sapi=0 tei=0, ns=41 nr=59
Jul  3 14:49:06.789: ISDN Se0/0:15 Q931: ALERTING pd = 8  callref = 0x8088 
Progress Ind i = 0x8188 - In-band info or appropriate now available  
Progress Ind i = 0x8188 - In-band info or appropriate now available 
Jul  3 14:49:06.789: ISDN Se0/0:15 Q921d: process_rxdata: Frame sent to L2
Jul  3 14:49:06.789: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x200), state (0x0),
dest_id (0x20A), event (0x141), 
sapi (0x0), ces (0x0), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x8221EC00), msecs (0x0), priority (0xA)
Jul  3 14:49:06.793: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519B5D8), size (17) 0x0201527608028088011E0281881E0281
Jul  3 14:49:06.793: 88
Jul  3 14:49:06.793: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052
 datastart = 519B5C8, netstart = 519B5D8, datasize = 33
Jul  3 14:49:06.793: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519B5C8), size (33) 0x826D936C000002000141044B00000100
Jul  3 14:49:06.793: 0201527608028088011E0281881E0281
Jul  3 14:49:06.793: 88
Jul  3 14:49:06.793: ISDN  Q921d: isdn_from_driver_process: event_count 1
Jul  3 14:49:06.797: ISDN Se0/0:15 PACKET: handle_mail: received:
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052, datasize = 33
Jul  3 14:49:06.797: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519B5D8), size (33) 0x0201527608028088011E0281881E0281
Jul  3 14:49:06.797: 88A80C28122600170581A00B700447C4
Jul  3 14:49:06.797: 50
Jul  3 14:49:06.797: ISDN Se0/0:15 PACKET: handle_mail: received data =  0x0201527608028088011E0281881E0281
Jul  3 14:49:06.797: 88
Jul  3 14:49:06.797: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x300), event (0x241), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x1), pkt (0x8268B698), msecs (0x0), priority (0xA)
Jul  3 14:49:06.801: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x300), event (0x241), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x82475F48), msecs (0x0), priority (0xA)
Jul  3 14:49:06.801: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531C518), size (13) 0x08028088011E0281881E028188
Jul  3 14:49:06.801: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 241
priv_ln = 4, int_id = -2106748052
 datastart = 531C508, netstart = 531C518, datasize = 29
Jul  3 14:49:06.805: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531C508), size (29) 0x826D936C0000020A0241044B00010100
Jul  3 14:49:06.805: 08028088011E0281881E028188
Jul  3 14:49:06.805: ISDN Se0/0:15 Q921d: srl_send_l2_pak:
source_id = Q.921, dest_id = Q.931, prim = DL_DATA_IND
priv_len = 4 int_id = 0x826D936C datasize = 29
Jul  3 14:49:06.805: ISDN Se0/0:15 Q921d: data =  0x826D936C000000000241044B00010100
Jul  3 14:49:06.805: 08028088011E0281881E028188
Jul  3 14:49:06.805: ISDN Se0/0:15 BACKHAUL: L3IF_rx_L2_pak: received data 0x08028088011E0281881E028188
Jul  3 14:49:06.805: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82386B58), ticks (10000), event (0x1250)
Jul  3 14:49:06.809: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x1), pkt (0x825E725C), msecs (0x0), priority (0xA)
Jul  3 14:49:06.809: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x82476B38), msecs (0x0), priority (0xA)
Jul  3 14:49:06.809: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531CA18), size (4) 0x02010154
Jul  3 14:49:06.813: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052
 datastart = 531CA08, netstart = 531CA18, datasize = 20
Jul  3 14:49:06.813: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531CA08), size (20) 0x826D936C0000020A0140044B00010100
Jul  3 14:49:06.813: 02010154
Jul  3 14:49:06.813: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
Jul  3 14:49:06.813: ISDN Se0/0:15 PACKET: handle_l2d_srq_mail: received:
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052, datasize = 4
line_status[0] = IF_ACTIVE
Jul  3 14:49:06.813: ISDN Se0/0:15 Q921f: User TX -> 0x02010154
Jul  3 14:49:06.817: ISDN Se0/0:15 Q921: User TX -> RR sapi=0 tei=0 nr=42
Jul  3 14:49:06.817: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
Jul  3 14:49:06.817: ISDN Se0/0:15 Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0
Jul  3 14:49:06.817: ISDN Se0/0:15 Q931d: L3_Go: call_id 0x8002 cr 0x88 state 1 event 0x2 ces 1
Jul  3 14:49:06.817: ISDN Se0/0:15 Q931d: L3_ProcessEvent: callref = 0x0088 CALL_PROC:U1_CallProc(nlcb)
Jul  3 14:49:06.821: ISDN Se0/0:15 Q931d: L3_state_change: callref 0x0088 old CALL_INIT, new OUT_CALL_PROC
Jul  3 14:49:06.821: ISDN Se0/0:15 CC: CCPRI_Go: source id 0x300, call id 0x8002, event 0x341 (pre-ccb recovery)
Jul  3 14:49:06.821: ISDN Se0/0:15 CC: CCPRI_Go: dispatching event 0x98 call id 0x8002 cref 0x0 Source->L3
Jul  3 14:49:06.821: ISDN Se0/0:15 CC: CCPQSIG_CallOrigination: cref 0x0 event 0x98
Jul  3 14:49:06.825: ISDN Se0/0:15 TGRM: get_tgrm_avail_state: idb 0x826D936C bchan 31 service_state 0 call_state 2 false busy 0x0 dsl 0
Jul  3 14:49:06.825: ISDN Se0/0:15 TGRM: update_tgrm_call_status: idb 0x826D936C bchan 31 availability state 1 call state(prev,new) (1,2), dsl 0
Jul  3 14:49:06.825: ISDN Se0/0:15 TGRM: update_tgrm_call_status: Calling TGRM with tgrm_call_isdn_update: idb 0x826D936C bchan 31 call state 1 call type 2 call dir 2
Jul  3 14:49:06.825: ISDN Se0/0:15 Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0
Jul  3 14:49:06.825: ISDN Se0/0:15 Q931d: L3_Go: call_id 0x8002 cr 0x88 state 3 event 0x1 ces 1
Jul  3 14:49:06.829: ISDN Se0/0:15 Q931d: L3_ProcessEvent: callref = 0x0088 ALERTING:U3_Alerting(nlcb)
Jul  3 14:49:06.829: ISDN Se0/0:15 Q931d: L3_state_change: callref 0x0088 old OUT_CALL_PROC, new CALL_DELIVERED
Jul  3 14:49:06.829: ISDN Se0/0:15 EVENT: process_rxstate: ces/callid 1/0x8002 calltype 2 CALL_PROCEEDING
Jul  3 14:49:06.829: ISDN Se0/0:15 PACKET: process_rxstate:  0x650001800206180421031F001803A983
Jul  3 14:49:06.829: 9F
Jul  3 14:49:06.829: ISDN Se0/0:15 EVENTd: call_alert: Response to outgoing call on bchan = 30
Jul  3 14:49:06.833: ISDN Se0/0:15 CC: CCPRI_Go: source id 0x300, call id 0x8002, event 0x341 (pre-ccb recovery)
Jul  3 14:49:06.833: ISDN Se0/0:15 CC: CCPRI_Go: dispatching event 0x94 call id 0x8002 cref 0x0 Source->L3
Jul  3 14:49:06.833: ISDN Se0/0:15 CC: CCPQSIG_CallRoutingOut: cref 0x0 event 0x94
Jul  3 14:49:06.833: ISDN Se0/0:15 EVENT: process_rxstate: ces/callid 1/0x8002 calltype 2 CALL_ALERT
Jul  3 14:49:06.837: ISDN Se0/0:15 PACKET: process_rxstate:  0x6200018002062701001E01081E028188
Jul  3 14:49:06.837: 1E028188
Jul  3 14:49:06.837: ISDN  CDAPI: cdapi_find_tsm found a GTD message ACM,

PRN,isdn*,,QSIG*,



:
end of gtd length is 27
Jul  3 14:49:11.713: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
Jul  3 14:49:11.713: ISDN Se0/0:15 Q921f: User RX <- 0x0201547608028088071C2591AA068001
Jul  3 14:49:11.717: 008201008B0100A11702014206042B0C
Jul  3 14:49:11.717: 0902800CCCE0F2E2E5E5E220C8EBFCFF
Jul  3 14:49:11.717: 4C0489343034
Jul  3 14:49:11.717: ISDN Se0/0:15 Q921: User RX <- INFO sapi=0 tei=0, ns=42 nr=59
Jul  3 14:49:11.717: ISDN Se0/0:15 Q931: CONNECT pd = 8  callref = 0x8088 
Facility i = 0x91AA068001008201008B0100A11702014206042B0C0902800CCCE0F2E2E5E5E220C8EBFCFF 
Connected Number i = 0x89, '404'
Jul  3 14:49:11.717: ISDN Se0/0:15 Q921d: process_rxdata: Frame sent to L2
Jul  3 14:49:11.721: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x200), state (0x0),
dest_id (0x20A), event (0x141), 
sapi (0x0), ces (0x0), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x82474170), msecs (0x0), priority (0xA)
Jul  3 14:49:11.721: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531B898), size (54) 0x0201547608028088071C2591AA068001
Jul  3 14:49:11.721: 008201008B0100A11702014206042B0C
Jul  3 14:49:11.721: 0902800CCCE0F2E2E5E5E220C8EBFCFF
Jul  3 14:49:11.721: 4C0489343034
Jul  3 14:49:11.721: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052
 datastart = 531B888, netstart = 531B898, datasize = 70
Jul  3 14:49:11.721: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531B888), size (70) 0x826D936C000002000141045F00000100
Jul  3 14:49:11.725: 0201547608028088071C2591AA068001
Jul  3 14:49:11.725: 008201008B0100A11702014206042B0C
Jul  3 14:49:11.725: 0902800CCCE0F2E2E5E5E220C8EBFCFF
Jul  3 14:49:11.725: 4C0489343034
Jul  3 14:49:11.725: ISDN  Q921d: isdn_from_driver_process: event_count 1
Jul  3 14:49:11.725: ISDN Se0/0:15 PACKET: handle_mail: received:
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052, datasize = 70
Jul  3 14:49:11.725: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531B898), size (70) 0x0201547608028088071C2591AA068001
Jul  3 14:49:11.725: 008201008B0100A11702014206042B0C
Jul  3 14:49:11.729: 0902800CCCE0F2E2E5E5E220C8EBFCFF
Jul  3 14:49:11.729: 4C048934303495029EED1E01CDF89502
Jul  3 14:49:11.729: 9F22DE86CDF8
Jul  3 14:49:11.729: ISDN Se0/0:15 PACKET: handle_mail: received data =  0x0201547608028088071C2591AA068001
Jul  3 14:49:11.729: 008201008B0100A11702014206042B0C
Jul  3 14:49:11.729: 0902800CCCE0F2E2E5E5E220C8EBFCFF
Jul  3 14:49:11.729: 4C0489343034
Jul  3 14:49:11.729: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x300), event (0x241), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x1), pkt (0x82721508), msecs (0x0), priority (0xA)
Jul  3 14:49:11.733: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x300), event (0x241), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x8221B944), msecs (0x0), priority (0xA)
Jul  3 14:49:11.733: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519A098), size (50) 0x08028088071C2591AA06800100820100
Jul  3 14:49:11.733: 8B0100A11702014206042B0C0902800C
Jul  3 14:49:11.733: CCE0F2E2E5E5E220C8EBFCFF4C048934
Jul  3 14:49:11.733: 3034
Jul  3 14:49:11.737: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 241
priv_ln = 4, int_id = -2106748052
 datastart = 519A088, netstart = 519A098, datasize = 66
Jul  3 14:49:11.737: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519A088), size (66) 0x826D936C0000020A0241044B00010100
Jul  3 14:49:11.737: 08028088071C2591AA06800100820100
Jul  3 14:49:11.737: 8B0100A11702014206042B0C0902800C
Jul  3 14:49:11.737: CCE0F2E2E5E5E220C8EBFCFF4C048934
Jul  3 14:49:11.737: 3034
Jul  3 14:49:11.737: ISDN Se0/0:15 Q921d: srl_send_l2_pak:
source_id = Q.921, dest_id = Q.931, prim = DL_DATA_IND
priv_len = 4 int_id = 0x826D936C datasize = 66
Jul  3 14:49:11.737: ISDN Se0/0:15 Q921d: data =  0x826D936C000000000241044B00010100
Jul  3 14:49:11.737: 08028088071C2591AA06800100820100
Jul  3 14:49:11.737: 8B0100A11702014206042B0C0902800C
Jul  3 14:49:11.741: CCE0F2E2E5E5E220C8EBFCFF4C048934
Jul  3 14:49:11.741: 3034
Jul  3 14:49:11.741: ISDN Se0/0:15 BACKHAUL: L3IF_rx_L2_pak: received data 0x08028088071C2591AA06800100820100
Jul  3 14:49:11.741: 8B0100A11702014206042B0C0902800C
Jul  3 14:49:11.741: CCE0F2E2E5E5E220C8EBFCFF4C048934
Jul  3 14:49:11.741: 3034
Jul  3 14:49:11.741: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82386B58), ticks (10000), event (0x1250)
Jul  3 14:49:11.745: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x1), pkt (0x82689954), msecs (0x0), priority (0xA)
Jul  3 14:49:11.745: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x8221C238), msecs (0x0), priority (0xA)
Jul  3 14:49:11.745: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519A458), size (4) 0x02010156
Jul  3 14:49:11.745: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052
 datastart = 519A448, netstart = 519A458, datasize = 20
Jul  3 14:49:11.749: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519A448), size (20) 0x826D936C0000020A0140044B00010100
Jul  3 14:49:11.749: 02010156
Jul  3 14:49:11.749: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
Jul  3 14:49:11.749: ISDN Se0/0:15 PACKET: handle_l2d_srq_mail: received:
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052, datasize = 4
line_status[0] = IF_ACTIVE
Jul  3 14:49:11.749: ISDN Se0/0:15 Q921f: User TX -> 0x02010156
Jul  3 14:49:11.753: ISDN Se0/0:15 Q921: User TX -> RR sapi=0 tei=0 nr=43
Jul  3 14:49:11.753: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
Jul  3 14:49:11.753: ISDN Se0/0:15 Q931d: L3_Go: source = 0x20A, event = 0x241, call id = 0x0, int id = 0x0
Jul  3 14:49:11.753: ISDN Se0/0:15 Q931d: L3_Go: call_id 0x8002 cr 0x88 state 4 event 0x7 ces 1
Jul  3 14:49:11.753: ISDN Se0/0:15 Q931d: L3_ProcessEvent: callref = 0x0088 CONNECT:U4_Connect(nlcb)
Jul  3 14:49:11.757: ISDN Se0/0:15 Q931d: L3_TxSarMsg: InfoLen = 5 octets 0x080200880F
Jul  3 14:49:11.757: ISDN Se0/0:15 Q931d: srl_send_l3_pak:
source_id = Q.931, dest_id = Q.921, prim = DL_DATA_REQ
priv_len = 4 int_id = 0x826D936C datasize = 21
Jul  3 14:49:11.757: ISDN Se0/0:15 Q931d: data =  0x826D936C000003000240044B00010800
Jul  3 14:49:11.757: 080200880F
Jul  3 14:49:11.757: ISDN Se0/0:15 Q931d: L3_state_change: callref 0x0088 old CALL_DELIVERED, new CALL_ACTIVE
Jul  3 14:49:11.761: ISDN Se0/0:15 CC: CCPRI_Go: source id 0x300, call id 0x8002, event 0x341 (pre-ccb recovery)
Jul  3 14:49:11.761: ISDN Se0/0:15 CC: CCPRI_Go: dispatching event 0x91 call id 0x8002 cref 0x0 Source->L3
Jul  3 14:49:11.761: ISDN Se0/0:15 CC: CCPQSIG_CallRoutingOut: cref 0x0 event 0x91
Jul  3 14:49:11.761: ISDN Se0/0:15 EVENT: process_rxstate: ces/callid 1/0x8002 calltype 2 CALL_CONNECT
Jul  3 14:49:11.761: ISDN Se0/0:15 PACKET: process_rxstate:  0x6000018002001C2591AA068001008201
Jul  3 14:49:11.761: 008B0100A11702014206042B0C090280
Jul  3 14:49:11.765: 0CCCE0F2E2E5E5E220C8EBFCFF4C0489
Jul  3 14:49:11.765: 343034
Jul  3 14:49:11.765: ISDN  CDAPI: cdapi_find_tsm found a GTD message ANM,

PRN,isdn*,,QSIG*,



:
end of gtd length is 27
Jul  3 14:49:11.765: ISDN Se0/0:15 CDAPI: cdapi_mib_update msg CALL_CONNECT, remote number 404, connected? No
Jul  3 14:49:11.769: ISDN Se0/0:15 EVENTd: calltrkr_call_connect: isdn_info=0x82A8C224, call_id=0x8002
Jul  3 14:49:11.769: ISDN Se0/0:15 PACKET: handle_mail: received:
dest_id = 0, source_id = 768, prim = 240
priv_ln = 4, int_id = -2106748052, datasize = 21
Jul  3 14:49:11.769: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(519B498), size (21) 0x080200880F6F40007E06F5A8C0A8003D
Jul  3 14:49:11.769: C0A80C2812
Jul  3 14:49:11.769: ISDN Se0/0:15 PACKET: handle_mail: received data =  0x080200880F
Jul  3 14:49:11.773: ISDN Se0/0:15 PACKET: L2IF_SendprimPkt:
source_id (0x20A), state (0x7), dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1),  call_id (0x0), 
chan_id (0x0), int_id (0x826D936C), free_p (0x0), pkt (0x826899D8), msecs (0x0), priority (0xA)
Jul  3 14:49:11.773: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x20A), state (0x7),
dest_id (0x200), event (0x140), 
sapi (0x0), ces (0x1), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x0),
pkt (0x8247742C), msecs (0x0), priority (0xA)
Jul  3 14:49:11.773: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531CDD8), size (9) 0x00017656080200880F
Jul  3 14:49:11.777: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052
 datastart = 531CDC8, netstart = 531CDD8, datasize = 25
Jul  3 14:49:11.777: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531CDC8), size (25) 0x826D936C0000020A0140044B00010100
Jul  3 14:49:11.777: 00017656080200880F
Jul  3 14:49:11.777: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82A50204), ticks (1000), event (0x1240)
Jul  3 14:49:11.777: ISDN  Q921d: isdn_l2d_srq_process: QUEUE_EVENT
Jul  3 14:49:11.777: ISDN Se0/0:15 PACKET: handle_l2d_srq_mail: received:
dest_id = 0, source_id = 522, prim = 140
priv_ln = 4, int_id = -2106748052, datasize = 9
line_status[0] = IF_ACTIVE
Jul  3 14:49:11.781: ISDN Se0/0:15 Q921f: User TX -> 0x00017656080200880F
Jul  3 14:49:11.781: ISDN Se0/0:15 Q921: User TX -> INFO sapi=0 tei=0, ns=59 nr=43
Jul  3 14:49:11.781: ISDN Se0/0:15 Q931: CONNECT_ACK pd = 8  callref = 0x0088
Jul  3 14:49:11.781: ISDN  Q921d: isdn_l2d_srq_process: event_count 1
Jul  3 14:49:11.793: ISDN  Q921d: isdn_from_driver_process: QUEUE_EVENT
Jul  3 14:49:11.793: ISDN Se0/0:15 Q921f: User RX <- 0x00010178
Jul  3 14:49:11.793: ISDN Se0/0:15 Q921: User RX <- RR sapi=0 tei=0 nr=60
Jul  3 14:49:11.793: ISDN Se0/0:15 Q921d: process_rxdata: Frame sent to L2
Jul  3 14:49:11.797: ISDN Se0/0:15 PACKET: L2IF_SendPkt:
source_id (0x200), state (0x0),
dest_id (0x20A), event (0x141), 
sapi (0x0), ces (0x0), call_id (0x0),
chan_id (0x0), int_id (0x826D936C), free_pkt (0x1),
pkt (0x82475950), msecs (0x0), priority (0xA)
Jul  3 14:49:11.797: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531C298), size (4) 0x00010178
Jul  3 14:49:11.797: ISDN Se0/0:15 PACKET: L2IF_SendPkt: Sending from L2
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052
 datastart = 531C288, netstart = 531C298, datasize = 20
Jul  3 14:49:11.797: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531C288), size (20) 0x826D936C000002000141044B00000100
Jul  3 14:49:11.797: 00010178
Jul  3 14:49:11.797: ISDN  Q921d: isdn_from_driver_process: event_count 1
Jul  3 14:49:11.801: ISDN Se0/0:15 PACKET: handle_mail: received:
dest_id = 0, source_id = 512, prim = 141
priv_ln = 4, int_id = -2106748052, datasize = 20
Jul  3 14:49:11.801: ISDN Se0/0:15 PACKET: L2IF_DispPak: packet - start(531C298), size (20) 0x000101780000000000000000C0A8003D
Jul  3 14:49:11.801: C0A80C28
Jul  3 14:49:11.801: ISDN Se0/0:15 PACKET: handle_mail: received data =  0x00010178
Jul  3 14:49:11.805: ISDN Se0/0:15 LIFd: LIF_StartTimer: timer (0x82386B58), ticks (10000), event (0x1250)
sneak#undebug all
All possible debugging has been turned off

 

Дебаг isdn, только q931:

debug isdn q931 isON.
sneak#
Jul  3 14:39:59.557: ISDN Se0/0:15 Q931: TX -> SETUP pd = 8  callref = 0x0087 
Sending Complete 
Bearer Capability i = 0x8090A3 
Standard = CCITT 
Transfer Capability = Speech  
Transfer Mode = Circuit 
Transfer Rate = 64 kbit/s 
Channel ID i = 0xA9839F 
Exclusive, Channel 31 
Calling Party Number i = 0x0080, '701' 
Plan:Unknown, Type:Unknown 
Called Party Number i = 0x80, '404' 
Plan:Unknown, Type:Unknown
Jul  3 14:39:59.677: ISDN Se0/0:15 Q931: RX <- CALL_PROC pd = 8  callref = 0x8087 
Channel ID i = 0xA9839F 
Exclusive, Channel 31
Jul  3 14:39:59.693: ISDN Se0/0:15 Q931: RX <- ALERTING pd = 8  callref = 0x8087 
Progress Ind i = 0x8188 - In-band info or appropriate now available  
Progress Ind i = 0x8188 - In-band info or appropriate now available 
Jul  3 14:40:02.877: ISDN Se0/0:15 Q931: RX <- CONNECT pd = 8  callref = 0x8087 
Facility i = 0x91AA068001008201008B0100A11702014206042B0C0902800CCCE0F2E2E5E5E220C8EBFCFF 
Connected Number i = 0x89, '404'
Jul  3 14:40:02.885: ISDN Se0/0:15 Q931: TX -> CONNECT_ACK pd = 8  callref = 0x0087
sneak#undebug all
All possible debugging has been turned off
sneak#

 

И еще один момент мне непонятен. Во время разговора (сеанс связи установлен), даю команды "show voice dsp" и "show voice port summary":

DSP  DSP             DSPWARE CURR  BOOT                         PAK     TX/RX
TYPE NUM CH CODEC    VERSION STATE STATE   RST AI VOICEPORT TS ABORT  PACK COUNT
==== === == ======== ======= ===== ======= === == ========= == ===== ============

Active Voice Call details

C549 000 00 g711alaw  4.1.41 Busy  Idle      0  0 0/0        1

Current total analog signalling channels: 0
Current max allowed digital timeslot for voice: 36
Current number of DSP group: 1
Group 0:
Current allocated analog signalling channels: 0
Current free analog signalling channels: 0
Current allocated digital signalling channels: 30
Current free digital signalling channels: 6
Port(s) served: 0/0
Current Available MIPS: 584
SPMM  DSPRM  State   Image     D-sig  D-sig     A-sig  A-sig  Mips Voice/Xcode
 Dsp   Dsp                  allocate   free  allocate   free  Free     Chan
 0/0     0      UP   FLEX6         6      0         0      0    84        1
 0/1     1      UP   FLEX6         6      0         0      0   100        0
 0/2     2      UP   FLEX6         6      0         0      0   100        0
 1/0     3      UP   FLEX6         6      0         0      0   100        0
 1/1     4      UP   FLEX6         6      0         0      0   100        0
 1/2     5      UP   FLEX6         0      6         0      0   100        0

 show voice port summary
                                    IN       OUT
PORT      CH   SIG-TYPE   ADMIN OPER STATUS   STATUS   EC
========= == ============ ===== ==== ======== ======== ==
0/0:15    01  isdn-voice  up    up   none     none     y
0/0:15    02  isdn-voice  up    dorm none     none     y
0/0:15    03  isdn-voice  up    dorm none     none     y
0/0:15    04  isdn-voice  up    dorm none     none     y
0/0:15    05  isdn-voice  up    dorm none     none     y
0/0:15    06  isdn-voice  up    dorm none     none     y
0/0:15    07  isdn-voice  up    dorm none     none     y
0/0:15    08  isdn-voice  up    dorm none     none     y
0/0:15    09  isdn-voice  up    dorm none     none     y
0/0:15    10  isdn-voice  up    dorm none     none     y
0/0:15    11  isdn-voice  up    dorm none     none     y
0/0:15    12  isdn-voice  up    dorm none     none     y
0/0:15    13  isdn-voice  up    dorm none     none     y
0/0:15    14  isdn-voice  up    dorm none     none     y
0/0:15    15  isdn-voice  up    dorm none     none     y
0/0:15    17  isdn-voice  up    dorm none     none     y
0/0:15    18  isdn-voice  up    dorm none     none     y
0/0:15    19  isdn-voice  up    dorm none     none     y
0/0:15    20  isdn-voice  up    dorm none     none     y
0/0:15    21  isdn-voice  up    dorm none     none     y
0/0:15    22  isdn-voice  up    dorm none     none     y
0/0:15    23  isdn-voice  up    dorm none     none     y
0/0:15    24  isdn-voice  up    dorm none     none     y
0/0:15    25  isdn-voice  up    dorm none     none     y
0/0:15    26  isdn-voice  up    dorm none     none     y
0/0:15    27  isdn-voice  up    dorm none     none     y
0/0:15    28  isdn-voice  up    dorm none     none     y
0/0:15    29  isdn-voice  up    dorm none     none     y
0/0:15    30  isdn-voice  up    dorm none     none     y
0/0:15    31  isdn-voice  up    dorm none     none     y

 

Меня сильно смущает вот это:

Current max allowed digital timeslot for voice: 36
0/0:15    01  isdn-voice  up    up   none     none     y

 

Как было макисмальное свободное количество тайм-слотов для голоса, так и осталось 36. То есть выходит дело, что он DSP-процессора под установленный линк так и не "отъел". И "Status IN", "Status out" для этого канала выставлено в "none". По-моему, так быть не должно. Или я не прав?

 

Kristoff_Vampire

 

Будьте так добры, скинтье пожалуйста конфиг. Можно в личку, можно на klinkov@ya.ru . Посмотрю как у Вас сделано.

 

Share this post


Link to post
Share on other sites

Странно еще и то, что во время разговора команда "show sip-ua calls" выдает вполне осмысленный результат:

sneak#show sip-ua calls
SIP UAC CALL INFO

  Number of SIP User Agent Client(UAC) calls: 0

SIP UAS CALL INFO

Call 1
SIP Call ID                : 3b0e2e1473c42860599fc93d67a9486f@192.168.12.40
  State of the call       : STATE_ACTIVE (6)
  Substate of the call    : SUBSTATE_NONE (0)
  Calling Number          : 701
  Called Number           : 404
  Bit Flags               : 0x1012003A 0x100000
  Source IP Address (Sig ): 192.168.12.40
  Destn SIP Req Addr:Port : 111.222.111.222:5060
  Destn SIP Resp Addr:Port: 111.222.111.222:5060
  Destination Name        : 111.222.111.222
  Number of Media Streams : 1
  Number of Active Streams: 1
  RTP Fork Object         : 0x0
  Media Stream 1
    State of the stream      : STREAM_ACTIVE
    Stream Call ID           : 133
    Stream Type              : voice+dtmf (1)
    Negotiated Codec         : g711alaw (160 bytes)
    Codec Payload Type       : 8
    Negotiated Dtmf-relay    : rtp-nte
    Dtmf-relay Payload Type  : 101
    Media Source IP Addr:Port: 192.168.12.40:18694
    Media Dest IP Addr:Port  : 111.222.111.222:17918
    Orig Media Dest IP Addr:Port : 0.0.0.0:0

  Number of SIP User Agent Server(UAS) calls: 1

 

То есть она якобы шлет RTP. А в реале - тишина.

Share this post


Link to post
Share on other sites

SuSt

Циска имеет "серый" адрес, а * - "белый" судя по замаскированному адресу "111.222.111.222" ?

Поэтому вопрос по схеме сети - как выглядит цепочка от * к циске? есть NAT?

Т.к. RTP не "любит" NAT...

Share this post


Link to post
Share on other sites

Puno

 

У меня никогда не было никакого NAT-а. Но тем не менее, ради интереса я попробовал дать Asterisk-у адрес из той же самой подсети, что и циска. Ничего не изменилось.

Share this post


Link to post
Share on other sites

Все, решил проблему.

 

Оказалось, для этого надо было включить на циске маршрутизацию ("ip routing"). Совершенно непонятно с какого бодуна ей это потребовалось. Тем не менее, после этого все заработало.

 

Всем спасибо за советы, подсказки и отзывчивость!

Share this post


Link to post
Share on other sites

ну SuSt ты даёш. :) У меня было такое подозренее но, не думал что ты не проверил доступность одной точки от другой :)

Share this post


Link to post
Share on other sites

Что значит "проверил доступность"? Они и с выключенной маршрутизацией друг друга прекрасно пинговали. Хуже того, я саму циску с астериска по телнету администрировал.

Share this post


Link to post
Share on other sites

По конфигу всё становится ясно. Так как ты использовал не прямой айпи сипсервера а ДНС имя то Киска просто не могла достучаться до ДНС сервера (он же в другой сети). Соответственно она не знала айпи сипсервера. Весь прикол. Если я не ошибаюсь в дебаге сипа всё видно по поводу destIP. Если у тебя астериск и циска находились бы в одной сети и никакого ДНС а по АЙПИ и в астериске canreinvite=no то всё бы работало :)

Share this post


Link to post
Share on other sites

Она ресолвила DNS-имя. Я проверял. И в одну подсеть их с астериском "засунуть" тоже пробовал. И вместо DNS-имени подставлял IP-адрес. Ничего из этого результата не принесло.

 

И потом, сигнализация-то всегдя проходила нормально, при звонках в обеих направлениях.

Share this post


Link to post
Share on other sites

тогда странно. Просто я всегда ip routing включаю. Раз когда то давно на эти грабли наступил. Только я сразу просёк что киска другие подсети не видит.

 

Share this post


Link to post
Share on other sites

такая фигня действительно иногда бывает на некоторых железяках, без ip routing проблемы с голосом, вообще довольно давно уже.

Share this post


Link to post
Share on other sites

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

Share this post


Link to post
Share on other sites

Столкнулся с аналогичной проблемой: есть циска 2620 и модуль VWIC-2MFT-E1 в который приходит поток со станции. Сигнализация проходит и соединение устанавливается, но голоса не слышно. tcpdump на астериске показывает что пакеты идут только от астериска на циску. Подскажите куда копать, ip routing включен. Циска 214.37, астериск 214.33 (pbx.xxxx.ru)

 

sh run:

!

version 12.3

service nagle

no service pad

service timestamps debug datetime msec

service timestamps log datetime msec

no service password-encryption

service sequence-numbers

!

hostname cs-s1

!

boot-start-marker

boot-end-marker

!

logging buffered 16384 debugging

enable password pwd

!

clock timezone Europe/Moscow 4

clock summer-time Europe/Moscow date Mar 30 2003 3:00 Oct 26 2003 4:00

network-clock-participate slot 1

voice-card 1

!

no aaa new-model

ip subnet-zero

ip cef

!

!

ip domain name lansp.ru

ip name-server xx.xx.214.4

!

isdn switch-type primary-net5

isdn voice-call-failure 0

isdn gateway-max-interworking

!

!

voice call send-alert

voice rtp send-recv

!

voice service pots

!

voice service voip

fax protocol t38 ls-redundancy 0 hs-redundancy 0 fallback cisco

sip

!

voice class codec 1

codec preference 1 g711alaw

codec preference 2 g711ulaw

!

!

!

!

!

!

!

no voice hpi capture buffer

no voice hpi capture destination

!

!

!

!

controller E1 1/0

framing NO-CRC4

pri-group timeslots 1-31

!

controller E1 1/1

framing NO-CRC4

pri-group timeslots 1-31

!

!

!

interface FastEthernet0/0

ip address xx.xx.214.37 255.255.255.252

speed auto

half-duplex

!

interface Serial1/0:15

no ip address

no logging event link-status

isdn switch-type primary-net5

isdn incoming-voice voice

isdn send-alerting

isdn sending-complete

no cdp enable

!

interface Serial1/1:15

no ip address

no logging event link-status

isdn switch-type primary-net5

isdn incoming-voice voice

no cdp enable

!

ip http server

ip classless

ip route 0.0.0.0 0.0.0.0 xx.xx.214.38

!

!

logging trap debugging

logging facility syslog

logging xx.xx.128.12

!

!

voice-port 1/0:15

disc_pi_off

cptone RU

bearer-cap Speech

!

voice-port 1/1:15

!

!

!

!

!

dial-peer voice 3 pots

destination-pattern 0.T

port 1/0:15

!

dial-peer voice 4 pots

max-conn 60

incoming called-number 5522...

direct-inward-dial

!

dial-peer voice 5 voip

destination-pattern 5522...

voice-class codec 1

session protocol sipv2

session target dns:pbx.xxxxx.ru

dtmf-relay rtp-nte

no vad

!

dial-peer voice 6 voip

incoming called-number 0T

destination-pattern 0T

voice-class codec 1

session protocol sipv2

session target dns:pbx.xxxxx.ru

dtmf-relay rtp-nte

no vad

!

!

line con 0

line aux 0

line vty 0 4

login

!

scheduler allocate 4000 1000

scheduler interval 500

ntp clock-period 17179923

ntp server xx.xx.212.2

!

!

end

sh ver:

Cisco Internetwork Operating System Software

IOS C2600 Software (C2600-IS-M), Version 12.3(3a), RELEASE SOFTWARE (fc2)

Copyright © 1986-2003 by cisco Systems, Inc.

Compiled Wed 15-Oct-03 06:38 by dchih

Image text-base: 0x80008098, data-base: 0x819E4D14

 

ROM: System Bootstrap, Version 12.2(7r) [cmong 7r], RELEASE SOFTWARE (fc1)

 

cs-s1 uptime is 1 day, 2 hours, 32 minutes

System returned to ROM by reload

System restarted at 13:19:12 Europe/Moscow Wed Aug 26 2009

System image file is "flash:c2600-is-mz.123-3a.bin"

 

cisco 2620XM (MPC860P) processor (revision 0x100) with 78848K/19456K bytes of memory.

Processor board ID FCZ100672KZ (4122772323)

M860 processor: part number 5, mask 2

Channelized E1, Version 1.0.

Bridging software.

X.25 software, Version 3.0.0.

Primary Rate ISDN software, Version 1.1.

1 FastEthernet/IEEE 802.3 interface(s)

62 Serial network interface(s)

2 Channelized E1/PRI port(s)

32K bytes of non-volatile configuration memory.

32768K bytes of processor board System flash (Read/Write)

 

Configuration register is 0x2102

sh diag:

Slot 0:

C2621XM 1FE Mainboard Port adapter, 1 port

Port adapter is analyzed

Port adapter insertion time unknown

EEPROM contents at hardware discovery:

Hardware Revision : 1.0

PCB Serial Number : FCZ100672KZ (4122772323)

Part Number : 73-7753-02

RMA History : 00

RMA Number : 0-0-0-0

Board Revision : D0

Deviation Number : 0-0

EEPROM format version 4

EEPROM contents (hex):

0x00: 04 FF 40 03 6C 41 01 00 C1 18 46 43 5A 31 30 30

0x10: 36 37 32 4B 5A 20 28 34 31 32 32 37 37 32 33 32

0x20: 33 29 82 49 1E 49 02 04 00 81 00 00 00 00 42 44

0x30: 30 80 00 00 00 00 FF FF FF FF FF FF FF FF FF FF

0x40: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

0x50: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

0x60: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

0x70: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

 

Slot 1:

High Density Voice Port adapter

Port adapter is analyzed

Port adapter insertion time unknown

EEPROM contents at hardware discovery:

Hardware Revision : 1.1

Top Assy. Part Number : 800-03567-01

Board Revision : F1

Deviation Number : 0-0

Fab Version : 02

PCB Serial Number : JAB05340HFV

RMA Test History : 00

RMA Number : 0-0-0-0

RMA History : 00

EEPROM format version 4

EEPROM contents (hex):

0x00: 04 FF 40 00 CC 41 01 01 C0 46 03 20 00 0D EF 01

0x10: 42 46 31 80 00 00 00 00 02 02 C1 8B 4A 41 42 30

0x20: 35 33 34 30 48 46 56 03 00 81 00 00 00 00 04 00

0x30: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

0x40: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

0x50: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

0x60: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

0x70: FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF

 

VIC Slot 0:

E1 (2 Port) Multi-Flex Trunk WAN Daughter Card

Hardware revision 1.0 Board revision B0

Serial number 35451006 Part number 800-04479-03

Test history 0x0 RMA number 00-00-00

 

Connector type PCI

EEPROM format version 1

EEPROM contents (hex):

0x20: 01 23 01 00 02 1C F0 7E 50 11 7F 03 00 00 00 00

0x30: 58 00 00 00 02 09 13 00 FF FF FF FF FF FF FF FF

 

HDV firmware: Compiled Tue 10-Sep-02 19:12 by richc

HDV memory size 524280 heap free 142049

sh sip-ua calls во время звонка c астериска в город:

SIP UAC CALL INFO

 

Number of UAC calls: 0

 

SIP UAS CALL INFO

 

Number of UAS calls: 1

 

Call 1

SIP Call ID : 3049072764f8d93a3b0d964374779ba8@xx.xx.214.33

State of the call : STATE_ACTIVE (6)

Substate of the call : SUBSTATE_NONE (0)

Calling Number : 5522032

Called Number : 5455042

Bit Flags : 0x1012003A 0x100000

Source IP Address (Sig ): xx.xx.214.37

Destn SIP Req Addr:Port : xx.xx.214.33:5060

Destn SIP Resp Addr:Port: xx.xx.214.33:5060

Destination Name : xx.xx.214.33

Number of Media Streams : 1

Number of Active Streams: 1

RTP Fork Object : 0x0

Media Stream 1

State of the stream : STREAM_ACTIVE

Stream Call ID : 83

Stream Type : voice+dtmf (1)

Negotiated Codec : g711ulaw (160 bytes)

Codec Payload Type : 0

Negotiated Dtmf-relay : rtp-nte

Dtmf-relay Payload Type : 101

Media Source IP Addr:Port: xx.xx.214.37:16856

Media Dest IP Addr:Port : xx.xx.214.33:10522

Orig Media Dest IP Addr:Port : 0.0.0.0:0

 

SIP UAS CALL INFO

 

Number of UAS calls: 1

Edited by a0d75

Share this post


Link to post
Share on other sites

sh call hi vo br

после или

sh call act vo br

во время

что там с tx?

BTW ip там именно не от сигнализации, а от RTP

 

debug ccsip all

глазами очень внимательно посмотреть, может поможет.

 

PS не победите, положите

debug ccsip messages сюда, или если что в личку, я посмотрю.

PPS. Если в личку то и с астериска

sip set debug on не плохо бы увидеть для полноты картины.

 

 

 

 

 

Share this post


Link to post
Share on other sites

Спасибо за ответ, чувствую что близок к пониманию в чем проблема, но знаний не хватает :(

deb ccsip all внимательно изучил, ничего не вызвало подозрений

 

sh call act vo br во время разговора показывает что от астериска только rx, а в e1 только tx идет:

 

Telephony call-legs: 1
SIP call-legs: 1
H323 call-legs: 0
MGCP call-legs: 0
Multicast call-legs: 0
Total call-legs: 2
1283 : 32457056hs.1 +278 pid:1 Answer 5470634 active
dur 00:00:04 tx:208/33280 rx:0/0
Tele 1/0:15:93: tx:0/0/0ms g711alaw noise:0 acom:0 i/0:0/0 dBm
1283 : 32457057hs.1 +277 pid:2 Originate 5522032 active
dur 00:00:04 tx:0/0 rx:208/33280
IP 10.193.214.33:14698 rtt:0ms pl:0/0ms lost:0/0/0 delay:0/0/0ms g711alaw

 

а в sh dial-peer voice 2 и 3 почему то выдает codec = g729r8:

 

VoiceOverIpPeer2       
        peer type = voice, information type = voice,
        description = `101 from E1 to SIP',         
        tag = 2, destination-pattern = `5522032',   
        answer-address = `', preference=0,          
        CLID Restriction = None                     
        CLID Network Number = `'                    
        CLID Second Number sent                     
        source carrier-id = `', target carrier-id = `',
        source trunk-group-label = `',  target trunk-group-label = `',
        numbering Type = `unknown'                                    
        group = 2, Admin state is up, Operation state is up,          
        incoming called-number = `', connections/maximum = 0/unlimited,
        DTMF Relay = enabled,                                          
        modem transport = system,                                      
        huntstop = enabled,                                            
        in bound application associated: 'DEFAULT'                     
        out bound application associated: ''
        dnis-map =
        permission :both
        incoming COR list:maximum capability
        outgoing COR list:minimum requirement
        Translation profile (Incoming):
        Translation profile (Outgoing):
        incoming call blocking:
        translation-profile = `'
        disconnect-cause = `no-service'
        advertise 0x40 capacity_update_timer 25 addrFamily 4 oldAddrFamily 4
        type = voip, session-target = `dns:pbx.xxxxx.ru',
        technology prefix:
        settle-call = disabled
        ip media DSCP = ef, ip signaling DSCP = af31, UDP checksum = disabled,
        session-protocol = sipv2, session-transport = system, req-qos = best-effort,
        acc-qos = best-effort,
        dtmf-relay = rtp-nte,
        RTP dynamic payload type values: NTE = 101
        Cisco: NSE=100, fax=96, fax-ack=97, dtmf=121, fax-relay=122
               CAS=123, ClearChan=125, PCM switch over u-law=0,A-law=8
        RTP comfort noise payload type = 19
        fax rate = voice,   payload size =  20 bytes
        fax protocol = system
        fax-relay ecm enable
        fax NSF = 0xAD0051 (default)
        codec = g729r8,   payload size =  20 bytes,
        Media Setting = flow-through (global)
        Expect factor = 10, Icpif = 20,
        Playout Mode is set to adaptive,
        Initial 60 ms, Max 300 ms
        Playout-delay Minimum mode is set to default, value 40 ms
        Fax nominal 300 ms
        Max Redirects = 1, signaling-type = cas,
        VAD = disabled, Poor QOV Trap = disabled,
        Source Interface = NONE
        voice class sip url = system,
        voice class sip rel1xx = system,
        voice class perm tag = `'
        Time elapsed since last clearing of voice call statistics never
        Connect Time = 27758, Charged Units = 0,
        Successful Calls = 23, Failed Calls = 0, Incomplete Calls = 0
        Accepted Calls = 0, Refused Calls = 0,
        Last Disconnect Cause is "10  ",
        Last Disconnect Text is "normal call clearing (16)",
        Last Setup Time = 32509043.

 

хотя в конфиге прописано alaw:

 

sh run:
..
voice class codec 1
codec preference 1 g711alaw
..
dial-peer voice 2 voip                                                
description 101 from E1 to SIP                                       
huntstop                                                             
destination-pattern 5522032                                          
voice-class codec 1
session protocol sipv2
session target dns:pbx.xxxxx.ru
dtmf-relay rtp-nte
no vad
!
dial-peer voice 3 voip
description ALL from SIP to E1
incoming called-number .
voice-class codec 1
session protocol sipv2
session target dns:pbx.xxxxx.ru
dtmf-relay rtp-nte
no vad
!

Share this post


Link to post
Share on other sites

Create an account or sign in to comment

You need to be a member in order to leave a comment

Create an account

Sign up for a new account in our community. It's easy!

Register a new account

Sign in

Already have an account? Sign in here.

Sign In Now
Sign in to follow this