音声とユニファイド コミュニケーション : Cisco Unity Express

Unity Express の Message Waiting Indication(MWI)の問題のトラブルシューティング

2006 年 10 月 3 日 - ライター翻訳版
その他のバージョン: PDFpdf | 機械翻訳版 (2013 年 8 月 21 日) | 英語版 (2012 年 2 月 8 日) | フィードバック

目次

概要
前提条件
      要件
      使用するコンポーネント
      表記法
MWI の概要
Cisco Unity Express の統合の問題
      Cisco CallManager Express と MWI
      Cisco CallManager Express システムのトラブルシューティングの方法
      Cisco CallManager と MWI
一般的な MWI とボイスメールのトレース
関連するシスコ サポート コミュニティ ディスカッション
関連情報

概要

このドキュメントでは、Cisco Unity Express の Message Waiting Indication(MWI; メッセージ受信表示)機能の概要について説明します。

前提条件

要件

このドキュメントの読者は、Cisco Unity Express の Command Line Interface(CLI; コマンドライン インターフェイス)に関する知識が必要です。

使用するコンポーネント

このドキュメントの情報は、Cisco Unity Express バージョン 1.0 および 2.3.x 以降に基づくものです。 このドキュメントのサンプル設定と画面出力はすべて、Cisco Unity Express バージョン 1.1.1 のものです。

このドキュメントの情報は、特定のラボ環境にあるデバイスに基づいて作成されたものです。このドキュメント内で使用されているデバイスは、すべてクリアな(デフォルト)設定で作業が開始されています。対象のネットワークが実稼働中である場合には、すべてのコマンドによる潜在的な影響について確実に理解しておく必要があります。

表記法

ドキュメント表記の詳細は、『シスコ テクニカル ティップスの表記法』を参照してください。

MWI の概要

MWI は Cisco CallManager Express または CallManager に登録されているユーザに対して新着のボイスメール メッセージが届いていることを視覚的に知らせる機能です。 Cisco Unity Express が Cisco CallManager に統合されていて、WAN の停止のためにシステムが Survivable Remote Site Telephony(SRST)モードで稼働している場合、MWI は機能しません。

Cisco Unity Express が Cisco CallManager Express に統合されている場合、新着のボイスメールがユーザのメールボックスに届くと、extension_MWI_on/off_number@CallManager_Express_IP_address に Session Initiation Protocol(SIP; セッション開始プロトコル)コールが送信されます。 また、ユーザがすべての新着メッセージを取得したときにも SIP コールが送信されます。 これは Cisco CallManager Express ルータの ephone-dn 番号と一致します。 ephone-dn 番号は、MWI 番号に、Cisco Unity Express 加入者の内線番号の桁数に等しいワイルドカード桁を加えたものになります。 たとえば、メールボックス 12345 の MWI オン番号が 420 で、Cisco CallManager Express の IP アドレスが 10.2.3.6 であるとします。 この場合、メッセージは 42012345@10.2.3.6 に送信されます。 「mwi on」設定パラメータが設定された ephone-dn 番号は「420...」となります。

Cisco CallManager に統合されている場合は、Java Telephony Application Programming Interface(JTAPI)プロトコルによって直接ランプが点灯します。 特定の番号にコールを送信する必要はありません。 JTAPI プロトコルは、MWI イベントを処理する setMessageWaiting コマンドをサポートしています。 そのため、Cisco CallManager で MWI 内線番号が設定されているかどうかに関係なく MWI は動作します。 ただし、Cisco Unity Express が SRST モードで稼働している場合は MWI は機能しない点に注意してください。 完全な MWI 更新は、Cisco Unity Express が Cisco CallManager に登録されて、IP 電話が CallManager フォールバック モードでなくなったときに初めて実行されます。

ほとんどの問題は、Cisco CallManager Express/CallManager と Cisco Unity Express の統合に関係するものです。 MWI は必ずしも物理ランプとは関連付けられていない点に注意してください。 メッセージを受け取る番号が電話機のプライマリ回線でない場合、電話機のディスプレイにはエンベロープ通知しか表示されません。 Cisco CallManager では、各回線で MWI をどのように処理するかを設定できます。 問題が発生しているユーザが 1 人か 2 人の場合は、ここから問題の原因を探し始めることができます。

MWI を受信するには、電話番号に Cisco Unity Express システム上の有効なメールボックスが関連付けられている必要があります。 番号はユーザに関連付けられていて、ユーザはメールボックスを所有している必要があります。 トラブルシューティングのためにデバッグや複雑な作業を行う前に、ユーザがメールボックスにログインしていて、ボイスメール メッセージを送受信できる状態になっているかどうかを確認する簡単な方法があります。

テストするユーザは GUI または CLI から検索できます。 この例では、user3 になります。 ユーザに対して設定されている内線番号を検索したり、ユーザ メールボックスの状態(有効、無効などの情報)を確認したり、ユーザに新しいメッセージや古いメッセージがあるかどうか確認したりすることができます。 この例では、CLI を使用してトラブルシューティングを行います。

  cue-3660-41a>show users
  administrator
  operator
  user1
  user2
  user3
  user4
  user6
  user7
  user8
  cue-3660-41a>show user detail username user3
  Full Name:          user
  First Name:         
  Last Name:          user
  Nickname:           user
  Phone:              11044
  Phone(E.164):       
  Language:           en_US
  cue-3660-41a>show voicemail mailboxes
  OWNER                              MSGS NEW  SAVED  MSGTIME MBXSIZE   USED
  "operator"                         0    0    0      0       3000      0 %
  "user1"                            0    0    0      0       3000      0 %
  "user2"                            0    0    0      0       3000      0 %
  "user3"                            0    0    0      0       3000      0 %
  "user4"                            0    0    0      0       3000      0 %
  "user6"                            0    0    0      0       3000      0 %
  "user7"                            0    0    0      0       3000      0 %
  "user8"                            0    0    0      0       3000      0 %
  cue-3660-41a>show voicemail detail mailbox user3
  Owner:                                  /sw/local/users/user3
  Type:                                   Personal
  Description:                            
  Busy state:                             idle
  Enabled:                                true
  Mailbox Size (seconds):                 3000
  Message Size (seconds):                 60
  Play Tutorial:                          true
  Space Used (seconds):                   0
  Total Message Count:                    0
  New Message Count:                      0
  Saved Message Count:                    0
  Expiration (days):                      30
  Greeting:                               standard
  Zero Out Number:                        
  Created/Last Accessed:                  Jun 17 2004 09:54:39 EDT
  cue-3660-41a>
  

このユーザが存在し、番号が関連付けられていて、メッセージが存在していないことを確認してください。 これらの事項に当てはまる場合、MWI の状態はオフです。

注:E.164(ITU-T)アドレスは MWI の目的には使用されません。 使用できるのはプライマリ電話番号だけです。

Cisco Unity Express の統合の問題

Cisco CallManager Express と MWI

まず始めに、設定を確認する必要があります。 Cisco CallManager Express で show running-config コマンドを発行して、設定を表示します。 また、より直接的に show telephony-service ephone-dn コマンドを発行することもできます。 次のような出力が表示されます。

  ephone-dn  44
   number 11099.....
   mwi on
  !
  !
  ephone-dn  45
   number 11098.....
   mwi off
  !
  

この出力は重要な情報を示しています。 MWI オンの番号は 11099 です。MWI オフの番号は 11098 です。ダイヤル プランの桁数は 5 桁です (MWI オンまたはオフのコードに続く 5 個のドット(.....)がこのことを示しています)。 つまり、MWI は 5 桁の電話番号(DN)に対してのみ動作します。

Cisco Unity Express 側では設定とライセンスを確認できます。 よくある問題の 1 つは、CallManager Express ライセンスではなく、Cisco CallManager ライセンスがロードされているために起こります。 これを確認するには、Cisco Unity Express から show software licenses コマンドを発行します。

  cue-3660-41a>show software licenses 
  Core:e
   - application mode: CCME
  
  !--- CCME は Cisco CallManager Express を表します。
  
   - total usable system ports: 8
  Voicemail/Auto Attendant:
   - max system mailbox capacity time: 6000
   - max general delivery mailboxes: 20
   - max personal mailboxes: 100
  Languages:
   - max installed languages: 1
   - max enabled languages: 1
  

アプリケーション モードが CCME ではなく CCM(Cisco CallManager)になっている場合は、MWI を除くすべての機能が動作します。 ライセンスが間違っている場合の唯一の解決方法は、ソフトウェアの再イメージ化を行い、ライセンスを再適用することです。 メッセージや設定を保存して復元することはできません。

次に、設定を確認します。 show run コマンドを発行して設定を表示するか、show ccn application コマンドを使用します。

  cue-3660-41a> show ccn application 
  Name:                                   ciscomwiapplication
  Description:                            ciscomwiapplication
  Script:                                 setmwi.aef
  ID number:                              0
  Enabled:                                yes
  Maximum number of sessions:             4
  strMWI_OFF_DN:                          11098
  strMWI_ON_DN:                           11099
  CallControlGroupID:                     0
  

注:アプリケーションは有効で、MWI_OFFMWI_ON の番号はそれぞれ 11098 と 11099 です。 システムには内線番号の桁数という概念がないため、単純に適切な MWI オンまたはオフの番号にコールを発信して、メールボックス内線番号を付加します。 コールを適切にルーティングするには、宛先パターンに適切な数のドットを含むダイヤル ピアを Cisco CallManager Express システムが持っている必要があります。

最後に、Cisco Unity Express の SIP ゲートウェイの IP アドレスが正しい Cisco CallManager Express の IP アドレスを指していることを確認します。

  cue-3660-41a>show ccn subsystem sip
  SIP Gateway:                            14.80.227.125
  SIP Port Number:                        5060
  

これが正しくない場合、コールは正しい Cisco CallManager Express に送信されません。 コールは失敗します。

シグナリングの問題のトラブルシューティングを開始する方法は 2 つあります。 最も簡単な方法は、Cisco Unity Express 側からデフォルト トレースを無効にし、必要に応じて再度有効にする方法です。 これを行うには、no trace all コマンドを発行します。 最初に発行するトレース コマンドは trace ccn stacksip dbug です。

注:トレースの詳細については、『CUE でのトレース データの設定と収集』を参照してください。

MWI メッセージを送信する前に、トレース バッファをクリアします。 トレース メッセージはすべてこのメモリ バッファに書き込まれます。 バッファをクリアするのは、テスト コールの後に以前のメッセージが表示されないようにするためです。 これを行うには clear trace コマンドを使用します。

次に、MWI メッセージを送信します。 これを行うには、mwi refresh telephonenumber xxxx コマンドを使用します。 更新コマンドは GUI からも発行できます。

最後に、トレース バッファを表示し、show trace buffer long コマンドの出力を確認します。 この例では重要な情報を強調表示しています。

  cue-3660-41a>trace ccn stacksip dbug
  cue-3660-41a>clear trace
  cue-3660-41a>mwi refresh telephonenumber 11043
  cue-3660-41a>show trace buffer long 
  Press <CTRL-C> to exit...
  2106 07/14 14:28:27.263 ACCN SIPL 0 --- send message --- to 14.80.227.125:5060
  INVITE sip:1109811043@14.80.227.125;user=phone SIP/2.0
  Via: SIP/2.0/UDP 14.80.227.145:5060
  From: "Cisco SIP Channel3" <sip:outbound-0@14.80.227.125>;tag=f0a4ab8e-488
  To: <sip:1109811043@14.80.227.125;user=phone>
  Call-ID: a1c0ece2-486@14.80.227.145:5060
  CSeq: 51 INVITE
  Contact: sip:outbound-0@14.80.227.145:5060
  User-Agent: Jasmin UA / ver 1.1
  Accept: application/sdp
  Content-Type: application/sdp
  Content-Length: 224
  
  v=0
  o=CiscoSystemsSIP-Workflow-App-UserAgent 3582 3582 IN IP4 14.80.227.145
  s=SIP Call
  c=IN IP4 14.80.227.145
  t=0 0
  m=audio 16902 RTP/AVP 0 111
  a=rtpmap:0 pcmu/8000
  a=rtpmap:111 telephone-event/8000
  a=fmtp:111 0-11
  
  2069 07/14 14:28:27.275 ACCN SIPL 0 receive 379 from 14.80.227.125:51955
  2070 07/14 14:28:27.275 ACCN SIPL 0 not found header for Date
  2070 07/14 14:28:27.275 ACCN SIPL 0 not found header for Allow-Events
  2070 07/14 14:28:27.276 ACCN SIPL 0 -------
  SIP/2.0 100 Trying
  Via: SIP/2.0/UDP 14.80.227.145:5060
  From: "Cisco SIP Channel3" <sip:outbound-0@14.80.227.125>;tag=f0a4ab8e-488
  To: <sip:1109811043@14.80.227.125;user=phone>;tag=5FF5244-43A
  Date: Sat, 15 Jun 2002 13:33:41 GMT
  Call-ID: a1c0ece2-486@14.80.227.145:5060
  Server: Cisco-SIPGateway/IOS-12.x
  CSeq: 51 INVITE
  Allow-Events: telephone-event
  Content-Length: 0
  
  2069 07/14 14:28:27.276 ACCN SIPL 0 receive 441 from 14.80.227.125:51955
  2070 07/14 14:28:27.294 ACCN SIPL 0 not found header for Date
  2070 07/14 14:28:27.294 ACCN SIPL 0 not found header for Allow-Events
  2070 07/14 14:28:27.294 ACCN SIPL 0 -------
  SIP/2.0 180 Ringing
  Via: SIP/2.0/UDP 14.80.227.145:5060
  From: "Cisco SIP Channel3" <sip:outbound-0@14.80.227.125>;tag=f0a4ab8e-488
  To: <sip:1109811043@14.80.227.125;user=phone>;tag=5FF5244-43A
  Date: Sat, 15 Jun 2002 13:33:41 GMT
  Call-ID: a1c0ece2-486@14.80.227.145:5060
  Server: Cisco-SIPGateway/IOS-12.x
  CSeq: 51 INVITE
  Allow: UPDATE
  Allow-Events: telephone-event
  Contact: <sip:1109811043@14.80.227.125:5060>
  Content-Length: 0
  
  2072 07/14 14:28:27.294 ACCN SIPL 0 ignore null remote tag for Dialog1610: callid=
   a1c0ece2-486@14.80.227.145:5060, localTag=f0a4ab8e-488, remoteTag=5FF5244-43A
  2072 07/14 14:28:27.294 ACCN SIPL 0 ltp95: ContactingState processResponse 100 Trying
  2072 07/14 14:28:27.294 ACCN SIPL 0 ignore null remote tag for Dialog1611: callid=
   a1c0ece2-486@14.80.227.145:5060, localTag=f0a4ab8e-488, remoteTag=5FF5244-43A
  2072 07/14 14:28:27.294 ACCN SIPL 0 ltp95: ContactingState processResponse 180 Ringing
  2106 07/14 14:28:32.274 ACCN SIPL 0 ltp95: ContactingState close terminate cause=20
  2106 07/14 14:28:32.275 ACCN SIPL 0 addHeadersAndBody: branch = null
  2106 07/14 14:28:32.276 ACCN SIPL 0 --- send message --- to 14.80.227.125:5060
  CANCEL sip:1109811043@14.80.227.125;user=phone SIP/2.0
  Via: SIP/2.0/UDP 14.80.227.145:5060
  From: "Cisco SIP Channel3" <sip:outbound-0@14.80.227.125>;tag=f0a4ab8e-488
  To: <sip:1109811043@14.80.227.125;user=phone>
  Call-ID: a1c0ece2-486@14.80.227.145:5060
  CSeq: 51 CANCEL
  Max-Forwards: 50
  Content-Length: 0
  
  2069 07/14 14:28:32.282 ACCN SIPL 0 receive 293 from 14.80.227.125:51955
  2070 07/14 14:28:32.283 ACCN SIPL 0 not found header for Date
  2070 07/14 14:28:32.283 ACCN SIPL 0 -------
  SIP/2.0 200 OK
  Via: SIP/2.0/UDP 14.80.227.145:5060
  From: "Cisco SIP Channel3" <sip:outbound-0@14.80.227.125>;tag=f0a4ab8e-488
  To: <sip:1109811043@14.80.227.125;user=phone>
  Date: Sat, 15 Jun 2002 13:33:46 GMT
  Call-ID: a1c0ece2-486@14.80.227.145:5060
  Content-Length: 0
  CSeq: 51 CANCEL
  
  2072 07/14 14:28:32.283 ACCN SIPL 0 ignore null remote tag for Dialog1612: callid=
   a1c0ece2-486@14.80.227.145:5060, localTag=f0a4ab8e-488, remoteTag=null
  2072 07/14 14:28:32.283 ACCN SIPL 0 ltp95: TerminatedState process response to CANCEL, 
   unregister
  2072 07/14 14:28:32.284 ACCN SIPL 0 ignore null remote tag for Dialog1609: callid=
   a1c0ece2-486@14.80.227.145:5060, localTag=f0a4ab8e-488, remoteTag=null
  2072 07/14 14:28:32.284 ACCN SIPL 0 com.cisco.jasmin.impl.sip.MessageDispatcherImpl 
   unregister Dialog1609: callid=a1c0ece2-486@14.80.227.145:5060, localTag=f0a4ab8e-488, 
   remoteTag=null
  2069 07/14 14:28:32.284 ACCN SIPL 0 receive 390 from 14.80.227.125:51955
  2070 07/14 14:28:32.284 ACCN SIPL 0 not found header for Date
  2070 07/14 14:28:32.284 ACCN SIPL 0 not found header for Allow-Events
  2070 07/14 14:28:32.284 ACCN SIPL 0 -------
  SIP/2.0 487 Request Cancelled
  Via: SIP/2.0/UDP 14.80.227.145:5060
  From: "Cisco SIP Channel3" <sip:outbound-0@14.80.227.125>;tag=f0a4ab8e-488
  To: <sip:1109811043@14.80.227.125;user=phone>;tag=5FF5244-43A
  Date: Sat, 15 Jun 2002 13:33:46 GMT
  Call-ID: a1c0ece2-486@14.80.227.145:5060
  Server: Cisco-SIPGateway/IOS-12.x
  CSeq: 51 INVITE
  Allow-Events: telephone-event
  Content-Length: 0
  
  2072 07/14 14:28:32.285 ACCN SIPL 0 LocalLineImpl outbound-0 send ACK to INVITE 487
  2072 07/14 14:28:32.285 ACCN SIPL 0 can not extract contact address from null
  2072 07/14 14:28:32.285 ACCN SIPL 0 --- send message --- to 14.80.227.125:5060
  ACK sip:1109811043@14.80.227.125;user=phone SIP/2.0
  Via: SIP/2.0/UDP 14.80.227.145:5060
  From: "Cisco SIP Channel3" <sip:outbound-0@14.80.227.125>;tag=f0a4ab8e-488
  To: <sip:1109811043@14.80.227.125;user=phone>;tag=5FF5244-43A
  Call-ID: a1c0ece2-486@14.80.227.145:5060
  CSeq: 51 ACK
  Max-Forwards: 50
  Content-Length: 0
  

この出力では、ユーザが INVITE メッセージを送信すると、Cisco CallManager Express が Trying メッセージを返します。 Cisco CallManager Express が Ringing メッセージを送信すると、ユーザが CANCEL メッセージを送信します。 MWI 番号は実際のコールのピックアップや受信には使用されません。 この番号にコールを発信すると、ランプがオンまたはオフになります。 この例では、11098 が MWI オンかオフかを知っている必要があります。 また、11043 が Cisco CallManager Express で有効な内線番号として設定されている必要があります。

必要な Cisco Unity Express トレースをすべて修正した後は、すべてのトレースを無効にして、デフォルト トレースを再び有効にします。 トレースを無効にするには clear trace all コマンドを発行します。 すべてのデフォルト トレースを再び有効にするには、ここに示すコードを Cisco Unity Express の CLI に貼り付けます。

注:Cisco Unity Express を再起動する場合は、デフォルト トレースを復元することもできます。

  trace ccn engine dbug
  trace ccn libldap dbug
  trace ccn subsystemappl dbug
  trace ccn managerappl dbug
  trace ccn managerchannel dbug
  trace ccn subsystemjtapi dbug
  trace ccn subsystemsip dbug
  trace ccn stacksip dbug
  trace ccn subsystemhttp dbug
  trace ccn vbrowsercore dbug
  trace ccn subsystemcmt dbug
  trace ccn libmedia dbug
  trace ccn managercontact dbug
  trace ccn stepcall dbug 
  trace ccn stepmedia dbug
  trace config-ccn sip-subsystem debug
  trace config-ccn jtapi-subsystem debug
  trace config-ccn sip-trigger debug
  trace config-ccn jtapi-trigger debug
  trace config-ccn http-trigger debug
  trace config-ccn group debug
  trace config-ccn application debug
  trace config-ccn script debug
  trace config-ccn prompt debug
  trace config-ccn miscellaneous debug
  trace voicemail database query
  trace voicemail database results
  trace voicemail database transaction
  trace voicemail database connection
  trace voicemail database execute
  trace voicemail mailbox login
  trace voicemail mailbox logout 
  trace voicemail mailbox send
  trace voicemail mailbox save
  trace voicemail mailbox receive
  trace voicemail mailbox delete
  trace voicemail message create
  trace voicemail message dec
  trace voicemail message delete
  trace voicemail message get
  trace voicemail message inc
  trace webinterface initwizard init
  

Cisco CallManager Express ルータ自体で簡単にすべての SIP メッセージを診断することもできます。 通常、debug ccsip messagesdebug ccsip media は最も便利なコマンドです。 SIP シグナリングだけが必要な場合、この診断は非常に早く、Cisco Unity Express がトレースする不要な情報も少なくて済みます。 Cisco Unity Express が正しい CallManager Express の IP アドレスにシグナリングを送信している場合、SIP シグナリングは各サーバでミラーリングされます。

Cisco Unity Express へのコールや Cisco Unity Express からのコールには G.711 が使用されますが、これもよくある問題の原因になります。 たとえば、次のような Cisco CallManager Express モジュールからの SIP パケットがデバッグで表示される場合があります。

  Mar 11 10:09:13.767 EST: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
  Sent: 
  SIP/2.0 488 Not Acceptable Media
  Via: SIP/2.0/UDP 172.18.106.88:5060
  From: "Cisco SIP Channel1" <sip:outbound-0@172.18.106.66>;tag=75b5194d-133
  To: <sip:1109811043@172.18.106.66;user=phone>;tag=23F1578C-252
  Date: Fri, 11 Mar 2005 15:09:13 GMT
  Call-ID: e34bafcc-131@172.18.106.88:5060
  Server: Cisco-SIPGateway/IOS-12.x
  CSeq: 51 INVITE
  Allow-Events: telephone-event
  Content-Length: 0
  

この出力は、Cisco Unity Express からの SIP INVITE メッセージが、G.711 が設定されたダイヤル ピアと一致しないため、Cisco CallManager Express がコールを拒否したことを示しています。 このコール拒否の問題を解決するには、MWI トラフィック専用のダイヤル ピアを追加します。 このセクションの例では、MWI on11099.....MWI off11098..... となっています。 次のダイヤル ピアを追加できます。

  dial-peer voice 123 voip
  incoming called-number 1109[8,9].....
  codec g711ulaw
  no vad
  !
  

それほど頻繁ではない問題として、ダイヤル ピア、VoIP 着信ルール、またはその他の場所で適用されるトランスレーション パターンに MWI トラフィックが照合されるときに起こるものがあります。 言い換えると、Class of Restriction(COR)ルールによってコールがブロックされることがあります。 MWI オン/オフ番号と MWI を点灯させるための内線番号をダイヤルしても、SIP 経由でコールが着信したときの動作は必ずしも同じではないことがあります。 COR の詳細については、『Class of Restrictions(COR)の設定』を参照してください。

要約すると、次のことを必ず確認する必要があります。

  • Cisco CallManager Express ライセンスがあること。 show software licenses コマンドを発行します。 Cisco CallManager ライセンスの場合は、MWI を除くすべての機能が動作します。

  • MWI オン番号およびオフ番号が Cisco CallManager Express で設定されていること。 ドットの数は内線番号の長さを示します。 show telephony-service ephone-dn コマンドを発行します。

  • Cisco Unity Express でドットなしのオン番号およびオフ番号と照合するための MWI オン番号およびオフ番号が Cisco Unity Express で設定されていること。 これは show ccn application コマンドで確認できます。

  • Cisco Unity Express が正しい Cisco CallManager Express サーバの IP アドレスを指していること。 これは show ccn subsystem sip コマンドで確認できます。

他の方法でうまく行かない場合は、trace ccn stacksip dbug コマンドを発行してトラブルシューティングを開始します。

Message Waiting Indicator(MWI)(Cisco Unified CallManager Express のみ)

症状:新しいバージョンの Cisco Unity Express にアップグレードした後、メールボックスにメッセージが残されても MWI が点灯しなくなった。

  • 説明 — アップグレードの手順で Session Initiation Protocol(SIP; セッション開始プロトコル)サブシステムの IP アドレスが削除された。

  • 推奨処置 — Cisco Unified CME ルータを指すように SIP の IP アドレスを再設定します。

Cisco CallManager Express システムのトラブルシューティングの方法

Cisco CallManager Express システムのトラブルシューティングを行う手順は次のとおりです。

  1. show ephone コマンドを入力して、登録されているすべての電話機を表示します。 電話機が 1 台も登録されていない場合は、次の作業を行います。

    1. デフォルト ルータと TFTP サーバ アドレス(オプション 150)を含む DHCP 設定を確認します。

    2. dir コマンドを使用して、必要なファイルがルータのフラッシュ メモリに存在していることを確認します。

    3. tftp-server コマンドが必要なファイルに対して設定されていることを確認します。

    4. debug ephone register MAC アドレス コマンドを使用して、Cisco IP Phone の登録アクティビティを表示します。

    5. debug ip dhcp コマンドを使用して、DHCP の動作を確認します。

  2. show ephone コマンドを入力して、登録されているすべての電話機を表示します。 電話機が登録されていて表示された場合は、次の作業を行います。

    1. 電話番号に割り当てられている電話機ボタンが正しいことを確認します。

    2. Cisco IP Phone が登録済みとして表示されることを確認します。

    3. 電話機の設定ディスプレイを使用して、Cisco IP Phone の IP パラメータ設定を確認します。

    4. show phone コマンドを発行したときにキープアライブ カウントが更新されることを確認します。

    5. debug ephone register MAC アドレス コマンドを入力して、電話機をリセットし、再登録が行われることを確認して、Cisco IP Phone を表示します。

    6. show ephone-dn summary コマンドを入力して、Cisco IP Phone の回線の状態を確認します。

    7. 電話機の IP アドレスを確認して、そのアドレスに Ping を発行します。

  3. debug ephone keepalive コマンドを使用して、Cisco IP Phone のキープアライブ デバッグを設定します。

  4. debug ephone state コマンドを使用して、Cisco IP Phone のステート デバッグを設定します。

Cisco CallManager と MWI

Cisco Unity Express が Cisco CallManager に統合されている場合、最も重要なことは、Unity Express が登録されていて、ログオン情報がすべて正しく設定されていることを確認することです。

トラブルシューティングを行うには、まず始めに電話機が SRST モードで稼働しているかどうかを確認します。 Cisco Unity Express モジュールがインストールされているルータにログインします。 次に、show ephone registered コマンドを発行します。 Cisco Unity Express が Cisco CallManager に正しく登録されていても、MWI はどの登録されている電話機でも受信されません。

  vnt-2651-44a#show ephone registered 
  
  ephone-3 Mac:0008.E31B.7AFC TCP socket:[2] activeLine:0 REGISTERED
  mediaActive:0 offhook:0 ringing:0 reset:0 reset_sent:0 paging 0 debug:0
  IP:14.80.119.206 51984 Telecaster 7960  keepalive 2697 max_line 6
  button 1: dn 1  number 2103  CM Fallback CH1   IDLE         
  button 2: dn 2  number 2199  CM Fallback CH1   IDLE         
  
  
  ephone-4 Mac:0008.E37F.A119 TCP socket:[4] activeLine:0 REGISTERED
  mediaActive:0 offhook:0 ringing:0 reset:0 reset_sent:0 paging 0 debug:0
  IP:14.80.119.207 50963 Telecaster 7960  keepalive 2696 max_line 6
  button 1: dn 3  number 2104  CM Fallback CH1   IDLE
  

REGISTERED で示される Cisco CallManager フォールバック状態の電話機が 1 台もない場合、前述のように、それらのデバイスでは SRST がアクティブにはなっていません。 次に、Cisco Unity Express と Cisco CallManager の設定を確認して、Unity Express が CallManager に登録されていることを確認します。

  VNT-AIM-CUE1>show ccn subsystem jtapi 
  Cisco Call Manager:                     14.80.227.127
  CCM JTAPI Username:                     site1cue
  CCM JTAPI Password:                     *****
  Call Control Group 1 CTI ports:         28001,28002,28003,28004
  

この出力には、Cisco Unity Express が Cisco CallManager にログインするときに使用するすべての Computer Telephony Integration(CTI)ルート ポイントの電話番号と JTAPI アカウントが表示されています。

Cisco Unity Express が Cisco CallManager に正しく登録されていることを確認する必要があります。 最初に、CTI ポートが実際に登録されていることを確認します。 これを行うには、Cisco CallManager Administration Web ページにアクセスするのが最も簡単です。 次に、Device > Phone の順に選択して、上の出力に表示されている CTI ポートを検索します。 Status フィールドと IP Address フィールドは、完全に入力されている必要があります。

unity_mwi_problems1.gif

CTI ポートを検索
※ 画像をクリックすると、大きく表示されます。

これらのポートが登録されていない場合、Cisco Unity Express は Cisco CallManager と通信できません。 もう 1 つの可能性はログインが正しくないことです。 この問題を解決するには、Cisco Unity Express モジュールから Cisco CallManager に Ping を発行します。 これが成功する場合は、Cisco CTIManager とディレクトリサービス(この例では DC Directory Server)が開始されていることを確認します。 これを確認するには、Cisco CallManager サーバから Start > Programs > Administrative Tools > Services の順に選択します。

unity_mwi_problems2.gif

Services の確認
※ 画像をクリックすると、大きく表示されます。

また、JTAPI ユーザ アカウント(この例では site1cue)が存在することも確認する必要があります。 CTI ポート、ルート ポイント、および Enable CTI Application Use にチェックマークが付いていることを確認します。 パスワードも確認します。

これ以外によくある問題は、CTI ポートの Calling Search Space に関係するものです。 この Calling Search Space には MWI ランプを点灯させる電話番号のパーティションが含まれている必要があります。 たとえば、Partition Line 1 で内線番号 1234 に対して MWI を設定するには、(ルート ポイントではなく)CTI ポートの Calling Search Space に Line1 パーティションが含まれている必要があります。 CTI ポートの Calling Search Space が None の場合、None パーティションに含まれる内線番号のみが MWI に対して動作します。

設定が正しい場合は、Cisco Unity Express モジュールで JTAPI 診断を有効にできます。 ただし、診断を有効または無効にするにはリブートが必要になります。 このレベルの診断は通常の trace デバッグ設定より強力です。 この診断を有効にすると内部フラッシュ カードに大量の情報が書き込まれ、フラッシュの寿命が短くなる可能性があるため、Advanced Integration Module(AIM)の場合は特に、この診断を有効のままにしておかないでください。

現在有効な JTAPI トレースを表示するには、show ccn trace jtapi コマンドを発行します。

注:デフォルトでは、すべての JTAPI トレースが無効になっています。

  VNT-AIM-CUE1>show ccn trace jtapi
  Warning:                                0
  Informational:                          0
  Jtapi Debugging:                        0
  Jtapi Implementation:                   0
  CTI Debugging:                          0
  CTI Implementation:                     0
  Protocol Debugging:                     0
  Misc Debugging:                         0
  

すべてのトレースを有効にするには、次のコマンドを発行します。

  VNT-AIM-CUE1>ccn trace jtapi debug all
  You will have to reload the system for your changes to take effect
  VNT-AIM-CUE1>ccn trace jtapi informational all
  You will have to reload the system for your changes to take effect
  VNT-AIM-CUE1>ccn trace jtapi warning all
  You will have to reload the system for your changes to take effect
  VNT-AIM-CUE1>show ccn trace jtapi
  Warning:                                1
  Informational:                          1
  Jtapi Debugging:                        1
  Jtapi Implementation:                   1
  CTI Debugging:                          1
  CTI Implementation:                     1
  Protocol Debugging:                     1
  Misc Debugging:                         1
  

ここでシステムをリロードする必要があります。 上の出力と同じ ccn trace コマンドを発行します。ただし、後で無効にするには、各コマンドの前に no キーワードを追加します。 たとえば、no ccn trace jtapi debug all のように入力します。 AIM では特にこれが重要になります。 これを怠るとパフォーマンスが低下したり、AIM のコンパクト フラッシュ カードの寿命が短くなる可能性があります。

リロード後、CiscoJtapi1.log ファイルの書き込みが開始され、最初のファイルが最大サイズに達すると CiscoJtapi2.log ファイルの書き込みが開始されます。

これらのログを表示するには、show log name CiscoJtapi1.log コマンドを発行します。 また、ログ ファイルを FTP サーバにコピーして、オフラインで情報を確認することもできます。 この場合のコマンドは、copy log CiscoJtapi1.log url ftp://user:passwd@ftpservipaddr/ です。

いずれの方法でも、すべての JTAPI 情報が表示されます。 この例では、Cisco Unity Express モジュールが登録を試みていますが、WAN の障害のために失敗しています。

  15252: Jul 14 03:58:24.412 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Trying 
   connection to server: 14.80.227.127
  15253: Jul 14 03:58:24.416 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Provider.tryOpen 
   () Failure java.net.NoRouteToHostException: No route to host
  15254: Jul 14 03:58:24.417 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) ProviderRetryThread 
   waiting for 30000 msecsCCNException = com.cisco.cti.client.CCNException: No route to host
  15255: Jul 14 03:58:54.803 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Trying connection 
   to server: 14.80.227.127
  15256: Jul 14 03:58:54.808 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Provider.tryOpen 
   () Failure java.net.NoRouteToHostException: No route to host
  15257: Jul 14 03:58:54.809 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) ProviderRetryThread 
   waiting for 30000 msecsCCNException = com.cisco.cti.client.CCNException: No route to host
  15258: Jul 14 03:59:24.817 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Trying connection  
   to server: 14.80.227.127
  15259: Jul 14 03:59:24.820 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Provider.tryOpen 
   () Failure java.net.NoRouteToHostException: No route to host
  15260: Jul 14 03:59:24.821 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) ProviderRetryThread 
   waiting for 30000 msecsCCNException = com.cisco.cti.client.CCNException: No route to host
  15261: Jul 14 03:59:55.210 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Trying connection  
   to server: 14.80.227.127
  

次のトレースでは、Cisco CallManager への Cisco Unity Express の完全な登録が表示されます。 この例では、8 つの CTI ポートが JTAPI ユーザに関連付けられています。 ただし、Cisco Unity Express のライセンスで許可されているポートは 4 つまでなので、使用されるポートは 4 つだけです。 また、Cisco CallManager に再登録された後、システムが自動的に完全な MWI 再同期を行うことにも注意してください。

  17937: Jul 14 11:28:56.037 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Trying 
   connection to server: 14.80.227.127
  17938: Jul 14 11:28:56.042 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) connected
  17939: Jul 14 11:28:56.043 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: created
  17940: Jul 14 11:28:56.045 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread 
   starting up...
  17941: Jul 14 11:28:56.056 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.ProviderOpenRequest {
  sequenceNumber = 238
  provider = 14.80.227.127
  qbeClientVersion = Cisco JTAPI 1.4(3.12) Release
  login = site1cue
  password = 0c0a000a2c
  filter = com.cisco.cti.protocol.ProviderEventFilter {
  deviceRegistered = true
  deviceUnregistered = true
  directoryChangeNotify = true
  }
  applicationID = Cisco IP IVR
  desiredServerHeartbeatTime = 30
  cmAssignedApplicationID = 0
  }
  17942: Jul 14 11:28:56.072 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) ReceiveThread 
   starting up...
  17943: Jul 14 11:28:56.114 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.ProviderOpenResponse {
  sequenceNumber = 238
  providerInfoString = 3.3(3)
  clientHeartbeat = 30
  serverHeartbeat = 30
  }
  17944: Jul 14 11:28:56.131 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Server response: 
   will send server heartbeat every 30 seconds
  17945: Jul 14 11:28:56.131 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) Server response: 
   expecting client heartbeat every 30 seconds
  17946: Jul 14 11:28:56.133 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) HeartbeatSendThread 
   starting up
  17947: Jul 14 11:28:56.135 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: created
  17948: Jul 14 11:28:56.136 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread starting up...
  17949: Jul 14 11:28:56.671 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.ProviderOpenCompletedEvent {
  eventSequence = 279
  reason = 0
  sequenceNumber = 238
  providerInfoString = 3.3(3)
  clientHeartbeat = 30
  serverHeartbeat = 30
  failureDescription = null
  bMonitorCallParkDNs = false
  }
  1ISC-7-UNK:(P1-14.80.227.127) EventThread: queuing 
   com.cisco.cti.protocol.ProviderOpenCompletedEvent
  17951: Jul 14 11:28:56.674 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.ProviderOpenCompletedEvent[279]
  17952: Jul 14 11:28:56.674 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) connected to 
   CTIManager version 3.3(3)
  17953: Jul 14 11:28:56.676 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.ProviderGetCapabilitiesRequest {
  sequenceNumber = 239
  }
  17954: Jul 14 11:28:56.679 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.ProviderGetCapabilitiesResponse {
  sequenceNumber = 239
  providerCapabilitiesInfo = com.cisco.cti.protocol.ProviderCapabilitiesInfo {
  controlAnyDevice = false
  maxNumberOfDevicesOpen = 0
  }
  }
  17955: Jul 14 11:28:56.680 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) can control any 
   device = false
  17956: Jul 14 11:28:56.681 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.ProviderGetDeviceInfoRequest {
  sequenceNumber = 240
  deviceGroup = 1
  enumerateRegisterableDevices = true
  }
  17957: Jul 14 11:28:56.685 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.ProviderGetDeviceInfoResponse {
  sequenceNumber = 240
  enumerationHandle = 3
  }
  17958: Jul 14 11:28:56.686 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.80.227.127) received Response: 
   com.cisco.cti.protocol.GetDeviceInfoFetchResponse {
  sequenceNumber = 241
  info = 11@[
  com.cisco.cti.protocol.DeviceInfo {
  name = CUE_SIte1_GMS
  type = 73
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = CUE_Site1_AA
  type = 73
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = CUE_Site1_VM
  type = 73
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p01
  type = 72
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p03
  type = 72
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p02
  type = 72
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p05
  type = 72
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p04
  type = 72
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p07
  type = 72
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p06
  type = 72
  allowsRegistration = true
  },
  com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p08
  type = 72
  allowsRegistration = true
  }]
  more = false
  }
  17960: Jul 14 11:28:56.706 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetDeviceInfoCloseRequest {
  sequenceNumber = 242
  enumerationHandle = 3
  }
  17961: Jul 14 11:28:56.709 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetDeviceInfoCloseResponse {
  sequenceNumber = 242
  }
  17962: Jul 14 11:28:56.710 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) creating controlled 
   devices
  17963: Jul 14 11:28:56.712 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p08(0,0) 
   updating lines
  17964: Jul 14 11:28:56.713 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 243
  deviceName = cue_site1_p08
  }
  17965: Jul 14 11:28:56.716 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 243
  enumerationHandle = 1
  }
  17966: Jul 14 11:28:56.718 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 244
  enumerationHandle = 1
  count = 10
  }
  17967: Jul 14 11:28:56.754 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = ol.LineInfo {
  name = 28008
  permanentLineID = 1936802189
  }]
  more = false
  }
  17968: Jul 14 11:28:56.761 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 245
  enumerationHandle = 1
  }
  17969: Jul 14 11:28:56.967 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 245
  }
  17970: Jul 14 11:28:56.968 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p08(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  17971: Jul 14 11:28:56.969 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p07(0,0) 
   updating lines
  17972: Jul 14 11:28:56.970 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 246
  deviceName = cue_site1_p07
  }
  17973: Jul 14 11:28:56.973 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 246
  enumerationHandle = 2
  }
  17974: Jul 14 11:28:56.975 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 247
  enumerationHandle = 2
  count = 10
  }
  17975: Jul 14 11:28:57.007 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 247
  info = 1@[
  com.cisconeID = 829100962
  }]
  more = false
  }
  17976: Jul 14 11:28:57.009 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 248
  enumerationHandle = 2
  }
  17977: Jul 14 11:28:57.227 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 248
  }
  17978: Jul 14 11:28:57.229 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p07(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  17979: Jul 14 11:28:57.229 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p06(0,0) 
   updating lines
  17980: Jul 14 11:28:57.230 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 249
  deviceName = cue_site1_p06
  }
  17981: Jul 14 11:28:57.233 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 249
  enumerationHandle = 3
  }
  17982: Jul 14 11:28:57.235 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 250
  enumerationHandle = 3
  count = 10
  }
  17983: Jul 14 11:28:57.260 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 250
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28006
  permanentLineID = 294850253
  }]
  more = false
  }
  17984: Jul 14 11:28:57.262 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 251
  enumerationHandle = 3
  }
  17985: Jul 14 11:28:57.265 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 251
  }
  17986: Jul 14 11:28:57.267 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p06(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  17987: Jul 14 11:28:57.268 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p05(0,0) 
   updating lines
  17988: Jul 14 11:28:57.268 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 252
  deviceName = cue_site1_p05
  }
  17989: Jul 14 11:28:57.271 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 252
  enumerationHandle = 4
  }
  17990: Jul 14 11:28:57.273 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 253
  enumerationHandle = 4
  count = 10
  }
  17991: Jul 14 11:28:57.309 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 253
  info = 1@[
  com.cisco.cti.protocol.LineInfo {7.311 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 254
  enumerationHandle = 4
  }
  17993: Jul 14 11:28:57.314 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 254
  }
  17994: Jul 14 11:28:57.316 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p05(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  17995: Jul 14 11:28:57.317 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p04(0,0) 
   updating lines
  17996: Jul 14 11:28:57.318 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 255
  deviceName = cue_site1_p04
  }
  17997: Jul 14 11:28:57.322 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 255
  enumerationHandle = 5
  }
  17998: Jul 14 11:28:57.324 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 256
  enumerationHandle = 5
  count = 10
  }
  17999: Jul 14 11:28:57.358 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 256
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28004
  permanentLineID = 1897211172
  }]
  more = false
  }
  18000: Jul
  enumerationHandle = 5
  }
  18001: Jul 14 11:28:57.363 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 257
  }
  18002: Jul 14 11:28:57.364 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p04(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18003: Jul 14 11:28:57.365 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p03(0,0) 
   updating lines
  18004: Jul 14 11:28:57.366 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 258
  deviceName = cue_site1_p03
  }
  18005: Jul 14 11:28:57.587 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 258
  enumerationHandle = 6
  }
  18006: Jul 14 11:28:57.589 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 259
  enumerationHandle = 6
  count = 10
  }
  18007: Jul 14 11:28:57.632 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 259
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28003
  permanentLineID = 2109152574
  }]
  more = false
  }
  18008: Jul 14 11:28:57.634 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 260
  enumerationHandle = 6
  }
  18009: Jul 14 11:28:57.637 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 260
  }
  18010: Jul 14 11:28:57.638 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p03(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18011: Jul 14 11:28:57.639 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p02(0,0) 
   updating lines
  18012: Jul 14 11:28:57.640 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 261
  deviceName = cue_site1_p02
  }
  18013: Jul 14 11:28:57.645 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 261
  enumerationHandle = 7
  }
  18014: Jul 14 11:28:57.646 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 262
  enumerationHandle = 7
  count = 10
  }
  18015: Jul 14 11:28:57.681 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 262
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28002
  permanentLineID = 1035863534
  }]
  more = false
  }
  18016: Jul 14 11:28:57.683 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLUNK:(P1-14.80.227.127) 
   received Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 263
  }
  18018: Jul 14 11:28:57.687 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p02(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18019: Jul 14 11:28:57.688 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p01(0,0) 
   updating lines
  18020: Jul 14 11:28:57.689 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 264
  deviceName = cue_site1_p01
  }
  18021: Jul 14 11:28:57.692 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 264
  enumerationHandle = 8
  }
  18022: Jul 14 11:28:57.694 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 265
  enumerationHandle = 8
  count = 10
  }
  18023: Jul 14 11:28:57.708 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 265
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28001
  permanentLineID = 1084634008
  }]
  more = false
  }
  18024: Jul 14 11:28:57.710 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 266
  enumerationHandle = 8
  }
  18025: Jul 14 11:28:57.713 EDT %JTAPI-esponse: 
   com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 266
  }
  18026: Jul 14 11:28:57.716 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p01(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18027: Jul 14 11:28:57.717 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_SIte1_GMS(0,0) 
   updating lines
  18028: Jul 14 11:28:57.718 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 267
  deviceName = CUE_SIte1_GMS
  }
  18029: Jul 14 11:28:57.725 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 267
  enumerationHandle = 9
  }
  18030: Jul 14 11:28:57.727 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 268
  enumerationHandle = 9
  count = 10
  }
  18031: Jul 14 11:28:57.961 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 268
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28111
  permanentLineID = 632514620
  }]
  more = false
  }
  18032: Jul 14 11:28:57.963 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 269
  enumerationHandle = 9
  }
  18033: Jul 14 11:28:57.966 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 269
  }
  18034: Jul 14 11:28:57.967 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_SIte1_GMS(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18035: Jul 14 11:28:57.968 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_AA(0,0) 
   updating lines
  18036: Jul 14 11:28:57.969 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 270
  deviceName = CUE_Site1_AA
  }
  18037: Jul 14 11:28:57.972 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 270
  enumerationHandle = 10
  }
  18038: Jul 14 11:28:57.974 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 271
  enumerationHandle = 10
  count = 10
  }
  18039: Jul 14 11:28:58.011 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 271
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28100
  permanentLineID = 117519949
  }]
  more = false
  }
  18040: Jul 14 11:28:58.013 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 272
  enumerationHandle = 10
  }
  18041: Jul 14 11:28:58.018 EDT %JTAved Response: 
   com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 272
  }
  18042: Jul 14 11:28:58.019 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_AA(0,0) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18043: Jul 14 11:28:58.020 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_VM(0,0) 
   updating lines
  18044: Jul 14 11:28:58.021 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 273
  deviceName = CUE_Site1_VM
  }
  18045: Jul 14 11:28:58.025 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 273
  enumerationHandle = 11
  }
  18046: Jul 14 11:28:58.035 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 274
  enumerationHandle = 11
  count = 10
  }
  18047: Jul 14 11:28:58.060 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 274
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28000
  permanentLineID = 1978608865
  }]
  more = false
  }
  18048: Jul 14 11:28:58.061 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 275
  enumerationHandle = 11
  }
  18049: Jul 14 11:28:58.277 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227s=1 current=1 
   created=0 removed=0
  18051: Jul 14 11:28:58.279 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) refreshing device 
   map: previous=11 current=11 created=0 removed=0
  18052: Jul 14 11:28:58.280 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.ProviderGetDeviceInfoRequest {
  sequenceNumber = 276
  deviceGroup = 3
  enumerateRegisterableDevices = true
  }
  18053: Jul 14 11:28:58.283 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.ProviderGetDeviceInfoResponse {
  sequenceNumber = 276
  enumerationHandle = 4
  }
  18054: Jul 14 11:28:58.285 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetDeviceInfoFetchRequest {
  sequenceNumber = 277
  enumerationHandle = 4
  count = 100
  type = 2
  }
  18055: Jul 14 11:28:58.296 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetDeviceInfoFetchResponse {
  sequenceNumber = 277
  info = null
  more = false
  }
  18056: Jul 14 11:28:58.298 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.GetDeviceInfoCloseRequest {
  sequenceNumber = 278
  enumerationHandle = 4
  }
  18057: Jul 14 11:28:58.507 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetDeviceInfoCloseResponse {
  sequenceNumber = 278
  }
  18058: Jul 14 11:28:58.508 EDT %JTAPI-MISC-7-UNK:Provider "(P1-site1cue)" changing 
   state to IN_SERVICE
  18059: Jul 14 11:28:58.509 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue)[ProviderRetryThread]
   (P1-site1cue) Request: getObservers
  18060: Jul 14 11:28:58.510 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) ProvInServiceEv [#684]
  18061: Jul 14 11:28:58.511 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.SubsystemJTAPI$ProviderObserver@107836e4]
   ObserverProxy.queueEvents: queuing asynchronously
  18062: Jul 14 11:28:58.511 EDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.SubsystemJTAPI$ProviderObserver@107836e4): 
   queuing com.cisco.jtapi.JtapiProviderEventSet
  18063: Jul 14 11:28:58.512 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.SubsystemJTAPI$ProviderObserver@107836e4): 
   delivering JPES[1]
  18064: Jul 14 11:28:58.513 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.SubsystemJTAPI$ProviderObserver@107836e4]
   ObserverProxy.deliverEvents()
  18065: Jul 14 11:28:58.517 EDT %JTAPI-JTAPIIMPL-7-UNK:
    [com.cisco.wf.subsystems.jtapi.SubsystemJTAPI$ProviderObserver@107836e4]
   ObserverProxy.deliverEvents() completed
  18066: Jul 14 11:28:58.522 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) CUE_SIte1_GMS(0,0)
  18067: Jul 14 11:28:58.525 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceOpenRequest {
  sequenceNumber = 279
  deviceName = CUE_SIte1_GMS
  filter = com.cisco.cti.protocol.DeviceEventFilter {
  deviceModeChanged = false
  keyPressed = false
  displayChanged = false
  startTransmission = true
  stopTransmission = true
  startReception = true
  stopReception = true
  softKeyPressed = false
  deviceData = true
  }
  disableAutoRecovery = false
  }
  18068: Jul 14 11:28:58.544 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   received Event: com.cisco.cti.protocol.DeviceRegisteredEvent {
  eventSequence = 280
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = CUE_SIte1_GMS
  type = 73
  allowsRegistration = true
  }
  loginAllowed = false
  loginUserID = 
  controllable = true
  reason = 0
  }
  18069: Jul 14 11:28:58.545 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceRegisteredEvent
  18070: Jul 14 11:28:58.546 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceRegisteredEvent[280]
  18071: Jul 14 11:28:58.546 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) Received 
   DeviceRegisteredEvent
  
  18072: Jul 14 11:28:59.303 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceOpenResponse {
  sequenceNumber = 279
  callManagerID = 16777227
  deviceID = 33
  }
  18073: Jul 14 11:28:59.306 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) DeviceMap: 
   opening device "CUE_SIte1_GMS"
  18074: Jul 14 11:28:59.314 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: queuing com.cisco.cti.client.implementation.Device
  18075: Jul 14 11:28:59.315 EDT %JTAPI-CTi.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 280
  deviceName = CUE_SIte1_GMS
  }
  18077: Jul 14 11:28:59.325 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_SIte1_GMS(16777227,33) 
   reopening line 28111(0,0)
  18078: Jul 14 11:28:59.328 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.LineOpenRequest {
  sequenceNumber = 281
  deviceName = CUE_SIte1_GMS
  lineName = 28111
  filter = com.cisco.cti.protocol.LineEventFilter {
  callStateChanged = true
  dtmf = true
  ring = false
  toneChanged = false
  globalCallHandleChanged = true
  openReceiveChannel = false
  partyInfoChanged = true
  bExistingCallEvent = true
  bNewCallEvent = true
  bLineCfwdAllStatus = true
  }
  disableAutoRecovery = false
  }
  18079: Jul 14 11:28:59.305 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceInServiceEvent {
  eventSequence = 281
  deviceCallManagerID = 16777227
  deviceID = 33
  }
  18080: Jul 14 11:28:59.330 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceInServiceEvent
  18081: Jul 14 11:28:59.331 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceInServiceEvent[281]
  18082: Jul 14 11:28:59.332 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Terminal 
   "CUE_SIte1_GMS" in service
  18083: Jul 14 11:28:59.333 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [CUE_SIte1_GMS] 
   CiscoTermInServiceEv [#685]
  18084: Jul 14 11:28:59.334 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 280
  enumerationHandle = 12
  }
  18085: Jul 14 11:28:59.336 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 282
  enumerationHandle = 12
  count = 10
  }
  18086: Jul 14 11:28:59.362 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineOpenResponse {
  sequenceNumber = 281
  callManagerID = 16777227
  lineID = 33
  }
  18087: Jul 14 11:28:59.364 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) CUE_Site1_AA(0,0)
  18088: Jul 14 11:28:59.367 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceOpenRequest {
  sequenceNumber = 283
  deviceName = CUE_Site1_AA
  filter = com.cisco.cti.protocol.DeviceEventFilter {
  deviceModeChanged = false
  keyPressed = false
  featureButtonPressed = false
  lampModeChanged = false
  ringModeChanged = false
  displayChanged = false
  startTransmission = true
  stopTransmission = true
  startReception = true
  stopReception = true
  softKeyPressed = false
  deviceData = true
  }
  dilse
  }
  18089: Jul 14 11:28:59.371 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.LineInServiceEvent {
  eventSequence = 282
  lineCallManagerID = 16777227
  lineID = 33
  }
  18090: Jul 14 11:28:59.371 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.LineInServiceEvent
  18091: Jul 14 11:28:59.372 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.LineInServiceEvent[282]
  18092: Jul 14 11:28:59.373 EDT %JTAPI-CTI-7-UNK:(P1-site1cue){Line:28111(16777227,33)} 
   LineInServiceEvent
  18093: Jul 14 11:28:59.374 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Address "28111" 
   in service
  18094: Jul 14 11:28:59.374 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [28111] 
   CiscoAddrInServiceEv [#686]
  18095: Jul 14 11:28:59.375 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d8576e6]
   ObserverProxy.queueEvents: queuing asynchronously
  18096: Jul 14 11:28:59.376 EDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d8576e6): 
   queuing com.cisco.jtapi.JtapiAddressEventSet
  18097: Jul 14 11:28:59.377 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d8576e6): 
   delivering JAES[1]
  18098: Jul 14 11:28:59.378 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d8576e6]
   ObserverProxy.deliverEvents()
  18099: Jul 14 11:28:59.391 EDT %JTAPI-JTAPIIMPL-7-UNK:[com.cisco.wf.subsyscompleted
  18100: Jul 14 11:28:59.403 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 282
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28111
  permanentLineID = 632514620
  }]
  more = false
  }
  18101: Jul 14 11:28:59.405 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 284
  enumerationHandle = 12
  }
  18102: Jul 14 11:28:59.408 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceRegisteredEvent {
  eventSequence = 283
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = CUE_Site1_AA
  type = 73
  allowsRegistration = true
  }
  loginAllowed = false
  loginUserID = 
  controllable = true
  reason = 0
  }
  18103: Jul 14 11:28:59.409 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceRegisteredEvent
  18104: Jul 14 11:28:59.410 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceRegisteredEvent[283]
  18105: Jul 14 11:28:59.411 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) Received 
   DeviceRegisteredEvent
  18106: Jul 14 11:28:59.412 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceOpenResponse {
  sequenceNumber = 283
  callManagerID = 16777227
  deviceID = 34
  }
  18107: Jul 14 11:28:59.414 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceInServiceEvent {
  eventSequence = 284
  deviceCallManagerID = 16777227
  deviceID = 34
  }
  18108: Jul 14 11:28:59.416 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) DeviceMap: opening 
   device "CUE_Site1_AA"
  18109: Jul 14 11:28:59.417 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: queuing com.cisco.cti.client.implementation.Device
  18110: Jul 14 11:28:59.418 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_AA(16777227,34) 
   reopening line 28100(0,0)
  18111: Jul 14 11:28:59.420 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.LineOpenRequest {
  sequenceNumber = 285
  deviceName = CUE_Site1_AA
  lineName = 28100
  filter = com.cisco.cti.protocol.LineEventFilter {
  callStateChanged = true
  dtmf = true
  ring = false
  toneChanged = false
  globalCallHandleChanged = true
  openReceiveChannel = false
  partyInfoChanged = true
  bExistingCallEvent = true
  bNewCallEvent = true
  bLineCfwdAllStatus = true
  }
  disableAutoRecovery = false
  }
  18112: Jul 14 11:28:59.422 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceInServiceEvent
  18113: Jul 14 11:28:59.423 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.proto
  18115: Jul 14 11:28:59.425 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [CUE_Site1_AA] 
   CiscoTermInServiceEv [#687]
  18116: Jul 14 11:28:59.428 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 284
  }
  18117: Jul 14 11:28:59.429 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_SIte1_GMS(16777227,33) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18118: Jul 14 11:28:59.430 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_AA(16777227,34) 
   updating lines
  18119: Jul 14 11:28:59.431 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 286
  deviceName = CUE_Site1_AA
  }
  18120: Jul 14 11:28:59.434 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineOpenResponse {
  sequenceNumber = 285
  callManagerID = 16777227
  lineID = 34
  }
  18121: Jul 14 11:28:59.436 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) cue_site1_p08(0,0)
  18122: Jul 14 11:28:59.436 EDT %JTAPI-CTIIMPL-7-UNK:(P1-site1cue) cue_site1_p08(0,0) 
   Device is not Opened previously, not attempting to open 
  18123: Jul 14 11:28:59.437 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) CUE_Site1_VM(0,0)
  18124: Jul 14 11:28:59.439 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceOpenRequest {
  sequenceNumber = 287
  deviceName = CUE_Site1_VM
  filter ssed = false
  lampModeChanged = false
  ringModeChanged = false
  displayChanged = false
  startTransmission = true
  stopTransmission = true
  startReception = true
  stopReception = true
  softKeyPressed = false
  deviceData = true
  }
  disableAutoRecovery = false
  }
  18125: Jul 14 11:28:59.442 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.LineInServiceEvent {
  eventSequence = 285
  lineCallManagerID = 16777227
  lineID = 34
  }
  18126: Jul 14 11:28:59.443 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.LineInServiceEvent
  18127: Jul 14 11:28:59.444 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.LineInServiceEvent[285]
  18128: Jul 14 11:28:59.445 EDT %JTAPI-CTI-7-UNK:(P1-site1cue){Line:28100(16777227,34)} 
   LineInServiceEvent
  18129: Jul 14 11:28:59.446 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Address "28100" 
   in service
  18130: Jul 14 11:28:59.447 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [28100] 
   CiscoAddrInServiceEv [#688]
  18131: Jul 14 11:28:59.448 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3f0ab6e7]
   ObserverProxy.queueEvents: queuing asynchronously
  18132: Jul 14 11:28:59.448 EDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3f0ab6e7): 
   queuing com.cisco.jtapi.JtapiAddressEventSet
  18133: Jul 14 11:28:59.449 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3f0ab6e7): 
   delivering JAES[1]
  18134: Jul 14 11:28:59.450 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3f0ab6e7]
   ObserverProxy.deliverEvents()
  18135: Jul 14 11:28:59.468 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3f0ab6e7]
   ObserverProxy.deliverEvents() completed
  18136: Jul 14 11:28:59.475 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 286
  enumerationHandle = 13
  }
  18137: Jul 14 11:28:59.476 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 288
  enumerationHandle = 13
  count = 10
  }
  18138: Jul 14 11:28:59.481 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceRegisteredEvent {
  eventSequence = 286
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = CUE_Site1_VM
  type = 73
  allowsRegistration = true
  }
  loginAllowed = false
  loginUserID = 
  controllable = true
  reason = 0
  }
  18139: Jul 14 11:28:59.482 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceRegisteredEvent
  18140: Jul 14 11:28:59.483 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceRegisteredEvent[286]
  18141: Jul 14 11:28:59.484 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) Received 
   DeviceRegisteredEvent
  18142: Jul 14 11:28:59.705 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceOpenResponse {
  sequenceNumber = 287
  callManagerID = 16777227
  deviceID = 35
  }
  18143: Jul 14 11:28:59.707 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) DeviceMap: opening 
   device "CUE_Site1_VM"
  18144: Jul 14 11:28:59.708 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: queuing com.cisco.cti.client.implementation.Device
  18145: Jul 14 11:28:59.709 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_VM(16777227,35) 
   reopening line 28000(0,0)
  18146: Jul 14 11:28:59.711 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.LineOpenRequest {
  sequenceNumber = 289
  deviceName = CUE_Site1_VM
  lineName = 28000
  filter = com.cisco.cti.protocol.LineEventFilter {
  callStateChanged = true
  dtmf = true
  ring = false
  toneChanged = false
  globalCallHandleChanged = true
  openReceiveChannel = false
  partyInfoChanged = true
  bExistingCallEvent = true
  bNewCallEvent = true
  bLineCfwdAllStatus = true
  }
  disableAutoRecovery = false
  }
  18147: Jul 14 11:28:59.714 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceInServiceEvent {
  eventSequ
  18149: Jul 14 11:28:59.716 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceInServiceEvent[287]
  18150: Jul 14 11:28:59.718 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Terminal 
   "CUE_Site1_VM" in service
  18151: Jul 14 11:28:59.718 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [CUE_Site1_VM] 
   CiscoTermInServiceEv [#689]
  18152: Jul 14 11:28:59.720 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 288
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28100
  permanentLineID = 117519949
  }]
  more = false
  }
  18153: Jul 14 11:28:59.722 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 290
  enumerationHandle = 13
  }
  18154: Jul 14 11:28:59.724 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineOpenResponse {
  sequenceNumber = 289
  callManagerID = 16777227
  lineID = 35
  }
  18155: Jul 14 11:28:59.726 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) cue_site1_p07(0,0)
  18156: Jul 14 11:28:59.726 EDT %JTAPI-CTIIMPL-7-UNK:(P1-site1cue) cue_site1_p07(0,0) 
   Device is not Opened previously, not attempting to open 
  18157: Jul 14 11:28:59.727 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) cue_site1_p06(0,0)
  18158: Jul 14 11:28:59.728 EDT %JTAPI-CTIIMPL-7-UNK:(P1-site1cue) cue_site1_p06(0,0) 
   Device is not Opened previously, not attempting to open 
  18159: Jul 14 11:28:59.728 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) cue_site1_p05(0,0)
  18160: Jul 14 11:28:59.729 EDT %JTAPI-CTIIMPL-7-UNK:(P1-site1cue) cue_site1_p05(0,0) 
   Device is not Opened previously, not attempting to open 
  18161: Jul 14 11:28:59.729 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) cue_site1_p04(0,0)
  18162: Jul 14 11:28:59.733 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceRegisterDeviceRequest {
  sequenceNumber = 291
  deviceName = cue_site1_p04
  ipAddr = 1802113708
  rtpPortNumber = 16384
  mediaSpecificationTimeout = 0
  mediaCaps = 2@[
  com.cisco.cti.protocol.MediaCapability {
  payloadCapability = 4
  maxFramesPerPacket = 30
  bitRate = 1
  },
  com.cisco.cti.protocol.MediaCapability {
  payloadCapability = 2
  maxFramesPerPacket = 30
  bitRate = 1
  }]
  filter = com.cisco.cti.protocol.DeviceEventFilter {
  deviceModeChanged = false
  keyPressed = false
  featureButtonPressed = false
  lampModeChanged = false
  ringModeChanged = false
  
  displayChanged = false
  startTransmission = true
  stopTransmission = true
  startReception = true
  stopReception = true
  softKeyPressed = false
  deviceData 163: Jul 14 11:28:59.737 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.LineInServiceEvent {
  eventSequence = 288
  lineCallManagerID = 16777227
  lineID = 35
  }
  18164: Jul 14 11:28:59.737 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.LineInServiceEvent
  18165: Jul 14 11:28:59.739 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.LineInServiceEvent[288]
  18166: Jul 14 11:28:59.739 EDT %JTAPI-CTI-7-UNK:(P1-site1cue){Line:28000(16777227,35)} 
   LineInServiceEvent
  18167: Jul 14 11:28:59.740 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Address "28000" in 
   service
  18168: Jul 14 11:28:59.741 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [28000] 
   CiscoAddrInServiceEv [#690]
  18169: Jul 14 11:28:59.741 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@40b3b6e1]
   ObserverProxy.queueEvents: queuing asynchronously
  18170: Jul 14 11:28:59.742 EDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@40b3b6e1): 
   queuing com.cisco.jtapi.JtapiAddressEventSet
  18171: Jul 14 11:28:59.744 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@40b3b6e1): 
   delivering JAES[1]
  18172: Jul 14 11:28:59.744 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@40b3b6e1]
   ObserverProxy.deliverEvents()
  18173: Jul 14 11:28:59.760 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.T
  18174: Jul 14 11:28:59.768 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 290
  }
  18175: Jul 14 11:28:59.769 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_AA(16777227,34) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18176: Jul 14 11:28:59.770 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_VM(16777227,35) 
   updating lines
  18177: Jul 14 11:28:59.771 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 292
  deviceName = CUE_Site1_VM
  }
  18178: Jul 14 11:28:59.775 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received Event: 
   com.cisco.cti.protocol.DeviceRegisteredEvent {
  eventSequence = 289
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p04
  type = 72
  allowsRegistration = true
  }
  loginAllowed = false
  loginUserID = 
  controllable = true
  reason = 0
  }
  18179: Jul 14 11:28:59.776 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceRegisteredEvent
  18180: Jul 14 11:28:59.777 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceRegisteredEvent[289]
  18181: Jul 14 11:28:59.778 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) Received 
   DeviceRegisteredEvent
  18182: Jul 14 11:28:59.780 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceRegisterDeviceResponse {
  sequenceNumber = 291
  callManagerID = 16777227
  deviceID = 36
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p04
  type = 72
  allowsRegistration = true
  }
  }
  18183: Jul 14 11:28:59.781 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) DeviceMap: opening 
   device "cue_site1_p04"
  18184: Jul 14 11:28:59.782 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: queuing com.cisco.cti.client.implementation.Device
  18185: Jul 14 11:28:59.783 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p04(16777227,36) 
   reopening line 28004(0,0)
  18186: Jul 14 11:28:59.785 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.LineOpenRequest {
  sequenceNumber = 293
  deviceName = cue_site1_p04
  lineName = 28004
  filter = com.cisco.cti.protocol.LineEventFilter {
  callStateChanged = true
  dtmf = true
  ring = false
  toneChanged = false
  globalCallHandleChanged = true
  openReceiveChannel = false
  partyInfoChanged = true
  bExistingCallEvent = true
  bNewCallEvent = true
  bLineCfwdAllStatus = true
  }
  disableAutoRecovery = false
  }
  18187: Jul 14 11:28:59.789 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceInServiceEvent {
  eventSequence = 290
  deviceCallManagerID = 16777227
  deviceID cti.protocol.DeviceInServiceEvent
  18189: Jul 14 11:28:59.790 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceInServiceEvent[290]
  18190: Jul 14 11:28:59.791 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Terminal 
   "cue_site1_p04" in service
  18191: Jul 14 11:28:59.792 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [cue_site1_p04] 
   CiscoTermInServiceEv [#691]
  18192: Jul 14 11:28:59.794 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 292
  enumerationHandle = 14
  }
  18193: Jul 14 11:28:59.796 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 294
  enumerationHandle = 14
  count = 10
  }
  18194: Jul 14 11:28:59.799 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineOpenResponse {
  sequenceNumber = 293
  callManagerID = 16777227
  lineID = 36
  }
  18195: Jul 14 11:28:59.800 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening 
   device (P1-site1cue) cue_site1_p03(0,0)
  18196: Jul 14 11:28:59.803 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceRegisterDeviceRequest {
  sequenceNumber = 295
  deviceName = cue_site1_p03
  ipAddr = 1802113708
  rtpPortNumber = 16386
  mediaSpecificationTimeout = 0
  mediaCaps = 2@[
  com.cisco.cti.ability {
  payloadCapability = 2
  maxFramesPerPacket = 30
  bitRate = 1
  }]
  filter = com.cisco.cti.protocol.DeviceEventFilter {
  deviceModeChanged = false
  keyPressed = false
  featureButtonPressed = false
  lampModeChanged = false
  ringModeChanged = false
  displayChanged = false
  startTransmission = true
  stopTransmission = true
  startReception = true
  stopReception = true
  softKeyPressed = false
  deviceData = true
  }
  disableAutoRecovery = false
  }
  18197: Jul 14 11:28:59.807 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.LineInServiceEvent {
  eventSequence = 291
  lineCallManagerID = 16777227
  lineID = 36
  }
  18198: Jul 14 11:28:59.808 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.LineInServiceEvent
  18199: Jul 14 11:28:59.809 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.LineInServiceEvent[291]
  18200: Jul 14 11:28:59.810 EDT %JTAPI-CTI-7-UNK:(P1-site1cue){Line:28004(16777227,36)} 
   LineInServiceEvent
  18201: Jul 14 11:28:59.810 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Address "28004" 
   in service
  18202: Jul 14 11:28:59.811 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [28004] 
   CiscoAddrInServiceEv [#692]
  18203: Jul 14 11:28:59.812 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3928f6e1]
   ObserverProxy.queueEvents: queuing asynchronously
  18204: Jul 14 11:28:59.812 EDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3928f6e1): 
   queuing com.cisco.jtapi.JtapiAddressEventSet
  18205: Jul 14 11:28:59.813 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3928f6e1): 
   delivering JAES[1]
  18206: Jul 14 11:28:59.814 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3928f6e1]
   ObserverProxy.deliverEvents()
  18207: Jul 14 11:28:59.948 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@3928f6e1]
   ObserverProxy.deliverEvents() completed
  18208: Jul 14 11:29:00.057 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 294
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28000
  permanentLineID = 1978608865
  }]
  more = false
  }
  18209: Jul 14 11:29:00.059 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 296
  enumerationHandle = 14
  }
  18210: Jul 14 11:29:00.062 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceRegisteredEvent {
  eventSequence = 292
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p03
  type = 72
  owsRegistration = true
  }
  loginAllowed = false
  loginUserID = 
  controllable = true
  reason = 0
  }
  18211: Jul 14 11:29:00.063 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceRegisteredEvent
  18212: Jul 14 11:29:00.064 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceRegisteredEvent[292]
  18213: Jul 14 11:29:00.065 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) Received 
   DeviceRegisteredEvent
  18214: Jul 14 11:29:00.067 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceRegisterDeviceResponse {
  sequenceNumber = 295
  callManagerID = 16777227
  deviceID = 37
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p03
  type = 72
  allowsRegistration = true
  }
  }
  18215: Jul 14 11:29:00.068 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) DeviceMap: opening 
   device "cue_site1_p03"
  18216: Jul 14 11:29:00.069 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: queuing com.cisco.cti.client.implementation.Device
  18217: Jul 14 11:29:00.070 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p03
   (16777227,37) reopening line 28003(0,0)
  18218: Jul 14 11:29:00.072 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.LineOpenRequest {
  sequenceNumber = 297
  deviceName = cue_site1_p03
  lineName = 28003
  filter = com.cisco.cti.protocol.LineEventFilter {
  callS
  partyInfoChanged = true
  bExistingCallEvent = true
  bNewCallEvent = true
  bLineCfwdAllStatus = true
  }
  disableAutoRecovery = false
  }
  18219: Jul 14 11:29:00.096 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceInServiceEvent {
  eventSequence = 293
  deviceCallManagerID = 16777227
  deviceID = 37
  }
  18220: Jul 14 11:29:00.097 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceInServiceEvent
  18221: Jul 14 11:29:00.098 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceInServiceEvent[293]
  18222: Jul 14 11:29:00.098 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Terminal 
   "cue_site1_p03" in service
  18223: Jul 14 11:29:00.099 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [cue_site1_p03] 
   CiscoTermInServiceEv [#693]
  18224: Jul 14 11:29:00.101 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 296
  }
  18225: Jul 14 11:29:00.102 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) CUE_Site1_VM(16777227,35) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18226: Jul 14 11:29:00.103 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p04(16777227,36) 
   updating lines
  18227: Jul 14 11:29:00.104 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 298
  deviceName = cue_site1_p04
  }
  18228: Jul 14 11:29:00.107 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineOpenResponse {
  sequenceNumber = 297
  callManagerID = 16777227
  lineID = 37
  }
  18229: Jul 14 11:29:00.108 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening device 
   (P1-site1cue) cue_site1_p02(0,0)
  18230: Jul 14 11:29:00.112 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceRegisterDeviceRequest {
  sequenceNumber = 299
  deviceName = cue_site1_p02
  ipAddr = 1802113708
  rtpPortNumber = 16388
  mediaSpecificationTimeout = 0
  mediaCaps = 2@[
  com.cisco.cti.protocol.MediaCapability {
  payloadCapability = 4
  maxFramesPerPacket = 30
  bitRate = 1
  },
  com.cisco.cti.protocol.MediaCapability {
  payloadCapability = 2
  maxFramesPerPacket = 30
  bitRate = 1
  }]
  filter = com.cisco.cti.protocol.DeviceEventFilter {
  deviceModeChanged = false
  keyPressed = false
  featureButtonPressed = false
  lampModeChanged = false
  ringModeChanged = false
  displayChanged = false
  startTransmission = true
  stopTransmission = true
  startReception = true
  stopReception = true
  softKeyPressed = false
  deviceData = true
  }
  disableAutoRecovery = false
  }
  18231: Jul 14 11:29:00.116 EDT %JTAPI-PROTOCOL-7-UNK:(P1-1 294
  lineCallManagerID = 16777227
  lineID = 37
  }
  18232: Jul 14 11:29:00.117 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.LineInServiceEvent
  18233: Jul 14 11:29:00.118 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.LineInServiceEvent[294]
  18234: Jul 14 11:29:00.119 EDT %JTAPI-CTI-7-UNK:(P1-site1cue){Line:28003(16777227,37)} 
   LineInServiceEvent
  18235: Jul 14 11:29:00.120 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Address "28003" 
   in service
  18236: Jul 14 11:29:00.120 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [28003] 
   CiscoAddrInServiceEv [#694]
  18237: Jul 14 11:29:00.121 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@2f3a76e1]
   ObserverProxy.queueEvents: queuing asynchronously
  18238: Jul 14 11:29:00.122 EDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@2f3a76e1): 
   queuing com.cisco.jtapi.JtapiAddressEventSet
  18239: Jul 14 11:29:00.123 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@2f3a76e1): 
   delivering JAES[1]
  18240: Jul 14 11:29:00.123 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@2f3a76e1]
   ObserverProxy.deliverEvents()
  18241: Jul 14 11:29:00.139 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@2f3a76e1]
   ObserverProxy.deliverEvents() completed
  18242: Jul 14 11:29:00.141 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227ceNumber = 298
  enumerationHandle = 15
  }
  18243: Jul 14 11:29:00.142 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 300
  enumerationHandle = 15
  count = 10
  }
  18244: Jul 14 11:29:00.147 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceRegisteredEvent {
  eventSequence = 295
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p02
  type = 72
  allowsRegistration = true
  }
  loginAllowed = false
  loginUserID = 
  controllable = true
  reason = 0
  }
  18245: Jul 14 11:29:00.147 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceRegisteredEvent
  18246: Jul 14 11:29:00.148 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceRegisteredEvent[295]
  18247: Jul 14 11:29:00.149 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) Received 
   DeviceRegisteredEvent
  18248: Jul 14 11:29:00.151 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceRegisterDeviceResponse {
  sequenceNumber = 299
  callManagerID = 16777227
  deviceID = 38
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p02
  type = 72
  allowsRegistration = true
  }
  }
  18249: Jul 14 11:29:00.152 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) DeviceMap: opening 
   device "cue_site1_p02"
  18250: Jul 14 11:29:00.154 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: queuing com.cisco.cti.client.implementation.Device
  18251: Jul 14 11:29:00.155 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p02(16777227,38) 
   reopening line 28002(0,0)
  18252: Jul 14 11:29:00.157 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.LineOpenRequest {
  sequenceNumber = 301
  deviceName = cue_site1_p02
  lineName = 28002
  filter = com.cisco.cti.protocol.LineEventFilter {
  callStateChanged = true
  dtmf = true
  ring = false
  toneChanged = false
  globalCallHandleChanged = true
  openReceiveChannel = false
  partyInfoChanged = true
  bExistingCallEvent = true
  bNewCallEvent = true
  bLineCfwdAllStatus = true
  }
  disableAutoRecovery = false
  }
  18253: Jul 14 11:29:00.161 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.DeviceInServiceEvent {
  eventSequence = 296
  deviceCallManagerID = 16777227
  deviceID = 38
  }
  18254: Jul 14 11:29:00.161 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceInServiceEvent
  18255: Jul 14 11:29:00.162 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceInServiceEvent[296]
  18256: Jul 14 11:29:00.163 EDT %JTAPI-JTAPIIMPL-7-UNKscoTermInServiceEv [#695]
  18258: Jul 14 11:29:00.166 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 300
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28004
  permanentLineID = 1897211172
  }]
  more = false
  }
  18259: Jul 14 11:29:00.188 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 302
  enumerationHandle = 15
  }
  18260: Jul 14 11:29:00.192 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineOpenResponse {
  sequenceNumber = 301
  callManagerID = 16777227
  lineID = 38
  }
  18261: Jul 14 11:29:00.193 EDT %JTAPI-CTI-7-UNK:(P1-14.80.227.127) reopening 
   device (P1-site1cue) cue_site1_p01(0,0)
  18262: Jul 14 11:29:00.197 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [ProviderRetryThread] sending: com.cisco.cti.protocol.DeviceRegisterDeviceRequest {
  sequenceNumber = 303
  deviceName = cue_site1_p01
  ipAddr = 1802113708
  rtpPortNumber = 16390
  mediaSpecificationTimeout = 0
  mediaCaps = 2@[
  com.cisco.cti.protocol.MediaCapability {
  payloadCapability = 4
  maxFramesPerPacket = 30
  bitRate = 1
  },
  com.cisco.cti.protocol.MediaCapability {
  payloadCapability = 2
  maxFramesPerPacket = 30
  bitRate = 1
  }]
  filter false
  featureButtonPressed = false
  lampModeChanged = false
  ringModeChanged = false
  displayChanged = false
  startTransmission = true
  stopTransmission = true
  startReception = true
  stopReception = true
  softKeyPressed = false
  deviceData = true
  }
  disableAutoRecovery = false
  }
  18263: Jul 14 11:29:00.202 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.LineInServiceEvent {
  eventSequence = 297
  lineCallManagerID = 16777227
  lineID = 38
  }
  18264: Jul 14 11:29:00.202 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.LineInServiceEvent
  18265: Jul 14 11:29:00.204 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.LineInServiceEvent[297]
  18266: Jul 14 11:29:00.204 EDT %JTAPI-CTI-7-UNK:(P1-site1cue){Line:28002(16777227,38)} 
   LineInServiceEvent
  18267: Jul 14 11:29:00.205 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Address "28002" 
   in service
  18268: Jul 14 11:29:00.206 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [28002] 
   CiscoAddrInServiceEv [#696]
  18269: Jul 14 11:29:00.207 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d4a36e0]
   ObserverProxy.queueEvents: queuing asynchronously
  18270: Jul 14 11:29:00.207 EDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d4a36e0): 
   queuing com.cisco.jtapi.JtapiAddressEventSet
  18271: Jul 14 11:29:00.208 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d4a36e0): 
   delivering JAES[1]
  18272: Jul 14 11:29:00.209 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d4a36e0]
   ObserverProxy.deliverEvents()
  18273: Jul 14 11:29:00.218 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@6d4a36e0]
   ObserverProxy.deliverEvents() completed
  18274: Jul 14 11:29:00.220 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 302
  }
  18275: Jul 14 11:29:00.222 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p04(16777227,36) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18276: Jul 14 11:29:00.223 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p03(16777227,37) 
   updating lines
  18277: Jul 14 11:29:00.224 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 304
  deviceName = cue_site1_p03
  }
  18278: Jul 14 11:29:00.231 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue)[Thread-37][28002]Request: 
   setMessageWaiting ( 2104,true )
  18279: Jul 14 11:29:00.232 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) [Thread-37] 
   sending: com.cisco.cti.protocol.LineSetMessageWaitingRequest {
  sequenceNumber = 305
  lineCallManagerID = 16777227
  lineID = 38
  lineName = 2104
  lampMode = 2
  }
  1828PROTOCOL-7-UNK:(P1-14.80.227.127) received Event: 
   com.cisco.cti.protocol.DeviceRegisteredEvent {
  eventSequence = 298
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p01
  type = 72
  allowsRegistration = true
  }
  loginAllowed = false
  loginUserID = 
  controllable = true
  reason = 0
  }
  18281: Jul 14 11:29:00.237 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceRegisteredEvent
  18282: Jul 14 11:29:00.238 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceRegisteredEvent[298]
  18283: Jul 14 11:29:00.238 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) Received 
   DeviceRegisteredEvent
  18284: Jul 14 11:29:00.240 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceRegisterDeviceResponse {
  sequenceNumber = 303
  callManagerID = 16777227
  deviceID = 39
  deviceInfo = com.cisco.cti.protocol.DeviceInfo {
  name = cue_site1_p01
  type = 72
  allowsRegistration = true
  }
  }
  18285: Jul 14 11:29:00.242 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) DeviceMap: opening 
   device "cue_site1_p01"
  18286: Jul 14 11:29:00.242 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   DeviceLineUpdateThread: queuing com.cisco.cti.client.implementation.Device
  18287: Jul 14 11:29:00.244 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p01(16777227,39) 
   reopening line 28001(0,0)
  18288: Jul 14 11:29:00.246 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.
  sequenceNumber = 306
  deviceName = cue_site1_p01
  lineName = 28001
  filter = com.cisco.cti.protocol.LineEventFilter {
  callStateChanged = true
  dtmf = true
  ring = false
  toneChanged = false
  globalCallHandleChanged = true
  openReceiveChannel = false
  partyInfoChanged = true
  bExistingCallEvent = true
  bNewCallEvent = true
  bLineCfwdAllStatus = true
  }
  disableAutoRecovery = false
  }
  18289: Jul 14 11:29:00.249 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received Event: 
   com.cisco.cti.protocol.DeviceInServiceEvent {
  eventSequence = 299
  deviceCallManagerID = 16777227
  deviceID = 39
  }
  18290: Jul 14 11:29:00.250 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.DeviceInServiceEvent
  18291: Jul 14 11:29:00.251 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.DeviceInServiceEvent[299]
  18292: Jul 14 11:29:00.252 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Terminal 
   "cue_site1_p01" in service
  18293: Jul 14 11:29:00.253 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [cue_site1_p01] 
   CiscoTermInServiceEv [#697]
  18294: Jul 14 11:29:00.255 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 304
  enumerationHandle = 16
  }
  18295: Jul 14 11:29:00.268 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 307
  enumerationHandle = 16
  count = 10
  }
  18296: Jul 14 11:29:00.271 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineSetMessageWaitingResponse {
  sequenceNumber = 305
  }
  18297: Jul 14 11:29:00.290 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineOpenResponse {
  sequenceNumber = 306
  callManagerID = 16777227
  lineID = 39
  }
  18298: Jul 14 11:29:00.291 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   ProviderRetryThread stopping retries
  18299: Jul 14 11:29:00.292 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) 
   ProviderRetryThread waiting until notified
  18300: Jul 14 11:29:00.294 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Event: com.cisco.cti.protocol.LineInServiceEvent {
  eventSequence = 300
  lineCallManagerID = 16777227
  lineID = 39
  }
  18301: Jul 14 11:29:00.294 EDT %JTAPI-MISC-7-UNK:(P1-14.80.227.127) EventThread: 
   queuing com.cisco.cti.protocol.LineInServiceEvent
  18302: Jul 14 11:29:00.295 EDT %JTAPI-CTIIMPL-7-UNK:(P1-14.80.227.127) EventThread 
   handling event com.cisco.cti.protocol.LineInServiceEvent[300]
  18303: Jul 14 11:29:00.296 EDT %JTAPI-CTI-7-UNK:(P1-site1cue){Line:28001(16777227,39)} 
   LineInServiceEvent
  18304: Jul 14 11:29:00.297 EDT %JTAPI-JTAPIIMPL-7-UNK:(P1-site1cue) Address "28001" 
   in service
  18305: Jul 14 11:29:00.298 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue) [28001] 
   CiscoDT %JTAPI-MISC-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@324e36e0): 
   queuing com.cisco.jtapi.JtapiAddressEventSet
  18308: Jul 14 11:29:00.300 EDT %JTAPI-JTAPIIMPL-7-UNK:ObserverThread
   (com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@324e36e0): 
   delivering JAES[1]
  18309: Jul 14 11:29:00.301 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@324e36e0]
   ObserverProxy.deliverEvents()
  18310: Jul 14 11:29:00.327 EDT %JTAPI-JTAPIIMPL-7-UNK:
   [com.cisco.wf.subsystems.jtapi.TAPIPortGroup$ServiceAddressObserver@324e36e0]
   ObserverProxy.deliverEvents() completed
  18311: Jul 14 11:29:00.376 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 307
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28003
  permanentLineID = 2109152574
  }]
  more = false
  }
  18312: Jul 14 11:29:00.377 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 308
  enumerationHandle = 16
  }
  18313: Jul 14 11:29:00.381 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 308
  }
  18314: Jul 14 11:29:00.382 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p03(16777227,37) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18315: Jul 14 11:29:00.383 EDT %JTAPI-CTI-7-UNK EDT %JTAPI-PROTOCOL-7-UNK:
   (P1-14.80.227.127) [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 309
  deviceName = cue_site1_p02
  }
  18317: Jul 14 11:29:00.387 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 309
  enumerationHandle = 17
  }
  18318: Jul 14 11:29:00.389 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 310
  enumerationHandle = 17
  count = 10
  }
  18319: Jul 14 11:29:00.397 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 310
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28002
  permanentLineID = 1035863534
  }]
  more = false
  }
  18320: Jul 14 11:29:00.398 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 311
  enumerationHandle = 17
  }
  18321: Jul 14 11:29:00.403 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 311
  }
  18322: Jul 14 11:29:00.405 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p02(16777227,38) 
   refreshing lines: previous=1 current=1 created=0 removed=0
  18323: Jul 14 11:29:00.405 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) cue_site1_p01(16777227,39) 
   updating lines
  18324: Jul 14 11:29:00.406 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.DeviceGetLineInfoRequest {
  sequenceNumber = 312
  deviceName = cue_site1_p01
  }
  18325: Jul 14 11:29:00.409 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.DeviceGetLineInfoResponse {
  sequenceNumber = 312
  enumerationHandle = 18
  }
  18326: Jul 14 11:29:00.411 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoFetchRequest {
  sequenceNumber = 313
  enumerationHandle = 18
  count = 10
  }
  18327: Jul 14 11:29:00.419 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoFetchResponse {
  sequenceNumber = 313
  info = 1@[
  com.cisco.cti.protocol.LineInfo {
  name = 28001
  permanentLineID = 1084634008
  }]
  more = false
  }
  18328: Jul 14 11:29:00.476 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [(P1-14.80.227.127) DeviceLineUpdateThread] sending: 
   com.cisco.cti.protocol.GetLineInfoCloseRequest {
  sequenceNumber = 314
  enumerationHandle = 18
  }
  18329: Jul 14 11:29:00.480 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.GetLineInfoCloseResponse {
  sequenceNumber = 314
  }
  18330: Jul 14 11:29:00.521 EDT %JTAPI-CTI-7-UNK:(P1-site1cue) 
  18331: Jul 14 11:29:01.514 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue)[Thread-36][28001]
   Request: setMessageWaiting ( 2104,true )
  18332: Jul 14 11:29:01.516 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) [Thread-36] 
   sending: com.cisco.cti.protocol.LineSetMessageWaitingRequest {
    sequenceNumber    = 315
    lineCallManagerID = 16777227
    lineID            = 39
    lineName          = 2104
    lampMode          = 2
    }
  18333: Jul 14 11:29:01.520 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineSetMessageWaitingResponse {
    sequenceNumber = 315
    }
  18334: Jul 14 11:29:02.807 EDT %JTAPI-JTAPI-7-UNK:(P1-site1cue)[Thread-37][28001]
   Request: setMessageWaiting ( 2103,false )
  18335: Jul 14 11:29:02.808 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) [Thread-37] 
   sending: com.cisco.cti.protocol.LineSetMessageWaitingRequest {
    sequenceNumber    = 316
    lineCallManagerID = 16777227
    lineID            = 39
    lineName          = 2103
    lampMode          = 1
    }
  18336: Jul 14 11:29:02.815 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   Response: com.cisco.cti.protocol.LineSetMessageWaitingResponse {
    sequenceNumber = 316
    }
  18337: Jul 14 11:29:26.129 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   server Heartbeat: com.cisco.cti.protocol.Heartbeat {
    }
  18338: Jul 14 11:29:41.158 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [HeartbeatSendThread] sending: com.cisco.cti.protocol.Heartbeat {
    }
  18339: Jul 14 11:29:56.473 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   server Heartbeat: com.cisco.cti.protocol
    }
  18340: Jul 14 11:30:11.480 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [HeartbeatSendThread] sending: com.cisco.cti.protocol.Heartbeat {
    }
  18341: Jul 14 11:30:26.172 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) received 
   server Heartbeat: com.cisco.cti.protocol.Heartbeat {
    }
  18342: Jul 14 11:30:41.503 EDT %JTAPI-PROTOCOL-7-UNK:(P1-14.80.227.127) 
   [HeartbeatSendThread] sending: com.cisco.cti.protocol.Heartbeat {
    }
  

一般的な MWI とボイスメールのトレース

MWI の概要」セクションで説明した統合の問題とは別に、trace 機能を使用してシステムの配信イベントと MWI イベントのトラブルシューティングを行うこともできます。 通常、これは一般的なボイスメールのトラブルシューティングのカテゴリに分類されます。 ただし、これらの問題は重なり合っている部分が多いため、いくつかの基本事項について説明します。

このセクションでは trace voicemail all コマンドの例について説明します。 この例では、ユーザ 11044 にコールが発信されて、ボイスメールに転送されます。 少なくとも、trace voicemail vmxl all コマンドと trace voicemail mwi all コマンドを発行する必要があります。

注:メッセージに緊急のマークを付けるには、2 を押します。 この例で示す MWI イベントは、実際にはシグナリングが行われたに発生します。 SIP/JTAPI シグナルが発生すると、シグナルが成功したことを通知するためにこのメッセージが出力されます。

注:同時に複数のコールが存在する場合、コール ID を使用すると特定のコールを追跡するのに便利です。 この例の場合、コール ID は 0x000000037e11d669 です。 Cisco CallManager Express に統合されたシステムの場合は、trace ccn stacksip dbug コマンドも発行する必要があります。 このコマンドでは、切断などのイベントが発生した時刻、さらに、番号が入力された時刻や他のイベントの発生も一層明確に表示されます。

  cue-3660-41a>show trace buffer long
  Press <CTRL-C> to exit...
  5047 07/15 13:33:44.198 voicemail ldap "getUserByPhoneNo" 11044
  5047 07/15 13:33:44.200 voicemail ldap "getUserByPhoneNo: userDn." 
   /sw/local/users/user3
  5047 07/15 13:33:44.200 voicemail ldap 0 getAttributeValue: 
   /sw/local/users/user3/Language/preferredLanguage
  5047 07/15 13:33:44.201 voicemail ldap 0 getAttributeValue: 
   /sw/local/users/user3/TelephoneNumbers/primaryExtension
  5047 07/15 13:33:44.202 voicemail database 0 Got connection: 1, inUse: 1, active: 3
  5047 07/15 13:33:44.202 voicemail database "SQL: " select mailboxid from vm_mbxusers 
   where owner=true and userdn='/sw/local/users/user3';
  5047 07/15 13:33:44.204 voicemail database "Database query results" 
   PERSONAL_00000000000000000000003
  5047 07/15 13:33:44.204 voicemail database 0 Freed connection: 1, inUse: 0, active: 3
  5047 07/15 13:33:44.255 voicemail database 0 Got connection: 2, inUse: 1, active: 3
  5047 07/15 13:33:44.255 voicemail database "SQL: " 0x000000037e11d669 select mailboxid 
   from vm_mbxusers where owner=true and userdn='/sw/local/users/user3';
  5047 07/15 13:33:44.257 voicemail database "Database query results" 0x000000037e11d669 
   PERSONAL_00000000000000000000003
  5047 07/15 13:33:44.258 voicemail database "SQL: " 0x000000037e11d669 select distinct 
   vm_mbxusers.mailboxid, orphanedtime from vm_mbxusers, vm_mailbox where 
   vm_mailbox.mailboxid=vm_mbxusers.mailboxid and (userdn='/sw/local/users/user3') and 
   orphanedtime=0 and owner=false;
  5047 07/15 13:33:44.265 voicemail database 0 Freed connection: 2, inUse: 0, active: 3
  18885 07/15 13:33:44.279 voicemail ldap "getSpokenNameByName: userDn." 
   /sw/local/users/user3
  18885 07/15 13:33:44.279 voicemail ldap "normalizeDN" /sw/local/users/user3
  18885 07/15 13:33:44.279 voicemail ldap "getSpokenName: dn." uid=user3,ou=users,
   ou=branch123,o=cisco.com
  18885 07/15 13:33:44.292 voicemail database 0 Got connection: 0, inUse: 1, active: 3
  18885 07/15 13:33:44.293 voicemail database "SQL: " 0x000000037e11d669 select 
   greetingid,greetingtype,messagelength,messagesize,greetingoid from vm_greeting 
   where greetingtype=10 and mailboxid='PERSONAL_00000000000000000000003';
  18885 07/15 13:33:44.296 voicemail database 0 Freed connection: 0, inUse: 0, active: 3
  1989 07/15 13:33:44.324 voicemail vxml "Sorry. Extension" 0x000000037e11d669 
   AvPHGreetENU021.wav
  1989 07/15 13:33:44.334 voicemail vxml 0 0x000000037e11d669 11044
  1989 07/15 13:33:44.334 voicemail vxml "is not available." 0x000000037e11d669 
   AvSubGreetingsENU018.wav
  1989 07/15 13:33:44.348 voicemail vxml "You may record your message at the tone. 
   When you are finished, press #" 0x000000037e11d669 AvSubSendMsgENU050.wav
  2043 07/15 13:33:51.757 voicemail agc "AGC processing buffer" 8160 0
  2043 07/15 13:33:52.777 voicemail agc "AGC processing buffer" 8160 0
  2043 07/15 13:33:53.797 voicemail agc "AGC processing buffer" 8160 0
  2043 07/15 13:33:54.817 voicemail agc "AGC processing buffer" 8160 0
  2043 07/15 13:33:55.837 voicemail agc "AGC processing buffer" 8160 0
  2043 07/15 13:33:56.257 voicemail agc "AGC processing buffer" 8160 0
  1989 07/15 13:33:56.627 voicemail vxml "To send this message with normal 
   priority, press 1. To send this message with urgent priority, press 2." 
   0x000000037e11d669 AvPHGreetENU002.wav
  1989 07/15 13:33:56.627 voicemail vxml "To listen to your message, press 3. 
   To re-record it, press 4." 0x000000037e11d669 AvAesopCustomENU004.wav
  1989 07/15 13:33:56.632 voicemail vxml "To cancel press 6" 
   0x000000037e11d669 AvPHGreetENU403.wav
  1989 07/15 13:34:03.395 voicemail vxml "callerMsgRecord.record_message.action" 
   0x000000037e11d669  2
  18885 07/15 13:34:03.402 voicemail ldap "getUserByPhoneNo" undefined
  18885 07/15 13:34:03.407 voicemail ldap "getUserByPhoneNo: No entry found."
  18885 07/15 13:34:03.407 voicemail message "Creating Message" 1089912843407_0
  18885 07/15 13:34:03.407 voicemail message "Message Length" 5398, Message Size: 44218
  18885 07/15 13:34:03.407 voicemail mailbox "Sending message(s) from" 
   0x000000037e11d669 /sw/local/users/user3
  18885 07/15 13:34:03.407 voicemail mailbox "Sending message to" 
   0x000000037e11d669 11044
  18885 07/15 13:34:03.408 voicemail database 0 Got connection: 1, inUse: 1, active: 3
  18885 07/15 13:34:03.408 voicemail mailbox "Message received" 0x000000037e11d669 
   PERSONAL_00000000000000000000003,1089912843407_0
  18885 07/15 13:34:03.408 voicemail database "SQL: " 0x000000037e11d669 select count
   (messageid) from vm_message where messageid='1089912843407_0';
  18885 07/15 13:34:03.413 voicemail database "Database query results" 
   0x000000037e11d669 0
  18885 07/15 13:34:03.413 voicemail database "SQL: " 0x000000037e11d669 update 
   vm_message set messageid='1089912843407_0',messagetype=1,sender='Unknown',
   urgent=true,private=false,attachedmsgid=null where messageId='OID_16650';
  18885 07/15 13:34:03.559 voicemail database "SQL: " 0x000000037e11d669 insert 
   into vm_usermsg values('PERSONAL_00000000000000000000003',
   '1089912843407_0',1,1089912843407);
  18885 07/15 13:34:03.564 voicemail database "SQL: " 0x000000037e11d669 select 
   totalmessagetime from vm_mailbox where mailboxid='PERSONAL_00000000000000000000003' 
   for update;
  18885 07/15 13:34:03.566 voicemail database "Database query results" 
   0x000000037e11d669 28061
  18885 07/15 13:34:03.567 voicemail database "SQL: " 0x000000037e11d669 update 
   vm_mailbox set totalmessagetime=33459 where 
   mailboxid='PERSONAL_00000000000000000000003';
  18885 07/15 13:34:03.570 voicemail database "Commiting transaction" 
   0x000000037e11d669
  18885 07/15 13:34:03.601 voicemail ldap 0 getAttributeValue: 
   /sw/local/users/user3/TelephoneNumbers/primaryExtension
  18885 07/15 13:34:03.601 voicemail mwi "setMessageWaiting" 
   0x000000037e11d669 11044,true
  18885 07/15 13:34:03.602 voicemail mwi " job state" adding job
  1677 07/15 13:34:03.602 voicemail mwi " job state" 
   http://localhost:8080/mwiapp?extn=11044&state=1
  18885 07/15 13:34:03.677 voicemail database 0 Freed connection: 1, inUse: 0, 
   active: 3
  1989 07/15 13:34:03.688 voicemail vxml "Thank you. Your message has been sent." 
   0x000000037e11d669 AvPHGreetENU008.wav
  1989 07/15 13:34:03.700 voicemail  "Hello, Unity-lite messaging system. If you 
   have a mailbox in this system press '*', Otherwise please hold for an operator." 
   0x000000037e11d669 AvAesopCustomENU001.wav
  1989 07/15 13:34:07.756 voicemail vxml 0 0x000000037e11d669 TIMEOUT
  1989 07/15 13:34:07.757 voicemail vxml 0 0x000000037e11d669 TIMEOUT
  

関連するシスコ サポート コミュニティ ディスカッション

シスコ サポート コミュニティは、どなたでも投稿や回答ができる情報交換スペースです。


関連情報