Examples
The following sample debug outputs display information about a V.92 reporting situation:
01:39:19: ISDN Se7/6:23: RX <- SETUP pd = 8 callref = 0x42A0
01:39:19: Bearer Capability i = 0x9090A2
01:39:19: Channel ID i = 0xA18396
01:39:19: Progress Ind i = 0x8183 - Origination address is non-ISDN
01:39:19: Calling Party Number i = 0xA1, '60112', Plan:ISDN, Type:National
01:39:19: Called Party Number i = 0xA1, '50138', Plan:ISDN, Type:National
01:39:19: Locking Shift to Codeset 6
01:39:19: Codeset 6 IE 0x28 i = 'ANALOG,savitha'
01:39:19: ISDN Se7/6:23: Incoming call id = 0x0038, dsl 0
01:39:19: ISDN Se7/6:23: NegotiateBchan: bchan 22 intid 0 serv_st 0 chan_st 0 callid 0x0000 ev 0x90 n/w? 0
01:39:19: Negotiated int_id 0 bchan 0 cr=0xC2A0 callid=0x0038 lo_chan 22 final int_id/bchan 0/22 cause 0x0
01:39:19: ISDN Se7/6:23: LIF_EVENT: ces/callid 1/0x38 CALL_INCOMING
01:39:19: ISDN Se7/6:23: CALL_INCOMING dsl 0 bchan 21
01:39:19: voice_parse_intf_name: Using the old NAS_PORT string
01:39:19: AAA/ACCT/EVENT/(00000007): CALL START
01:39:19: AAA/ACCT(00000000): add node, session 9
01:39:19: AAA/ACCT/NET(00000007): add, count 1
01:39:19: AAA/ACCT/EVENT/(00000007): ATTR REPLACE
01:39:19: ISDN Se7/6:23: CALL_INCOMING: call type is VOICE ULAW, bchan = 21
01:39:19: ISDN Se7/6:23: Event: Received a VOICE call from 60112 on B21 at 64 Kb/s Tone Value 0
01:39:19: AAA/ACCT/DS0: channel=21, ds1=6, t3=0, slot=7, ds0=117465109
01:39:19: AAA/ACCT/DS0: channel=21, ds1=6, t3=0, slot=7, ds0=117465109
01:39:19: VDEV_ALLOCATE: 1/5 is allocated
01:39:19: ISDN Se7/6:23: RM returned call_type 1 resource type 0 response 2
01:39:19: EVENT_FROM_ISDN: dchan_idb=0x63B3D334, call_id=0x38, ces=0x0
bchan=0x15, event=0x1, cause=0x0
01:39:19: dev in call to isdn : set dnis_collected & fap_notify
01:39:19: EVENT_FROM_ISDN:(0038): DEV_INCALL at slot 1 and port 5
01:39:19: EVENT_FROM_ISDN: decode:calling oct3 0xA1, called oct3 0xA1, oct3a 0x0,mask 0x3D
01:39:19: EVENT_FROM_ISDN: csm_call_info:calling oct3 0xA1, called oct3 0xA1, oct3a 0x0,mask 0x3D
01:39:19: CSM_PROC_IDLE: CSM_EVENT_ISDN_CALL at slot 1, port 5
01:39:19: CSM DSPLIB(1/5/csm_flags=0x12): np_dsplib_prepare_modem
01:39:19: csm_connect_pri_vdev: TS allocated at bp_stream 0, bp_Ch 5, vdev_common 0x62EAD8F4 1/5
01:39:19: ISDN Se7/6:23: EVENT to CSM:DEV_INCALL: calltype=VOICE, bchan=21
01:39:19: ISDN Se7/6:23: TX -> CALL_PROC pd = 8 callref = 0xC2A0
01:39:19: Channel ID i = 0xA98396
01:39:19: ISDN Se7/6:23: TX -> ALERTING pd = 8 callref = 0xC2A0
01:39:19: CSM DSPLIB(1/5):DSPLIB_MODEM_INIT: Modem session transition to IDLE
01:39:19: CSM DSPLIB(1/5): Modem went offhook
01:39:19: CSM_PROC_IC2_RING: CSM_EVENT_MODEM_OFFHOOK at slot 1, port 5
01:39:19: ISDN Se7/6:23: VOICE_ANS Event: call id 0x38, bchan 21, ces 0
01:39:19: ISDN Se7/6:23: isdn_send_connect(): msg 74, call id 0x38, ces 0 bchan 21, call type VOICE
01:39:19: ISDN Se7/6:23: TX -> CONNECT pd = 8 callref = 0xC2A0
01:39:19: ISDN Se7/6:23: RX <- CONNECT_ACK pd = 8 callref = 0x42A0
01:39:19: ISDN Se7/6:23: LIF_EVENT: ces/callid 1/0x38 CALL_PROGRESS
01:39:19: ISDN Se7/6:23: event CALL_PROGRESS dsl 0
01:39:19: ISDN Se7/6:23: CALL_PROGRESS: CALL_CONNECTED call id 0x38, bchan 21, dsl 0
01:39:19: EVENT_FROM_ISDN: dchan_idb=0x63B3D334, call_id=0x38, ces=0x0
bchan=0x15, event=0x4, cause=0x0
01:39:19: EVENT_FROM_ISDN:(0038): DEV_CONNECTED at slot 1 and port 5
01:39:19: CSM_PROC_IC6_WAIT_FOR_CONNECT: CSM_EVENT_ISDN_CONNECTED at slot 1, port 5
01:39:19: CSM DSPLIB(1/5): np_dsplib_call_accept
01:39:19: ISDN Se7/6:23: EVENT to CSM:DEV_CONNECTED: calltype=VOICE, bchan=21
01:39:19: CSM DSPLIB(1/5):DSPLIB_MODEM_WAIT_ACTIVE: Modem session transition to ACTIVE
01:39:19: CSM DSPLIB(1/5): Modem state changed to (CONNECT_STATE)
01:39:22: CSM DSPLIB(1/5): Modem state changed to (V8BIS_EXCHANGE_STATE)
01:39:24: CSM DSPLIB(1/5): Modem state changed to (LINK_STATE)
01:39:28: CSM DSPLIB(1/5): Modem state changed to (RANGING_STATE)
01:39:30: CSM DSPLIB(1/5): Modem state changed to (HALF_DUPLEX_TRAIN_STATE)
01:39:45: CSM DSPLIB(1/5): Modem state changed to (TRAINUP_STATE)
01:39:45: CSM DSPLIB(1/5): Modem state changed to (EC_NEGOTIATING_STATE)
01:39:46: CSM DSPLIB(1/5): Modem state changed to (STEADY_STATE)
01:39:46: TTY1/05: DSR came up
01:39:46: tty1/05: Modem: IDLE->(unknown)
01:39:46: TTY1/05: EXEC creation
01:39:46: CHAT1/05: Attempting line activation script
01:39:46: CHAT1/05: Asserting DTR
01:39:50: voice_parse_intf_name: Using the old NAS_PORT string
01:39:50: voice_parse_intf_name: Using the old NAS_PORT string
01:39:50: AAA/AUTHEN/LOGIN (00000007): Pick method list 'default'
01:39:50: RADIUS/ENCODE(00000007): ask "Username: "
01:39:50: RADIUS/ENCODE(00000007): send packet; GET_USER
01:39:50: TTY1/05: set timer type 10, 30 seconds
01:39:50: TTY1/05: Autoselect(2) sample 7E
01:39:50: TTY1/05: Autoselect(2) sample 7EFF
01:39:50: TTY1/05: Autoselect(2) sample 7EFF7D
01:39:50: TTY1/05: Autoselect(2) sample 7EFF7D23
01:39:50: TTY1/05 Autoselect cmd: ppp negotiate
01:39:50: TTY1/05: EXEC creation
01:39:50: CHAT1/05: Attempting line activation script
01:39:50: CHAT1/05: Asserting DTR
01:39:54: voice_parse_intf_name: Using the old NAS_PORT string
01:39:54: voice_parse_intf_name: Using the old NAS_PORT string
01:39:54: TTY1/05: no timer type 1 to destroy
01:39:54: TTY1/05: no timer type 0 to destroy
01:39:54: As1/05 LCP: I CONFREQ [Closed] id 0 len 50
01:39:54: As1/05 LCP: ACCM 0x00000000 (0x020600000000)
01:39:54: As1/05 LCP: MagicNumber 0x00002EB8 (0x050600002EB8)
01:39:54: As1/05 LCP: PFC (0x0702)
01:39:54: As1/05 LCP: ACFC (0x0802)
01:39:54: As1/05 LCP: Callback 6 (0x0D0306)
01:39:54: As1/05 LCP: MRRU 1614 (0x1104064E)
01:39:54: As1/05 LCP: EndpointDisc 1 Local
01:39:54: As1/05 LCP: (0x131701CC7F60A0E7A211D6B549000102)
01:39:54: As1/05 LCP: (0x2BC43900000000)
01:39:54: As1/05 LCP: Lower layer not up, Fast Starting
01:39:54: voice_parse_intf_name: Using the old NAS_PORT string
01:39:54: voice_parse_intf_name: Using the old NAS_PORT string
01:39:54: As1/05 PPP: Treating connection as a callin
01:39:54: As1/05 PPP: Phase is ESTABLISHING, Passive Open
01:39:54: As1/05 LCP: State is Listen
01:39:54: As1/05 PPP: Authorization required
01:39:54: As1/05 LCP: O CONFREQ [Listen] id 1 len 25
01:39:54: As1/05 LCP: ACCM 0x000A0000 (0x0206000A0000)
01:39:54: As1/05 LCP: AuthProto CHAP (0x0305C22305)
01:39:54: As1/05 LCP: MagicNumber 0x099EBCBA (0x0506099EBCBA)
01:39:54: As1/05 LCP: PFC (0x0702)
01:39:54: As1/05 LCP: ACFC (0x0802)
01:39:54: As1/05 LCP: O CONFREJ [Listen] id 0 len 11
01:39:54: As1/05 LCP: Callback 6 (0x0D0306)
01:39:54: As1/05 LCP: MRRU 1614 (0x1104064E)
01:39:54: As1/05 LCP: I CONFACK [REQsent] id 1 len 25
01:39:54: As1/05 LCP: ACCM 0x000A0000 (0x0206000A0000)
01:39:54: As1/05 LCP: AuthProto CHAP (0x0305C22305)
01:39:54: As1/05 LCP: MagicNumber 0x099EBCBA (0x0506099EBCBA)
01:39:54: As1/05 LCP: PFC (0x0702)
01:39:54: As1/05 LCP: ACFC (0x0802)
01:39:54: As1/05 LCP: I CONFREQ [ACKrcvd] id 1 len 43
01:39:54: As1/05 LCP: ACCM 0x00000000 (0x020600000000)
01:39:54: As1/05 LCP: MagicNumber 0x00002EB8 (0x050600002EB8)
01:39:54: As1/05 LCP: PFC (0x0702)
01:39:54: As1/05 LCP: ACFC (0x0802)
01:39:54: As1/05 LCP: EndpointDisc 1 Local
01:39:54: As1/05 LCP: (0x131701CC7F60A0E7A211D6B549000102)
01:39:54: As1/05 LCP: (0x2BC43900000000)
01:39:54: As1/05 LCP: O CONFACK [ACKrcvd] id 1 len 43
01:39:54: As1/05 LCP: ACCM 0x00000000 (0x020600000000)
01:39:54: As1/05 LCP: MagicNumber 0x00002EB8 (0x050600002EB8)
01:39:54: As1/05 LCP: PFC (0x0702)
01:39:54: As1/05 LCP: ACFC (0x0802)
01:39:54: As1/05 LCP: EndpointDisc 1 Local
01:39:54: As1/05 LCP: (0x131701CC7F60A0E7A211D6B549000102)
01:39:54: As1/05 LCP: (0x2BC43900000000)
01:39:54: As1/05 LCP: State is Open
01:39:54: As1/05 PPP: Phase is AUTHENTICATING, by this end
01:39:54: As1/05 CHAP: O CHALLENGE id 1 len 26 from "s5400"
01:39:54: As1/05 LCP: I IDENTIFY [Open] id 2 len 18 magic 0x00002EB8 MSRASV4.00
01:39:54: As1/05 LCP: I IDENTIFY [Open] id 3 len 23 magic 0x00002EB8 MSRAS-1-PTE-PC1
01:39:54: As1/05 CHAP: I RESPONSE id 1 len 34 from "Administrator"
01:39:54: As1/05 PPP: Phase is FORWARDING, Attempting Forward
01:39:54: As1/05 PPP: Phase is AUTHENTICATING, Unauthenticated User
01:39:54: AAA/AUTHEN/PPP (00000007): Pick method list 'default'
01:39:54: As1/05 PPP: Sent CHAP LOGIN Request
01:39:54: RADIUS/ENCODE(00000007): Unsupported AAA attribute parent-interface
01:39:54: RADIUS/ENCODE(00000007): Unsupported AAA attribute parent-interface-type
01:39:54: RADIUS/ENCODE(00000007): acct_session_id: 9
01:39:54: RADIUS(00000007): sending
01:39:54: RADIUS: Send to unknown id 2 10.107.164.120:1645, Access-Request, len 128
01:39:54: RADIUS: authenticator 13 E4 F2 9F BC 3E CE 52 - CC 93 0C E0 01 0C 73 7B
01:39:54: RADIUS: Framed-Protocol [7] 6 PPP [1]
01:39:54: RADIUS: User-Name [1] 15 "Administrator"
01:39:54: RADIUS: CHAP-Password [3] 19 *
01:39:54: RADIUS: Called-Station-Id [30] 7 "50138"
01:39:54: RADIUS: Calling-Station-Id [31] 7 "60112"
01:39:54: RADIUS: Vendor, Cisco [26] 30
01:39:54: RADIUS: cisco-nas-port [2] 24 "Async1/05*Serial7/6:21"
01:39:54: RADIUS: NAS-Port [5] 6 221
01:39:54: RADIUS: NAS-Port-Type [61] 6 Async [0]
01:39:54: RADIUS: Service-Type [6] 6 Framed [2]
01:39:54: RADIUS: NAS-IP-Address [4] 6 10.0.58.107
01:39:54: RADIUS: Received from id 2 10.107.164.120:1645, Access-Accept, len 62
01:39:54: RADIUS: authenticator EF 45 A3 D4 A7 EE D0 65 - 03 50 B4 3E 07 87 2E 2F
01:39:54: RADIUS: Vendor, Cisco [26] 30
01:39:54: RADIUS: cisco-nas-port [2] 24 "Async1/05*Serial7/6:21"
01:39:54: RADIUS: Service-Type [6] 6 Framed [2]
01:39:54: RADIUS: Framed-Protocol [7] 6 PPP [1]
01:39:54: RADIUS: Received from id 7
01:39:54: As1/05 PPP: Received LOGIN Response PASS
01:39:54: As1/05 PPP/AAA: Check Attr: interface
01:39:54: As1/05 PPP/AAA: Check Attr: service-type
01:39:54: As1/05 PPP/AAA: Check Attr: Framed-Protocol
01:39:54: As1/05 PPP: Phase is FORWARDING, Attempting Forward
01:39:54: As1/05 PPP: Phase is AUTHENTICATING, Authenticated User
01:39:54: As1/05 AAA/AUTHOR/LCP: Process Author
01:39:54: As1/05 AAA/AUTHOR/LCP: Process Attr: service-type
01:39:54: As1/05 CHAP: O SUCCESS id 1 len 4
01:39:54: AAA/ACCT/NET(00000007): Pick method list 'default'
01:39:54: AAA/ACCT/SETMLIST(00000007): Handle FFFFFFFF, mlist 630B11E4, Name default
01:39:54: AAA/ACCT/EVENT/(00000007): NET UP
01:39:54: AAA/ACCT/NET(00000007): Queueing record is START
01:39:54: As1/05 PPP: Phase is UP
01:39:54: As1/05 AAA/AUTHOR/IPCP: FSM authorization not needed
01:39:54: As1/05 AAA/AUTHOR/FSM: We can start IPCP
01:39:54: As1/05 IPCP: O CONFREQ [Closed] id 1 len 10
01:39:54: As1/05 IPCP: Address 10.1.1.2 (0x030646010102)
01:39:54: AAA/ACCT(00000007): Accouting method=radius (radius)
01:39:54: RADIUS/ENCODE(00000007): Unsupported AAA attribute timezone
01:39:54: RADIUS/ENCODE(00000007): Unsupported AAA attribute parent-interface
01:39:54: RADIUS/ENCODE(00000007): Unsupported AAA attribute parent-interface-type
01:39:54: RADIUS(00000007): sending
01:39:54: RADIUS: Send to unknown id 8 10.107.164.120:1646, Accounting-Request, len 243
01:39:54: RADIUS: authenticator 41 87 FA 03 EB F9 94 62 - B2 3A 24 B8 27 4C A4 BC
01:39:54: RADIUS: Acct-Session-Id [44] 10 "00000009"
01:39:54: RADIUS: Framed-Protocol [7] 6 PPP [1]
01:39:54: RADIUS: Connect-Info [77] 26 "52000/28800 V90/V44/LAPM"
01:39:54: RADIUS: Vendor, Cisco [26] 48
01:39:54: RADIUS: Cisco AVpair [1] 42 "v92-info=V.92 QC MOH/No QC Requested/0/0"
01:39:54: RADIUS: Vendor, Cisco [26] 32
01:39:54: RADIUS: Cisco AVpair [1] 26 "connect-progress=Call Up"
01:39:54: RADIUS: Authentic [45] 6 RADIUS [1]
01:39:54: RADIUS: User-Name [1] 15 "Administrator"
01:39:54: RADIUS: Acct-Status-Type [40] 6 Start [1]
01:39:54: RADIUS: Called-Station-Id [30] 7 "50138"
01:39:54: RADIUS: Calling-Station-Id [31] 7 "60112"
01:39:54: RADIUS: Vendor, Cisco [26] 30
01:39:54: RADIUS: cisco-nas-port [2] 24 "Async1/05*Serial7/6:21"
01:39:54: RADIUS: NAS-Port [5] 6 221
01:39:54: RADIUS: NAS-Port-Type [61] 6 Async [0]
01:39:54: RADIUS: Service-Type [6] 6 Framed [2]
01:39:54: RADIUS: NAS-IP-Address [4] 6 10.0.58.107
01:39:54: RADIUS: Acct-Delay-Time [41] 6 0
01:39:54: RADIUS: Received from id 8 10.107.164.120:1646, Accounting-response, len 20
01:39:54: RADIUS: authenticator E5 5C D3 69 88 D5 2E 8E - 49 AF 63 22 01 53 33 7B
01:39:54: AAA/ACCT/NET(00000007): START protocol reply PASS
01:39:54: As1/05 CCP: I CONFREQ [Not negotiated] id 4 len 211
01:39:54: As1/05 CCP: Type254
01:39:54: As1/05 CCP: (0xFEC90100000000000000000000000000)
01:39:54: As1/05 CCP: (0x000074FFC7000000000068000000A000)
01:39:54: As1/05 CCP: (0x00006C20563905000000C0000000A400)
01:39:54: As1/05 CCP: (0x0000BC000000186400007000E80018C8)
01:39:54: As1/05 CCP: (0x130017CCF1770000000001000000E8FE)
01:39:54: As1/05 CCP: (0xC70076CDF17706000000000000000000)
01:39:54: As1/05 CCP: (0x00000000000000000000000000000000)
01:39:54: As1/05 CCP: (0x00000000000000000000000000000000)
01:39:54: As1/05 CCP: (0x00000000000000000000000000000000)
01:39:54: As1/05 CCP: (0x00000000000000000000220020000001)
01:39:54: As1/05 CCP: (0x0800000000005016B1CBA2E7D611B549)
01:39:54: As1/05 CCP: (0x0001022BC439C800000000000000C800)
01:39:54: As1/05 CCP: (0x00004D000000281FB8)
01:39:54: As1/05 CCP: MS-PPC supported bits 0x00000006 (0x120600000006)
01:39:54: As1/05 LCP: O PROTREJ [Open] id 2 len 217 protocol CCP
01:39:54: As1/05 LCP: (0x80FD010400D3FEC90100000000000000)
01:39:54: As1/05 LCP: (0x000000000000000074FFC70000000000)
01:39:54: As1/05 LCP: (0x68000000A00000006C20563905000000)
01:39:54: As1/05 LCP: (0xC0000000A4000000BC00000018640000)
01:39:54: As1/05 LCP: (0x7000E80018C8130017CCF17700000000)
01:39:54: As1/05 LCP: (0x01000000E8FEC70076CDF17706000000)
01:39:54: As1/05 LCP: (0x00000000000000000000000000000000)
01:39:54: As1/05 LCP: (0x00000000000000000000000000000000)
01:39:54: As1/05 LCP: (0x00000000000000000000000000000000)
01:39:54: As1/05 LCP: (0x00000000000000000000000000000000)
01:39:54: As1/05 LCP: (0x2200200000010800000000005016B1CB)
01:39:54: As1/05 LCP: (0xA2E7D611B5490001022BC439C8000000)
01:39:54: As1/05 LCP: (0x00000000C80000004D000000281FB812)
01:39:54: As1/05 LCP: (0x0600000006)
01:39:54: As1/05 IPCP: I CONFREQ [REQsent] id 5 len 34
01:39:54: As1/05 IPCP: Address 0.0.0.0 (0x030600000000)
01:39:54: As1/05 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
01:39:54: As1/05 IPCP: PrimaryWINS 0.0.0.0 (0x820600000000)
01:39:54: As1/05 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
01:39:54: As1/05 IPCP: SecondaryWINS 0.0.0.0 (0x840600000000)
01:39:54: As1/05 AAA/AUTHOR/IPCP: Start. Her address 0.0.0.0, we want 10.2.2.6
01:39:54: As1/05 AAA/AUTHOR/IPCP: Authorization succeeded
01:39:54: As1/05 AAA/AUTHOR/IPCP: Done. Her address 0.0.0.0, we want 10.2.2.6
01:39:54: As1/05 AAA/AUTHOR/IPCP: no author-info for primary dns
01:39:54: As1/05 AAA/AUTHOR/IPCP: no author-info for primary wins
01:39:54: As1/05 AAA/AUTHOR/IPCP: no author-info for seconday dns
01:39:54: As1/05 AAA/AUTHOR/IPCP: no author-info for seconday wins
01:39:54: As1/05 IPCP: O CONFREJ [REQsent] id 5 len 28
01:39:54: As1/05 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)
01:39:54: As1/05 IPCP: PrimaryWINS 0.0.0.0 (0x820600000000)
01:39:54: As1/05 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)
01:39:54: As1/05 IPCP: SecondaryWINS 0.0.0.0 (0x840600000000)
01:39:54: As1/05 IPCP: I CONFACK [REQsent] id 1 len 10
01:39:54: As1/05 IPCP: Address 70.1.1.2 (0x030646010102)
01:39:54: As1/05 IPCP: I CONFREQ [ACKrcvd] id 6 len 10
01:39:54: As1/05 IPCP: Address 0.0.0.0 (0x030600000000)
01:39:54: As1/05 IPCP: O CONFNAK [ACKrcvd] id 6 len 10
01:39:54: As1/05 IPCP: Address 70.2.2.6 (0x030646020206)
01:39:55: As1/05 IPCP: I CONFREQ [ACKrcvd] id 7 len 10
01:39:55: As1/05 IPCP: Address 70.2.2.6 (0x030646020206)
01:39:55: As1/05 IPCP: O CONFACK [ACKrcvd] id 7 len 10
01:39:55: As1/05 IPCP: Address 70.2.2.6 (0x030646020206)
01:39:55: As1/05 IPCP: State is Open
01:39:55: AAA/ACCT/EVENT/(00000007): IPCP_PASS
01:39:55: As1/05 IPCP: Install route to 10.2.2.6
01:39:55: As1/05 IPCP: Add link info for cef entry 10.2.2.6
01:40:50: ISDN Se7/6:23: RX <- DISCONNECT pd = 8 callref = 0x42A0
01:40:50: Cause i = 0x8190 - Normal call clearing
01:40:50: ISDN Se7/6:23: LIF_EVENT: ces/callid 1/0x38 CALL_DISC
01:40:50: EVENT_FROM_ISDN: dchan_idb=0x63B3D334, call_id=0x38, ces=0x0
bchan=0x15, event=0x0, cause=0x10
01:40:50: EVENT_FROM_ISDN:(0038): DEV_IDLE at slot 1 and port 5
01:40:50: CSM_PROC_IC7_OC6_CONNECTED: CSM_EVENT_ISDN_DISCONNECTED at slot 1, port 5
01:40:50: CSM DSPLIB(1/5): np_dsplib_call_hangup reason 14
01:40:50: CSM(1/5): Enter csm_enter_disconnecting_state
01:40:50: VDEV_DEALLOCATE: slot 1 and port 5 is deallocated
01:40:50: ISDN Se7/6:23: EVENT to CSM:DEV_IDLE: calltype=VOICE, bchan=21
01:40:50: ISDN Se7/6:23: process_disc_ack(): call id 0x38, ces 0, call type VOICE cause 0x10
01:40:50: ISDN Se7/6:23: TX -> RELEASE pd = 8 callref = 0xC2A0
01:40:50: AAA/ACCT/EVENT/(00000007): CALL STOP
01:40:50: AAA/ACCT/CALL STOP(00000007): Sending stop requests
01:40:50: AAA/ACCT(00000007): Send all stops
01:40:50: AAA/ACCT/NET(00000007): STOP
01:40:50: AAA/ACCT/NET(00000007): Queueing record is STOP osr 1
01:40:50: AAA/ACCT(00000007): Accouting method=radius (radius)
01:40:50: RADIUS/ENCODE(00000007): Unsupported AAA attribute timezone
01:40:50: RADIUS/ENCODE(00000007): Unsupported AAA attribute parent-interface
01:40:50: RADIUS/ENCODE(00000007): Unsupported AAA attribute parent-interface-type
01:40:50: RADIUS(00000007): sending
01:40:50: RADIUS: Send to unknown id 9 10.107.164.120:1646, Accounting-Request, len 315
01:40:50: RADIUS: authenticator 2E 6A 04 D0 04 9A D3 D5 - F7 DD 99 E0 C3 99 27 60
01:40:50: RADIUS: Acct-Session-Id [44] 10 "00000009"
01:40:50: RADIUS: Framed-Protocol [7] 6 PPP [1]
01:40:50: RADIUS: Framed-IP-Address [8] 6 70.2.2.6
01:40:50: RADIUS: Acct-Terminate-Cause[49] 6 lost-carrier [2]
01:40:50: RADIUS: Vendor, Cisco [26] 33
01:40:50: RADIUS: Cisco AVpair [1] 27 "disc-cause-ext=No Carrier"
01:40:50: RADIUS: Vendor, Cisco [26] 35
01:40:50: RADIUS: Cisco AVpair [1] 29 "connect-progress=LAN Ses Up"
01:40:50: RADIUS: Acct-Session-Time [46] 6 56
01:40:50: RADIUS: Connect-Info [77] 26 "52000/28800 V90/V44/LAPM"
01:40:50: RADIUS: Vendor, Cisco [26] 48
01:40:50: RADIUS: Cisco AVpair [1] 42 "v92-info=V.92 QC MOH/No QC Requested/0/0"
01:40:50: RADIUS: Acct-Input-Octets [42] 6 285
01:40:50: RADIUS: Acct-Output-Octets [43] 6 295
01:40:50: RADIUS: Acct-Input-Packets [47] 6 5
01:40:50: RADIUS: Acct-Output-Packets [48] 6 5
01:40:50: RADIUS: User-Name [1] 15 "Administrator"
01:40:50: RADIUS: Acct-Status-Type [40] 6 Stop [2]
01:40:50: RADIUS: Called-Station-Id [30] 7 "50138"
01:40:50: RADIUS: Calling-Station-Id [31] 7 "60112"
01:40:50: RADIUS: Vendor, Cisco [26] 30
01:40:50: RADIUS: cisco-nas-port [2] 24 "Async1/05*Serial7/6:21"
01:40:50: RADIUS: NAS-Port [5] 6 221
01:40:50: RADIUS: NAS-Port-Type [61] 6 Async [0]
01:40:50: RADIUS: Service-Type [6] 6 Framed [2]
01:40:50: RADIUS: NAS-IP-Address [4] 6 10.0.58.107
01:40:50: RADIUS: Acct-Delay-Time [41] 6 0
01:40:50: RADIUS: Received from id 9 10.107.164.120:1646, Accounting-response, len 20
01:40:50: RADIUS: authenticator D0 3F 32 D7 7C 8C 5E 22 - 9A 69 EF 17 AC 32 81 21
01:40:50: AAA/ACCT/NET(00000007): STOP protocol reply PASS
01:40:50: AAA/ACCT/NET(00000007): Cleaning up from Callback osr 0
01:40:50: AAA/ACCT(00000007): del node, session 9
01:40:50: AAA/ACCT/NET(00000007): free_rec, count 0
01:40:50: AAA/ACCT/NET(00000007) reccnt 0, csr TRUE, osr 0
01:40:50: AAA/ACCT/NET(00000007): Last rec in db, intf not enqueued
01:40:50: ISDN Se7/6:23: RX <- RELEASE_COMP pd = 8 callref = 0x42A0
01:40:50: ISDN Se7/6:23: CCPRI_ReleaseCall(): bchan 22, call id 0x38, call type VOICE
01:40:50: CCPRI_ReleaseChan released b_dsl 0 B_Chan 22
01:40:50: ISDN Se7/6:23: LIF_EVENT: ces/callid 1/0x38 CALL_CLEARED
01:40:50: ISDN Se7/6:23: received CALL_CLEARED call_id 0x38
01:40:50: no resend setup, no redial
01:40:50: no resend setup, no redial
01:40:50: AAA/ACCT/DS0: channel=21, ds1=6, t3=0, slot=7, ds0=117465109
01:40:50: EVENT_FROM_ISDN: dchan_idb=0x63B3D334, call_id=0x38, ces=0x1
bchan=0x15, event=0x0, cause=0x0
01:40:50: ISDN Se7/6:23: EVENT to CSM:DEV_IDLE: calltype=VOICE, bchan=21
01:40:51: CSM DSPLIB(1/5): Modem state changed to (TERMINATING_STATE)
01:40:51: CSM DSPLIB(1/5): Modem went onhook
01:40:51: CSM_PROC_IC8_OC8_DISCONNECTING: CSM_EVENT_MODEM_ONHOOK at slot 1, port 5
01:40:51: CSM(1/5): Enter csm_enter_idle_state
01:40:51: CSM DSPLIB(1/5):DSPLIB_IDLE: Modem session transition to FLUSHING
01:40:51: CSM DSPLIB(1/5):DSPLIB_IDLE: Modem session transition to IDLE
01:40:51: TTY1/05: DSR was dropped
01:40:51: tty1/05: Modem: READY->(unknown)
01:40:52: TTY1/05: dropping DTR, hanging up
01:40:52: DSPLIB(1/5): np_dsplib_process_dtr_notify()
01:40:52: CSM DSPLIB(1/5): Modem went onhook
01:40:52: CSM_PROC_IDLE: CSM_EVENT_MODEM_ONHOOK at slot 1, port 5
01:40:52: TTY1/05: Async Int reset: Dropping DTR
01:40:52: tty1/05: Modem: HANGUP->(unknown)
01:40:52: AAA/ACCT/EVENT/(00000007): NET DOWN
01:40:52: As1/05 IPCP: Remove link info for cef entry 70.2.2.6
01:40:52: As1/05 IPCP: State is Closed
01:40:52: As1/05 PPP: Phase is TERMINATING
01:40:52: As1/05 LCP: State is Closed
01:40:52: As1/05 PPP: Phase is DOWN
01:40:52: As1/05 IPCP: Remove route to 70.2.2.6
01:40:52: As1/05 LCP: State is Closed
01:40:53: TTY1/05: cleanup pending. Delaying DTR
01:40:54: TTY1/05: cleanup pending. Delaying DTR
01:40:55: TTY1/05: cleanup pending. Delaying DTR
01:40:56: TTY1/05: cleanup pending. Delaying DTR
01:40:57: TTY1/05: no timer type 0 to destroy
01:40:57: TTY1/05: no timer type 1 to destroy
01:40:57: TTY1/05: no timer type 3 to destroy
01:40:57: TTY1/05: no timer type 4 to destroy
01:40:57: TTY1/05: no timer type 2 to destroy
01:40:57: Async1/05: allowing modem_process to continue hangup
01:40:57: TTY1/05: restoring DTR
01:40:57: TTY1/05: autoconfigure probe started
01:40:57: As1/05 LCP: State is Closed