Comprobar PVDM2-48 si esta correcta

marcosroma21 · ‎08-19-2013

Buenos días, resulta que tengo montada una tarjeta VIC2-4FXO y no me funciona a la hora de realizar llamadas, tuvimos tormenta por la zona y dejo de funcionar, con lo que compre una nueva tarjeta, pero sigue sin hacerlo y tengo la duda si la que se ha podido averiar es el DSP PVDM2-48.

Como puedo comprobar el modulo DSP PVDM2-48 esta funcionando correctamente??

VoIP#sh diag

Slot 0:

C2851 Motherboard with 2GE and integrated VPN Port adapter, 2 ports

Port adapter is analyzed

Port adapter insertion time unknown

EEPROM contents at hardware discovery:

PCB Serial Number : FOC10233C3Q

Hardware Revision : 1.0

Top Assy. Part Number : 800-26922-02

Board Revision : A0

Deviation Number : 0

Fab Version : 03

RMA Test History : 00

RMA Number : 0-0-0-0

RMA History : 00

Processor type : 87

Hardware date code : 20060612

Chassis Serial Number : FCZ102770MF

Chassis MAC Address : 0018.19d1.04e8

MAC Address block size : 32

CLEI Code : COM3E00BRA

Product (FRU) Number : CISCO2851

Part Number : 73-8480-04

Version Identifier : V03

EEPROM format version 4

EEPROM contents (hex):

0x00: 04 FF C1 8B 46 4F 43 31 30 32 33 33 43 33 51 40

0x10: 03 E9 41 01 00 C0 46 03 20 00 69 2A 02 42 41 30

0x20: 88 00 00 00 00 02 03 03 00 81 00 00 00 00 04 00

0x30: 09 87 83 01 32 19 C4 C2 8B 46 43 5A 31 30 32 37

0x40: 37 30 4D 46 C3 06 00 18 19 D1 04 E8 43 00 20 C6

0x50: 8A 43 4F 4D 33 45 30 30 42 52 41 CB 8F 43 49 53

0x60: 43 4F 32 38 35 31 20 20 20 20 20 20 82 49 21 20

0x70: 04 89 56 30 33 20 D9 02 40 C1 FF FF FF FF FF FF

PVDM Slot 0:

48-channel (G.711) Voice/Fax PVDMII DSP SIMM PVDM daughter card

Hardware Revision : 4.0

Part Number : 73-8540-05

Board Revision : A0

Deviation Number : 0

Fab Version : 04

PCB Serial Number : FOC10217P41

RMA Test History : 00

RMA Number : 0-0-0-0

RMA History : 00

Processor type : 00

Product (FRU) Number : PVDM2-48

Version Identifier : V01

EEPROM format version 4

EEPROM contents (hex):

0x00: 04 FF 40 03 ED 41 04 00 82 49 21 5C 05 42 41 30

0x10: 88 00 00 00 00 02 04 C1 8B 46 4F 43 31 30 32 31

0x20: 37 50 34 31 03 00 81 00 00 00 00 04 00 09 00 CB

0x30: 88 50 56 44 4D 32 2D 34 38 89 56 30 31 20 D9 02

0x40: 40 C1 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

WIC Slot 0:

E1 (1 port) Multi-Flex Trunk WAN daughter card

Hardware revision 1.0 Board revision B0

Serial number 34800001 Part number 800-04475-04

FRU Part Number VWIC-1MFT-E1=

Test history 0x0 RMA number 00-00-00

Connector type PCI

EEPROM format version 1

EEPROM contents (hex):

0x20: 01 21 01 00 02 13 01 81 50 11 7B 04 00 00 00 00

0x30: 58 00 00 00 06 04 26 00 FF FF FF FF FF FF FF FF

WIC Slot 1:

2nd generation - FXO Voice daughter card (4 port)

Hardware Revision : 5.0

Top Assy. Part Number : 800-33585-01

Board Revision : E0

Deviation Number : 0-0

Fab Version : 04

PCB Serial Number : FOC16473VFC

RMA Test History : 00

RMA Number : 0-0-0-0

RMA History : 00

Product (FRU) Number : VIC2-4FXO

Version Identifier : V04

CLEI Code : CNUIAXJAAC

EEPROM format version 4

EEPROM contents (hex):

0x00: 04 FF 40 00 4C 41 05 00 C0 46 03 20 00 83 31 01

0x10: 42 45 30 80 00 00 00 00 02 04 C1 8B 46 4F 43 31

0x20: 36 34 37 33 56 46 43 03 00 81 00 00 00 00 04 00

0x30: CB 89 56 49 43 32 2D 34 46 58 4F 89 56 30 34 20

0x40: C6 8A 43 4E 55 49 41 58 4A 41 41 43 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

WIC Slot 2:

VIC2 - BRI-NT/TE Voice daughter card (2 port)

Hardware Revision : 1.1

Top Assy. Part Number : 800-21861-03

Board Revision : B0

Deviation Number : 0-0

Fab Version : 02

PCB Serial Number : FOC12460JXY

RMA Test History : 00

RMA Number : 0-0-0-0

RMA History : 00

Product (FRU) Number : VIC2-2BRI-NT/TE

Version Identifier : V02

CLEI Code : CNUIAVZAAA

EEPROM format version 4

EEPROM contents (hex):

0x00: 04 FF 40 00 51 41 01 01 C0 46 03 20 00 55 65 03

0x10: 42 42 30 80 00 00 00 00 02 02 C1 8B 46 4F 43 31

0x20: 32 34 36 30 4A 58 59 03 00 81 00 00 00 00 04 00

0x30: CB 8F 56 49 43 32 2D 32 42 52 49 2D 4E 54 2F 54

0x40: 45 89 56 30 32 20 C6 8A 43 4E 55 49 41 56 5A 41

0x50: 41 41 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

WIC Slot 3:

VIC2 - BRI-NT/TE Voice daughter card (2 port)

Hardware Revision : 1.1

Top Assy. Part Number : 800-33406-01

Board Revision : D0

Deviation Number : 0-0

Fab Version : 03

PCB Serial Number : FOC162435LG

RMA Test History : 00

RMA Number : 0-0-0-0

RMA History : 00

Product (FRU) Number : VIC2-2BRI-NT/TE

Version Identifier : V03

CLEI Code : CNUIAVZAAB

EEPROM format version 4

EEPROM contents (hex):

0x00: 04 FF 40 00 51 41 01 01 C0 46 03 20 00 82 7E 01

0x10: 42 44 30 80 00 00 00 00 02 03 C1 8B 46 4F 43 31

0x20: 36 32 34 33 35 4C 47 03 00 81 00 00 00 00 04 00

0x30: CB 8F 56 49 43 32 2D 32 42 52 49 2D 4E 54 2F 54

0x40: 45 89 56 30 33 20 C6 8A 43 4E 55 49 41 56 5A 41

0x50: 41 42 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

Adrian Saavedra · ‎08-20-2013

Hola Marcos,

Puedes utilizar el comando show voice dsp group all. Con este comando puedes validar el estado de un DSP. También te paso un link a un buen documento donde hacen una descripción de la salida de este comando:

https://supportforums.cisco.com/docs/DOC-29151

También existen otros documentos de troubleshooting (más complejos) para los DSPs y que generalmente son realizados por el TAC. Algunos docs son:

http://docwiki.cisco.com/wiki/Cisco_IOS_Voice_Troubleshooting_and_Monitoring_--_Digital_Signal_Processor_Troubleshooting

https://supportforums.cisco.com/docs/DOC-14060

http://docwiki.cisco.com/wiki/Cisco_IOS_Voice_Troubleshooting_and_Monitoring_--_Digital_Signal_Processor_Troubleshooting

http://www.cisco.com/image/gif/paws/116078/116078-technologies_technote-commandreference-00.pdf

También es bueno conectar un teléfono analógico directamente al rack/regleta donde llegan las líneas de la calle y revisar si hay tono. A veces es bueno revisar que las líneas tengan protecciones y que estén en buen estado.

Espero que sea de utilidad, si es así recuerda calificar el post.

Suerte,

- Adrián.

marcosroma21 · ‎08-22-2013

Buenos días, lo primero de todo, muchas gracias por su respuesta.

La verdad es que estoy un poco perdido con este problema, los dsp parecen funcionar correctamente.

VoIP-AP41# show voice dsp group all

DSP groups on slot 0:

dsp 1:

State: UP, firmware: 9.2.2

Max signal/voice channel: 16/16

Max credits: 240

num_of_sig_chnls_allocated: 16

Transcoding channels allocated: 0

Group: FLEX_GROUP_VOICE, complexity: FLEX

Shared credits: 225, reserved credits: 0

Signaling channels allocated: 16

Voice channels allocated: 1

Credits used: 15

Voice channels:

Ch01: voice port: 0/2/0.1, codec: g711ulaw, credits allocated: 15 --->>(((Esto es una llamada atraves de una tarjeta RDSI que tengo montada)))

dsp 2:

State: UP, firmware: 9.2.2

Max signal/voice channel: 16/16

Max credits: 240

num_of_sig_chnls_allocated: 6

Transcoding channels allocated: 0

Group: FLEX_GROUP_VOICE, complexity: FLEX

Shared credits: 240, reserved credits: 0

Signaling channels allocated: 6

Voice channels allocated: 0

Credits used: 0

dsp 3:

State: UP, firmware: 9.2.2

Max signal/voice channel: 16/16

Max credits: 240

num_of_sig_chnls_allocated: 0

Transcoding channels allocated: 0

Group: FLEX_GROUP_VOICE, complexity: FLEX

Shared credits: 240, reserved credits: 0

Signaling channels allocated: 0

Voice channels allocated: 0

Credits used: 0

DSP groups on slot 1:

This command is not applicable to slot 1

DSP groups on slot 2:

This command is not applicable to slot 2

0 DSP resource allocation failure

El problema que tengo es que hace como dos meses y como ya comente dejo de funcionar solo las llamadas que se realizaban con la

tarjeta VIC2-4FXO.

Sin tener ningún cambio de configuración puse la nueva tarjeta y seguía sin funcionar, es por lo que me resulta todo tan extraño.

(((Por si tuviera algo que ver en este mismo slot donde tengo VIC2-4FXO probé otra tarjeta VWIC2-1MFT-T1-E1 y me funciono sin problemas,

entiendo que no dejara ninguna configuración residual y que tenga que ser borrada, esto me parecería raro, pero ya no se por donde

tirar)))

La línea a la que tengo conectada VIC2-4FXO, funciona sin problemas, y me explico, si conecto un domo puedo realizar y recibir llamadas sin problemas.

Dejo unos debug para ver si me podéis ayudar.

VoIP#debug voice hpi all

voip hpi all debugging is ON

009234: Aug 22 07:32:01.932: //-1/xxxxxxxxxxxx/HPI/[]/hpi_dspmgr_open:

009235: Aug 22 07:32:01.932: hpi [] DSP [0/0x0] S_HPI_CLOSED(0) E_HPI_DSPRM_OPEN/SET_CODEC(58)

009236: Aug 22 07:32:01.932: HPI/[]/hpi_dspmgr_open:

tdm_info=0x00000000, tsp_info=0x47721A90, dsp_sdb=0x477225E4

call_id=4294967295, dsprm_type=25

009237: Aug 22 07:32:01.932: hpi [] DSP [0/0x0] -> S_HPI_CLOSED(0)

009238: Aug 22 07:32:01.932: //-1/C53336CD8F3E/HPI/[]/hpi_dspmgr_open:

Allocated DSP resource: ; dsp_intf=0x47F8F840 hpi_cdb=0x480F550C ret=1

009239: Aug 22 07:32:01.932: //-1/C53336CD8F3E/HPI/[]/hpi_dspmgr_open: exit@13416

009240: Aug 22 07:32:01.932: //-1/C53336CD8F3E/HPI/[]/hpi_dspmgr_update_callid:

009241: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_init:

009242: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_init:

Open channel

Packet details:

Packet Length=16, Channel Id=37, Packet Id=74

ALawULawSelect=A Law, Associated SignalingChannel=37

Timeslot=65535, SerialPort=65535

009243: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009244: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] S_HPI_CLOSED(0) E_HPI_DSP_OPEN_VOICE_CHANNEL(11)

009245: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009246: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009247: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_stop_service:

009248: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_idle_service:

009249: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_idle_service:

Packet details:

Packet Length=8, Channel Id=37, Packet Id=68

009250: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009251: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSP_IDLE_MODE(5)

009252: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009253: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009254: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_open_service:

009255: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_open_service:

Setting codec g711ulaw

009256: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_set_codec:

009257: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSPRM_OPEN/SET_CODEC(58)

009258: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009259: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_voice_config_params:

009260: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_encap_config:

009261: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_encap_config:

RTP information

Packet details:

Packet Length=38, Channel Id=37, Packet Id=92 TransportProtocol=2

Tx SSRC=0x00, Rx SSRC=0x00, Tx VPXCC=0x0, Rx VPXCC=0x0

SID support=1, TSE payload=101, Sequence number start=0x0, Redundancy=0

ClearChannel payload type=0, Fax payload type=0, ALaw PCM switchover=0

MuLaw PCM switchover=0, DTMF payload type=0, NTE receive payload type=101

009262: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009263: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSP_ENC_CONFIG(29)

009264: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009265: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009266: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_set_playout_config:

009267: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_set_playout_config:

Packet details:

Packet Length=18, Channel Id=37, Packet Id=76

Mode=1, Initial=60(ms), Min=40(ms), Max=250(ms), fax_nom=300(ms)

009268: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009269: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSP_SET_VOICE_PLAYOUT_DELAY(13)

009270: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009271: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009272: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_info_field_size_config:

009273: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_info_field_size_config:

Info field size=160

009274: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_idle_code_det:

009275: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_idle_code_det:

Packet details:

Packet Length=14, Channel Id=37, Packet Id=116

Enable=FALSE, Code=0x00000000, Duration=6000(ms)

009276: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009277: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSP_IDLE_CODE_CONTROL (50)

009278: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009279: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009280: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_echo_cancel:

009281: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_echo_cancel:

Packet details:

Packet Length=22, Channel Id=37, Packet Id=66

Flags=0x00000000, NLP Threshold=-21(dB), Suppressor Coverage=7(s), Attenuation=2(s), fx_mode=0

009282: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009283: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSP_ECHO_CANCELLER_CONTROL(3)

009284: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009285: Aug 22 07:32:01.940: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009286: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_cng_config:

009287: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_start_service:

009288: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_voice_mode:

009289: Aug 22 07:32:01.940: //1434/C53336CD8F3E/HPI/[]/hpi_voice_mode:

Packet details:

Packet Length=34, Channel Id=37, Packet Id=73

CodingType=1, Voice field size=160(bytes), VAD Flag=0,

Echo Length=8(ms), ComfortNoise=enable, Inband detect=0x00000001,

DigitRelay=0, AGC Flag=0, ECAN TestGroup=0,

ECAN TestNumber=0, DynamicPayload=0

009290: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009291: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSP_VOICE_MODE(10)

009292: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009293: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_VOICE(2)

009294: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_start_service: exit@3400

009295: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_voice_config_params:

009296: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_pak_suppress:

009297: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_pak_suppress:

Start packet suppression

Packet details:

Packet Length=10, Channel Id=37, Packet Id=106

Mode=0

009298: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009299: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_VOICE(2) E_HPI_DSP_GEN_PACKET_CONTROL(41)

009300: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009301: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_VOICE(2)

009302: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_voice_config_params:

009303: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_echo_cancel:

009304: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_echo_cancel:

Packet details:

Packet Length=22, Channel Id=37, Packet Id=66

Flags=0x00000000, NLP Threshold=-21(dB), Suppressor Coverage=7(s), Attenuation=2(s), fx_mode=0

009305: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009306: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_VOICE(2) E_HPI_DSP_ECHO_CANCELLER_CONTROL(3)

009307: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009308: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_VOICE(2)

009309: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_set_gain:

009310: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_set_gain:

Packet details:

Packet Length=12, Channel Id=37, Packet Id=91

Gain: In=0.0(dB), Out=0.0(dB)

009311: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009312: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_VOICE(2) E_HPI_DSP_SET_GAINS(28)

009313: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009314: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_VOICE(2)

009315: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_agc_control:

009316: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_agc_control:

Packet details:

Packet Length=14, Channel Id=37, Packet Id=98

AGC control: In= off, 0(dBm), Out=off, 0(dBm)

009317: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009318: Aug 22 07:32:01.944: invalid event -1 of type 0

009319: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009320: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_digit_relay_config:

009321: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_digit_relay_config:

Packet details:

Packet Length=10, Channel Id=37, Packet Id=85

Mode=2

009322: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009323: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_VOICE(2) E_HPI_DSP_DIGIT_RELAY(22)

009324: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009325: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_VOICE(2)

009326: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_vad_enable:

009327: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_vad_enable:

Packet details:

Packet Length=8, Channel Id=37, Packet Id=77

VAD=0 (OFF)

009328: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009329: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_VOICE(2) E_HPI_DSP_VAD_DISABLE(14)

009330: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009331: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_VOICE(2)

009332: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_voice_config_params:

009333: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_dtmf_mode:

009334: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_dtmf_mode:

Packet details:

Packet Length=10, Channel Id=37, Packet Id=65

dtmf_or_mf=0

009335: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009336: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_VOICE(2) E_HPI_DSP_DTMF_MODE(2)

009337: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009338: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_DTMF(3)

009339: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_inband_tone_on:

009340: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_inband_tone_on:

CPTone ON

Packet details:

Packet Length=38, Channel Id=37, Packet Id=72

Tone ID=4, Num of frequencies=2, Frequency(hz)=350/440, Power(dBm0)=6942/6942, Direction=1

Cadence(ms)=65535/0 0/0 0/0 0/0

009341: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009342: Aug 22 07:32:01.944: hpi [50/0/19.1] DSP [37/0x25] S_HPI_DTMF(3) E_HPI_DSP_CP_TONE_ON(9)

009343: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009344: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009345: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009346: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009347: Aug 22 07:32:01.944: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=0(0), RTP Timestamp=00012ECF88

009348: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009349: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009350: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009351: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=0(0), Duration=100(ms)

009352: Aug 22 07:32:01.948: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_DTMF(3)

009353: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_inband_tone_off:

009354: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_inband_tone_off:

CPTone OFF

Packet details:

Packet Length=8, Channel Id=37, Packet Id=71

009355: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009356: Aug 22 07:32:01.948: hpi [50/0/19.1] DSP [37/0x25] S_HPI_DTMF(3) E_HPI_DSP_CP_TONE_OFF(8)

009357: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009358: Aug 22 07:32:01.948: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_DTMF(3)

009359: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_voice_config_params:

009360: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_dtmf_mode:

009361: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_dtmf_mode:

Packet details:

Packet Length=10, Channel Id=37, Packet Id=65

dtmf_or_mf=0

009362: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009363: Aug 22 07:32:01.948: hpi [50/0/19.1] DSP [37/0x25] S_HPI_DTMF(3) E_HPI_DSP_DTMF_MODE(2)

009364: Aug 22 07:32:01.948: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009365: Aug 22 07:32:01.948: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_DTMF(3)

009366: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009367: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009368: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009369: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=6(6), RTP Timestamp=00012F4C88

009370: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009371: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009372: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009373: Aug 22 07:32:02.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=6(6), Duration=100(ms)

009374: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009375: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009376: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009377: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=5(5), RTP Timestamp=00012FC988

009378: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009379: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009380: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009381: Aug 22 07:32:02.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=5(5), Duration=100(ms)

009382: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009383: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009384: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009385: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=5(5), RTP Timestamp=0001304688

009386: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009387: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009388: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009389: Aug 22 07:32:02.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=5(5), Duration=100(ms)

009390: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009391: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009392: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009393: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=9(9), RTP Timestamp=000130C388

009394: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009395: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009396: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009397: Aug 22 07:32:02.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=9(9), Duration=100(ms)

009398: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009399: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009400: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009401: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=9(9), RTP Timestamp=0001314088

009402: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009403: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009404: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009405: Aug 22 07:32:02.948: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=9(9), Duration=100(ms)

009406: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009407: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009408: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009409: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=9(9), RTP Timestamp=000131BD88

009410: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009411: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009412: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009413: Aug 22 07:32:03.148: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=9(9), Duration=100(ms)

009414: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009415: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009416: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009417: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=5(5), RTP Timestamp=0001323A88

009418: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009419: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009420: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009421: Aug 22 07:32:03.348: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=5(5), Duration=100(ms)

009422: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009423: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009424: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009425: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=0(0), RTP Timestamp=000132B788

009426: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009427: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009428: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009429: Aug 22 07:32:03.548: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=0(0), Duration=100(ms)

009430: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009431: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009432: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=192

009433: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message: DTMF digit notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=192

Digit=8(8), RTP Timestamp=0001333488

009434: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

009435: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009436: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

Packet details:

Packet Id=203

009437: Aug 22 07:32:03.748: //1434/C53336CD8F3E/HPI/[]/hpi_receive_message:

DTMF digit end notification

Packet details:

Packet Length=256, Channel Id=37, Packet Id=203

Digit=8(8), Duration=100(ms)

009438: Aug 22 07:32:03.752: //1434/C53336CD8F3E/HPI/[]/hpi_get_multimedia_capabilities:

009439: Aug 22 07:32:03.752: //1434/C53336CD8F3E/HPI/[]/hpi_get_multimedia_capabilities: exit@6362

009440: Aug 22 07:32:03.756: //-1/xxxxxxxxxxxx/HPI/[]/hpi_dspmgr_open:

009441: Aug 22 07:32:03.756: hpi [] DSP [0/0x0] S_HPI_CLOSED(0) E_HPI_DSPRM_OPEN/SET_CODEC(58)

009442: Aug 22 07:32:03.756: HPI/[]/hpi_dspmgr_open:

tdm_info=0x00000000, tsp_info=0x00000000, dsp_sdb=0x46416220

call_id=1435, dsprm_type=32

009443: Aug 22 07:32:03.756: hpi [] DSP [0/0x0] -> S_HPI_CLOSED(0)

009444: Aug 22 07:32:03.756: //1435/C53336CD8F3E/HPI/[0/1:1]/hpi_dspmgr_open:

Allocated DSP resource: 0/1:1; dsp_intf=0x47F9364C hpi_cdb=0x4809348C ret=1

009445: Aug 22 07:32:03.756: //1435/C53336CD8F3E/HPI/[0/1:1]/hpi_dspmgr_open: exit@13416

009446: Aug 22 07:32:13: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId C53336CDA3311E38F3EEEC98816AA83, SetupTime 09:32:03.756 Madrid Thu Aug 22 2013, PeerAddress 0655999508, PeerSubAddress , DisconnectCause 10 , DisconnectText normal call clearing (16), ConnectTime 09:32:13.756 Madrid Thu Aug 22 2013, DisconnectTime 09:32:13.756 Madrid Thu Aug 22 2013, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

009447: Aug 22 07:32:13: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEATURE_VSA=fn:TWC,ft:08/22/2013 09:32:03.752,cgn:4717,cdn:0655999508,frs:0,fid:1603,fcid:C53336CDA3311E38F3EEEC98816AA83,legID:59B

009448: Aug 22 07:32:13.756: //1435/C53336CD8F3E/HPI/[0/1:1]/hpi_dspmgr_close:

009449: Aug 22 07:32:13.756: //1435/C53336CD8F3E/HPI/[0/1:1]/hpi_dspmgr_close:

009450: Aug 22 07:32:13.756: //1435/C53336CD8F3E/HPI/[0/1:1]/hpi_dsprm_callback:

009451: Aug 22 07:32:13.756: hpi [0/1/0 (1435)] DSP [1/0x1] -> S_HPI_CLOSED(0)

009452: Aug 22 07:32:13.756: hpi [0/1/0 (1435)] DSP [1/0x1] -> S_HPI_CLOSED(0)

009453: Aug 22 07:32:13.756: //-1/xxxxxxxxxxxx/HPI/[]/hpi_dspmgr_close_cleanup:

009454: Aug 22 07:32:13.756: //-1/xxxxxxxxxxxx/HPI/[]/hpi_dspmgr_close_cleanup:

009455: Aug 22 07:32:13: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId C53336CDA3311E38F3EEEC98816AA83, SetupTime 09:32:01.932 Madrid Thu Aug 22 2013, PeerAddress 4717, PeerSubAddress , DisconnectCause 10 , DisconnectText normal call clearing (16), ConnectTime 09:32:13.772 Madrid Thu Aug 22 2013, DisconnectTime 09:32:13.772 Madrid Thu Aug 22 2013, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

009456: Aug 22 07:32:13: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEATURE_VSA=fn:TWC,ft:08/22/2013 09:32:01.932,cgn:4717,cdn:,frs:0,fid:1602,fcid:C53336CDA3311E38F3EEEC98816AA83,legID:59A

009457: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_stop_service:

009458: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_idle_service:

009459: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_idle_service:

Packet details:

Packet Length=8, Channel Id=37, Packet Id=68

009460: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009461: Aug 22 07:32:13.772: hpi [50/0/19.1] DSP [37/0x25] S_HPI_DTMF(3) E_HPI_DSP_IDLE_MODE(5)

009462: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009463: Aug 22 07:32:13.772: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_IDLE(1)

009464: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_close_service:

009465: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_close_service:

Close Channel

Packet details:

Packet Length=8, Channel Id=37, Packet Id=75

009466: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_send_message:

009467: Aug 22 07:32:13.772: hpi [50/0/19.1] DSP [37/0x25] S_HPI_IDLE(1) E_HPI_DSP_CLOSE_VOICE_CHANNEL(12)

009468: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/caplog_hpi_msg_log:

009469: Aug 22 07:32:13.772: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_CLOSED(0)

009470: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_dspmgr_close:

009471: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_dspmgr_close:

009472: Aug 22 07:32:13.772: //1434/C53336CD8F3E/HPI/[]/hpi_dsprm_callback:

009473: Aug 22 07:32:13.772: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_CLOSED(0)

009474: Aug 22 07:32:13.776: hpi [50/0/19.1] DSP [37/0x25] -> S_HPI_CLOSED(0)

009475: Aug 22 07:32:13.776: //-1/xxxxxxxxxxxx/HPI/[]/hpi_dspmgr_close_cleanup:

009476: Aug 22 07:32:13.776: //-1/xxxxxxxxxxxx/HPI/[]/hpi_dspmgr_close_cleanup:

VoIP#debug voip ccapi all

voip ccapi all debugging is on

009919: Aug 22 08:29:12.908: //-1/C2389EF18F7E/CCAPI/cc_api_display_ie_subfields:

cc_api_call_setup_ind_common:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=4717

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=0 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

009920: Aug 22 08:29:12.912: //-1/C2389EF18F7E/CCAPI/cc_api_call_setup_ind_common:

Interface=0x476CEA78, Call Info(

Calling Number=4717,(Calling Name=)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=(TON=Unknown, NPI=Unknown),

Calling Translated=FALSE, Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE,

Incoming Dial-peer=20027, Progress Indication=ORIGINATING SIDE IS NON ISDN(3), Calling IE Present=TRUE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, CLID Transparent=FALSE), Call Id=-1

009921: Aug 22 08:29:12.912: //-1/C2389EF18F7E/CCAPI/cc_api_call_setup_ind_common:

Interface Type=26, Protocol=0

009922: Aug 22 08:29:12.912: //-1/C2389EF18F7E/CCAPI/ccCheckClipClir:

In: Calling Number=4717(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

009923: Aug 22 08:29:12.912: //-1/C2389EF18F7E/CCAPI/ccCheckClipClir:

Calling Party Number Is User Provided

009924: Aug 22 08:29:12.912: //-1/C2389EF18F7E/CCAPI/ccCheckClipClir:

Out: Calling Number=4717(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

009925: Aug 22 08:29:12.912: //1461/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

Total Call Count=0, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

009926: Aug 22 08:29:12.912: //1461/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

Total Call Count=1

009927: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

009928: Aug 22 08:29:12.912: :cc_get_feature_vsa malloc success

009929: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

009930: Aug 22 08:29:12.912: cc_get_feature_vsa count is 1

009931: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

009932: Aug 22 08:29:12.912: :FEATURE_VSA attributes are: feature_name:0,fearture_time:1187276536,feature_id:1635

009933: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_init_call_entry:

009934: Aug 22 08:29:12.912: call_init_entry calle is 464226D0,callid is 1461

009935: Aug 22 08:29:12.912: //1461/C2389EF18F7E/CCAPI/cc_insert_guid_pod_entry:

Incoming=TRUE, Call Id=1461

009936: Aug 22 08:29:12.912: //1461/C2389EF18F7E/CCAPI/cc_api_call_setup_ind_common:

Set Up Event Sent;

Call Info(Calling Number=4717(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=(TON=Unknown, NPI=Unknown))

009937: Aug 22 08:29:12.912: //1461/C2389EF18F7E/CCAPI/cc_process_call_setup_ind:

Event=0x46434548

009938: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:

Matching Parameters; Called Number=, Call Transfer Consult Id=

009939: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_search:

Searching Node;

Called Number=, Call Transfer Consult Id=

009940: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

009941: Aug 22 08:29:12.912: Comparing SSOpCode; 0x50 and 0x0

009942: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_match_compare:

009943: Aug 22 08:29:12.912: Comparing SSOpCode; 0x12 and 0x0

009944: Aug 22 08:29:12.912: //-1/xxxxxxxxxxxx/CCAPI/cc_setupind_registration_lookup:

No Matching Node

009945: Aug 22 08:29:12.912: //1461/C2389EF18F7E/CCAPI/ccCallSetContext:

Context=0x47DCC540

009946: Aug 22 08:29:12.912: //1461/C2389EF18F7E/CCAPI/cc_process_call_setup_ind:

>>>>CCAPI handed cid 1461 with tag 20027 to app "_ManagedAppProcess_Default"

009947: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/ccCallSetupAck:

Call Id=1461

009948: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/cc_api_set_transfer_info:

Transfer Number=, Transfer Reason=0x0

009949: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/cc_api_set_transfer_info:

Call Transfer Reset

009950: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/ccGenerateToneInfo:

Stop Tone On Digit=TRUE, Tone=Dial Tone,

Tone Direction=Network, Params=0x0, Call Id=1461

009951: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/ccSetDigitTimeouts:

Initial Digit Timeout=-1000(ms), Inter Digit Timeout=-1000(ms)

009952: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/ccSetDigitTimeouts:

Call Entry(Inter Digit Timeout=10000(ms), Initial Digit Timeout=10000(ms))

009953: Aug 22 08:29:12.916: //1461/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:

(callID=0x5B5, digit_event=0x1, enable=TRUE, consume=FALSE)

009954: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=1461

009955: Aug 22 08:29:12.916: //1461/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x476CEA78, callID=0x5B5, disp=0, digit_event=0x1, enable=TRUE, consume=FALSE)

009956: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x476CEA78, Call Id=1461

009957: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/cc_api_call_report_digits_done:

Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))

009958: Aug 22 08:29:12.916: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=0, DigitBeginFlags=0x0,

Rtp Timestamp=0x1427E98, Rtp Expiration=0x0

009959: Aug 22 08:29:12.916: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009960: Aug 22 08:29:12.920: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=0, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009961: Aug 22 08:29:12.920: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009962: Aug 22 08:29:12.920: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009963: Aug 22 08:29:13.116: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=6, DigitBeginFlags=0x0,

Rtp Timestamp=0x142FB98, Rtp Expiration=0x0

009964: Aug 22 08:29:13.116: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009965: Aug 22 08:29:13.116: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=6, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009966: Aug 22 08:29:13.116: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009967: Aug 22 08:29:13.116: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009968: Aug 22 08:29:13.316: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=5, DigitBeginFlags=0x0,

Rtp Timestamp=0x1437898, Rtp Expiration=0x0

009969: Aug 22 08:29:13.316: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009970: Aug 22 08:29:13.316: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=5, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009971: Aug 22 08:29:13.316: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009972: Aug 22 08:29:13.316: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009973: Aug 22 08:29:13.516: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=5, DigitBeginFlags=0x0,

Rtp Timestamp=0x143F598, Rtp Expiration=0x0

009974: Aug 22 08:29:13.516: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009975: Aug 22 08:29:13.516: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=5, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009976: Aug 22 08:29:13.516: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009977: Aug 22 08:29:13.516: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009978: Aug 22 08:29:13.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=9, DigitBeginFlags=0x0,

Rtp Timestamp=0x1447298, Rtp Expiration=0x0

009979: Aug 22 08:29:13.716: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009980: Aug 22 08:29:13.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=9, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009981: Aug 22 08:29:13.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009982: Aug 22 08:29:13.716: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009983: Aug 22 08:29:13.916: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=9, DigitBeginFlags=0x0,

Rtp Timestamp=0x144EF98, Rtp Expiration=0x0

009984: Aug 22 08:29:13.916: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009985: Aug 22 08:29:13.916: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=9, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009986: Aug 22 08:29:13.916: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009987: Aug 22 08:29:13.916: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009988: Aug 22 08:29:14.116: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=9, DigitBeginFlags=0x0,

Rtp Timestamp=0x1456C98, Rtp Expiration=0x0

009989: Aug 22 08:29:14.116: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009990: Aug 22 08:29:14.116: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=9, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009991: Aug 22 08:29:14.116: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009992: Aug 22 08:29:14.116: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009993: Aug 22 08:29:14.316: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=5, DigitBeginFlags=0x0,

Rtp Timestamp=0x145E998, Rtp Expiration=0x0

009994: Aug 22 08:29:14.316: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009995: Aug 22 08:29:14.316: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=5, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

009996: Aug 22 08:29:14.316: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

009997: Aug 22 08:29:14.316: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

009998: Aug 22 08:29:14.516: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=0, DigitBeginFlags=0x0,

Rtp Timestamp=0x1466698, Rtp Expiration=0x0

009999: Aug 22 08:29:14.516: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

010000: Aug 22 08:29:14.516: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=0, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

010001: Aug 22 08:29:14.516: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

010002: Aug 22 08:29:14.516: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

010003: Aug 22 08:29:14.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_begin:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=8, DigitBeginFlags=0x0,

Rtp Timestamp=0x146E398, Rtp Expiration=0x0

010004: Aug 22 08:29:14.716: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_begin:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

010005: Aug 22 08:29:14.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Destination Interface=0x0, Destination Mask=0x3, Destination Call Id=-1,

Source Call Id=1461, Digit=8, Duration=100,

Xrule Calling Tag=0, Xrule Called Tag=0, Digit Tone Mode=DTMF

010006: Aug 22 08:29:14.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_digit_end:

Call Entry(Handoff Depth=0)

010007: Aug 22 08:29:14.716: //-1/xxxxxxxxxxxx/CCAPI/cc_api_call_digit_end:

CALL_ERROR_INFORMATIONAL; Call Entry Is Not Found

010008: Aug 22 08:29:14.716: //1461/xxxxxxxxxxxx/CCAPI/ccCallReportDigits:

(callID=0x5B5, digit_event=0x0, enable=FALSE, consume=FALSE)

010009: Aug 22 08:29:14.716: //1461/C2389EF18F7E/CCAPI/ccCallReportDigits:

Enabled=TRUE, Call Id=1461

010010: Aug 22 08:29:14.716: //1461/xxxxxxxxxxxx/CCAPI/cc_api_call_report_digits_done:

(vdbPtr=0x476CEA78, callID=0x5B5, disp=0, digit_event=0x0, enable=FALSE, consume=FALSE)

010011: Aug 22 08:29:14.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_report_digits_done:

Enabled=TRUE, Disposition=0x0, Interface=0x476CEA78, Call Id=1461

010012: Aug 22 08:29:14.716: //1461/C2389EF18F7E/CCAPI/cc_api_call_report_digits_done:

Call Entry(Initial Digit Timeout=10000(ms), Inter Digit Timeout=10000(ms))

010013: Aug 22 08:29:14.716: //1461/C2389EF18F7E/CCAPI/ccCallProceeding:

Progress Indication=NULL(0)

010014: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/ccCallSetupRequest:

Destination=, Calling IE Present=TRUE, Mode=0,

Outgoing Dial-peer=3014, Params=0x47DC9D88, Progress Indication=ORIGINATING SIDE IS NON ISDN(3)

010015: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/ccCheckClipClir:

In: Calling Number=4717(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

010016: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/ccCheckClipClir:

Calling Party Number Is User Provided

010017: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/ccCheckClipClir:

Out: Calling Number=4717(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed)

010018: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/ccCallSetupRequest:

Destination Pattern=06........, Called Number=0655999508, Digit Strip=TRUE

010019: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/ccCallSetupRequest:

Calling Number=4717(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=0655999508(TON=Unknown, NPI=Unknown),

Redirect Number=, Display Info=Marcos Rodrigue

Account Number=, Final Destination Flag=FALSE,

Guid=C2389EF1-0A3B-11E3-8F7E-EEC98816AA83, Outgoing Dial-peer=3014

010020: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/cc_api_display_ie_subfields:

ccCallSetupRequest:

cisco-username=

----- ccCallInfo IE subfields -----

cisco-ani=4717

cisco-anitype=0

cisco-aniplan=0

cisco-anipi=0

cisco-anisi=0

dest=0655999508

cisco-desttype=0

cisco-destplan=0

cisco-rdie=FFFFFFFF

cisco-rdn=

cisco-rdntype=0

cisco-rdnplan=0

cisco-rdnpi=0

cisco-rdnsi=0

cisco-redirectreason=0 fwd_final_type =0

final_redirectNumber =

hunt_group_timeout =0

010021: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/ccIFCallSetupRequestPrivate:

Interface=0x46C5866C, Interface Type=6, Destination=, Mode=0x0,

Call Params(Calling Number=4717,(Calling Name=Marcos Rodrigue)(TON=Unknown, NPI=Unknown, Screening=Not Screened, Presentation=Allowed),

Called Number=0655999508(TON=Unknown, NPI=Unknown), Calling Translated=FALSE,

Subscriber Type Str=RegularLine, FinalDestinationFlag=FALSE, Outgoing Dial-peer=3014, Call Count On=FALSE,

Source Trkgrp Route Label=, Target Trkgrp Route Label=, tg_label_flag=0, Application Call Id=)

010022: Aug 22 08:29:14.720: //1462/xxxxxxxxxxxx/CCAPI/cc_insert_call_entry:

Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

010023: Aug 22 08:29:14.720: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010024: Aug 22 08:29:14.720: :cc_get_feature_vsa malloc success

010025: Aug 22 08:29:14.720: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010026: Aug 22 08:29:14.720: cc_get_feature_vsa count is 2

010027: Aug 22 08:29:14.720: //-1/xxxxxxxxxxxx/CCAPI/cc_get_feature_vsa:

010028: Aug 22 08:29:14.720: :FEATURE_VSA attributes are: feature_name:0,fearture_time:1187277832,feature_id:1636

010029: Aug 22 08:29:14.720: //-1/xxxxxxxxxxxx/CCAPI/cc_init_call_entry:

010030: Aug 22 08:29:14.720: call_init_entry calle is 46421C48,callid is 1462

010031: Aug 22 08:29:14.720: //1461/C2389EF18F7E/CCAPI/cc_peer_bind:

Bind=TRUE, Binder Call Id=1461, Bindee Call Id=1462

010032: Aug 22 08:29:14.720: //1462/C2389EF18F7E/CCAPI/cc_insert_guid_pod_entry:

Incoming=FALSE, Call Id=1462

010033: Aug 22 08:29:14.724: //1462/C2389EF18F7E/CCAPI/cc_set_voice_port_value:

CC_IF_TELEPHONY: Echo=0, Playout=0

010034: Aug 22 08:29:14.724: //1462/C2389EF18F7E/CCAPI/ccIFCallSetupRequestPrivate:

SPI Call Setup Request Is Success; Interface Type=6, FlowMode=1

010035: Aug 22 08:29:14.724: //1461/C2389EF18F7E/CCAPI/ccCallSetupRequest:exit@2318

010036: Aug 22 08:29:14.724: //1462/C2389EF18F7E/CCAPI/ccCallSetContext:

Context=0x47DC9D38

010037: Aug 22 08:29:14.724: //1461/C2389EF18F7E/CCAPI/ccSaveDialpeerTag:

Outgoing Dial-peer=3014

010038: Aug 22 08:29:14.728: //1462/C2389EF18F7E/CCAPI/cc_api_call_proceeding:

Interface=0x46C5866C, Progress Indication=NULL(0)

010039: Aug 22 08:29:14.728: //1462/C2389EF18F7E/CCAPI/cc_api_call_proceeding:exit@6513

010040: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/cc_api_call_disconnected:

Cause Value=16, Interface=0x46C5866C, Call Id=1462

010041: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/cc_api_call_disconnected:

Call Entry(Responsed=TRUE, Cause Value=16, Retry Count=0)

010042: Aug 22 08:29:24.728: //1461/xxxxxxxxxxxx/CCAPI/ccCallReleaseResources:

release reserved xcoding resource.

010043: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallSetAAA_Accounting:

Accounting=0, Call Id=1462

010044: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=16)

010045: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallDisconnect:

Start Calling Accounting;

Call Entry(Incoming=FALSE)

010046: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Disconnect Cause=16)

010047: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallDisconnect:

Call Entry(Disconnect Cause=16)

010048: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

010049: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

010050: Aug 22 08:29:24.728: //1462/xxxxxxxxxxxx/CCAPI/cc_api_request_rt_packet_stats_done:

CALL_ERROR_INFORMATIONAL; tag not properly returned from SPI

010051: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallGetVoipFlag:

Data Bitmask=0x1, Call Id=1462

010052: Aug 22 08:29:24.728: //1462/C2389EF18F7E/CCAPI/ccCallGetVoipFlag:

Flag=FALSE

010053: Aug 22 08:29:24: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId C2389EF1A3B11E38F7EEEC98816AA83, SetupTime 10:29:14.728 Madrid Thu Aug 22 2013, PeerAddress 0655999508, PeerSubAddress , DisconnectCause 10 , DisconnectText normal call clearing (16), ConnectTime 10:29:24.728 Madrid Thu Aug 22 2013, DisconnectTime 10:29:24.728 Madrid Thu Aug 22 2013, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

010054: Aug 22 08:29:24.728: //-1/xxxxxxxxxxxx/CCAPI/cc_syslog_feature_vsa:

010055: Aug 22 08:29:24.728: list is 46C46C00, list->featurename is 0,feat id is 1636

010056: Aug 22 08:29:24.728: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

010057: Aug 22 08:29:24.728: :Inside cc_build_feature_vsa

010058: Aug 22 08:29:24.728: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

010059: Aug 22 08:29:24.728: feature call basic

010060: Aug 22 08:29:24.728: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

010061: Aug 22 08:29:24.728: cc_build_feature_vsa attr is fn:TWC,ft:08/22/2013 10:29:14.720,cgn:4717,cdn:0655999508,frs:0,fid:1636,fcid:C2389EF1A3B11E38F7EEEC98816AA83,legID:5B6

010062: Aug 22 08:29:24.732: //-1/xxxxxxxxxxxx/CCAPI/cc_syslog_feature_vsa:

010063: Aug 22 08:29:24.732: cur is 46C46C00

010064: Aug 22 08:29:24.732: //-1/xxxxxxxxxxxx/CCAPI/cc_syslog_feature_vsa:

010065: Aug 22 08:29:24.732: fn:TWC,ft:08/22/2013 10:29:14.720,cgn:4717,cdn:0655999508,frs:0,fid:1636,fcid:C2389EF1A3B11E38F7EEEC98816AA83,legID:5B6

010066: Aug 22 08:29:24: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEATURE_VSA=fn:TWC,ft:08/22/2013 10:29:14.720,cgn:4717,cdn:0655999508,frs:0,fid:1636,fcid:C2389EF1A3B11E38F7EEEC98816AA83,legID:5B6

010067: Aug 22 08:29:24.732: //1462/C2389EF18F7E/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x46C5866C, Tag=0x0, Call Id=1462,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

010068: Aug 22 08:29:24.732: //1462/C2389EF18F7E/CCAPI/cc_api_call_disconnect_done:

Prefix Is Not Defined From Peer; Peer=3014, Called Number=0655999508

010069: Aug 22 08:29:24.732: //1462/C2389EF18F7E/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

010070: Aug 22 08:29:24.732: //1462/C2389EF18F7E/CCAPI/cc_delete_guid_pod_entry:

Incoming=FALSE

010071: Aug 22 08:29:24.732: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010072: Aug 22 08:29:24.732: :cc_free_feature_vsa freeing 46C46C00

010073: Aug 22 08:29:24.732: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010074: Aug 22 08:29:24.732: vsacount in free is 1

010075: Aug 22 08:29:24.732: //1462/C2389EF18F7E/CCAPI/cc_delete_call_entry:

Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=FALSE)

010076: Aug 22 08:29:24.732: //1462/C2389EF18F7E/CCAPI/cc_delete_call_entry:

Deleting profileTable[0x47ADF370]

010077: Aug 22 08:29:24.732: //1462/xxxxxxxxxxxx/CCAPI/cc_get_call_entry:

Call Entry Is Not Found

010078: Aug 22 08:29:24.736: //1461/C2389EF18F7E/CCAPI/ccCallDisconnect:

Cause Value=16, Tag=0x0, Call Entry(Previous Disconnect Cause=0, Disconnect Cause=0)

010079: Aug 22 08:29:24.736: //1461/C2389EF18F7E/CCAPI/ccCallDisconnect:

Start Calling Accounting;

Call Entry(Incoming=TRUE)

010080: Aug 22 08:29:24.736: //1461/C2389EF18F7E/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Disconnect Cause=0)

010081: Aug 22 08:29:24.736: //1461/C2389EF18F7E/CCAPI/ccCallDisconnect:

Cause Value=16, Call Entry(Responsed=TRUE, Cause Value=16)

010082: Aug 22 08:29:24.736: //1461/C2389EF18F7E/CCAPI/cc_api_get_transfer_info:

Transfer Number Is Null

010083: Aug 22 08:29:24.748: //1461/C2389EF18F7E/CCAPI/ccCallGetVoipFlag:

Data Bitmask=0x1, Call Id=1461

010084: Aug 22 08:29:24.748: //1461/C2389EF18F7E/CCAPI/ccCallGetVoipFlag:

Flag=FALSE

010085: Aug 22 08:29:24: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId C2389EF1A3B11E38F7EEEC98816AA83, SetupTime 10:29:12.908 Madrid Thu Aug 22 2013, PeerAddress 4717, PeerSubAddress , DisconnectCause 10 , DisconnectText normal call clearing (16), ConnectTime 10:29:24.748 Madrid Thu Aug 22 2013, DisconnectTime 10:29:24.748 Madrid Thu Aug 22 2013, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

010086: Aug 22 08:29:24.748: //-1/xxxxxxxxxxxx/CCAPI/cc_syslog_feature_vsa:

010087: Aug 22 08:29:24.748: list is 46C466F0, list->featurename is 0,feat id is 1635

010088: Aug 22 08:29:24.748: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

010089: Aug 22 08:29:24.748: :Inside cc_build_feature_vsa

010090: Aug 22 08:29:24.748: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

010091: Aug 22 08:29:24.748: feature call basic

010092: Aug 22 08:29:24.748: //-1/xxxxxxxxxxxx/CCAPI/cc_build_feature_vsa:

010093: Aug 22 08:29:24.748: cc_build_feature_vsa attr is fn:TWC,ft:08/22/2013 10:29:12.912,cgn:4717,cdn:,frs:0,fid:1635,fcid:C2389EF1A3B11E38F7EEEC98816AA83,legID:5B5

010094: Aug 22 08:29:24.748: //-1/xxxxxxxxxxxx/CCAPI/cc_syslog_feature_vsa:

010095: Aug 22 08:29:24.748: cur is 46C466F0

010096: Aug 22 08:29:24.748: //-1/xxxxxxxxxxxx/CCAPI/cc_syslog_feature_vsa:

010097: Aug 22 08:29:24.748: fn:TWC,ft:08/22/2013 10:29:12.912,cgn:4717,cdn:,frs:0,fid:1635,fcid:C2389EF1A3B11E38F7EEEC98816AA83,legID:5B5

010098: Aug 22 08:29:24: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEATURE_VSA=fn:TWC,ft:08/22/2013 10:29:12.912,cgn:4717,cdn:,frs:0,fid:1635,fcid:C2389EF1A3B11E38F7EEEC98816AA83,legID:5B5

010099: Aug 22 08:29:24.752: //1461/C2389EF18F7E/CCAPI/cc_api_call_disconnect_done:

Disposition=0, Interface=0x476CEA78, Tag=0x0, Call Id=1461,

Call Entry(Disconnect Cause=16, Voice Class Cause Code=0, Retry Count=0)

010100: Aug 22 08:29:24.752: //1461/C2389EF18F7E/CCAPI/cc_api_call_disconnect_done:

Call Disconnect Event Sent

010101: Aug 22 08:29:24.752: //1461/C2389EF18F7E/CCAPI/cc_delete_guid_pod_entry:

Incoming=TRUE

010102: Aug 22 08:29:24.752: //1461/C2389EF18F7E/CCAPI/cc_delete_call_entry:

ccFreeRawMsgInfo=0x464384C0

010103: Aug 22 08:29:24.752: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010104: Aug 22 08:29:24.752: :cc_free_feature_vsa freeing 46C466F0

010105: Aug 22 08:29:24.752: //-1/xxxxxxxxxxxx/CCAPI/cc_free_feature_vsa:

010106: Aug 22 08:29:24.752: vsacount in free is 0

010107: Aug 22 08:29:24.752: //1461/C2389EF18F7E/CCAPI/cc_delete_call_entry:

Total Call Count=1, Call Entry(Call Count On=FALSE, Incoming Call=TRUE)

010108: Aug 22 08:29:24.752: //1461/C2389EF18F7E/CCAPI/cc_delete_call_entry:

Total Call Count=0

010109: Aug 22 08:29:24.752: //1461/C2389EF18F7E/CCAPI/cc_delete_call_entry:

Deleting profileTable[0x47ADF4FC]

VoIP#debug vpm all

All Voice Port Module debugging is enabled

Note: DSP message debugging is not part of this command.

Use debug vpm dsp to enable it

010193: Aug 22 08:33:17.571: htsp_process_event: [50/0/19.1, EFXS_ONHOOK, E_DSP_SIG_1100]efxs_onhook_offhook htsp_setup_ind

010194: Aug 22 08:33:17.571: [50/0/19.1] get_local_station_id calling num=4717 calling name=Marcos Rodrigue calling time=08/22 10:33 orig called=

010195: Aug 22 08:33:17.571: TGRM: reg_invoke_tgrm_accept_call(0, TGRM_CALL_VOICE, TGRM_DIRECTION_IN, 50, 0, 19, 65535)

010196: Aug 22 08:33:17.571: TGRM: Calling vtsp_tsp_call_setup_ind().

010197: Aug 22 08:33:17.575: TGRM: reg_invoke_tgrm_call_update(50, 0, 19, 65535, 0, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)

010198: Aug 22 08:33:17.583: htsp_process_event: [50/0/19.1, EFXS_WAIT_SETUP_ACK, E_HTSP_SETUP_ACK]efxs_check_auto_call

010199: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010200: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_OPEN_VOICE_CHANNEL

010201: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010202: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_IDLE_MODE

010203: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010204: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_ENC_CONFIG 2

010205: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010206: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_SET_VOICE_PLAYOUT_DELAY

010207: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010208: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010209: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_ECHO_CANCELLER_CONTROL

010210: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010211: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_VOICE_MODE codec=1 vad=0 size=160 edsp_state=0

010212: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010213: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_GEN_PACKET_CONTROL

010214: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010215: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_ECHO_CANCELLER_CONTROL

010216: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010217: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_SET_GAINS

010218: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010219: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010220: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010221: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010222: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_DTMF_MODE

010223: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010224: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_CP_TONE_ON 4 dir 1

010225: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010226: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_CP_TONE_OFF

010227: Aug 22 08:33:17.583: htsp_digit_ready(50/0/19.1): digit = 0

010228: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010229: Aug 22 08:33:17.583: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_DTMF_MODE

010230: Aug 22 08:33:17.783: htsp_digit_ready(50/0/19.1): digit = 6

010231: Aug 22 08:33:17.871: htsp_process_event: [0/1/2, S_DOWN, E_DSP_SIG_0000]

010232: Aug 22 08:33:17.871: [0/1/2, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010233: Aug 22 08:33:17.983: htsp_digit_ready(50/0/19.1): digit = 5

010234: Aug 22 08:33:18.183: htsp_digit_ready(50/0/19.1): digit = 5

010235: Aug 22 08:33:18.383: htsp_digit_ready(50/0/19.1): digit = 9

010236: Aug 22 08:33:18.551: htsp_process_event: [0/1/2, S_DOWN, E_DSP_SIG_0100]

010237: Aug 22 08:33:18.551: [0/1/2, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010238: Aug 22 08:33:18.551: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0000]

010239: Aug 22 08:33:18.551: [0/1/3, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010240: Aug 22 08:33:18.583: htsp_digit_ready(50/0/19.1): digit = 9

010241: Aug 22 08:33:18.783: htsp_digit_ready(50/0/19.1): digit = 9

010242: Aug 22 08:33:18.983: htsp_digit_ready(50/0/19.1): digit = 5

010243: Aug 22 08:33:19.183: htsp_digit_ready(50/0/19.1): digit = 0

010244: Aug 22 08:33:19.411: htsp_digit_ready(50/0/19.1): digit = 8

010245: Aug 22 08:33:19.419: htsp_timer_stop3

010246: Aug 22 08:33:19.419: htsp_process_event: [50/0/19.1, EFXS_OFFHOOK, E_HTSP_PROCEEDING]efxs_offhook_proceeding

010247: Aug 22 08:33:19.419: [50/0/19.1] set signal state = 0x8 timestamp = 0

010248: Aug 22 08:33:19.419: edsp_set_sig_state: [50/0/19.1] packet_len=12 channel_id=37 packet_id=39 state=0x8 timestamp=0x0

010249: Aug 22 08:33:19.419: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010250: Aug 22 08:33:19.419: edsp_soutput: [50/0/19.1] DN=19 chnl=37 EFXS_PROCEEDINGhtsp_setup_req

010251: Aug 22 08:33:19.419: htsp_process_event: [0/1/0, FXOGS_ONHOOK, E_HTSP_SETUP_REQ]fxogs_onhook_setup

010252: Aug 22 08:33:19.419: [0/1/0] set signal state = 0x0 timestamp = 0

010253: Aug 22 08:33:19.419: dsp_set_sig_state: [0/1/0] packet_len=12 channel_id=128 packet_id=39 state=0x0 timestamp=0x0

010254: Aug 22 08:33:19.419: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_BUSY, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)

010255: Aug 22 08:33:19.423: htsp_timer - 10000 msec

010256: Aug 22 08:33:20.531: htsp_process_event: [0/1/2, S_DOWN, E_DSP_SIG_0000]

010257: Aug 22 08:33:20.531: [0/1/2, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010258: Aug 22 08:33:20.531: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0100]

010259: Aug 22 08:33:20.531: [0/1/3, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010260: Aug 22 08:33:20.683: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0000]

010261: Aug 22 08:33:20.683: [0/1/3, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010262: Aug 22 08:33:21.707: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0100]

010263: Aug 22 08:33:21.707: [0/1/3, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010264: Aug 22 08:33:22.279: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0000]

010265: Aug 22 08:33:22.279: [0/1/3, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010266: Aug 22 08:33:23.527: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0100]

010267: Aug 22 08:33:23.527: [0/1/3, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010268: Aug 22 08:33:24.979: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0000]

010269: Aug 22 08:33:24.979: [0/1/3, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010270: Aug 22 08:33:26.175: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0100]

010271: Aug 22 08:33:26.179: [0/1/3, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010272: Aug 22 08:33:26.335: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0000]

010273: Aug 22 08:33:26.335: [0/1/3, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010274: Aug 22 08:33:26.383: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0100]

010275: Aug 22 08:33:26.387: [0/1/3, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010276: Aug 22 08:33:27.299: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0000]

010277: Aug 22 08:33:27.299: [0/1/3, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010278: Aug 22 08:33:27.871: htsp_process_event: [0/1/2, S_DOWN, E_DSP_SIG_0100]

010279: Aug 22 08:33:27.871: [0/1/2, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010280: Aug 22 08:33:29.423: htsp_process_event: [0/1/0, FXOGS_WAIT_TIP_GROUND, E_HTSP_EVENT_TIMER]fxogs_offhook_disc

010281: Aug 22 08:33:29.423: htsp_timer_stop

010282: Aug 22 08:33:29.423: [0/1/0] set signal state = 0x4 timestamp = 0

010283: Aug 22 08:33:29.423: dsp_set_sig_state: [0/1/0] packet_len=12 channel_id=128 packet_id=39 state=0x4 timestamp=0x0

010284: Aug 22 08:33:29.423: htsp_timer - 2000 msechtsp_release_req: cause 16, no_onhook 0

010285: Aug 22 08:33:29.423: htsp_process_event: [0/1/0, FXOGS_ONHOOK, E_HTSP_RELEASE_REQ]fxogs_onhook_release

010286: Aug 22 08:33:29.423: htsp_timer_stop2

010287: Aug 22 08:33:29.423: htsp_timer_stop3

010288: Aug 22 08:33:29.423: TGRM: reg_invoke_tgrm_call_update(0, 1, 0, 65535, 1, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_OUT)

010289: Aug 22 08:33:29: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId 540DD766A3C11E38F83EEC98816AA83, SetupTime 10:33:19.423 Madrid Thu Aug 22 2013, PeerAddress 0655999508, PeerSubAddress , DisconnectCause 10 , DisconnectText normal call clearing (16), ConnectTime 10:33:29.423 Madrid Thu Aug 22 2013, DisconnectTime 10:33:29.423 Madrid Thu Aug 22 2013, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

010290: Aug 22 08:33:29: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEATURE_VSA=fn:TWC,ft:08/22/2013 10:33:19.415,cgn:4717,cdn:0655999508,frs:0,fid:1638,fcid:540DD766A3C11E38F83EEC98816AA83,legID:5B8

010291: Aug 22 08:33:29.427: flex_dsprm_close_cleanuphtsp_release_req: cause 16, no_onhook 0

010292: Aug 22 08:33:29.427: htsp_process_event: [50/0/19.1, EFXS_OFFHOOK, E_HTSP_RELEASE_REQ]efxs_offhook_disc

010293: Aug 22 08:33:29.427: htsp_timer_stop

010294: Aug 22 08:33:29.427: [50/0/19.1] set signal state = 0x4 timestamp = 0

010295: Aug 22 08:33:29.427: edsp_set_sig_state: [50/0/19.1] packet_len=12 channel_id=37 packet_id=39 state=0x4 timestamp=0x0

010296: Aug 22 08:33:29.427: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010297: Aug 22 08:33:29.427: edsp_soutput: [50/0/19.1] DN=19 chnl=37 StationRingOff

010298: Aug 22 08:33:29.427: [50/0/19.1] set signal state = 0xC timestamp = 0

010299: Aug 22 08:33:29.427: edsp_set_sig_state: [50/0/19.1] packet_len=12 channel_id=37 packet_id=39 state=0xC timestamp=0x0

010300: Aug 22 08:33:29.427: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010301: Aug 22 08:33:29.427: edsp_soutput: [50/0/19.1] DN=19 chnl=37 EFXS_SUP_DISC (cause 16)

010302: Aug 22 08:33:29.427: [50/0/19.1] set signal state = 0x4 timestamp = 10

010303: Aug 22 08:33:29.427: edsp_set_sig_state: [50/0/19.1] packet_len=12 channel_id=37 packet_id=39 state=0x4 timestamp=0xA

010304: Aug 22 08:33:29.427: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010305: Aug 22 08:33:29.427: edsp_soutput: [50/0/19.1] DN=19 chnl=37 StationRingOff

010306: Aug 22 08:33:29.427: htsp_timer - 10 msec

010307: Aug 22 08:33:29.439: htsp_process_event: [50/0/19.1, EFXS_CPC, E_HTSP_EVENT_TIMER]efxs_cpc_timer

010308: Aug 22 08:33:29.439: edsp_req_sig_state: [50/0/19.1] packet_len=8 channel_id=37 packet_id=40

010309: Aug 22 08:33:29.439: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010310: Aug 22 08:33:29.439: edsp_soutput: [50/0/19.1] DN=19 chnl=37 Request State

010311: Aug 22 08:33:29.439: htsp_timer - 30000 msec

010312: Aug 22 08:33:29.439: htsp_process_event: [50/0/19.1, EFXS_WAIT_ONHOOK, E_DSP_SIG_0100]efxs_waitonhook_onhook

010313: Aug 22 08:33:29.439: htsp_timer_stop htsp_report_onhook_sig

010314: Aug 22 08:33:29.439: TGRM: reg_invoke_tgrm_call_update(50, 0, 19, 65535, 0, TGRM_CALL_IDLE, TGRM_CALL_VOICE, TGRM_DIRECTION_IN)

010315: Aug 22 08:33:29: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1, ConnectionId 540DD766A3C11E38F83EEC98816AA83, SetupTime 10:33:17.579 Madrid Thu Aug 22 2013, PeerAddress 4717, PeerSubAddress , DisconnectCause 10 , DisconnectText normal call clearing (16), ConnectTime 10:33:29.439 Madrid Thu Aug 22 2013, DisconnectTime 10:33:29.439 Madrid Thu Aug 22 2013, CallOrigin 2, ChargedUnits 0, InfoType 2, TransmitPackets 0, TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0

010316: Aug 22 08:33:29: %VOIPAAA-5-VOIP_FEAT_HISTORY: FEATURE_VSA=fn:TWC,ft:08/22/2013 10:33:17.575,cgn:4717,cdn:,frs:0,fid:1637,fcid:540DD766A3C11E38F83EEC98816AA83,legID:5B7

010317: Aug 22 08:33:29.439: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010318: Aug 22 08:33:29.439: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_IDLE_MODE

010319: Aug 22 08:33:29.439: edsp_soutput: [50/0/19.1] DN=19 chnl=37

010320: Aug 22 08:33:29.439: edsp_soutput: [50/0/19.1] DN=19 chnl=37 MSG_RX_CLOSE_VOICE_CHANNEL

010321: Aug 22 08:33:29.439: edsprm_close_cleanup

010322: Aug 22 08:33:30.083: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0100]

010323: Aug 22 08:33:30.083: [0/1/3, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010324: Aug 22 08:33:31.271: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0000]

010325: Aug 22 08:33:31.271: [0/1/3, S_DOWN, E_DSP_SIG_0000] -> ERROR: INVALID INPUT

010326: Aug 22 08:33:31.411: htsp_process_event: [0/1/3, S_DOWN, E_DSP_SIG_0100]

010327: Aug 22 08:33:31.411: [0/1/3, S_DOWN, E_DSP_SIG_0100] -> ERROR: INVALID INPUT

010328: Aug 22 08:33:31.423: htsp_process_event: [0/1/0, FXOGS_ONHOOK, E_HTSP_EVENT_TIMER]

010329: Aug 22 08:33:31.423: [0/1/0, FXOGS_ONHOOK, E_HTSP_EVENT_TIMER] -> ERROR: INVALID INPUT

010330: Aug 22 08:33:32.039: htsp_process_event: [0/1/0, FXOGS_ONHOOK, E_DSP_SIG_0110]

010331: Aug 22 08:33:32.039: fxogs_line_reversal_clid_wait. On_hook line reversal detected possibily due to crossed cable

Un saludo y muchas gracias.