Use the following
commands to verify PacketCable and PCMM call trace functionality on the Cisco
CMTS router:
- debug
cable
dynamic-qos
subscriber
-
debug
cable
dynamic-qos
trace
- show
cable
dynamic-qos
trace
To verify debugging information for all the configured
subscribers on the Cisco CMTS router, use the
debug
cable
dynamic-qos
subscriber command as shown in the following
example:
Router# debug cable dynamic-qos subscriber
CMTS dynqos subscriber debugging is on ubr10k#
*Mar 17 08:32:27.135: Pktcbl(gdb): Created gate IE on Cable7/1/0, gateid = 10804
2
*Mar 17 08:32:27.135: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:27.135: Pktcbl(mm): Change profile 0 qos 0
*Mar 17 08:32:27.135: Pktcbl(gdb): IPC timer [id 108042] [10000 msec]
*Mar 17 08:32:27.135: Pktcbl(gdb): Started gate [id 108042] timer [type 8] [1000
0 msec]
*Mar 17 08:32:27.135: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:27.135: Pktcbl(gdb): MM gate spec: t1:200, t2:0, t3:0, t4:0
*Mar 17 08:32:27.135: Pktcbl(gdb): MM traffic profile type: 6
*Mar 17 08:32:27.135: Pktcbl(gdb): MM Authorized Profile
*Mar 17 08:32:27.135: Pktcbl(gdb): MM Reserved Profile
*Mar 17 08:32:27.135: Pktcbl(gdb): MM Committed Profile
*Mar 17 08:32:27.135: Classifier prototype: 1, src: 9.9.1.95, dest: 2.39.26.11,
src port: 0, dest port: 0
*Mar 17 08:32:27.179: Pktcbl(mm): Received gate-set IPC RSP from LC for gate 108
042 rsp 1 state new(4) old(2)
*Mar 17 08:32:27.179: Pktcbl(gdb): Cancelled gate [id 108042] timer [type 8]
*Mar 17 08:32:27.179: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:27.179: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:27.179: Pktcbl(gdb): Started gate [id 108042] timer [type 3] [0 ms
ec]
*Mar 17 08:32:27.179: PktCbl(d2r): extract id: gate=108042, resource=74
*Mar 17 08:32:27.179: PktCbl(d2r): extract id: gate=108042, resource=74
*Mar 17 08:32:27.179: Pktcbl(gdb): TOS Overwrite gate spec info,gate_id=108042 d
ir=1 gie=26DD5C98
*Mar 17 08:32:27.179: Pktcbl(gdb): TOS Overwrite Gate=108042 DSCP=0xD0 mask=0xF
*Mar 17 08:32:27.179: PktCbl(d2r): extract id: gate=108042, resource=74
*Mar 17 08:32:27.179: PktCbl(mm-r2d): DSA-ACK notification received on RP, gatei
d 108042 sfid 74
*Mar 17 08:32:27.179: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:27.183: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:27.183: Pktcbl(mm): Building GCP message, added obj TRANSACTION
ubr10k# ID ; len:8 padding:0
*Mar 17 08:32:27.183: Pktcbl(mm): Building GCP message, added obj AM ID
; len:8 padding:0
*Mar 17 08:32:27.183: Pktcbl(mm): Building GCP message, added obj SUBSCRIBER ID
; len:8 padding:0
*Mar 17 08:32:27.183: Pktcbl(mm): Building GCP message, added obj GATE ID
; len:8 padding:0
*Mar 17 08:32:27.183: Pktcbl(mm): Building GCP message, added obj OPAQUE
; len:12 padding:0
*Mar 17 08:32:27.183: Pktcbl(mm): Built GCP message, GATE SET ACK , lengt
h: 44, copsLen 72
*Mar 17 08:32:27.183: --- Pktcbl: Sending GCP message ------
*Mar 17 08:32:27.183: TRANSACTION ID : Object.[snum/stype/len 1/1/8]
*Mar 17 08:32:27.183: transaction id : 0x1
*Mar 17 08:32:27.183: gcp cmd : 5 (GATE SET ACK)
*Mar 17 08:32:27.183: AM ID : Object.[snum/stype/len 2/1/8]
*Mar 17 08:32:27.183: AM ID : 0x1 (0/1)
*Ma
ubr10k#r 17 08:32:27.183: SUBSCRIBER ID : Object.[snum/stype/len 3/1/8]
*Mar 17 08:32:27.183: Addr : 21.21.2.10
*Mar 17 08:32:27.183: GATE ID : Object.[snum/stype/len 4/1/8]
*Mar 17 08:32:27.183: GateID : 108042 (0x1A60A)
*Mar 17 08:32:27.183: OPAQUE : Object.[snum/stype/len 11/1/12]
*Mar 17 08:32:27.183: data : [31 32 33 34 00 00 00 00 ]
*Mar 17 08:32:27.183: --------------------------------------
SLOT 7/1: Mar 17 08:32:27.152: Pktcbl(gdb): Gate ID 108042 not found in gdb, pkt
cbl_find_gate_ie.
SLOT 5/0: Mar 17 08:32:27.151: Pktcbl(gdb): Gate ID 108042 not found in gdb, pkt
cbl_find_gate_ie.
ubr10k#
*Mar 17 08:32:56.656: Pktcbl(mm): Received GATE SET message, tid=0x2
*Mar 17 08:32:56.656: --- Pktcbl(mm): Received GCP message ------
*Mar 17 08:32:56.656: TRANSACTION ID : Object.[snum/stype/len 1/1/8]
*Mar 17 08:32:56.656: transaction id : 0x2
*Mar 17 08:32:56.656: gcp cmd : 4 (GATE SET)
*Mar 17 08:32:56.656: AM ID : Object.[snum/stype/len 2/1/8]
*Mar 17 08:32:56.656: AM ID : 0x1 (0/1)
*Mar 17 08:32:56.656: SUBSCRIBER ID : Object.[snum/stype/len 3/1/8]
*Mar 17 08:32:56.656: Addr : 21.21.2.10
*Mar 17 08:32:56.656: GATE ID : Object.[snum/stype/len 4/1/8]
*Mar 17 08:32:56.656: GateID : 108042 (0x1A60A)
*Mar 17 08:32:56.656: GATE SPEC : Object.[snum/stype/len 5/1/16]
*Mar 17 08:32:56.656: flag : 0x3
*Mar 17 08:32:56.656: dscp : 0xD8
*Mar 17 08:32:56.656: dscp tos mask : 0xF0
*Mar 17 08:32:56.656: Timers t1 : 0, t2 : 0
*Mar 17 08:32:56.656: t3 : 0, t4 : 0
*Mar 17 08:32:56.656: session class : 0x0
*Mar 17 08:32:56.656: TRAFFIC PROFILE : Object.[snum/stype/len 7/6/56]
*Mar 17 08:32:56.656: envelope : 0x7
*Mar 17 08:32:56.656: service number : 0x0
*Mar 17 08:32:56.656: Authorized :
*Mar 17 08:32:56.656: Request Xmit Policy: 0x17F
*Mar 17 08:32:56.656: Grant size : 232
*Mar 17 08:32:56.656: Grant Per Interval : 2
*Mar 17 08:32:56.656: Grant Interval : 20000
*Mar 17 08:32:56.656: Tolerated Jitter : 800
*Mar 17 08:32:56.656: Required Mask : 0
*Mar 17 08:32:56.656: Forbidden Mask : 0
*Mar 17 08:32:56.656: Aggr Rule Mask : 0
*Mar 17 08:32:56.656: Reserved :
*Mar 17 08:32:56.656: Request Xmit Policy: 0x17F
*Mar 17 08:32:56.656: Grant size : 232
*Mar 17 08:32:56.656: Grant Per Interval : 2
*Mar 17 08:32:56.656: Grant Interval : 20000
*Mar 17 08:32:56.656: Tolerated Jitter : 800
*Mar 17 08:32:56.656: Required Mask : 0
*
ubr10k#Mar 17 08:32:56.656: Forbidden Mask : 0
*Mar 17 08:32:56.656: Aggr Rule Mask : 0
*Mar 17 08:32:56.656: CLASSIFIER : Object.[snum/stype/len 6/1/24]
*Mar 17 08:32:56.656: protocol : 1
*Mar 17 08:32:56.656: dscp : 0x0
*Mar 17 08:32:56.656: dscp tos mask : 0x0
*Mar 17 08:32:56.656: src/port : 9.9.1.95 0
*Mar 17 08:32:56.656: dest/port : 2.39.26.11 0
*Mar 17 08:32:56.656: priority : 64
*Mar 17 08:32:56.656: CLASSIFIER : Object.[snum/stype/len 6/1/24]
*Mar 17 08:32:56.656: protocol : 1
*Mar 17 08:32:56.656: dscp : 0x0
*Mar 17 08:32:56.656: dscp tos mask : 0x0
*Mar 17 08:32:56.656: src/port : 9.9.1.43 0
*Mar 17 08:32:56.656: dest/port : 2.39.26.19 0
*Mar 17 08:32:56.656: priority : 64
*Mar 17 08:32:56.656: OPAQUE : Object.[snum/stype/
ubr10k#len 11/1/12]
*Mar 17 08:32:56.656: data : [31 32 33 34 00 00 00 00 ]
*Mar 17 08:32:56.656: --------------------------------------
*Mar 17 08:32:56.656: Backup gate IE [108042]
*Mar 17 08:32:56.656: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:56.656: Pktcbl(mm): Change profile 1 qos 1
*Mar 17 08:32:56.656: Pktcbl(gdb): IPC timer [id 108042] [10000 msec]
*Mar 17 08:32:56.656: Pktcbl(gdb): Started gate [id 108042] timer [type 8] [1000
0 msec]
*Mar 17 08:32:56.656: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:56.656: Pktcbl(gdb): MM gate spec: t1:200, t2:0, t3:0, t4:0
*Mar 17 08:32:56.656: Pktcbl(gdb): MM traffic profile type: 6
*Mar 17 08:32:56.656: Pktcbl(gdb): MM Authorized Profile
*Mar 17 08:32:56.660: Pktcbl(gdb): MM Reserved Profile
*Mar 17 08:32:56.660: Pktcbl(gdb): MM Committed Profile
*Mar 17 08:32:56.660: Classifier prototype: 1, src: 9.9.1.95, dest: 2.39.26.11,
src port: 0, dest port: 0
*Mar 17 08:32:56.660: Classifier prototype: 1, src: 9.9.1.43, dest: 2.39.26.19,
src port: 0, dest port: 0
*Mar 17 08:32:56.696: Pktcbl(mm): Received gate-set IPC RSP from LC for gate 108
042 rsp 1 state new(4) old(4)
*Mar 17 08:32:56.696: Pktcbl(gdb): Cancelled gate [id 108042] timer [type 8]
*Mar 17 08:32:56.696: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:56.696: Pktcbl(gdb): Started gate [id 108042] timer [type 3] [0 ms
ec]
*Mar 17 08:32:56.696: Pktcbl(gdb): Cleanup saved gate IE info, gate(108042)
*Mar 17 08:32:56.696: PktCbl(d2r): extract id: gate=108042, resource=74
*Mar 17 08:32:56.696: Pktcbl(gdb): TOS Overwrite gate spec info,gate_id=108042 d
ir=1 gie=26DD5C98
*Mar 17 08:32:56.696: Pktcbl(gdb): TOS Overwrite Gate=108042 DSCP=0xD0 mask=0xF
*Mar 17 08:32:56.696: PktCbl(d2r): extract id: gate=108042, resource=74
*Mar 17 08:32:56.696: PktCbl(mm-r2d): DSA-ACK notification received on RP, gatei
d 108042 sfid 74
*Mar 17 08:32:56.696: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:56.696: Pktcbl(gdb): Found Cable7/1/0 for Gate=108042 21.21.2.10
*Mar 17 08:32:56.696: Pktcbl(mm): Building GCP message, added obj TRANSACTION ID
; len:8 padding:0
*Mar 17 08:32:56.696: Pktcbl(mm): Building GCP message, added obj AM ID
; len:8 padding:0
*Mar 17 08:32:56.696: Pktcbl(mm): Building GCP message, added obj SUBSCRIBER ID
; len:8 padding:0
*Mar 17 08:32:56.696: Pktcbl(mm): Building GCP message, added obj GATE ID
; len:8 padding:0
*Mar 17 08:32:56.696: Pktcbl(mm): Building GCP message, added obj OPAQUE
; len:12 padding:0
*Mar 17 08:32:56.696: Pktcbl(mm): Built GCP message, GATE SET ACK , lengt
h: 44, copsLen 72
*Mar 17 08:32:56.696: --- Pktcbl: Sending GCP message ------
*Mar 17 08:32:56.696: TRANSACTION ID : Object.[snum/stype/len 1/1/8]
*Mar 17 08:32:56.696: transaction id : 0x2
*Mar 17 08:32:56.696: gcp cmd : 5 (GATE SET ACK)
*Mar 17 08:32:56.696: AM ID : Object.[snum/stype/len 2/1/8]
*Mar 17 08:32:56.696: AM ID : 0x1 (0/1)
*Mar 17 08:32:56.696: SUBSCRIBER ID : Object.[snum/stype/len 3/1/8]
*Mar 17 08:32:56.696: Addr : 21.21.2.10
*Mar 17 08:32:56.696: GATE ID : Object.[snum/stype/len 4/1/8]
*Mar 17 08:32:56.696: GateID : 108042 (0x1A60A)
*Mar 17 08:32:56.696: OPAQUE : Object.[snum/stype/len 11/1/12]
*Mar 17 08:32:56.696: data : [31 32 33 34 00 00 00 00 ]
*Mar 17 08:32:56.696: --------------------------------------
To verify call trace debugging information for all the
configured subscribers on the Cisco CMTS router, use the
debug
cable
dynamic-qos
trace command as shown in the following example:
Router# debug cable dynamic-qos trace
CMTS dynqos trace debugging is on ubr10k#
*Mar 17 02:43:05.448: Pktcbl(mm): Received GATE SET message, tid=0x2
*Mar 17 02:43:05.448: --- Pktcbl(mm): Received GCP message ------
*Mar 17 02:43:05.448: TRANSACTION ID : Object.[snum/stype/len 1/1/8]
*Mar 17 02:43:05.448: transaction id : 0x2
*Mar 17 02:43:05.448: gcp cmd : 4 (GATE SET)
*Mar 17 02:43:05.448: AM ID : Object.[snum/stype/len 2/1/8]
*Mar 17 02:43:05.448: AM ID : 0x1 (0/1)
*Mar 17 02:43:05.448: SUBSCRIBER ID : Object.[snum/stype/len 3/1/8]
*Mar 17 02:43:05.448: Addr : 21.21.2.10
*Mar 17 02:43:05.448: GATE ID : Object.[snum/stype/len 4/1/8]
*Mar 17 02:43:05.452: GateID : 58886 (0xE606)
*Mar 17 02:43:05.452: GATE SPEC : Object.[snum/stype/len 5/1/16]
*Mar 17 02:43:05.452: flag : 0x3
*Mar 17 02:43:05.452: dscp : 0xD8
*Mar 17 02:43:05.452: dscp tos mask : 0xF0
*Mar 17 02:43:05.452: Timers t1 : 0, t2 : 0
*Mar 17 02:43:05.452: t3 : 0, t4 : 0
*Mar 17 02:43:05.452: session class : 0x0
*Mar 17 02:43:05.452: TRAFFIC PROFILE : Object.[snum/stype/len 7/6/56]
*Mar 17 02:43:05.452: envelope : 0x7
*Mar 17 02:43:05.452: service number : 0x0
*Mar 17 02:43:05.452: Authorized :
*Mar 17 02:43:05.452: Request Xmit Policy: 0x17F
*Mar 17 02:43:05.452: Grant size : 232
*Mar 17 02:43:05.452: Grant Per Interval : 2
*Mar 17 02:43:05.452: Grant Interval : 20000
*Mar 17 02:43:05.452: Tolerated Jitter : 800
*Mar 17 02:43:05.452: Required Mask : 0
*Mar 17 02:43:05.452: Forbidden Mask : 0
*Mar 17 02:43:05.452: Aggr Rule Mask : 0
*Mar 17 02:43:05.452: Reserved :
*Mar 17 02:43:05.452: Request Xmit Policy: 0x17F
*Mar 17 02:43:05.452: Grant size : 232
*Mar 17 02:43:05.452: Grant Per Interval : 2
*Mar 17 02:43:05.452: Grant Interval : 20000
*Mar 17 02:43:05.452: Tolerated Jitter : 800
*Mar 17 02:43:05.452: Required Mask : 0
*Ma
ubr10k#r 17 02:43:05.452: Forbidden Mask : 0
*Mar 17 02:43:05.452: Aggr Rule Mask : 0
*Mar 17 02:43:05.452: CLASSIFIER : Object.[snum/stype/len 6/1/24]
*Mar 17 02:43:05.452: protocol : 1
*Mar 17 02:43:05.452: dscp : 0x0
*Mar 17 02:43:05.452: dscp tos mask : 0x0
*Mar 17 02:43:05.452: src/port : 9.9.1.95 0
*Mar 17 02:43:05.452: dest/port : 2.39.26.11 0
*Mar 17 02:43:05.452: priority : 64
*Mar 17 02:43:05.452: CLASSIFIER : Object.[snum/stype/len 6/1/24]
*Mar 17 02:43:05.452: protocol : 1
*Mar 17 02:43:05.452: dscp : 0x0
*Mar 17 02:43:05.452: dscp tos mask : 0x0
*Mar 17 02:43:05.452: src/port : 9.9.1.43 0
*Mar 17 02:43:05.452: dest/port : 2.39.26.19 0
*Mar 17 02:43:05.452: priority : 64
*Mar 17 02:43:05.452: OPAQUE : Object.[snum/stype/le
ubr10k#n 11/1/12]
*Mar 17 02:43:05.452: data : [31 32 33 34 00 00 00 00 ]
*Mar 17 02:43:05.452: --------------------------------------
*Mar 17 02:43:05.452: Backup gate IE [58886]
*Mar 17 02:43:05.452: Pktcbl(gdb): Found Cable7/1/0 for Gate=58886 21.21.2.10
*Mar 17 02:43:05.452: Pktcbl(mm): Change profile 1 qos 1
*Mar 17 02:43:05.452: Pktcbl(gdb): IPC timer [id 58886] [10000 msec]
*Mar 17 02:43:05.452: Pktcbl(gdb): Started gate [id 58886] timer [type 8] [10000
msec]
*Mar 17 02:43:05.452: Pktcbl(gdb): Found Cable7/1/0 for Gate=58886 21.21.2.10
*Mar 17 02:43:05.452: Pktcbl(gdb): MM gate spec: t1:200, t2:0, t3:0, t4:0
*Mar 17 02:43:05.452: Pktcbl(gdb): MM traffic profile type: 6
*Mar 17 02:43:05.452: Pktcbl(gdb): MM Authorized Profile
*Mar 17 02:43:05.452: Pktcbl(gdb): MM Reserved Profile
*Mar 17 02:43:05.452: Pktcbl(gdb): MM Committed Profile
*Mar 17 02:43:05.452: Classifier prototype: 1, src: 9.9.1.95, dest: 2.39.26.11,
src port: 0, dest port: 0
*Mar 17 02:43:05.452: Classifier prototype: 1, src: 9.9.1.43, dest: 2.39.26.19,
src port: 0, dest port: 0
*Mar 17 02:43:05.480: Pktcbl(mm): Received gate-set IPC RSP from LC for gate 588
86 rsp 1 state new(4) old(4)
*Mar 17 02:43:05.480: Pktcbl(gdb): Cancelled gate [id 58886] timer [type 8]
*Mar 17 02:43:05.480: Pktcbl(gdb): Found Cable7/1/0 for Gate=58886 21.21.2.10
*Mar 17 02:43:05.480: Pktcbl(gdb): Started gate [id 58886] timer [type 3] [0 mse
c]
*Mar 17 02:43:05.480: Pktcbl(gdb): Cleanup saved gate IE info, gate(58886)
*Mar 17 02:43:05.484: PktCbl(d2r): extract id: gate=58886, resource=64
*Mar 17 02:43:05.484: Pktcbl(gdb): TOS Overwrite gate spec info,gate_id=58886 di
r=1 gie=265BDB84
*Mar 17 02:43:05.484: Pktcbl(gdb): TOS Overwrite Gate=58886 DSCP=0xD0 mask=0xF
*Mar 17 02:43:05.484: PktCbl(d2r): extract id: gate=58886, resource=64
*Mar 17 02:43:05.484: PktCbl(mm-r2d): DSA-ACK notification received on RP, gatei
d 58886 sfid 64
*Mar 17 02:43:05.484: Pktcbl(gdb): Found Cable7/1/0 for Gate=58886 21.21.2.10
*Mar 17 02:43:05.484: Pktcbl(gdb): Found Cable7/1/0 for Gate=58886 21.21.2.10
*Mar 17 02:43:05.484: Pktcbl(mm): Building GCP message, added obj TRANSACTION ID
; len:8 padding:0
*Mar 17 02:43:05.484: Pktcbl(mm): Building GCP message, added obj AM ID
; len:8 padding:0
*Mar 17 02:43:05.484: Pktcbl(mm): Building GCP message, added obj SUBSCRIBER ID
; len:8 padding:0
*Mar 17 02:43:05.484: Pktcbl(mm): Building GCP message, added obj GATE ID
; len:8 padding:0
*Mar 17 02:43:05.484: Pktcbl(mm): Building GCP message, added obj OPAQUE
; len:12 padding:0
*Mar 17 02:43:05.484: Pktcbl(mm): Built GCP message, GATE SET ACK , lengt
h: 44, copsLen 72
*Mar 17 02:43:05.484: --- Pktcbl: Sending GCP message ------
*Mar 17 02:43:05.484: TRANSACTION ID : Object.[snum/stype/len 1/1/8]
*Mar 17 02:43:05.484: transaction id : 0x2
*Mar 17 02:43:05.484: gcp cmd : 5 (GATE SET ACK)
*Mar 17 02:43:05.484: AM ID : Object.[snum/stype/len 2/1/8]
*Mar 17 02:43:05.484: AM ID : 0x1 (0/1)
*Mar 17 02:43:05.484: SUBSCRIBER ID : Object.[snum/stype/len 3/1/8]
*Mar 17 02:43:05.484: Addr : 21.21.2.10
*Mar 17 02:43:05.484: GATE ID : Object.[snum/stype/len 4/1/8]
*Mar 17 02:43:05.484: GateID : 58886 (0xE606)
*Mar 17 02:43:05.484: OPAQUE : Object.[snum/stype/len 11/1/12]
*Mar 17 02:43:05.484: data : [31 32 33 34 00 00 00 00 ]
*Mar 17 02:43:05.484: --------------------------------------
To verify the
number of subscribers for whom call trace is enabled on the Cisco CMTS router,
use the
show
cable
dynamic-qos
trace command as shown in the following example:
Router# show cable dynamic-qos trace
Total number of subscribers: 0
Max configured number of subscribers: 12