[cisco-voip] C2801 2VIC BRI TE PTP: outgoing call cleared after first ring

Harald Klein hari at vt100.at
Sun Nov 6 18:43:16 EST 2005 

Hi All,

incoming calls work great, but outgoing calls are are disconnected after
the first ring (no chance to answer)..

i see two errors in the log:
Nov  6 23:30:51.231: ISDN BR0/0/0 **ERROR**: host_information: Unexpected
INFO - call id 0x8011
and
Nov  6 23:31:03.831: ISDN BR0/0/0 **ERROR**: host_alerting: Unexpected
Alert clearing the call

any idea?

regards, hari

config and debug:

!
boot system flash:c2801-ipvoicek9-mz.124-3a.bin
!
isdn switch-type basic-net3
isdn voice-call-failure 0
isdn logging
isdn tei-negotiation first-call
!
voice-card 0
 codec complexity high
 dsp services dspfarm
!
!
voice call send-alert
voice rtp send-recv
!
voice service pots
 supported-language de
!
voice service voip
 allow-connections sip to sip
 sip
  subscription maximum accept 100
  registrar server expires max 600 min 60
!
!
voice class media 1
 media flow-around
!
!
voice class codec 1
 codec preference 1 g711alaw
!
!
!
interface BRI0/0/0
 description BRI PTP 43316123456
 no ip address
 isdn switch-type basic-net3
 isdn overlap-receiving T302 10000
 isdn point-to-point-setup
 isdn autodetect
 isdn incoming-voice voice
 isdn map address .* plan isdn type unknown
 isdn T306 60000
 isdn outgoing ie progress-indicator
 no isdn outgoing display-ie
 isdn outgoing ie called-number
 isdn static-tei 0
!
!
voice-port 0/0/0
 echo-cancel mode 1
 compand-type a-law
 cptone AT
 description BRI PTP 43316123456
 bearer-cap Speech
!
voice-port 0/0/1
!
!
!
!
!
dial-peer voice 1 pots
 description incoming ISDN
 incoming called-number .T
 direct-inward-dial
 port 0/0/0
 forward-digits all
!
dial-peer voice 2 voip
 description outgoing VoIP
 service session
 destination-pattern .T
 voice-class codec 1
 session protocol sipv2
 session target ipv4:10.1.1.9
 session transport udp
 dtmf-relay rtp-nte
 no vad
!
dial-peer voice 5 pots
 description outgoing ISDN
 destination-pattern 0T
 port 0/0/0
!
gateway
 timer receive-rtp 1200
!
sip-ua
 set pstn-cause 6 sip-status 503
 set pstn-cause 18 sip-status 408
 set pstn-cause 27 sip-status 502
 set pstn-cause 31 sip-status 480
 set pstn-cause 44 sip-status 503
 set pstn-cause 58 sip-status 503
 set pstn-cause 88 sip-status 503
 set pstn-cause 95 sip-status 503
 set pstn-cause 102 sip-status 504
 set pstn-cause 111 sip-status 500
 retry invite 3
 retry response 3
 retry bye 3
 retry cancel 3
 timers trying 1000
 sip-server ipv4:10.1.1.9
!




Nov  6 23:30:51.063: ISDN BR0/0/0 Q931d: Called plan/type unspecified in
call_decode, so ignored.
Nov  6 23:30:51.063: ISDN BR0/0/0 Q931d: No call_decode, isdn default set
Nov  6 23:30:51.063: ISDN BR0/0/0 Q931d: isdn_parser_map_lkup: Comparing
004917620508062/.*, (regexp)
Nov  6 23:30:51.063: ISDN BR0/0/0 Q931d: isdn_parser_map_lkup: overriding
plan/type for 004917620508062, unknown/unknown to isdn/unknown
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931: Applying typeplan for sw-type 0x1
is 0x0 0x1, Called num 004917612345678
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: CC_CHAN_GetIdleChanbri: Found
idle channel B1
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: L3_Go: source 0x0400, event
0x0080, call id 0x     int id 0x8011
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: L3_Go: event 0x80  cr_len 1 cr 0
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: L3_Go: call_id 0x8011 cr 0x0
state 0 event 0x80 ces 1
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: L3_ProcessEvent: callref =
0x0000 CC_SETUP_REQ:U0_SetupReq(nlcb)
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: srl_send_l3_pak:
        source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim =
DL_DATA_REQ 0x0240
        priv_len = 4 int_id = 0x646162E8 datasize = 57
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: data =
Nov  6 23:30:51.067:    646162E8000003000240046500016E75
Nov  6 23:30:51.067:    0801110504038090A31801816C090180
Nov  6 23:30:51.067:    38373037303532701081303034393137
Nov  6 23:30:51.067:    363230353038303632
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931d: L3_state_change: callref 0x0011
old NULL_STATE, new CALL_INIT
Nov  6 23:30:51.067: ISDN BR0/0/0 Q931: TX -> SETUP pd = 8  callref = 0x11
        Bearer Capability i = 0x8090A3
                Standard = CCITT
                Transfer Capability = Speech
                Transfer Mode = Circuit
                Transfer Rate = 64 kbit/s
        Channel ID i = 0x81
        Calling Party Number i = 0x0180, '8707052'
                Plan:ISDN, Type:Unknown
        Called Party Number i = 0x81, '004917612345678'
                Plan:ISDN, Type:Unknown
Nov  6 23:30:51.227: ISDN BR0/0/0 Q931: RX <- SETUP_ACK pd = 8  callref =
0x91
        Channel ID i = 0x89
Nov  6 23:30:51.227: ISDN BR0/0/0 Q931d: L3IF_rx_L2_pak: received data
Nov  6 23:30:51.227:    0801910D180189
Nov  6 23:30:51.227: ISDN BR0/0/0 Q931d: L3_Go: source 0x020A, event
0x000D, call id 0x     int id 0x0
Nov  6 23:30:51.227: ISDN BR0/0/0 Q931d: L3_Go: event 0xD  cr_len 1 cr 17
Nov  6 23:30:51.227: ISDN BR0/0/0 Q931d: L3_Go: call_id 0x8011 cr 0x11
state 1 event 0xD ces 1
Nov  6 23:30:51.227: ISDN BR0/0/0 Q931d: L3_ProcessEvent: callref =
0x0011 SETUP_ACK:U1_SetupAck(nlcb)
Nov  6 23:30:51.231: ISDN BR0/0/0 Q931d: L3_state_change: callref 0x0011
old CALL_INIT, new OVERLAP_SENDING
Nov  6 23:30:51.231: ISDN BR0/0/0 **ERROR**: host_information: Unexpected
INFO - call id 0x8011
Nov  6 23:31:03.827: ISDN BR0/0/0 Q931: RX <- ALERTING pd = 8  callref =
0x91
        Notification Ind i = 0xE8
Nov  6 23:31:03.831: ISDN BR0/0/0 Q931d: L3IF_rx_L2_pak: received data
Nov  6 23:31:03.831:    080191012701E8
Nov  6 23:31:03.831: ISDN BR0/0/0 Q931d: L3_Go: source 0x020A, event
0x0001, call id 0x     int id 0x0
Nov  6 23:31:03.831: ISDN BR0/0/0 Q931d: L3_Go: event 0x1  cr_len 1 cr 17
Nov  6 23:31:03.831: ISDN BR0/0/0 Q931d: L3_Go: call_id 0x8011 cr 0x11
state 2 event 0x1 ces 1
Nov  6 23:31:03.831: ISDN BR0/0/0 Q931d: L3_ProcessEvent: callref =
0x0011 ALERTING:U2_Alerting(nlcb)
Nov  6 23:31:03.831: ISDN BR0/0/0 Q931d: L3_state_change: callref 0x0011
old OVERLAP_SENDING, new CALL_DELIVERED
Nov  6 23:31:03.831: ISDN BR0/0/0 **ERROR**: host_alerting: Unexpected
Alert clearing the call
Nov  6 23:31:03.831: %ISDN-4-ISDN_UNEXPECTED_EVENT: BR0/0/0: Occurred at
../isdn/isdnif_rxstate.c:6371
Nov  6 23:31:03.831: ISDN BR0/0/0 Q931d: L3_Go: source 0x0400, event
0x0084, call id 0x     int id 0x8011
Nov  6 23:31:03.835: ISDN BR0/0/0 Q931d: L3_Go: event 0x84  cr_len 1 cr 17
Nov  6 23:31:03.835: ISDN BR0/0/0 Q931d: L3_Go: call_id 0x8011 cr 0x11
state 4 event 0x84 ces 1
Nov  6 23:31:03.835: ISDN BR0/0/0 Q931d: L3_ProcessEvent: callref =
0x0011 CC_DISCONNECT_REQ:Ux_DiscReq(nlcb)
Nov  6 23:31:03.835: ISDN BR0/0/0 Q931d: srl_send_l3_pak:
        source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim =
DL_DATA_REQ 0x0240
        priv_len = 4 int_id = 0x646162E8 datasize = 24
Nov  6 23:31:03.835: ISDN BR0/0/0 Q931d: data =
Nov  6 23:31:03.835:    646162E8000003000240045100010800
Nov  6 23:31:03.835:    0801114508028090
Nov  6 23:31:03.835: ISDN BR0/0/0 Q931d: L3_state_change: callref 0x0011
old CALL_DELIVERED, new DISCONNECT_REQUEST
Nov  6 23:31:03.835: ISDN BR0/0/0 Q931: TX -> DISCONNECT pd = 8  callref
= 0x11
        Cause i = 0x8090 - Normal call clearing
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931: RX <- RELEASE pd = 8  callref =
0x91
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: L3IF_rx_L2_pak: received data
Nov  6 23:31:03.971:    0801914D
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: L3_Go: source 0x020A, event
0x004D, call id 0x     int id 0x0
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: L3_Go: event 0x4D  cr_len 1 cr 17
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: L3_Go: call_id 0x8011 cr 0x11
state 11 event 0x4D ces 1
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: L3_ProcessEvent: callref =
0x0011 RELEASE:U11_Release(nlcb)
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: srl_send_l3_pak:
        source_id = Q.931 0x0300, dest_id = Q.921 0x0000, prim =
DL_DATA_REQ 0x0240
        priv_len = 4 int_id = 0x646162E8 datasize = 20
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: data =
Nov  6 23:31:03.971:    646162E8000003000240040500010800
Nov  6 23:31:03.971:    0801115A
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: L3_state_change: callref 0x0011
old DISCONNECT_REQUEST, new NULL_STATE
Nov  6 23:31:03.971: ISDN BR0/0/0 Q931d: L3_Go: source 0x0400, event
0x00AB, call id 0x     int id 0x8011
Nov  6 23:31:03.975: ISDN BR0/0/0 Q931d: L3_Go: event 0xAB  cr_len 1 cr 17
Nov  6 23:31:03.975: ISDN BR0/0/0 Q931d: L3_Go: call_id 0x8011 cr 0x0
state 0 event 0xAB ces 1
Nov  6 23:31:03.975: ISDN BR0/0/0 Q931d: L3_ProcessEvent: callref =
0x0000 CC_REL_COMP_REQ:Ux_RelCompReq(nlcb)
Nov  6 23:31:03.975: ISDN BR0/0/0 Q931: TX -> RELEASE_COMP pd = 8 
callref = 0x11
Nov  6 23:31:03.975: //57/339477DA8042/CCAPI/cc_api_call_disconnected:
   Cause Value=34, Interface=0x64E778F4, Call Id=57
Nov  6 23:31:03.975: //57/339477DA8042/CCAPI/cc_api_call_disconnected:
   Call Entry(Responsed=TRUE, Cause Value=34, Retry Count=0)
Nov  6 23:31:03.975: //57/339477DA8042/CCAPI/ccCallSetAAA_Accounting:
   Accounting=1, Call Id=57
Nov  6 23:31:03.979: //57/339477DA8042/CCAPI/ccCallDisconnect:
   Cause Value=34, Tag=0x0, Call Entry(Previous Disconnect Cause=0,
Disconnect Cause=34)
Nov  6 23:31:03.979: //57/339477DA8042/CCAPI/ccCallDisconnect:
   Cause Value=34, Call Entry(Responsed=TRUE, Cause Value=34)
Nov  6 23:31:03.979: %VOIPAAA-5-VOIP_CALL_HISTORY: CallLegType 1,
ConnectionId 339477DA 4E5411DA 8042F9B7 47A91F2E, SetupTime 23:31:03.969
GMT Sun Nov 6 2005, PeerAddress 0004917620508062, PeerSubAddress ,
DisconnectCause 22  , DisconnectText no circuit (34), ConnectTime
23:31:03.979 GMT Sun Nov 6 2005, DisconnectTime 23:31:03.979 GMT Sun Nov
6 2005, CallOrigin 1, ChargedUnits 0, InfoType 2, TransmitPackets 0,
TransmitBytes 0, ReceivePackets 0, ReceiveBytes 0
Nov  6 23:31:03.979: //57/339477DA8042/CCAPI/cc_api_call_disconnect_done:
   Disposition=0, Interface=0x64E778F4, Tag=0x0, Call Id=57,
   Call Entry(Disconnect Cause=34, Voice Class Cause Code=0, Retry
Count=0)
Nov  6 23:31:03.979: //57/339477DA8042/CCAPI/cc_api_call_disconnect_done:
   Call Disconnect Event Sent

More information about the cisco-voip mailing list