コール フローのトレース
ここでは、Cisco Communications Manager トレース ファイル CCM000000000 の例を使用してコール フローについて説明します。このケース スタディのトレースでは、コール フロー自体に焦点を絞っています。詳細なトレース情報については、Cisco Unified IP Phone コールに関連するトピックを参照してください(たとえば、初期化、登録、およびキープアライブ メカニズムなど)。
このコール フローでは、Cluster 2 にある Cisco Unified IP Phone(電話番号 1001)が PSTN 上の電話機(電話番号 3333)にコールを発信します。トレースでデバイスを追跡するには、デバイスの TCP ハンドル値、タイムスタンプ、または名前を調べます。デバイスの TCP ハンドル値は、デバイスがリブートされるかオフラインになるまで変わりません。
次のトレースでは、Cisco Unified IP Phone(1001)がオフフックになっています。トレースは、Cisco Unified IP Phone に表示される固有のメッセージ、TCP ハンドル、発信番号を示しています。ユーザはまだ番号をダイヤルしていないため、この時点では着信番号は表示されていません。
16:05:46.37515:20:18.390 CCM|StationInit - InboundStim - OffHookMessageID tcpHandle=0x5138d98 15:20:18.390 CCM|StationD - stationOutputDisplayText tcpHandle=0x5138d98, Display=1001
次のトレースでは、ユーザが DN 3333 をダイヤルしています(数字を 1 つずつダイヤルします)。番号 3333 は、PSTN ネットワーク上の電話機の宛先番号です。現在アクティブな Unified Communications Manager の番号分析プロセスが番号を分析して、コールのルーティング先を検出します。番号分析の詳細については、Cisco Unified IP Phone コールに関連するトピックを参照してください。
15:20:18.390 CCM|Digit analysis: match(fqcn="", cn="1001", pss="", dd="")15:20:19.703 CCM|Digit analysis: match(fqcn="", cn="1001", pss="", dd="3") 15:20:20.078 CCM|Digit analysis: match(fqcn="", cn="1001", pss="", dd="33") 15:20:20.718 CCM|Digit analysis: match(fqcn="", cn="1001", pss="", dd="333") 15:20:21.421 CCM|Digit analysis: match(fqcn="", cn="1001", pss="", dd="3333") 15:20:21.421 CCM|Digit analysis: analysis results
次のトレースでは、番号分析が完了して発信側と着信側が一致し、情報が解析されています。
|CallingPartyNumber=1001|DialingPattern=3333 |DialingRoutePatternRegularExpression=(3333) |PretransformDigitString=3333 |PretransformPositionalMatchList=3333 |CollectedDigits=3333 |PositionalMatchList=3333
次のトレースでは、番号 0 が発信元のロケーションを示し、番号 1 が宛先のロケーションを示しています。BW = -1 は、発信元のロケーションの帯域幅を示します。値 -1 は、帯域幅が無限であることを暗黙的に示します。コールは LAN 環境にある Cisco Unified IP Phone から発信されたため、帯域幅は無限であると見なされます。BW = 64 は、宛先のロケーションの帯域幅を示します。コールの宛先は PSTN 上にある電話機で、使用されるコーデック タイプは G.711(64 Kbps)です。
15:20:21.421 CCM|Locations:Orig=0 BW=-1 Dest=1 BW=64 (-1 implies infinite bw available)
次のトレースは、発信側および着信側の情報を示しています。この例では、管理者が表示名(John Smith など)を設定していないため、発呼側の名前と番号は同じです。
15:20:21.421 CCM|StationD - stationOutputCallInfo CallingPartyName=1001, CallingParty=1001, CalledPartyName=, CalledParty=3333, tcpHandle=0x5138d98
次のトレースは、H.323 コードが初期化され、H.225 セットアップ メッセージを送信していることを示しています。従来の HDLC SAPI メッセージ、16 進表記の着信側の IP アドレス、およびポート番号も確認できます。
15:20:21.421 CCM|Out Message -- H225SetupMsg -- Protocol= H225Protocol15:20:21.421 CCM|MMan_Id= 1. (iep= 0 dsl= 0 sapi= 0 ces= 0 IpAddr=e24610ac IpPort=47110)
次のトレースは、発信側および着信側の情報と H.225 アラート メッセージを示しています。また、Cisco Unified IP Phone の 16 進数値と IP アドレスのマッピングも示されています。Cisco Unified IP Phone(1001)の IP アドレスは 172.16.70.231 です。
15:20:21.437 CCM|StationD - stationOutputCallInfo CallingPartyName=1001, CallingParty=1001, CalledPartyName=, CalledParty=3333, tcpHandle=0x5138d9815:20:21.453 CCM|In Message -- H225AlertMsg -- Protocol= H225Protocol 15:20:21.953 CCM|StationD - stationOutputOpenReceiveChannel tcpHandle=0x5138d98 myIP: e74610ac (172.16.70.231)
次のトレースは、このコールで使用されている圧縮タイプ(G.711 mu-law)を示しています。
15:20:21.953 CCM|StationD - ConferenceID: 0 msecPacketSize: 20 compressionType:(4)Media_Payload_G711Ulaw64k
H.323 は H.225 アラート メッセージが送信されたあとで、H.245 を初期化します。次のトレースは、発信側および着信側の情報と H.245 メッセージを示しています。TCP ハンドル値はこれまでと同じで、同じコールが続いていることを示しています。
ONE FOR EACH Channel- 16:53:36.855 CCM|H245Interface(3) paths established ip = e98e6b80, port = 1304|<CT::1,100,105,1.1682><IP::128.107.142.233>ONE FOR EACH Channel- 16:53:37.199 CCM|H245Interface(3) OLC outgoing confirm ip = b870701, port = 49252|<CT::1,100,128,3.9><IP::1.7.135.11> H323 EP has answered the call and H245 channel setup in progress: 16:53:13.479 CCM|In Message -- H225ConnectMsg -- Protocol= H225Protocol| 16:03:25.359 CCM|StationD(1): TCPPid = [1.100.117.1] CallInfo callingPartyName='' callingParty=13001 cgpnVoiceMailbox= calledPartyName='' calledParty=11002 cdpnVoiceMailbox= originalCalledPartyName='' originalCalledParty=11002 originalCdpnVoiceMailbox= originalCdpnRedirectReason=0 lastRedirectingPartyName='' lastRedirectingParty=11002 lastRedirectingVoiceMailbox= lastRedirectingReason=0 callType=2(OutBound) lineInstance=1 callReference=16777217. version: 0|<CT::1,100,11,2.1><IP::><DEV::> 16:03:25.328 CCM|StationD(1): TCPPid = [1.100.117.1] OpenReceiveChannel conferenceID=16777217 passThruPartyID=16777233 millisecondPacketSize=20 compressionType=4(Media_Payload_G711Ulaw64k) qualifierIn=?. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,11,1.1><IP::><DEV::> 16:03:25.359 CCM|StationD(2): TCPPid = [1.100.117.2] StartMediaTransmission conferenceID=16777218 passThruPartyID=16777249 remoteIpAddress=e98e6b80(64.255.0.0) remotePortNumber=65344 milliSecondPacketSize=20 compressType=4(Media_Payload_G711Ulaw64k) qualifierOut=?. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,105,1.213><IP::128.107.142.233> 16:03:25.375 CCM|StationD(2): TCPPid = [1.100.117.2] star_StationOutputStartMultiMediaTransmission conferenceID=16777218 passThruPartyID=16777250 remoteIpAddress=e98e6b80(66.255.0.0) remotePortNumber=65346 compressType=101(Media_Payload_H263) qualifierOut=?. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,105,1.215><IP::128.107.142.233> 16:03:25.328 CCM|StationD(1): TCPPid=[1.100.117.1] OpenMultiReceiveChannel conferenceID=16777217 passThruPartyID=1000011 compressionType=101(Media_Payload_H263) qualifierIn=?. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,11,1.1><IP::><DEV::>
次のトレースは、H.225 接続メッセージとその他の情報を示しています。H.225 接続メッセージが受信されると、コールが接続します。
15:20:22.968 CCM|In Message -- H225ConnectMsg -- Protocol= H225Protocol15:20:22.968 CCM|StationD - stationOutputCallInfo CallingPartyName=1001, CallingParty=1001, CalledPartyName=, CalledParty=3333, tcpHandle=0x5138d98 15:20:22.062 CCM|MediaCoordinator - wait_AuConnectInfoInd 15:20:22.062 CCM|StationD - stationOutputStartMediaTransmission tcpHandle=0x5138d98 myIP: e74610ac (172.16.70.231) 15:20:22.062 CCM|StationD - RemoteIpAddr: e24610ac (172.16.70.226) RemoteRtpPortNumber: 16758 msecPacketSize: 20 compressionType:(4)Media_Payload_G711Ulaw64k 15:20:22.062 CCM|Locations:Orig=0 BW=-1Dest=1 BW=6(-1 implies infinite bw available) 16:03:25.359 CCM|MediaManager(1) - wait_AuConnectInfo - recieved response, fowarding, CI(16777217,16777218)|<CT::1,100,105,1.213><IP::128.107.142.233> 16:03:25.359 CCM|MediaCoordinator - wait_AuConnectInfoInd|<CT::1,100,105,1.213><IP::128.107.142.233> 16:03:25.359 CCM|ConnectionManager - wait_AuConnectInfoInd, CI(16777217,16777218)|<CT::1,100,105,1.213><IP::128.107.142.233>
次のメッセージは、Cisco Unified IP Phone(1001)からのオンフック メッセージを受信していることを示しています。オンフック メッセージを受信するとすぐに、H.225 と Skinny Station デバイスの接続解除メッセージが送信され、H.225 メッセージの全文が表示されます。この最後のメッセージには、コールが終端したことが示されています。
15:20:27.296 CCM|StationInit - InboundStim - OnHookMessageID tcpHandle=0x5138d9815:20:27.296 CCM|ConnectionManager -wait_AuDisconnectRequest (16777247,16777248): STOP SESSION 15:20:27.296 CCM|MediaManager - wait_AuDisconnectRequest - StopSession sending disconnect to (64,5) and remove connection from list 15:20:27.296 CCM| Device SEP003094C26105 , UnRegisters with SDL Link to monitor NodeID= 1 15:20:27.296 CCM|StationD - stationOutputCloseReceiveChannel tcpHandle=0x5138d98 myIP: e74610ac (172.16.70.231) 15:20:27.296 CCM|StationD - stationOutputStopMediaTransmission tcpHandle=0x5138d98 myIP: e74610ac (172.16.70.231) 15:20:28.328 CCM|In Message -- H225ReleaseCompleteMsg -- Protocol= H225Protocol 16:03:33.344 CCM|StationInit - InboundStim - StationOnHookMessageID: Msg Size(received, defined) = 4, 12|<CT::1,100,105,1.219><IP::128.107.142.233> 16:03:33.359 CCM|ConnectionManager - wait_AuDisconnectRequest(16777217,16777218): STOP SESSION|<CT::1,100,105,1.219><IP::128.107.142.233> 16:03:33.359 CCM|StationD(2): TCPPid = [1.100.117.2] CloseReceiveChannel conferenceID=16777218 passThruPartyID=16777249. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,105,1.219><IP::128.107.142.233> 16:03:33.359 CCM|StationD(2): TCPPid = [1.100.117.2] StopMediaTransmission conferenceID=16777218 passThruPartyID=16777249. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,105,1.219><IP::128.107.142.233> 16:03:33.359 CCM|StationD(2): TCPPid = [1.100.117.2] star_StationOutputCloseMultiMediaReceiveChannel conferenceID=16777218 passThruPartyID=16777249. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,105,1.219><IP::128.107.142.233> 16:03:33.359 CCM|StationD(2): TCPPid = [1.100.117.2] star_StationOutputStopMultiMediaTransmission conferenceID=16777218 passThruPartyID=16777250. myIP: e98e6b80 (128.107.142.233)|<CT::1,100,105,1.219><IP::128.107.142.233>