TelePresence CE ソフトウェアのイベントログのキーワード

Kenichi Ogami · ‎2016-06-30

はじめに
イベントログの取得
イベントログの確認
イベントログの出力例
参考情報

はじめに

本ドキュメントでは、Cisco TelePresence CE (Collaboration Endpoint) ソフトウェアのイベントログ (eventlog) のキーワードについて記載します。イベントログのキーワードから、コールの発着信、接続、資料共有、切断などの履歴を確認することができます。本ドキュメントの記載は CE 8.2.0 を元に作成しています。

イベントログの取得

CE ソフトウェアのイベントログは、TelePresence Endpoint の Web GUI にログインし、Maintenance > System Logs タブの画面から取得できます。

左側の Download logs archive をクリックすると、各種ログファイル (下の画面に表示されているファイル) を圧縮したファイルをダウンロードできます。

再現性がある場合は、デバッグログを実行したり、Extended logging をスタートしてシステムログを取得します。

イベントログの確認

システムログに含まれるイベントログ eventlog/all.txt (複数のログがマージされたファイル) のキーワードについて紹介します。

会議セッションの作成 (ConferenceSessionCreated)

会議セッションが作成されたことを示します。会議セッションの識別子は (c=XX) で表示されます。コールが開始される前に会議セッションが作成されます。

2016-06-28T14:03:14.346+09:00 a9 appl[1735]: 12062.60 MainEvents I: ConferenceSessionCreated(c=4)

コールの発信 (OutgoingCallInvoked)

エンドポイントからコールが発信されたことを示します。コールの識別子は (p=XX) で表示されます。通常 1 つの会議セッションに 1 コールが関連づけられますが、マルチサイトコールの場合は、1 つの会議セッションに複数のコールが関連づけられます。

remoteURI に宛先の URI、localURI に送信元の URI が表示されます。

※ p=1 がイベントログに表示されていますが、予約された番号のため、無視してください。

SIP

2016-06-28T14:03:14.357+09:00 a9 appl[1735]: 12062.61 MainEvents I: OutgoingCallInvoked(p=5) remoteURI='sip:sx20@video.uc.lab' localURI='sip:sx10@video.uc.lab' bookingID=''

H.323

2016-06-28T14:05:04.488+09:00 a9 appl[1735]: 12172.74 MainEvents I: OutgoingCallInvoked(p=6) remoteURI='h323:sx20@video.uc.lab' localURI='h323:sx10@video.uc.lab' bookingID=''

コールの着信 (IncomingCallInvite)

エンドポイントにコールが着信したことを示します。

2016-06-28T23:34:36.905+09:00 a9 appl[1735]: 46345.16 MainEvents I: IncomingCallInvite(p=10) remoteURI='sip:sx20@video.uc.lab' displayName='sx20@video.uc.lab' localURI='sip:sx10@video.uc.lab'

コールの呼出中 (CallAlertIndication)

エンドポイントからコールが発信した場合に、SIP 180 Ringing や H.225 Alerting を受信した際に表示されます。リングバックトーンがなっていることを示します。

2016-06-28T14:03:14.879+09:00 a9 appl[1735]: 12063.13 MainEvents I: CallAlertIndication(p=5)

コールの応答 (IncomingCallAccepted)

エンドポイントがコールを受信した場合に、応答を押した際に表示されます。

2016-06-28T23:34:43.145+09:00 a9 appl[1735]: 46351.40 MainEvents I: IncomingCallAccepted(p=10)

コールの接続 (CallConnected)

コールが接続され、通話状態になったことを示します。

2016-06-28T14:03:16.448+09:00 a9 appl[1735]: 12064.70 MainEvents I: CallConnected(p=5)

資料共有開始 (PresentationStarted)

資料共有が開始されたことを示します。

2016-06-28T14:55:55.992+09:00 a9 appl[1735]: 15224.25 MainEvents I: PresentationStarted() c=8 local p=1

資料共有停止 (PresentationStopped)

資料共有が停止されたことを示します。

2016-06-28T14:56:07.844+09:00 a9 appl[1735]: 15236.10 MainEvents I: PresentationStopped() c=8 local p=1 cause=[normal]

保留リクエスト (CallSuspendRequested)

保留を実行しようとしたことを示します。

2016-06-28T14:15:56.478+09:00 a9 appl[1735]: 12824.73 MainEvents I: CallSuspendRequested(p=8)

保留 (CallSuspended)

保留リクエスト後、保留が行われたことを示します。

2016-06-28T14:15:56.758+09:00 a9 appl[1735]: 12825.01 MainEvents I: CallSuspended(p=8)

保留解除リクエスト (CallResumeRequested)

保留を解除しようとしたことを示します。

2016-06-28T14:16:03.040+09:00 a9 appl[1735]: 12831.29 MainEvents I: CallResumeRequested(p=8)

保留解除完了 (CallResumed)

保留解除リクエスト後、保留解除が行われたことを示します。

2016-06-28T14:16:03.283+09:00 a9 appl[1735]: 12831.54 MainEvents I: CallResumed(p=8)

マイクのミュート (Mic Muted)

マイクのミュートが実行されたことを示します。

2016-06-28T14:56:18.924+09:00 a9 appl[1735]: 15247.18 MainEvents I: Mic Muted

マイクのミュート解除 (Mic Unmuted)

マイクのミュート解除が実行されたことを示します。

2016-06-28T14:56:22.930+09:00 a9 appl[1735]: 15251.18 MainEvents I: Mic Unmuted

コールの切断リクエスト (CallDisconnectRequested)

コールの切断リクエストが行われたことを示します。ローカルのエンドポイントから切断した場合や、コール発信時にエラーを受信した場合に表示されます。cause に切断理由が表示されます。

2016-06-28T14:56:37.356+09:00 a9 appl[1735]: 15265.61 MainEvents I: CallDisconnectRequested(p=9) remoteURI='sip:sx20@video.uc.lab' cause=[normal('') 'LocalDisconnect']

コールの切断受信 (CallDisconnectIndicated)

接続先からコールの切断メッセージ (SIP BYE, H.225 Release-complete) を受信した場合に表示されます。

2016-06-28T14:07:52.805+09:00 a9 appl[1735]: 12341.06 MainEvents I: CallDisconnectIndicated(p=7) remoteURI='sip:sx20@video.uc.lab' cause=[disconnected('Normal') 'RemoteDisconnect']

コールの切断完了 (CallDisconnected)

コールの切断が完了したことを示します。

2016-06-28T14:56:37.414+09:00 a9 appl[1735]: 15265.67 MainEvents I: CallDisconnected(p=9) remoteURI='sip:sx20@video.uc.lab' causeToLocal=[normal('') 'LocalDisconnect'] causeToRemote=[normal('') 'LocalDisconnect']

会議セッションの終了 (ConferenceSessionReleased)

コールに関連付けられた会議セッションが終了したことを示します。

2016-06-28T14:56:37.515+09:00 a9 appl[1735]: 15265.77 MainEvents I: ConferenceSessionReleased(c=8)

Touch, Web-GUI, リモコン操作 (about to execute command)

Touch, Web-GUI, リモコンで操作が行われたことを示します。User の後に実行したユーザ名が記載され、from の後に Touch や Web-GUI の操作元の IP アドレスが表示されます。リモコンの場合は from の後は空欄になります。

Web-GUI (from 192.168.99.1. ) からダイヤルを実行

2016-06-28T14:55:19.826+09:00 a9 appl[1735]: 15188.08 CuilApp User admin about to execute command '/Dial callType: Video number: sx20@video.uc.lab protocol: SIP' from 192.168.99.1.

リモコン (from . ) からミュートを実行

2016-06-28T14:56:18.921+09:00 a9 appl[1735]: 15247.18 CuilApp User root about to execute command '/Audio/Microphones/Mute' from .

エンコード処理開始 (ENC_FSM-X: start)

ビデオのエンコード処理 (送信方向) を開始したことを示します。解像度やビットレートなどが表示されます。ENC_FSM はメディアチャネル毎に start するため、通常 ENC_FSM_0 がメインビデオとなり、資料共有が ENC_FSM_1 となることが多いです。また、マルチサイトのホストとなる場合は、メインビデオが 2 チャネル分になるため、最初に接続した参加者のメインビデオが ENC_FSM_0、次に接続した参加者のメインビデオが ENC_FSM_1 となります。

メインビデオ

2016-06-28T14:55:22.840+09:00 a9 eventlog[1735]: ENC_FSM-0: start standard H.264, width 1280, height: 720, framerate 3000, bitrate 960000, video mtu 1296, packetization mode 0, maxnalusize 1382400, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0, dynamic mbps 108000, static mbps 108000, analyzerConfig '', EncodeForMotion

資料共有

2016-06-28T14:55:56.501+09:00 a9 eventlog[1735]: ENC_FSM-1: start standard H.264, width 1920, height: 1080, framerate 500, bitrate 480000, video mtu 1296, packetization mode 1, maxnalusize 3133440, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0, dynamic mbps 40800, static mbps 40800, analyzerConfig '', EncodeForSharpness

エンコード処理停止 (ENC_FSM-X I: ENC_Stopping)

ビデオのエンコード処理を停止したことを示します。資料共有の開始などによってメインビデオの解像度が変更になる場合にも一旦停止して再作成されます。

2016-06-28T14:55:56.357+09:00 a9 appl[1735]: 15224.61 ENC_FSM-0 I: ENC_Stopping_doiVIDENCAllFramesClosed

デコード処理開始 (DEC_FSM-X I: create)

ビデオのデコード処理 (受信方向) を開始したことを示します。DEC_FSM はメディアチャネル毎に start するため、通常 DEC_FSM_0 がメインビデオとなり、資料共有が DEC_FSM_1 となることが多いです。また、マルチサイトのホストとなる場合は、メインビデオが 2 チャネル分になるため、最初に接続した参加者のメインビデオが DEC_FSM_0、次に接続した参加者のメインビデオが DEC_FSM_1 となります。

2016-06-28T14:55:24.121+09:00 a9 appl[1735]: 15192.37 DEC_FSM-0 I: create H.264NIL width=1920 height=1088 mbps=243000 dec=ttvdec_h264_dce

イベントログの出力例

下記の送信元から宛先に SIP でコールした時のイベントログの抜粋を例として記載します。このコールの例では、会議セッションの識別子は c=8、コールの識別子は p=9 です。

送信元: sx10@video.uc.lab
宛先: sx20@video.uc.lab

- Web-GUI で発信

2016-06-28T14:55:19.826+09:00 a9 appl[1735]: 15188.08 CuilApp   User admin about to execute command '/Dial callType: Video number: sx20@video.uc.lab protocol: SIP' from 192.168.99.1.

- 会議セッション (c=8) の開始
2016-06-28T14:55:20.334+09:00 a9 appl[1735]: 15188.59 MainEvents I: ConferenceSessionCreated(c=8)

- コールの発信 (p=9)
2016-06-28T14:55:20.349+09:00 a9 appl[1735]: 15188.60 MainEvents I: OutgoingCallInvoked(p=9) remoteURI='sip:sx20@video.uc.lab' localURI='sip:sx10@video.uc.lab' bookingID=''
2016-06-28T14:55:20.622+09:00 a9 appl[1735]: 15188.88 MainEvents I: ParticipantJoinedConference(c=8,p=9)

- INVITE 送信
2016-06-28T14:55:20.644+09:00 a9 appl[1735]: 15188.90 SipPacket I: SIP Msg: Outgoing => INVITE, CSeq: 100 INVITE, Remote: 192.168.97.94:5061, CallId: 65f4ef0020ac002e82a84831e4d2d9a2
2016-06-28T14:55:20.658+09:00 a9 appl[1735]: 15188.91 SipPacket I: SIP Msg: Incoming <= 100 Trying, CSeq: 100 INVITE, Remote: 192.168.97.94:5061, CallId: 65f4ef0020ac002e82a84831e4d2d9a2
2016-06-28T14:55:20.832+09:00 a9 appl[1735]: 15189.09 SipPacket I: SIP Msg: Incoming <= 180 Ringing, CSeq: 100 INVITE, Remote: 192.168.97.94:5061, CallId: 65f4ef0020ac002e82a84831e4d2d9a2
2016-06-28T14:55:20.834+09:00 a9 appl[1735]: 15189.09 MainEvents I: CallAlertIndication(p=9)

- 応答受信
2016-06-28T14:55:22.430+09:00 a9 appl[1735]: 15190.68 SipPacket I: SIP Msg: Incoming <= 200 OK, CSeq: 100 INVITE, Remote: 192.168.97.94:5061, CallId: 65f4ef0020ac002e82a84831e4d2d9a2
2016-06-28T14:55:22.433+09:00 a9 appl[1735]: 15190.69 SipMedia I: Incoming SDP media lines:
2016-06-28T14:55:22.433+09:00 a9 appl[1735]: 15190.69 SipMedia I:   0: audio       RTP/AVP         Port: 2416, Crypto: no, Direction: sendrecv
2016-06-28T14:55:22.433+09:00 a9 appl[1735]: 15190.69 SipMedia I:   1: video       RTP/AVP         Port: 2418, Crypto: no, Direction: sendrecv
2016-06-28T14:55:22.434+09:00 a9 appl[1735]: 15190.69 SipMedia I:   2: application UDP/BFCP        Port: 2442, Crypto: no
2016-06-28T14:55:22.434+09:00 a9 appl[1735]: 15190.69 SipMedia I:   3: video       RTP/AVP         Port: 2420, Crypto: no, Direction: sendrecv
2016-06-28T14:55:22.435+09:00 a9 appl[1735]: 15190.69 SipMedia I:   4: application RTP/AVP         Port: 2422, Crypto: no, Direction: sendrecv
2016-06-28T14:55:22.436+09:00 a9 appl[1735]: 15190.69 SipMedia I:   5: application UDP/UDT/IX      Port: 2457, Crypto: no
2016-06-28T14:55:22.443+09:00 a9 appl[1735]: 15190.70 MainEvents I: CallConnected(p=9)
2016-06-28T14:55:22.503+09:00 a9 appl[1735]: 15190.76 SipPacket I: SIP Msg: Outgoing => ACK, CSeq: 100 ACK, Remote: 192.168.97.94:5061, CallId: 65f4ef0020ac002e82a84831e4d2d9a2

- メインビデオ作成: 1280x720, 30fps, 9600bps
2016-06-28T14:55:22.840+09:00 a9 eventlog[1735]: ENC_FSM-0: start standard H.264, width 1280, height: 720, framerate 3000, bitrate 960000, video mtu 1296, packetization mode 0, maxnalusize 1382400, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0, dynamic mbps 108000, static mbps 108000, analyzerConfig '', EncodeForMotion
2016-06-28T14:55:24.121+09:00 a9 appl[1735]: 15192.37 DEC_FSM-0 I: create H.264NIL width=1920 height=1088 mbps=243000 dec=ttvdec_h264_dce

- 資料共有開始
2016-06-28T14:55:55.978+09:00 a9 appl[1735]: 15224.23 MainEvents I: PresentationTokenGranted() c=8 p=1
2016-06-28T14:55:55.992+09:00 a9 appl[1735]: 15224.25 MainEvents I: PresentationStarted() c=8 local p=1

- メインビデオチャネル再作成: 1024x576, 30fps, 4800bps
2016-06-28T14:55:56.357+09:00 a9 appl[1735]: 15224.61 ENC_FSM-0 I: ENC_Stopping_doiVIDENCAllFramesClosed
2016-06-28T14:55:56.401+09:00 a9 eventlog[1735]: ENC_FSM-0: start standard H.264, width 1024, height: 576, framerate 3000, bitrate 480000, video mtu 1296, packetization mode 0, maxnalusize 884736, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0, dynamic mbps 69120, static mbps 69120, analyzerConfig '', EncodeForMotion

- 資料共有チャネル作成: 1920x1080, 5fps, 4800bps
2016-06-28T14:55:56.501+09:00 a9 eventlog[1735]: ENC_FSM-1: start standard H.264, width 1920, height: 1080, framerate 500, bitrate 480000, video mtu 1296, packetization mode 1, maxnalusize 3133440, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0, dynamic mbps 40800, static mbps 40800, analyzerConfig '', EncodeForSharpness

- 資料共有終了
2016-06-28T14:56:07.844+09:00 a9 appl[1735]: 15236.10 MainEvents I: PresentationStopped() c=8 local p=1 cause=[normal]
2016-06-28T14:56:07.948+09:00 a9 appl[1735]: 15236.20 MainEvents I: PresentationTokenReleased() c=8
2016-06-28T14:56:07.991+09:00 a9 appl[1735]: 15236.24 ENC_FSM-0 I: ENC_Stopping_doiVIDENCAllFramesClosed
2016-06-28T14:56:08.132+09:00 a9 appl[1735]: 15236.39 ENC_FSM-1 I: ENC_Stopping_doiVIDENCAllFramesClosed

- メインビデオチャネル再作成: 1280x720, 30fps, 9600bps
2016-06-28T14:56:08.052+09:00 a9 eventlog[1735]: ENC_FSM-0: start standard H.264, width 1280, height: 720, framerate 3000, bitrate 960000, video mtu 1296, packetization mode 0, maxnalusize 1382400, maxtilerows -1, maxtilecolumns -1, minspatialsegidc -1, maxreferenceframes 0, dynamic mbps 108000, static mbps 108000, analyzerConfig '', EncodeForMotion

- コールの切断
2016-06-28T14:56:37.356+09:00 a9 appl[1735]: 15265.61 MainEvents I: CallDisconnectRequested(p=9) remoteURI='sip:sx20@video.uc.lab' cause=[normal('') 'LocalDisconnect']
2016-06-28T14:56:37.414+09:00 a9 appl[1735]: 15265.67 MainEvents I: CallDisconnected(p=9) remoteURI='sip:sx20@video.uc.lab' causeToLocal=[normal('') 'LocalDisconnect'] causeToRemote=[normal('') 'LocalDisconnect']
2016-06-28T14:56:37.426+09:00 a9 appl[1735]: 15265.68 SipPacket I: SIP Msg: Outgoing => BYE, CSeq: 101 BYE, Remote: 192.168.97.94:5061, CallId: 65f4ef0020ac002e82a84831e4d2d9a2

- 会議セッションの終了
2016-06-28T14:56:37.515+09:00 a9 appl[1735]: 15265.77 MainEvents I: ConferenceSessionReleased(c=8)

2016-06-28T14:56:37.532+09:00 a9 appl[1735]: 15265.79 SipPacket I: SIP Msg: Incoming <= 200 OK, CSeq: 101 BYE, Remote: 192.168.97.94:5061, CallId: 65f4ef0020ac002e82a84831e4d2d9a2
2016-06-28T14:56:37.636+09:00 a9 appl[1735]: 15265.89 ENC_FSM-0 I: ENC_Stopping_doiVIDENCAllFramesClosed