The OpenNET Project / Index page

[ новости /+++ | форум | теги | ]

форумы  помощь  поиск  регистрация  майллист  вход/выход  слежка  RSS
"VoIP Cisco, коллеги подскажите куда смотреть"
Вариант для распечатки  
Пред. тема | След. тема 
Форум Маршрутизаторы CISCO и др. оборудование. (VoIP)
Изначальное сообщение [ Отслеживать ]

"VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 15:25 
Доброго времени суток!
Сразу к делу. Схема:
sip шлюз - cisco 3825 - ip - cisco 2801 - pri АТС
Есть сеть филиалов с похожей схемой - все в норме, звонки ходят. Маршрутизаторы huawei ar2220 и cisco 2600 с aim-voice платами.
Есть Cisco 3825,  к ней подключен sip шлюз с телефоном (для тестов), до всех точек с него дозваниваюсь. Добавили точку и поставили cisco 2801 с платой pvdm2-16 и тут ступор, не могу поднять телефонию до нее. Каким то образом самый самый первый звонок от 3825 до 2801 по h323 прошел(как только плату купил,воткнул, смонтировали, подключили, позвонил - норм), решил попробовать на sip и все...ничего не звонит не по sip не по h323. Откатывал конфиги, уже все, не звонит.
Дело скорее всего в кодеках, игрался с ними(поэтому так много voice clas codec) не могу понять только почему и где, т.к. имею очень мала опыта и знаний=(
В самом конце дебаги будут.
Дебаги все выложу ниже.

Конфиги:
1)sh run 2801:
Building configuration...

Current configuration : 2124 bytes
!
version 12.4
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname Router
!
boot-start-marker
boot system flash c2801-ipvoicek9-mz.124-5b.bin
boot-end-marker
!
no aaa new-model
!
resource policy
!
network-clock-participate wic 2
mmi polling-interval 60
no mmi auto-configure
no mmi pvc
mmi snmp-timeout 180
ip cef
!
isdn switch-type primary-net5
!
voice-card 0
dsp services dspfarm
!
voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
h323
sip
!
voice class codec 100
codec preference 1 g711alaw
codec preference 2 g711ulaw
!
voice class codec 4
codec preference 1 g729br8
codec preference 2 g729r8 bytes 80
codec preference 3 g723r63 bytes 48
codec preference 4 g723r53 bytes 40
codec preference 5 g728
codec preference 6 g711alaw
codec preference 7 g711ulaw
!
voice class codec 10
codec preference 1 g729r8 bytes 160
codec preference 2 g729br8 bytes 160
codec preference 3 g711alaw
!
voice class codec 12
codec preference 1 g728
!
controller E1 0/2/0
framing NO-CRC4
pri-group timeslots 1-16
!
!
!
interface FastEthernet0/0
ip address 10.34.0.233 255.255.128.0
duplex auto
speed auto
!
interface FastEthernet0/1
ip address 10.34.193.33 255.255.255.224
duplex auto
speed auto
!
interface FastEthernet0/1/0
!
interface FastEthernet0/1/1
shutdown
!
interface FastEthernet0/1/2
shutdown
!
interface FastEthernet0/1/3
shutdown
!
interface Serial0/2/0:15
no ip address
encapsulation hdlc
no logging event link-status
isdn switch-type primary-net5
isdn incoming-voice voice
no cdp enable
!
interface Vlan1
no ip address
!
ip http server
no ip http secure-server
!
disable-eadi
!
control-plane
!
voice-port 0/2/0:15
cptone RU
bearer-cap Speech
!
!
!
!
!
dial-peer voice 39 pots
destination-pattern 39..
direct-inward-dial
port 0/2/0:15
forward-digits all
!

scheduler allocate 20000 1000
end


2)sh run 3825
Building configuration...

Current configuration : 5139 bytes
!
version 12.4
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname Router
!
boot-start-marker
boot-end-marker
!
card type e1 0 1
card type command needed for slot/vwic-slot 1/0
!
no aaa new-model
network-clock-participate wic 1
!
no ip dhcp use vrf connected
!
ip cef
!
multilink bundle-name authenticated
!
isdn switch-type primary-net5
!
voice-card 0
no dspfarm
!
voice service voip
allow-connections h323 to h323
allow-connections h323 to sip
allow-connections sip to h323
allow-connections sip to sip
h323
sip
  registrar server expires max 3600 min 600
!
voice class codec 1
codec preference 1 g711alaw
codec preference 2 g711ulaw
!
voice class codec 2
codec preference 1 g729br8
!
voice class codec 3
codec preference 1 g729r8
!
voice class codec 4
codec preference 1 g729br8
codec preference 2 g729r8 bytes 80
codec preference 3 g723r63 bytes 48
codec preference 4 g723r53 bytes 40
codec preference 5 g728
codec preference 6 g711alaw
!
voice class codec 5
!
voice class codec 225
!
voice class codec 10
codec preference 1 g729r8 bytes 160
codec preference 2 g729br8 bytes 160
codec preference 3 g711alaw
!
voice register global
mode cme
source-address 10.34.0.200 port 5060
max-dn 50
max-pool 50
!
voice register dn  2
number 81342299
!
voice register dn  5
number 80342299
!
voice register pool  2
id mac 001F.CE24.018A
number 1 dn 2
voice-class codec 4
username 81342299 password 1111
!
voice register pool  5
id mac 001F.CE24.018A
number 1 dn 5
voice-class codec 1
username 80342299 password 1111
!
username 81345603
archive
log config
  hidekeys
!
!
controller E1 0/1/0
!
controller E1 0/1/1
!
!
!
!
!
interface GigabitEthernet0/0
no ip address
duplex auto
speed auto
media-type rj45
!
interface GigabitEthernet0/0.2809
encapsulation dot1Q 2809
ip address 10.34.0.200 255.255.128.0
!
interface GigabitEthernet0/1
ip address 10.34.193.1 255.255.255.224
duplex auto
speed auto
media-type rj45
!
interface FastEthernet0/0/0
!
interface FastEthernet0/0/1
!
interface FastEthernet0/0/2
!
interface FastEthernet0/0/3
!
interface Vlan1
no ip address
!
no ip http server
no ip http secure-server
!
control-plane
!
mgcp behavior g729-variants static-pt
!
dial-peer voice 2 voip
description VLG-CENTR
destination-pattern 2...
voice-class codec 1
session protocol sipv2
session target ipv4:10.34.0.1
dtmf-relay h245-alphanumeric
fax protocol t38 ls-redundancy 5 hs-redundancy 2 fallback none
no vad
!
dial-peer voice 1 voip
destination-pattern 39..
voice-class codec 1
session target ipv4:10.34.0.233
fax protocol t38 ls-redundancy 5 hs-redundancy 2 fallback none
!
sip-ua
!
!
!
line con 0
line aux 0
line vty 0 4
login
!
scheduler allocate 20000 1000

!
webvpn cef
!
end

3)sh ver 2801
Cisco IOS Software, 2801 Software (C2801-IPVOICEK9-M), Version 12.4(5b), RELEASE SOFTWARE (fc2)
Technical Support: http://www.cisco.com/techsupport
Copyright (c) 1986-2006 by Cisco Systems, Inc.
Compiled Wed 19-Apr-06 08:19 by ssearch

ROM: System Bootstrap, Version 12.3(8r)T9, RELEASE SOFTWARE (fc1)

Router uptime is 2 hours, 19 minutes
System returned to ROM by reload at 08:35:40 UTC Thu Mar 28 2013
System image file is "flash:c2801-ipvoicek9-mz.124-5b.bin"


This product contains cryptographic features and is subject to United
States and local country laws governing import, export, transfer and
use. Delivery of Cisco cryptographic products does not imply
third-party authority to import, export, distribute or use encryption.
Importers, exporters, distributors and users are responsible for
compliance with U.S. and local country laws. By using this product you
agree to comply with applicable laws and regulations. If you are unable
to comply with U.S. and local laws, return this product immediately.

A summary of U.S. laws governing Cisco cryptographic products may be found at:
http://www.cisco.com/wwl/export/crypto/tool/stqrg.html

If you require further assistance please contact us by sending email to
export@cisco.com.

Cisco 2801 (revision 6.0) with 116736K/14336K bytes of memory.
Processor board ID FCZ110872YE
6 FastEthernet interfaces
16 Serial interfaces
1 Channelized E1/PRI port
1 DSP, 16 Voice resources
DRAM configuration is 64 bits wide with parity disabled.
191K bytes of NVRAM.
62720K bytes of ATA CompactFlash (Read/Write)

Configuration register is 0x2102


4)sh ver 3825
Router#sh ver
Cisco IOS Software, 3800 Software (C3825-ADVIPSERVICESK9-M), Version 12.4(11)XW9, RELEASE SOFTWARE (fc1)
Technical Support: http://www.cisco.com/techsupport
Copyright (c) 1986-2008 by Cisco Systems, Inc.
Compiled Sat 09-Aug-08 19:49 by prod_rel_team

ROM: System Bootstrap, Version 12.4(13r)T, RELEASE SOFTWARE (fc1)

Router uptime is 2 hours, 21 minutes
System returned to ROM by reload at 08:40:29 UTC Thu Mar 28 2013
System image file is "flash:c3825-advipservicesk9-mz.124-11.XW9.bin"


This product contains cryptographic features and is subject to United
States and local country laws governing import, export, transfer and
use. Delivery of Cisco cryptographic products does not imply
third-party authority to import, export, distribute or use encryption.
Importers, exporters, distributors and users are responsible for
compliance with U.S. and local country laws. By using this product you
agree to comply with applicable laws and regulations. If you are unable
to comply with U.S. and local laws, return this product immediately.

A summary of U.S. laws governing Cisco cryptographic products may be found at:
http://www.cisco.com/wwl/export/crypto/tool/stqrg.html

If you require further assistance please contact us by sending email to
export@cisco.com.

Cisco 3825 (revision 1.1) with 222208K/39936K bytes of memory.
Processor board ID FCZ110872UZ
4 FastEthernet interfaces
2 Gigabit Ethernet interfaces
2 Channelized E1/PRI ports
2 Channelized (E1 or T1)/PRI ports
1 Virtual Private Network (VPN) Module
DRAM configuration is 64 bits wide with parity enabled.
479K bytes of NVRAM.
507024K bytes of ATA System CompactFlash (Read/Write)

Configuration register is 0x2102


5)sh inv 2801
Router#sh inv
NAME: "2801 chassis", DESCR: "2801 chassis, Hw Serial#: 1166469395, Hw Revision: 6.0"
PID: CISCO2801         , VID: V03 , SN: FCZ110872YE

NAME: "C2801 Motherboard with 2 Fast Ethernet", DESCR: "C2801 Motherboard with 2 Fast Ethernet"
PID: CISCO2801         , VID: 6.0, SN: FCZ110872YE

NAME: "WIC/VIC/HWIC 1", DESCR: "4 Port FE Switch"
PID: HWIC-4ESW         , VID: V01 , SN: FOC11223LYC

NAME: "WIC/VIC 2", DESCR: "One port E1 voice interface daughtercard with G.703"
PID: VWIC-1MFT-G703=   , VID: 1.0, SN: 24409794

NAME: "PVDMII DSP SIMM with one DSP", DESCR: "PVDMII DSP SIMM with one DSP"
PID: PVDM2-16          , VID: V01 , SN: FOC13064NHZ


Дебаги:
1)ccsip messages (voice clas codec 1)

*Mar 28 11:09:50.559: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
INVITE sip:3950@10.34.0.200:5060 SIP/2.0
Via: SIP/2.0/UDP 10.34.0.234;branch=z9hG4bK5712c4679bcc73b2813efaecb61cc3f2;rport
From: <sip:81342299@10.34.0.200:5060>;tag=2588e53a6d12efc553f4c52bbec0c77b
To: <sip:3950@10.34.0.200:5060>
Call-ID: a6118d9336e2ddaf21c8cc439929101f@10.34.0.234
CSeq: 17008 INVITE
Contact: <sip:81342299@10.34.0.234>
Supported: replaces
User-Agent: QVI-2104  Rev 9.70.36.10 PCB 61.1 LOGIC 0 BIOS 1, Built on Feb  2 2012, 17:21:45
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, NOTIFY, REFER
Content-Type: application/sdp
Max-Forwards: 70
Content-Length:   378

v=0
o=Qtech 7775537 7775538 IN IP4 10.34.0.234
s=-
c=IN IP4 10.34.0.234
t=0 0
m=audio 8004 RTP/AVP 18 0 8 4
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=yes
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=ptime:20
a=sqn:0
a=cdsc:1 audio RTP/AVP 18 0 8 4
a=cdsc:2 imagea udptl t38
a=T38FaxUdpEC:t38UDPRedundancy
a=T38FaxVersion:0
a=T38MaxBitRate:9600

*Mar 28 11:09:50.563: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.34.0.234;branch=z9hG4bK5712c4679bcc73b2813efaecb61cc3f2;rport
From: <sip:81342299@10.34.0.200:5060>;tag=2588e53a6d12efc553f4c52bbec0c77b
To: <sip:3950@10.34.0.200:5060>
Date: Thu, 28 Mar 2013 11:09:50 GMT
Call-ID: a6118d9336e2ddaf21c8cc439929101f@10.34.0.234
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 17008 INVITE
Allow-Events: telephone-event
Content-Length: 0

*Mar 28 11:09:50.567: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
INVITE sip:3950@10.34.0.233:5060 SIP/2.0
Via: SIP/2.0/UDP 10.34.0.200:5060;branch=z9hG4bK620E5
Remote-Party-ID: <sip:81342299@10.34.0.200>;party=calling;screen=no;privacy=off
From: <sip:81342299@10.34.0.200>;tag=8767CC-256A
To: <sip:3950@10.34.0.233>
Date: Thu, 28 Mar 2013 11:09:50 GMT
Call-ID: D7llFD653E-96CE11E2-8074D3DB-2A2A1515@10.34.0.200
Supported: 100rel,timer,resource-priority,replaces
Min-SE:  1800
Cisco-Guid: 3623628014-2530087394-2154746843-707400981
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1364468990
Contact: <sip:81342299@10.34.0.200:5060>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 69
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 224

v=0
o=CiscoSystemsSIP-GW-UserAgent 3886 9550 IN IP4 10.34.0.200
s=SIP Call
c=IN IP4 10.34.0.200
t=0 0
m=audio 19100 RTP/AVP 0 8 19
c=IN IP4 10.34.0.200
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:19 CN/8000

*Mar 28 11:09:50.591: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.34.0.200:5060;branch=z9hG4bK620E5
From: <sip:81342299@10.34.0.200>;tag=8767CC-256A
To: <sip:3950@10.34.0.233>;tag=868E6C-1CF2
Date: Thu, 28 Mar 2013 11:04:53 GMT
Call-ID: D7FD653E-96CE11E2-8074D3DB-2A2A1515@10.34.0.200
Timestamp: 1364468990
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Content-Length: 0

*Mar 28 11:09:50.639: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 500 Internal Server Error
Via: SIP/2.0/UDP 10.34.0.200:5060;branch=z9hG4bK620E5
From: <sip:81342299@10.34.0.200>;tag=8767CC-256A
To: <sip:3950@10.34.0.233>;tag=868E6C-1CF2
Date: Thu, 28 Mar 2013 11:04:53 GMT
Call-ID: D7FD653E-96CE11E2-8074D3DB-2A2A1515@10.34.0.200
Timestamp: 1364468990
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 101 INVITE
Allow-Events: telephone-event
Reason: Q.850;cause=16
Content-Length: 0

*Mar 28 11:09:50.639: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
ACK sip:3950@10.34.0.233:5060 SIP/2.0
Via: SIP/2.0/UDP 10.34.0.200:5060;branch=z9hG4bK620E5
From: <sip:81342299@10.34.0.200>;tag=8767CC-256A
To: <sip:3950@10.34.0.233>;tag=868E6C-1CF2
Date: Thu, 28 Mar 2013 11:09:50 GMT
Call-ID: D7FD653E-96CE11E2-8074D3DB-2A2A1515@10.34.0.200
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

*Mar 28 11:09:50.639: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 10.34.0.234;branch=z9hG4bK5712c4679bcc73b2813efaecb61cc3f2;rport
From: <sip:81342299@10.34.0.200:5060>;tag=2588e53a6d12efc553f4c52bbec0c77b
To: <sip:3950@10.34.0.200:5060>;tag=876814-1825
Date: Thu, 28 Mar 2013 11:09:50 GMT
Call-ID: a6118d9336e2ddaf21c8cc439929101f@10.34.0.234
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 17008 INVITE
Allow-Events: telephone-event
Reason: Q.850;cause=41
Content-Length: 0

*Mar 28 11:09:50.655: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
ACK sip:3950@10.34.0.200:5060 SIP/2.0
Via: SIP/2.0/UDP 10.34.0.234;branch=z9hG4bK5712c4679bcc73b2813efaecb61cc3f2;rport
From: <sip:81342299@10.34.0.200:5060>;tag=2588e53a6d12efc553f4c52bbec0c77b
To: <sip:3950@10.34.0.200:5060>;tag=876814-1825
Call-ID: a6118d9336e2ddaf21c8cc439929101f@10.34.0.234
CSeq: 17008 ACK
Content-Length: 0



2) ccsip calls (voice class codec 1), настараживают строчки no codec, и нули в ip адресах. По номерам кауз искал - ничего так и не нашел=\

*Mar 28 11:11:28.679: //62/126D1C668075/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x684BF7D8
State of The Call        : STATE_DEAD
TCP Sockets Used         : NO
Calling Number           : 81342299
Called Number            : 3950
Source IP Address (Sig  ): 10.34.0.200
Destn SIP Req Addr:Port  : 10.34.0.233:5060
Destn SIP Resp Addr:Port : 10.34.0.233:5060
Destination Name         : 10.34.0.233

*Mar 28 11:11:28.679: //62/126D1C668075/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream             : 1
Negotiated Codec         : No Codec
Negotiated Codec Bytes   : 0
Nego. Codec payload      : 255 (tx), 255 (rx)
Negotiated Dtmf-relay    : 0
Dtmf-relay Payload       : 0 (tx), 0 (rx)
Source IP Address (Media): 10.34.0.200
Source IP Port    (Media): 17256
Destn  IP Address (Media): 0.0.0.0
Destn  IP Port    (Media): 0
Orig Destn IP Address:Port (Media): 0.0.0.0:0

*Mar 28 11:11:28.679: //62/126D1C668075/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC)    : 41
Disconnect Cause (SIP)   : 500

*Mar 28 11:11:28.699: //61/126D1C668075/SIP/Call/sipSPICallInfo:
The Call Setup Information is:
Call Control Block (CCB) : 0x684BD10C
State of The Call        : STATE_DEAD
TCP Sockets Used         : NO
Calling Number           : 81342299
Called Number            : 3950
Source IP Address (Sig  ): 10.34.0.200
Destn SIP Req Addr:Port  : 10.34.0.234:5060
Destn SIP Resp Addr:Port : 10.34.0.234:5060
Destination Name         : 10.34.0.234

*Mar 28 11:11:28.699: //61/126D1C668075/SIP/Call/sipSPIMediaCallInfo:
Number of Media Streams: 1
Media Stream             : 1
Negotiated Codec         : g711ulaw
Negotiated Codec Bytes   : 160
Nego. Codec payload      : 0 (tx), 0 (rx)
Negotiated Dtmf-relay    : 0
Dtmf-relay Payload       : 0 (tx), 0 (rx)
Source IP Address (Media): 10.34.0.200
Source IP Port    (Media): 16850
Destn  IP Address (Media): 10.34.0.234
Destn  IP Port    (Media): 8004
Orig Destn IP Address:Port (Media): 0.0.0.0:0

*Mar 28 11:11:28.699: //61/126D1C668075/SIP/Call/sipSPICallInfo:
Disconnect Cause (CC)    : 41
Disconnect Cause (SIP)   : 503


Понимаю, тысячу раз все это делалось и тысяча статей есть, но рыл, проблема сохранилась, прошу помощи=)

С уважением, Михаил

Ответить | Правка | Cообщить модератору

Оглавление

Сообщения по теме [Сортировка по времени | RSS]


1. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 15:27 
Дебаги продолжение:
3)cch323 all на 3825 (voice class codec 1)


*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: callID=84
*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0 incoming_media=0
*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_post_call_setup_request: incoming_call=0 incoming_media=0
*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x6888FC60) is in use
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_call_setup: gw_id=1
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_call_setup: Inserted ccb
*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_create_call_info_type_container:
cch323_create_call_info_type_container: creating callInfoType container for callID = 84
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_h245_state_mc_mode_outgoing: call_spi_mode = 3
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_h245_state_mc_mode_outgoing: h245 state m/c mode=0x4F0, h323_ctl=0x0
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_get_peer_info: Entry
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_get_peer_info: Have peer
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_get_peer_info: faxrate[43] proto[1] bitmask[10082] t38_inhibit[0] global_fax[0] t38fax[1]
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_pref_codec_list: Peer channel present: dp pref mask=3
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_pref_codec_list: First preferred codec(bytes)=5(160)
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_get_peer_info: Preferred codec set to G711_ULAW_64K with Bytes = 160
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_get_peer_info: Flow Mode set to FLOW_THROUGH
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_get_peer_info: peer: 686E45A8, peer->voice_peer_tag: 1, ccb: 6888FC60
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_h323_parms_from_dp: ccb h245addr bits = system[0x0007]
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_h323_parms_from_dp: ccb ccm bits = system[0x0028]
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_update_setup_callinfo: callingNumber = 81342299
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_update_setup_callinfo: called_oct3 = 0x0, calling_oct3 = 0x0, calling_oct3a = 0x80
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_h323_control_options_outgoing: h245 sm mode = 1264
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_set_h323_control_options_outgoing: h323_ctl=0x20
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_call_setup: No account/pin number available
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_store_call_info: Call_setup Playout Mode: 1,Init 60, Min 40, Max 1000, Fax 300
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_call_setup_normal: Entry
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_call_setup_normal:  SETUP_NTF_TIMER is not running. Continue processing.
*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_h225FD_Print: fd [2] Accepts newcall
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_IDLE state
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_store_call_info: Call_setup Playout Mode: 1,Init 60, Min 40, Max 1000, Fax 300
*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0xB internal event to H225 SM
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_SETUP while at state H225_IDLE
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/check_qos_and_send_setup: Setup ccb 0x6888FC60
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_rotary_validate: No peer_ccb available
*Mar 28 11:23:27.575: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0xE internal event to H225 SM
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_FS_SETUP while at state H225_IDLE
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/idle_fsSetup_hdlr: Setup ccb 0x6888FC60
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/idle_fsSetup_hdlr: send_fastStart_setup called
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/send_fastStart_setup: Entry
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_build_local_encoded_fastStartOLCs: state_mc_mode=0x4F0 on outbound leg
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_build_local_encoded_fastStartOLCs: srcAddress = 0xA2200C8, h245_lport = 0, flow mode = 1, minimum_qos=0
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/h245_set_local_audio_mask: Near-end Pref Codecs = G711_ULAW_64K G711_ALAW_64K
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_generic_open_logical_channel: current codec = 5:160:160
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_generic_open_logical_channel: Codec is G711_ULAW_64K
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_generic_open_logical_channel: Filling in qosCapability field to 0
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_generic_open_logical_channel: current codec = 6:160:160
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_generic_open_logical_channel: Codec is G711_ALAW_64K
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/cch323_generic_open_logical_channel: Filling in qosCapability field to 0
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/generic_send_setup: Entry
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/generic_send_setup:
generic_send_setup: is_overlap = 0, info_complete = 0
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/generic_send_setup: sending calling IE
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/generic_send_setup: ====== PI = 0
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/generic_send_setup: Send infoXCap=128, infoXRate=16, rateMult=0, xMode=128, info_layer1_prot=163
*Mar 28 11:23:27.575: //84/BEF64D8480B8/H323/generic_send_setup: src address = 10.34.0.200; dest address = 10.34.0.233
*Mar 28 11:23:27.579: //84/BEF64D8480B8/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_REQ_FS_SETUP state
*Mar 28 11:23:27.579: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 IWF SM
*Mar 28 11:23:27.579: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE
*Mar 28 11:23:27.611: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:23:27.611: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x68723194, len=119, msgPtr=0x68753378
*Mar 28 11:23:27.611: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:23:27.611: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type CALLPROCIND_CHOSEN
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/callproc_ind: Entry
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/callproc_ind: ====== PI = 0
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_h225_receiver: CALLPROCIND_CHOSEN: src address = 10.34.0.200; dest address = 10.34.0.233
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_FS_CALLPROC_IND while at state H225_REQ_FS_SETUP
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_h225_set_new_state: Changing from H225_REQ_FS_SETUP state to H225_ACC_FS_CALLPROC state
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_selectFastStart_codecs: Codec: loc(5), rem(5); Bytes: loc(160), Fwd(160), Rev(160)
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_receive_fastStart_cap_response: Send cap ind to peer leg
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_build_olc_for_ccapi: audioFastStartArray=0x67597E28
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_build_olc_for_ccapi: channel_info ptr=0x687547B8, ccb ptr=0x6888FC60
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_build_olc_for_ccapi: Channel Information:
        Logical Channel Number (fwd): 1
        Logical Channel Number (rev): 1
        Channel address (fwd/rev):        0xA2200E9
        RTP  Channel (fwd/rev):           16974
        RTCP Channel (fwd/rev):           16975
        QoS Capability (fwd/rev):         0
        Symmetric Audio Codec:            5
        Symmetric Audio Codec Bytes:      160
        Flow Mode:                        0
        Silence Suppression:              1
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_build_olc_for_ccapi: NumOfElements = 1 idx = 1
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_receive_fastStart_cap_response: set have_lcl_caps to 1
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_iwf_set_new_state: changing from IWF_OLC_IDLE state to IWF_OLC_DONE state
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/fastStartIdle: H245_EV_OLC_IN/OUT_DONE sent to olc
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_olc_in_sm: Received H245_EV_OLC_IN_DONE while at state H245_OLC_IN_STATE_IDLE
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_olc_in_set_new_state: Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_ESTABLISHED state
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_olc_out_sm: Received H245_EV_OLC_OUT_DONE while at state H245_OLC_OUT_STATE_IDLE
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_olc_out_set_new_state: Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_ESTABLISHED state
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_do_open_channel_ind: Sending event CC_EV_H245_OPEN_CHANNEL_IND, channelInfo pointer 0x687547B8
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_connect_init: waiting for FS response, cannot connect
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h323_common_setup_rtcp_parameters: updating RTP session type, ccb->status = 18002008,
olc->rtcp_session.type = 2, do_rtcp = 1, iwf_state = 0,
negotiated_codec = G711_ULAW_64K, mediaWait = 0, h245_lport = 16788,
srcAddress = 10.34.0.200, srcCallID = 84, h245.status = 40C
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h323_open_rtp_stream: Media In-active notification object not attached to ccb
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_set_dtmf_iw_enabled: negotiated dtmf relay: 0, dtmf_iw_enabled: 0, dtmf_sccp_enabled: 0
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_rtp_open_notify: Entry
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/generic_callproc_notify: Entry
*Mar 28 11:23:27.611: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_IDLE
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_CONNECT_REQ_EVENT
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_h245_connection_sm: state=0, event=1, ccb=6888FC60, listen state=0
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_CONNECT_REQ_EVENT while at H245_NONE state
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_h245_connection_sm: In connection_sm; doing tunneling
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/cch323_h245_set_new_state: Changing from H245_NONE state to H245_CONNECTED state
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_iwf_set_new_state: changing from IWF_IDLE state to IWF_AWAIT_H245_CONN state
*Mar 28 11:23:27.611: //84/BEF64D8480B8/H323/h245_connect_init: connection pending
*Mar 28 11:23:27.611: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2 internal event to H245 IWF SM
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_H245_CONNECTED while at state IWF_AWAIT_H245_CONN
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/start_H245_state_machines: h245 status = 2040C
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/start_H245_state_machines: start MSD procedures
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_H245_CONN state to IWF_AWAIT_CAP_MSD_RESP state
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/h245_connect_init: connection already established
*Mar 28 11:23:27.615: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 CAP OUT SM
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_REQ while at state IDLE
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: cch323_send_cap_request: ccb->callID: 84, ccb->h245.state_mc_mode: 1264
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: ccb->callID: 84, video_cap_mask: 0x0
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/h245_copy_preferred_codec_list: Copying Preferred codec into caps table
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/h245_send_generic_audio_caps: [trans]audio mask after operation=0x3
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: Included rtp in caps table
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: Included t38 fax in caps table
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: Included signal in caps table
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: Included alpha in caps table
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: Included hookflash in caps table
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_send_cap_request: Included rtp-nte in caps table
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/h245_cap_out_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
*Mar 28 11:23:27.615: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 MS SM
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MSD while at state H245_MS_NONE
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/cch323_run_h245_ms_sm: Sent MSD Request
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/h245_ms_set_new_state: Changing from H245_MS_NONE state to H245_MS_OUTGOING_WAIT state
*Mar 28 11:23:27.615: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.615: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:23:27.615: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.631: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:23:27.631: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x68723194, len=376, msgPtr=0x68932F14
*Mar 28 11:23:27.631: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.200; dest address = 10.34.0.233
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/h245_connect_init: connection already established
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_h245_cap_ind: Masks au=0x4FFFF data=0x2 uinp=0x32
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_IND while at state IDLE
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/h245_cap_in_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x4 internal event to H245 IWF SM
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_CAP_IND while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/h323_validate_cap_ind: Remote Pref Codecs UnOrdered (0x4FFFF) = [G729IETF] [G729a] [G729b] [G729ab] [G711_ULAW_64K] [G711_ALAW_64K] [G726r16] [G726r24] [G726r32] [CLEARCHANNEL] [G728] [G723ar63] [G723ar53] [G723r63] [G723r53] [GSMFR] [G729r8 pre-IETF] []
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/h245_handle_cap_ind: TCS received from remote end. Setting h245 status flag H245_CAP_REQUEST_RCVD
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_set_extended_caps: Copying codec list into extended caps structure
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_set_extended_caps: G711_ULAW_64K
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_set_extended_caps: G711_ALAW_64K
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_iwf_cap_notify: Mask sent to other leg=3
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 CAP IN SM
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_RESP while at state AWAITING_RESPONSE
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/h245_cap_in_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_peer_caps_ack: Sending caps resp event to CAP sm
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_PEER_CAPS_ACK while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 CAP IN SM
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_RESP while at state IDLE
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_run_h245_cap_in_sm: Invalid event H245_EVENT_CAP_RESP for state IDLE
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x68723194, len=98, msgPtr=0x687547B8
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.200; dest address = 10.34.0.233
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/h245_connect_init: connection already established
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_IND while at state H245_MS_OUTGOING_WAIT
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/MSDetermination: rtt=60 rsdnum=8888, ltt=60 lsdnum=259
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/MSDetermination: Am MASTER, ccb->h245.h245_mdStatus = 0x1
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/cch323_run_h245_ms_sm: MS_Determine_indication to Appl: Sent MSD ACK!
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/h245_ms_set_new_state: Changing from H245_MS_OUTGOING_WAIT state to H245_MS_INCOMING_WAIT state
*Mar 28 11:23:27.635: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:23:27.635: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x68723194, len=95, msgPtr=0x68723434
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.200; dest address = 10.34.0.233
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h245_connect_init: connection already established
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_CFM while at state AWAITING_RESPONSE
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h245_cap_out_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x5 internal event to H245 IWF SM
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_CAP_CFM while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h245_iwf_handle_send_caps_ack_to_peer: Sending caps ack to other leg
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_do_caps_ack: dstCallID=-1, srcCallID=84
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_CAP_MSD_RESP state to IWF_AWAIT_MSD_RESP state
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x68723194, len=94, msgPtr=0x68723434
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.200; dest address = 10.34.0.233
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_MSD_RESP
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h245_connect_init: connection already established
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_CFM while at state H245_MS_INCOMING_WAIT
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/cch323_run_h245_ms_sm: Local Status = 0x1, Remote Status = 0x1
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/cch323_run_h245_ms_sm: MSD Determine CFM to APPL, both sides agree!
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h245_ms_set_new_state: Changing from H245_MS_INCOMING_WAIT state to H245_MS_NONE state
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x7 internal event to H245 IWF SM
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_MSD_CFM while at state IWF_AWAIT_MSD_RESP
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h323_find_negotiated_codec: Audio Mask 0x4FFFF, Negotiated Codec G711_ULAW_64K(5) Codec Bytes 160
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h323_find_negotiated_dtmf: peer_data_cap_mask=0x2, peer_user_input_cap_mask=0x32
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/cch323_set_dtmf_iw_enabled: negotiated dtmf relay: 0, dtmf_iw_enabled: 0, dtmf_sccp_enabled: 0
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h323_find_negotiated_dtmf: H323 <-> SIP DTMF = 0x1
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h323_find_negotiated_dtmf: Negotiated DTMF relay - 0x0
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_MSD_RESP state to IWF_ACTIVE state
*Mar 28 11:23:27.639: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x19 internal event to H225 SM
*Mar 28 11:23:27.639: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_H245_SUCCESS while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x68723194, len=46, msgPtr=0x68A8031C
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/release_ind: Disconnect cause 16 location code 0
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/h323_set_release_source_for_peer: ownCallId[84], src[4]
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 10.34.0.200; dest address = 10.34.0.233
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_traverse_enum_contact_list: State = 8
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_post_call_statistics: callID=84
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_do_get_call_statistics: Entry, call_id=84
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_do_get_call_statistics: Stats request failed, callID=84, rc=-7
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_do_call_disconnect: gw_id=1, discCause=16
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ACC_FS_CALLPROC
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_h225_send_release: Cause = 16; Location = 0
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_h225_send_release: h225TerminateRequest: src address = 170000584; dest address = 10.34.0.233
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_CALLPROC state to H225_IDLE state
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_call_generic_cleanup: Disconnected flag 1
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_store_call_history_entry: Trying to store call history/remove call_info while RTP session exists
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_store_call_history_entry: Call Active Playout Mode: 1,Init 60, Min 40, Max 1000, Fax 300
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_store_call_history_entry: Call History Playout Mode: 1,Init 60, Min 40, Max 1000, Fax 300
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_ACTIVE
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_DISCONNECT_EVENT
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_h245_connection_sm: state=2, event=4, ccb=6888FC60, listen state=0
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_DISCONNECT_EVENT while at H245_CONNECTED state
*Mar 28 11:23:27.659: //84/BEF64D8480B8/H323/h245_iwf_set_new_state: changing from IWF_ACTIVE state to IWF_IDLE state
*Mar 28 11:23:27.659: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x6888FC60)


Ответить | Правка | ^ к родителю #0 | Наверх | Cообщить модератору

2. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 15:27 
4)cch323 all на 2801 при том же звонке что выше

*Mar 28 11:21:11.111: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:21:11.111: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x64D30CA4, len=220, msgPtr=0x64E2273C
*Mar 28 11:21:11.111: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: CCB not found; allocating one
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_get_new_ccb: ccb (0x647DBB8C) is in use
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/setup_ind: callingNumber[81342299] calledNumber[3950]
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/setup_ind: ---- calling IE present
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/setup_ind: ====== PI = 0
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/setup_ind: Receive: infoXCap 0
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/setup_ind: Receive: infoXCap ccb 0
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/setup_ind: Receive bearer cap infoXRate 16, rateMult 0
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_extract_signaled_carriers: Entry
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_determine_source_ip_group_info: No Source IP group match with Src CarrierId/Zone ID
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_determine_source_ip_group_info: No Source IP group matched with rte id/zone_id/acl
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_determine_source_ip_group_info: No Source IP group match with Incoming Called Number
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_determine_source_group: Entry
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_determine_source_ip_group_info: No Source IP group match with Destination pattern
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/setup_ind:
setup_ind: is_overlap = 0, info_complete = 0

*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_is_preauth_reqd: FALSE
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_FS_SETUP_IND while at state H225_IDLE
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/idle_fsSetupInd_hdlr: Setup ccb 0x647DBB8C
*Mar 28 11:21:11.115: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/act_fastStartSetupInd: no match is found
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_fastStart_codec_match: ccb->remote_fastStart=0x64D44848
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_fastStart_codec_match: symm_mask=3, tempOtherCodec=6, templocalCodec=6, audioFastStartArray=0x64E46DD0
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_fastStart_codec_match: Executing legacy code
*Mar 28 11:21:11.115: //39/1E576A4C80BE/H323/cch323_fastStart_codec_match: Inbound legs state_mc_mode=0x10F
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_fastStart_codec_match: Executing legacy code
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_selectFastStart_codecs: Codec: loc(5), rem(5); Bytes: loc(160), Fwd(160), Rev(160)
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_build_olc_for_ccapi: audioFastStartArray=0x64E46DD0
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_build_olc_for_ccapi: channel_info ptr=0x64E46FD4, ccb ptr=0x647DBB8C
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_build_olc_for_ccapi: Channel Information:
        Logical Channel Number (fwd): 1
        Logical Channel Number (rev): 1
        Channel address (fwd/rev):        0xA2200C8
        RTP  Channel (fwd/rev):           19570
        RTCP Channel (fwd/rev):           19571
        QoS Capability (fwd/rev):         0
        Symmetric Audio Codec:            5
        Symmetric Audio Codec Bytes:      160
        Flow Mode:                        0
        Silence Suppression:              1
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_build_olc_for_ccapi: Channel Information:
        Logical Channel Number (fwd): 1
        Logical Channel Number (rev): 1
        Channel address (fwd/rev):        0xA2200C8
        RTP  Channel (fwd/rev):           19570
        RTCP Channel (fwd/rev):           19571
        QoS Capability (fwd/rev):         0
        Symmetric Audio Codec:            6
        Symmetric Audio Codec Bytes:      160
        Flow Mode:                        0
        Silence Suppression:              1
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_build_olc_for_ccapi: NumOfElements = 2 idx = 2
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/act_fastStartSetupInd: codec match = 1
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_REQ_FS_SETUP state
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_update_faststart_media_info: Remote qosCapability 0
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/process_fastStart_setup_for_qos: This seems to be a normal call with Sync OFF; just do a generic notify
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_set_h245_state_mc_mode_incoming: h245 state m/c mode=0x10F, h323_ctl=0x0
*Mar 28 11:21:11.119: //-1/xxxxxxxxxxxx/H323/cch323_associate_incoming_peer: timeout = 1, reason = -1
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_create_incoming_callinfo_block: peer is NULL - may affect modem pass through! ccb: 647DBB8C, ccNewCallInfo 64AC0918
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/h323_gw_setup_ind: is_overlap = 0, info_complete = 0
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indications
*Mar 28 11:21:11.119: //39/1E576A4C80BE/H323/cch323_update_carriers_in_call_info: tg_label_flag 0x0
*Mar 28 11:21:11.123: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: callID=39
*Mar 28 11:21:11.123: //-1/xxxxxxxxxxxx/H323/cch245_event_handler: Event CC_EV_H245_SET_MODE: data ptr=0x64E416E0
*Mar 28 11:21:11.123: //-1/xxxxxxxxxxxx/H323/cch323_set_mode: callID=39, flow Mode=1 spi_mode=0x6
*Mar 28 11:21:11.123: //39/1E576A4C80BE/H323/cch323_do_set_dialpeer_tag: gw_id=1
*Mar 28 11:21:11.127: //39/1E576A4C80BE/H323/cch323_do_call_proceeding: gw_id=1
*Mar 28 11:21:11.127: //39/1E576A4C80BE/H323/cch323_do_call_proceeding: set_mode NOT called yet...saved deferred CALL_PROC
*Mar 28 11:21:11.127: //39/1E576A4C80BE/H323/cch323_build_fastStart_cap_response: selectCodec=5, codec_mask=1, configured_codecBytes=160, forward_codecBytes=160, reverse_codecBytes=160, audioFastStartArray=0x64E46DD0
*Mar 28 11:21:11.127: //39/1E576A4C80BE/H323/cch323_prepare_fastStart_cap_response: Entry
*Mar 28 11:21:11.127: //39/1E576A4C80BE/H323/cch323_prepare_fastStart_cap_response: Retrieved qosCapability of 0
*Mar 28 11:21:11.127: //39/1E576A4C80BE/H323/cch323_prepare_fastStart_cap_response: Filling in qosCapability field to 0
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_prepare_fastStart_cap_response: audioFastStartArray = 0x0
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_prepare_fastStart_cap_response: ccb->local_fastStart=0x64D45220, srcAddr=0xA2200E9, lport=16464, rport=19570, rc=1
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_build_fastStart_cap_response: local_fastStart=0x64D45220, negotiated_codec=5, negotiated_codec_bytes=160
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/h323_open_rtp_stream: Media In-active notification object not attached to ccb
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_rtp_open_notify: Entry
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_do_cap_ind: Registry cap_ind is not done
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_build_fastStart_cap_response: Done
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_process_set_mode: Setting inbound leg mode flags to 0x10F, flow-mode to FLOW_THROUGH
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_process_set_mode: Sending deferred CALL_PROC
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_do_call_proceeding: gw_id=1
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_do_call_proceeding: set_mode called so we can proceed with CALLPROC
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_REQ_FS_SETUP
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/cch323_h225_set_new_state: Changing from H225_REQ_FS_SETUP state to H225_ACC_FS_CALLPROC state
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/generic_send_callproc: ====== PI = 0
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_FASTSTART while at state IWF_IDLE
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/h245_iwf_set_new_state: changing from IWF_OLC_IDLE state to IWF_OLC_DONE state
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/fastStartIdle: H245_EV_OLC_IN/OUT_DONE sent to olc
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/h245_olc_in_sm: Received H245_EV_OLC_IN_DONE while at state H245_OLC_IN_STATE_IDLE
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/h245_olc_in_set_new_state: Changing from H245_OLC_IN_STATE_IDLE state to H245_OLC_IN_STATE_ESTABLISHED state
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/h245_olc_out_sm: Received H245_EV_OLC_OUT_DONE while at state H245_OLC_OUT_STATE_IDLE
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/h245_olc_out_set_new_state: Changing from H245_OLC_OUT_STATE_IDLE state to H245_OLC_OUT_STATE_ESTABLISHED state
*Mar 28 11:21:11.131: //39/1E576A4C80BE/H323/fastStartIdle: ccb->status_1 = 0x1
*Mar 28 11:21:11.143: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:21:11.143: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x64D30CA4, len=239, msgPtr=0x64E46DD0
*Mar 28 11:21:11.143: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:21:11.147: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.147: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_IDLE
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_CONNECT_REQ_EVENT
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_h245_connection_sm: state=0, event=1, ccb=647DBB8C, listen state=0
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_CONNECT_REQ_EVENT while at H245_NONE state
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_h245_connection_sm: In connection_sm; doing tunneling
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_h245_set_new_state: Changing from H245_NONE state to H245_CONNECTED state
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/h245_iwf_set_new_state: changing from IWF_IDLE state to IWF_AWAIT_H245_CONN state
*Mar 28 11:21:11.147: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2 internal event to H245 IWF SM
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_H245_CONNECTED while at state IWF_AWAIT_H245_CONN
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_H245_CONN state to IWF_AWAIT_CAP_MSD_RESP state
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_h245_cap_ind: Masks au=0x3 data=0x6 uinp=0x32
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_IND while at state IDLE
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/h245_cap_in_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
*Mar 28 11:21:11.147: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 CAP OUT SM
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_REQ while at state IDLE
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/h245_send_generic_audio_caps: Using DP configured codecs
*Mar 28 11:21:11.147: //39/1E576A4C80BE/H323/h245_normalize_preferred_codec_list: Near-end Pref Codecs = G729IETF G729b G711_ULAW_64K G711_ALAW_64K G726r16 G726r24 G726r32 CLEARCHANNEL G728 G723ar63 G723ar53 G723r63 G723r53 GSMFR GSMEFR G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF G729r8 pre-IETF
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/h245_send_generic_audio_caps: [reg]audio mask after operation=0x4FFFF
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/cch323_send_cap_request: Included rtp in caps table
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/cch323_send_cap_request: Included signal in caps table
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/cch323_send_cap_request: Included alpha in caps table
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/cch323_send_cap_request: Included hookflash in caps table
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/h245_cap_out_set_new_state: changing from IDLE state to AWAITING_RESPONSE state
*Mar 28 11:21:11.151: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 MS SM
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MSD while at state H245_MS_NONE
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/cch323_run_h245_ms_sm: Sent MSD Request
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/h245_ms_set_new_state: Changing from H245_MS_NONE state to H245_MS_OUTGOING_WAIT state
*Mar 28 11:21:11.151: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x4 internal event to H245 IWF SM
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_CAP_IND while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:21:11.151: //39/1E576A4C80BE/H323/h323_validate_cap_ind: Remote Pref Codecs UnOrdered (0x3) = [G711_ULAW_64K] [G711_ALAW_64K] []
*Mar 28 11:21:11.155: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:21:11.155: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.155: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:21:11.155: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.155: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x0 internal event to H245 CAP IN SM
*Mar 28 11:21:11.155: //39/1E576A4C80BE/H323/cch323_run_h245_cap_in_sm: Received H245_EVENT_CAP_RESP while at state AWAITING_RESPONSE
*Mar 28 11:21:11.155: //39/1E576A4C80BE/H323/h245_cap_in_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
*Mar 28 11:21:11.155: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:21:11.155: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.155: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:21:11.155: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x64D30CA4, len=98, msgPtr=0x64D188A0
*Mar 28 11:21:11.155: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:21:11.159: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.159: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_IND while at state H245_MS_OUTGOING_WAIT
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/MSDetermination: rtt=60 rsdnum=5130, ltt=60 lsdnum=3082
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/MSDetermination: Am MASTER, ccb->h245.h245_mdStatus = 0x1
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/cch323_run_h245_ms_sm: MS_Determine_indication to Appl: Sent MSD ACK!
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/h245_ms_set_new_state: Changing from H245_MS_OUTGOING_WAIT state to H245_MS_INCOMING_WAIT state
*Mar 28 11:21:11.159: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x2F internal event to H225 SM
*Mar 28 11:21:11.159: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.167: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:21:11.167: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x64D30CA4, len=95, msgPtr=0x64D30294
*Mar 28 11:21:11.167: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:21:11.171: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:21:11.171: //39/1E576A4C80BE/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 11:21:11.171: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.171: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:21:11.171: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:21:11.171: //39/1E576A4C80BE/H323/cch323_run_h245_cap_out_sm: Received H245_EVENT_CAP_CFM while at state AWAITING_RESPONSE
*Mar 28 11:21:11.171: //39/1E576A4C80BE/H323/h245_cap_out_set_new_state: changing from AWAITING_RESPONSE state to IDLE state
*Mar 28 11:21:11.171: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x5 internal event to H245 IWF SM
*Mar 28 11:21:11.171: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_CAP_CFM while at state IWF_AWAIT_CAP_MSD_RESP
*Mar 28 11:21:11.171: //39/1E576A4C80BE/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_CAP_MSD_RESP state to IWF_AWAIT_MSD_RESP state
*Mar 28 11:21:11.175: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:21:11.175: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x64D30CA4, len=94, msgPtr=0x64D30294
*Mar 28 11:21:11.175: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:21:11.175: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.175: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x1 internal event to H245 IWF SM
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_PROC_TUNNEL while at state IWF_AWAIT_MSD_RESP
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/cch323_run_h245_ms_sm: Received event H245_EVENT_MS_CFM while at state H245_MS_INCOMING_WAIT
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/cch323_run_h245_ms_sm: Local Status = 0x1, Remote Status = 0x1
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/cch323_run_h245_ms_sm: MSD Determine CFM to APPL, both sides agree!
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/h245_ms_set_new_state: Changing from H245_MS_INCOMING_WAIT state to H245_MS_NONE state
*Mar 28 11:21:11.175: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x7 internal event to H245 IWF SM
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_MSD_CFM while at state IWF_AWAIT_MSD_RESP
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/h323_find_negotiated_codec: Audio Mask 0x3, Negotiated Codec G711_ULAW_64K(5) Codec Bytes 160
*Mar 28 11:21:11.175: //39/1E576A4C80BE/H323/h323_find_negotiated_dtmf: peer_data_cap_mask=0x6, peer_user_input_cap_mask=0x32
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/h323_find_negotiated_dtmf: Negotiated DTMF relay - 0x0
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/cch323_do_cap_ind: Registry cap_ind is not done
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/cch323_do_cap_ind: Registry cap_ind is not done
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/h245_iwf_set_new_state: changing from IWF_AWAIT_MSD_RESP state to IWF_ACTIVE state
*Mar 28 11:21:11.179: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x19 internal event to H225 SM
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_H245_SUCCESS while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/cch323_do_call_disconnect: gw_id=1, discCause=16
*Mar 28 11:21:11.179: //-1/xxxxxxxxxxxx/H323/cch323_iev_queue_service: Dispatch 0x9 internal event to H225 SM
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ACC_FS_CALLPROC
*Mar 28 11:21:11.179: //39/1E576A4C80BE/H323/cch323_h225_send_release: Cause = 16; Location = 0
*Mar 28 11:21:11.183: //39/1E576A4C80BE/H323/cch323_h225_send_release: h225TerminateRequest: src address = 170000617; dest address = 10.34.0.200
*Mar 28 11:21:11.183: //39/1E576A4C80BE/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_CALLPROC state to H225_WAIT_FOR_REL_COMP state
*Mar 28 11:21:11.199: //-1/xxxxxxxxxxxx/H323/cch323_ct_main: SOCK 2 Event 0x1
*Mar 28 11:21:11.199: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: owner_data=0x64D30CA4, len=46, msgPtr=0x64D66D20
*Mar 28 11:21:11.199: //-1/xxxxxxxxxxxx/H323/cch323_gw_process_read_socket: Received msg for H.225
*Mar 28 11:21:11.199: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN
*Mar 28 11:21:11.199: //39/1E576A4C80BE/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 11:21:11.199: //39/1E576A4C80BE/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_WAIT_FOR_REL_COMP
*Mar 28 11:21:11.199: //39/1E576A4C80BE/H323/cch323_h225_set_new_state: Changing from H225_WAIT_FOR_REL_COMP state to H225_IDLE state
*Mar 28 11:21:11.199: //39/1E576A4C80BE/H323/cch323_call_generic_cleanup: Disconnected flag 1
*Mar 28 11:21:11.199: //39/1E576A4C80BE/H323/cch323_store_call_history_entry: Trying to store call history/remove call_info while RTP session exists
*Mar 28 11:21:11.199: //39/1E576A4C80BE/H323/cch323_store_call_history_entry: Call Active Playout Mode: 0,Init 0, Min 0, Max 0, Fax 0
*Mar 28 11:21:11.199: //39/1E576A4C80BE/H323/cch323_store_call_history_entry: Call History Playout Mode: 0,Init 0, Min 0, Max 0, Fax 0
*Mar 28 11:21:11.203: //39/1E576A4C80BE/H323/run_h245_iwf_sm: received IWF_EV_H245_DISCONN while at state IWF_ACTIVE
*Mar 28 11:21:11.203: //39/1E576A4C80BE/H323/cch323_send_event_to_h245_connection_sm: Changing to new event H245_DISCONNECT_EVENT
*Mar 28 11:21:11.203: //39/1E576A4C80BE/H323/cch323_h245_connection_sm: state=2, event=4, ccb=647DBB8C, listen state=0
*Mar 28 11:21:11.203: //39/1E576A4C80BE/H323/cch323_h245_connection_sm: H245_CONNECT: Received event H245_DISCONNECT_EVENT while at H245_CONNECTED state
*Mar 28 11:21:11.203: //39/1E576A4C80BE/H323/h245_iwf_set_new_state: changing from IWF_ACTIVE state to IWF_IDLE state
*Mar 28 11:21:11.203: //-1/xxxxxxxxxxxx/H323/cch323_call_generic_cleanup: Freeing ccb (0x647DBB8C)

Ответить | Правка | ^ к родителю #1 | Наверх | Cообщить модератору

3. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 15:29 
10.34.0.200 ip адрес cisco 3825
10.34.0.233 ip адрес cisco 2801
10.34.0.234 ip адрес sip шлюза qtech
Ответить | Правка | ^ к родителю #2 | Наверх | Cообщить модератору

4. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от mdenisov (ok) on 28-Мрт-13, 16:29 
Ну зачем же сразу так в дебаги лезть, для начала на 10.34.0.233 посмотрите sh call hist voice id $id для отбившегося вызова, интересует Release Source и Internal Error Code. Также не помешает sh isdn stat и debug isdn q931.
Ответить | Правка | ^ к родителю #0 | Наверх | Cообщить модератору

5. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 18:04 
> Ну зачем же сразу так в дебаги лезть, для начала на 10.34.0.233
> посмотрите sh call hist voice id $id для отбившегося вызова, интересует
> Release Source и Internal Error Code. Также не помешает sh isdn
> stat и debug isdn q931.

sh call hist voice last 1(после неудачного вызова) показал ReleaseSource=2, internal error code нету.

ReleaseSource Number value of the release source - подскажите пожалуйста что это значит?
DisconnectCause=10 - это говорит о нормальном завершении вызова, так ли это в моем случае?, я вроде не хотел чтобы вызов завершался юзером.(Indicates that the call is being cleared because one of the users involved in the call has requested that the call be cleared.Normal Call Clearing)

GENERIC:
SetupTime=18007320 ms
Index=37
PeerAddress=81342299
PeerSubAddress=
PeerId=0
PeerIfIndex=0
LogicalIfIndex=0
DisconnectCause=10
DisconnectText=normal call clearing (16)
ConnectTime=0 ms
DisconnectTime=18022810 ms
CallDuration=00:00:00 sec
CallOrigin=2
ReleaseSource=2
ChargedUnits=0
InfoType=speech
TransmitPackets=0
TransmitBytes=0
ReceivePackets=0
ReceiveBytes=0
VOIP:
ConnectionId[0x3C5C97EC 0x96E411E2 0x80EBD3DB 0x2A2A1515]
IncomingConnectionId[0x3C5C97EC 0x96E411E2 0x80EBD3DB 0x2A2A1515]
CallID=45
RemoteIPAddress=10.34.0.200
RemoteUDPPort=18648
RemoteSignallingIPAddress=10.34.0.200
RemoteSignallingPort=48076
RemoteMediaIPAddress=10.34.0.200
RemoteMediaPort=18648
SRTP = off
Fallback Icpif=0
Fallback Loss=0
Fallback Delay=0
RoundTripDelay=0 ms
SelectedQoS=best-effort
tx_DtmfRelay=inband-voice
FastConnect=TRUE

AnnexE=FALSE

Separate H245 Connection=FALSE

H245 Tunneling=TRUE

SessionProtocol=cisco
ProtocolCallId=
SessionTarget=
OnTimeRvPlayout=0
GapFillWithSilence=0 ms
GapFillWithPrediction=0 ms
GapFillWithInterpolation=0 ms
GapFillWithRedundancy=0 ms
HiWaterPlayoutDelay=0 ms
LoWaterPlayoutDelay=0 ms
PlayoutMode = undefined
PlayoutInitialDelay=0 ms
ReceiveDelay=0 ms
LostPackets=0
EarlyPackets=0
LatePackets=0
VAD = enabled
CoderTypeRate=g711ulaw
CodecBytes=160
cvVoIPCallHistoryIcpif=0
MediaSetting=flow-around
CallerName=
CallerIDBlocked=False
OriginalCallingNumber=81342299
OriginalCallingOctet=0x0
OriginalCalledNumber=3950
OriginalCalledOctet=0x80
OriginalRedirectCalledNumber=
OriginalRedirectCalledOctet=0xFF
TranslatedCallingNumber=81342299
TranslatedCallingOctet=0x0
TranslatedCalledNumber=3950
TranslatedCalledOctet=0x80
TranslatedRedirectCalledNumber=
TranslatedRedirectCalledOctet=0xFF
GwReceivedCalledNumber=3950
GwReceivedCalledOctet3=0x80
GwReceivedCallingNumber=81342299
GwReceivedCallingOctet3=0x0
GwReceivedCallingOctet3a=0x80
MediaInactiveDetected=no
MediaInactiveTimestamp=
MediaControlReceived=
Username=


Router#show isdn status
Global ISDN Switchtype = primary-net5
ISDN Serial0/2/0:15 interface
        dsl 0, interface ISDN Switchtype = primary-net5
    Layer 1 Status:
        ACTIVE
    Layer 2 Status:
        TEI = 0, Ces = 1, SAPI = 0, State = MULTIPLE_FRAME_ESTABLISHED
    Layer 3 Status:
        0 Active Layer 3 Call(s)
    Active dsl 0 CCBs = 0
    The Free Channel Mask:  0x80007FFF
    Number of L2 Discards = 0, L2 Session ID = 1
    Total Allocated ISDN CCBs = 0


Router#debug isdn q931
debug isdn q931 is              ON.
Router#term
Router#terminal mon
Router#terminal monitor
Router#
*Mar 28 13:47:05.730: ISDN Se0/2/0:15 Q931: Applying typeplan for sw-type 0x12 is 0x0 0x0, Calling num 81342299
*Mar 28 13:47:05.734: ISDN Se0/2/0:15 Q931: Applying typeplan for sw-type 0x12 is 0x0 0x0, Called num 3950
*Mar 28 13:47:05.734: ISDN Se0/2/0:15 Q931: TX -> SETUP pd = 8  callref = 0x0092
        Bearer Capability i = 0x8090A3
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0xA9838F
                Exclusive, Channel 15
        Calling Party Number i = 0x0080, '81342299'
                Plan:Unknown, Type:Unknown
        Called Party Number i = 0x80, '3950'
                Plan:Unknown, Type:Unknown
*Mar 28 13:47:05.746: ISDN Se0/2/0:15 Q931: RX <- SETUP_ACK pd = 8  callref = 0x8092
        Channel ID i = 0xA9838F
                Exclusive, Channel 15
*Mar 28 13:47:13.222: ISDN Se0/2/0:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x0092
        Cause i = 0x8090 - Normal call clearing
*Mar 28 13:47:13.230: ISDN Se0/2/0:15 Q931: RX <- RELEASE pd = 8  callref = 0x8092
*Mar 28 13:47:13.234: ISDN Se0/2/0:15 Q931: TX -> RELEASE_COMP pd = 8  callref = 0x0092

Подскажите пжст еще что зырить нужно, почитал ваши посты более ранние, никак всеравн не могу сообразить=\ я тут вижу только что все нормально=\

Ответить | Правка | ^ к родителю #4 | Наверх | Cообщить модератору

6. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 18:09 

Это на всякий,тут кауза 16=\

Router#debug cch323 h225
H225 State Machine tracing is enabled
Router#
Router#
Router#
Router#term
Router#terminal mon
Router#terminal monitor
Router#
Router#
Router#
*Mar 28 14:07:38.454: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type SETUPIND_CHOSEN
*Mar 28 14:07:38.454: //-1/xxxxxxxxxxxx/H323/setup_ind: Entry
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/setup_ind: callingNumber[81342299] calledNumber[3950]
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/setup_ind: ---- calling IE present
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/setup_ind: ====== PI = 0
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/setup_ind: Receive: infoXCap 0
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/setup_ind: Receive: infoXCap ccb 0
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/setup_ind:
setup_ind: is_overlap = 0, info_complete = 0

*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/cch323_h225_receiver: SETUPIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_FS_SETUP_IND while at state H225_IDLE
*Mar 28 14:07:38.454: //49/5E7909DA80FD/H323/idle_fsSetupInd_hdlr: Setup ccb 0x647DBB8C
*Mar 28 14:07:38.458: //49/5E7909DA80FD/H323/act_fastStartSetupInd: no match is found
*Mar 28 14:07:38.458: //49/5E7909DA80FD/H323/act_fastStartSetupInd: codec match = 1
*Mar 28 14:07:38.458: //49/5E7909DA80FD/H323/cch323_h225_set_new_state: Changing from H225_IDLE state to H225_REQ_FS_SETUP state
*Mar 28 14:07:38.458: //49/5E7909DA80FD/H323/cch323_create_incoming_callinfo_block: peer is NULL - may affect modem pass through! ccb: 647DBB8C, ccNewCallInfo 64AC0918
*Mar 28 14:07:38.458: //49/5E7909DA80FD/H323/cch323_h225_handle_deferred_ind: UnBuffering deferred indications
*Mar 28 14:07:38.466: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_CALLPROC while at state H225_REQ_FS_SETUP
*Mar 28 14:07:38.466: //49/5E7909DA80FD/H323/cch323_h225_set_new_state: Changing from H225_REQ_FS_SETUP state to H225_ACC_FS_CALLPROC state
*Mar 28 14:07:38.466: //49/5E7909DA80FD/H323/generic_send_callproc: ====== PI = 0
*Mar 28 14:07:38.466: //49/5E7909DA80FD/H323/fastStartIdle: ccb->status_1 = 0x1
*Mar 28 14:07:38.478: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 14:07:38.478: //49/5E7909DA80FD/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 14:07:38.478: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.478: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.482: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.482: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.482: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 14:07:38.482: //49/5E7909DA80FD/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 14:07:38.482: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.482: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_TRANSMIT_TUNNELING while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.490: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 14:07:38.490: //49/5E7909DA80FD/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 14:07:38.494: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.494: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type FACILIND_CHOSEN
*Mar 28 14:07:38.494: //49/5E7909DA80FD/H323/cch323_h225_receiver: FACILIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 14:07:38.494: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_FACILITY_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:38.494: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_H245_SUCCESS while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:48.170: //-1/xxxxxxxxxxxx/H323/cch323_h225_receiver: Received msg of type RELEASEIND_CHOSEN
*Mar 28 14:07:48.170: //49/5E7909DA80FD/H323/release_ind: Disconnect cause 16 location code 0
*Mar 28 14:07:48.170: //49/5E7909DA80FD/H323/cch323_h225_receiver: RELEASEIND_CHOSEN: src address = 10.34.0.233; dest address = 10.34.0.200
*Mar 28 14:07:48.170: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_RELEASE_IND while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:48.174: //49/5E7909DA80FD/H323/run_h225_sm: Received event H225_EV_RELEASE while at state H225_ACC_FS_CALLPROC
*Mar 28 14:07:48.174: //49/5E7909DA80FD/H323/cch323_h225_send_release: Cause = 16; Location = 0
*Mar 28 14:07:48.174: //49/5E7909DA80FD/H323/cch323_h225_send_release: h225TerminateRequest: src address = 170000617; dest address = 10.34.0.200
*Mar 28 14:07:48.178: //49/5E7909DA80FD/H323/cch323_h225_set_new_state: Changing from H225_ACC_FS_CALLPROC state to H225_IDLE state

Ответить | Правка | ^ к родителю #5 | Наверх | Cообщить модератору

8. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 18:18 
*Mar 28 13:47:13.222: ISDN Se0/2/0:15 Q931: TX -> DISCONNECT pd = 8  callref = 0x0092
        Cause i = 0x8090 - Normal call clearing

данный дисконект - проблема в атс?

Ответить | Правка | ^ к родителю #6 | Наверх | Cообщить модератору

12. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от mdenisov (ok) on 28-Мрт-13, 18:27 
> *Mar 28 13:47:13.222: ISDN Se0/2/0:15 Q931: TX -> DISCONNECT pd = 8

Так написано же TX - циска передала станции disconnect, станция не причем.

Ответить | Правка | ^ к родителю #8 | Наверх | Cообщить модератору

7. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от mdenisov (ok) on 28-Мрт-13, 18:10 
Release Source 2 - Calling party located in the VoIP network. Смотрите sh call hist voice last на предыдущей циске, эта не виновата.
Ответить | Правка | ^ к родителю #5 | Наверх | Cообщить модератору

9. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 18:22 
> Release Source 2 - Calling party located in the VoIP network. Смотрите
> sh call hist voice last на предыдущей циске, эта не виновата.

Router#show call history voice last 2

GENERIC:
SetupTime=20584200 ms
Index=59
PeerAddress=3950
PeerSubAddress=
PeerId=1
PeerIfIndex=18
LogicalIfIndex=0
DisconnectCause=10
DisconnectText=normal call clearing (16)
ConnectTime=0 ms
DisconnectTime=20586540 ms
CallDuration=00:00:00 sec
CallOrigin=1
ReleaseSource=2
ChargedUnits=0
InfoType=speech
TransmitPackets=0
TransmitBytes=0
ReceivePackets=0
ReceiveBytes=0
VOIP:
ConnectionId[0x1BD9C0EB 0x96EA11E2 0x810FD3DB 0x2A2A1515]
IncomingConnectionId[0x1BD9C0EB 0x96EA11E2 0x810FD3DB 0x2A2A1515]
CallID=140
RemoteIPAddress=10.34.0.233
RemoteUDPPort=17250
RemoteSignallingIPAddress=10.34.0.233
RemoteSignallingPort=1720
RemoteMediaIPAddress=10.34.0.233
RemoteMediaPort=17250
SRTP = off
TextRelay = off
Fallback Icpif=0
Fallback Loss=0
Fallback Delay=0
RoundTripDelay=0 ms
SelectedQoS=best-effort
tx_DtmfRelay=inband-voice
FastConnect=TRUE

AnnexE=FALSE

Separate H245 Connection=FALSE

H245 Tunneling=TRUE

SessionProtocol=cisco
ProtocolCallId=
SessionTarget=ipv4:10.34.0.233
OnTimeRvPlayout=0
GapFillWithSilence=0 ms
GapFillWithPrediction=0 ms
GapFillWithInterpolation=0 ms
GapFillWithRedundancy=0 ms
HiWaterPlayoutDelay=0 ms
LoWaterPlayoutDelay=0 ms
ReceiveDelay=0 ms
LostPackets=0
EarlyPackets=0
LatePackets=0
VAD = enabled
CoderTypeRate=g711ulaw
CodecBytes=160
cvVoIPCallHistoryIcpif=0
MediaSetting=flow-through
CallerName=
CallerIDBlocked=False
OriginalCallingNumber=81342299
OriginalCallingOctet=0x0
OriginalCalledNumber=3950
OriginalCalledOctet=0x0
OriginalRedirectCalledNumber=
OriginalRedirectCalledOctet=0x80
TranslatedCallingNumber=81342299
TranslatedCallingOctet=0x0
TranslatedCalledNumber=3950
TranslatedCalledOctet=0x0
TranslatedRedirectCalledNumber=
TranslatedRedirectCalledOctet=0x80
GwReceivedCalledNumber=3950
GwReceivedCalledOctet3=0x0
GwOutpulsedCalledNumber=3950
GwOutpulsedCalledOctet3=0x0
GwReceivedCallingNumber=81342299
GwReceivedCallingOctet3=0x0
GwReceivedCallingOctet3a=0x80
GwOutpulsedCallingNumber=81342299
GwOutpulsedCallingOctet3=0x0
GwOutpulsedCallingOctet3a=0x80
MediaInactiveDetected=no
MediaInactiveTimestamp=
MediaControlReceived=
LongDurationCallDetected=no
LongDurationCallTimerStamp=
LongDurationCallDuration=
Username=

GENERIC:
SetupTime=20584200 ms
Index=60
PeerAddress=81342299
PeerSubAddress=
PeerId=40006
PeerIfIndex=24
LogicalIfIndex=0
DisconnectCause=10
DisconnectText=normal call clearing (16)
ConnectTime=0 ms
DisconnectTime=20586560 ms
CallDuration=00:00:00 sec
CallOrigin=2
ReleaseSource=2
ChargedUnits=0
InfoType=speech
TransmitPackets=0
TransmitBytes=0
ReceivePackets=0
ReceiveBytes=0
VOIP:
ConnectionId[0x1BD9C0EB 0x96EA11E2 0x810FD3DB 0x2A2A1515]
IncomingConnectionId[0x1BD9C0EB 0x96EA11E2 0x810FD3DB 0x2A2A1515]
CallID=139
RemoteIPAddress=10.34.0.234
RemoteUDPPort=8004
RemoteSignallingIPAddress=10.34.0.234
RemoteSignallingPort=5060
RemoteMediaIPAddress=10.34.0.234
RemoteMediaPort=8004
SRTP = off
TextRelay = off
Fallback Icpif=0
Fallback Loss=0
Fallback Delay=0
RoundTripDelay=0 ms
SelectedQoS=best-effort
tx_DtmfRelay=inband-voice
FastConnect=FALSE

AnnexE=FALSE

Separate H245 Connection=FALSE

H245 Tunneling=FALSE

SessionProtocol=sipv2
ProtocolCallId=d8a4cb781fdf1da6ed2cd608cc834b80@10.34.0.234
SessionTarget=10.34.0.234
OnTimeRvPlayout=0
GapFillWithSilence=0 ms
GapFillWithPrediction=0 ms
GapFillWithInterpolation=0 ms
GapFillWithRedundancy=0 ms
HiWaterPlayoutDelay=0 ms
LoWaterPlayoutDelay=0 ms
ReceiveDelay=0 ms
LostPackets=0
EarlyPackets=0
LatePackets=0
VAD = disabled
CoderTypeRate=g711ulaw
CodecBytes=160
cvVoIPCallHistoryIcpif=0
MediaSetting=flow-around
CallerName=
CallerIDBlocked=False
OriginalCallingNumber=81342299
OriginalCallingOctet=0x0
OriginalCalledNumber=3950
OriginalCalledOctet=0x0
OriginalRedirectCalledNumber=
OriginalRedirectCalledOctet=0x80
TranslatedCallingNumber=81342299
TranslatedCallingOctet=0x0
TranslatedCalledNumber=3950
TranslatedCalledOctet=0x0
TranslatedRedirectCalledNumber=
TranslatedRedirectCalledOctet=0x80
GwReceivedCalledNumber=3950
GwReceivedCalledOctet3=0x0
GwReceivedCallingNumber=81342299
GwReceivedCallingOctet3=0x0
GwReceivedCallingOctet3a=0x80
MediaInactiveDetected=no
MediaInactiveTimestamp=
MediaControlReceived=
LongDurationCallDetected=no
LongDurationCallTimerStamp=
LongDurationCallDuration=
Username=81342299
Router#

Ответить | Правка | ^ к родителю #7 | Наверх | Cообщить модератору

10. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 18:26 
Release Source 2 - Calling party located in the VoIP network - тут аналогично, тоже получается все в порядке?
Ответить | Правка | ^ к родителю #9 | Наверх | Cообщить модератору

11. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от mdenisov (ok) on 28-Мрт-13, 18:26 
Опять Release Source 2, смотрите на предыдущем устройстве почему оно отбило вызов.
Ответить | Правка | ^ к родителю #9 | Наверх | Cообщить модератору

13. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 18:29 
> Опять Release Source 2, смотрите на предыдущем устройстве почему оно отбило вызов.

т.е. sip шлюз которое? спасибо ОГРОМНОЕ за помощь, буду рыть далее в этом направлении!

Ответить | Правка | ^ к родителю #11 | Наверх | Cообщить модератору

14. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от imbir email(ok) on 28-Мрт-13, 18:32 
>> Опять Release Source 2, смотрите на предыдущем устройстве почему оно отбило вызов.
> т.е. sip шлюз которое? спасибо ОГРОМНОЕ за помощь, буду рыть далее в
> этом направлении!

здесь просто sip шлюз qtech, не сильно я разбираюсь в нем(как и во всем другом voip=)), дебаг на нем так просто не посмотришь, или я просто не умею, сейчас буду разбираться. Огромное спасибо еще раз!

Ответить | Правка | ^ к родителю #13 | Наверх | Cообщить модератору

15. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от mdenisov (ok) on 28-Мрт-13, 18:34 
Не за что. Я такой шлюз никогда не видел, так что не подскажу. Из предполагаемых причин - несоответствие кодеков и хотение шлюзом early offer от циски.
Ответить | Правка | ^ к родителю #14 | Наверх | Cообщить модератору

16. "VoIP Cisco, коллеги подскажите куда смотреть"  +/
Сообщение от orangeant on 01-Апр-13, 13:43 
Еще одна возможная причина:

Mar 28 13:47:05.734: ISDN Se0/2/0:15 Q931: TX -> SETUP pd = 8  callref = 0x0092
Mar 28 13:47:05.746: ISDN Se0/2/0:15 Q931: RX <- SETUP_ACK pd = 8  callref = 0x8092

От циски пришел SETUP без IE Sending Complete -> станция считает, что будет исп-ся overlap
От станции пришел SETUP_ACK - она ждет дополнительные цифры номера.
Дальше новых цифр нет, call proceeding тоже нет -> сброс вызова

Ответить | Правка | ^ к родителю #5 | Наверх | Cообщить модератору

Архив | Удалить

Рекомендовать для помещения в FAQ | Индекс форумов | Темы | Пред. тема | След. тема




Партнёры:
PostgresPro
Inferno Solutions
Hosting by Hoster.ru
Хостинг:

Закладки на сайте
Проследить за страницей
Created 1996-2024 by Maxim Chirkov
Добавить, Поддержать, Вебмастеру