SIP to H323

From TD-er's Wiki
Jump to navigationJump to search

Asterisk

<-- SIP read from 129.125.71.173:5060:
INVITE sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP  129.125.71.173:5060;rport;branch=z9hG4bK28425BFFA543520B0B9D0FE0CA28AD10
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24313 INVITE
Max-Forwards: 70
Content-Type: application/sdp
User-Agent: X-Lite release 1105d
Content-Length: 312

v=0
o=502 1178656389 1178656399 IN IP4 129.125.71.173
s=X-Lite
c=IN IP4 129.125.71.173
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 97 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:97 speex/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv

--- (11 headers 14 lines)---
Using INVITE request as basis request -     39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
Sending to 129.125.71.173 : 5060 (NAT)
Reliably Transmitting (no NAT) to 129.125.71.173:5060:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP   129.125.71.173:5060;rport;branch=z9hG4bK28425BFFA543520B0B9D0FE0CA28AD10;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>;tag=as3321ad7e
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24313 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="18d198f5"
Content-Length: 0


---
Scheduling destruction of call   '39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173' in 15000 ms
Found user '502'

<-- SIP read from 129.125.71.173:5060:
ACK sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP  129.125.71.173:5060;rport;branch=z9hG4bK28425BFFA543520B0B9D0FE0CA28AD10
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>;tag=as3321ad7e
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24313 ACK
Max-Forwards: 70
Content-Length: 0


--- (9 headers 0 lines)---

<-- SIP read from 129.125.71.173:5060:
INVITE sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP  129.125.71.173:5060;rport;branch=z9hG4bK5ECEFA77EC7446BB73C0364FD5D9D9FB
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24314 INVITE
Proxy-Authorization: Digest   username="502",realm="asterisk",nonce="18d198f5",response="af4294cd96f7dba63e0fbf3ea9c0e161",uri="sip:601@129.125.21.241",algorithm=MD5
Max-Forwards: 70 
Content-Type: application/sdp
User-Agent: X-Lite release 1105d
Content-Length: 312

v=0
o=502 1178656389 1178656399 IN IP4 129.125.71.173
s=X-Lite
c=IN IP4 129.125.71.173
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 97 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:97 speex/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv

--- (12 headers 14 lines)---
Using INVITE request as basis request -   39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
Sending to 129.125.71.173 : 5060 (NAT)
Found user '502'
Found RTP audio format 0
Found RTP audio format 8
Found RTP audio format 3
Found RTP audio format 98
Found RTP audio format 97
Found RTP audio format 101
Peer audio RTP is at port 129.125.71.173:8000
Found description format pcmu
Found description format pcma
Found description format gsm
Found description format iLBC
Found description format speex
Found description format telephone-event
Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x60e   (gsm|ulaw|alaw|speex|ilbc)/video=0x0 (nothing), combined - 0xc (ulaw|alaw)
Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1   (telephone-event), combined - 0x1 (telephone-event) 
Looking for 601 in from-internal (domain 129.125.21.241)
list_route: hop: <sip:502@129.125.71.173:5060>
Transmitting (no NAT) to 129.125.71.173:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP  129.125.71.173:5060;rport;branch=z9hG4bK5ECEFA77EC7446BB73C0364FD5D9D9FB;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24314 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Content-Length: 0


---
    -- Executing Macro("SIP/502-083b1650", "exten-vm|novm|601") in new stack
    -- Executing Macro("SIP/502-083b1650", "user-callerid") in new stack
    -- Executing GotoIf("SIP/502-083b1650", "0?report") in new stack
    -- Executing GotoIf("SIP/502-083b1650", "0?start") in new stack
    -- Executing Set("SIP/502-083b1650", "REALCALLERIDNUM=502") in new stack
    -- Executing NoOp("SIP/502-083b1650", "REALCALLERIDNUM is 502") in new  stack 
    -- Executing Set("SIP/502-083b1650", "AMPUSER=502") in new stack
    -- Executing Set("SIP/502-083b1650", "AMPUSERCIDNAME=Fabio SIP freePBX")  in new stack 
    -- Executing GotoIf("SIP/502-083b1650", "0?report") in new stack
    -- Executing Set("SIP/502-083b1650", "CALLERID(all)=Fabio SIP freePBX  <502>") in new stack 
    -- Executing NoOp("SIP/502-083b1650", "Using CallerID "Fabio SIP freePBX"  <502>") in new stack 
    -- Executing Set("SIP/502-083b1650", "FROMCONTEXT=exten-vm") in new stack
    -- Executing Set("SIP/502-083b1650", "VMBOX=novm") in new stack
    -- Executing Set("SIP/502-083b1650", "EXTTOCALL=601") in new stack
    -- Executing Set("SIP/502-083b1650", "CFUEXT=") in new stack
    -- Executing Set("SIP/502-083b1650", "RT=") in new stack
    -- Executing Macro("SIP/502-083b1650", "record-enable|601|IN") in new  stack 
    -- Executing GotoIf("SIP/502-083b1650", "0 > 0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing AGI("SIP/502-083b1650",      "recordingcheck|20070312-140616|1173704776.2") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
PHP Warning:  Unknown(): Unable to load dynamic library    '/usr/lib/php4/imap.so' - libc-client.so.0: cannot open shared object file: No such file or directory in Unknown on line 0
  recordingcheck|20070312-140616|1173704776.2: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing NoOp("SIP/502-083b1650", "No recording needed") in new stack
    -- Executing GotoIf("SIP/502-083b1650", "0?dolocaldial|1") in new stack
    -- Executing Macro("SIP/502-083b1650", "dial||tr|601") in new stack
    -- Executing AGI("SIP/502-083b1650", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
PHP Warning:  Unknown(): Unable to load dynamic library   '/usr/lib/php4/imap.so' - libc-client.so.0: cannot open shared object file: No such file or directory in Unknown on line 0
  dialparties.agi: Starting New Dialparties.agi
    --  dialparties.agi: priority is 1
  dialparties.agi: Caller ID name is 'Fabio SIP freePBX' number is '502'
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 601 to extension map
    --  dialparties.agi: Extension 601 cf is disabled
    --  dialparties.agi: Extension 601 do not disturb is disabled
       >  dialparties.agi: extnum: 601
       >  dialparties.agi: exthascw: 0
       >  dialparties.agi: exthascfb: 0
       >  dialparties.agi: extcfb:
       >  dialparties.agi: exthascfu: 0
       >  dialparties.agi: extcfu:
  == Parsing '/etc/asterisk/manager.conf': Found
  == Parsing '/etc/asterisk/manager_custom.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  == Manager 'admin' logged off from 127.0.0.1
       >  dialparties.agi: ExtensionState: 0
    --  dialparties.agi: Checking CW and CFB status for extension 601
    --  dialparties.agi: DbSet CALLTRACE/601 to 502
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing Dial("SIP/502-083b1650", "OOH323/601||tr") in new stack
---   ooh323_request - data 601 format 0x4 (ulaw)
---   find_peer
+++   find_peer
+++   ooh323_request
---   ooh323_call- 601
+++   ooh323_call
    -- Called 601
Transmitting (no NAT) to 129.125.71.173:5060:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP   129.125.71.173:5060;rport;branch=z9hG4bK5ECEFA77EC7446BB73C0364FD5D9D9FB;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>;tag=as4618ff70
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24314 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Content-Length: 0


---
---   onNewCallCreated ooh323c_o_2
---   find_call
+++   find_call
setting callid number 502
 Outgoing call 601(ooh323c_o_2) - Codec prefs - (gsm|ulaw)
        Adding capabilities to call(outgoing, ooh323c_o_2)
        Adding gsm capability to call(outgoing, ooh323c_o_2)
        Adding g711 ulaw capability to call(outgoing, ooh323c_o_2)
---   configure_local_rtp
+++   configure_local_rtp
+++   onNewCallCreated ooh323c_o_2
--- onAlerting ooh323c_o_2
---   find_call
+++   find_call
+++ onAlerting ooh323c_o_2
    -- OOH323/601-489a is ringing
---   onCallEstablished ooh323c_o_2
---   find_call
+++   find_call
+++   onCallEstablished ooh323c_o_2
    -- OOH323/601-489a answered SIP/502-083b1650
We're at 129.125.21.241 port 15850
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x8 (alaw) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (no NAT) to 129.125.71.173:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP    129.125.71.173:5060;rport;branch=z9hG4bK5ECEFA77EC7446BB73C0364FD5D9D9FB;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>;tag=as4618ff70
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24314 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Content-Type: application/sdp
Content-Length: 242

v=0
o=root 9003 9003 IN IP4 129.125.21.241
s=session
c=IN IP4 129.125.21.241
t=0 0
m=audio 15850 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

---

<-- SIP read from 129.125.71.173:5060:
ACK sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP    129.125.71.173:5060;rport;branch=z9hG4bK7A7CAC8E1253CB3802136E51E8472AE3
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>;tag=as4618ff70
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24314 ACK
Max-Forwards: 70
Content-Length: 0


--- (9 headers 0 lines)---

<-- SIP read from 129.125.71.173:5060:


--- (0 headers 0 lines) Nat keepalive ---
---   setup_rtp_connection
---   find_call
+++   find_call
+++   setup_rtp_connection
---   close_rtp_connection
---   find_call
+++   find_call
+++   close_rtp_connection
---   onCallCleared ooh323c_o_2
---   find_call
+++   find_call
---   ooh323_hangup
    hanging 601
+++   ooh323_hangup
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/502-083b1650' in macro 'dial'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/502-083b1650'  in macro 'exten-vm' 
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/502-083b1650'
Scheduling destruction of call     '39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173' in 32000 ms
set_destination: Parsing <sip:502@129.125.71.173:5060> for address/port to  send to 
set_destination: set destination to 129.125.71.173, port 5060
Reliably Transmitting (no NAT) to 129.125.71.173:5060:
BYE sip:502@129.125.71.173:5060 SIP/2.0
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK221b90c0
From: <sip:601@129.125.21.241>;tag=as4618ff70
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
Contact: <sip:601@129.125.21.241>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 102 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0


---

<-- SIP read from 129.125.71.173:5060:
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK221b90c0
From: <sip:601@129.125.21.241>;tag=as4618ff70
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 102 BYE
Server: X-Lite release 1105d
Content-Length: 0


--- (9 headers 0 lines)---
Destroying call '39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173'
---   ooh323_destroy
 Destroying 601
+++   ooh323_destroy



Gatekeeper

root@wingtip111:~# 2007/03/12 14:05:41.879      2             RasSrv.cxx(168)     RAS     Read from 129.125.21.241:13030
2007/03/12 14:05:41.880 3             RasSrv.cxx(207)   RAS
admissionRequest {
    requestSeqNum = 5
    callType = pointToPoint <<null>>
    callModel = direct <<null>>
    endpointIdentifier =  9 characters {
      0038 0038 0037 0030 005f 0065 006e 0064   8870_end
      0070                                      p
    }
    destinationInfo = 1 entries {
      [0]=dialedDigits "601"
    }
    srcInfo = 1 entries {
      [0]=dialedDigits "502"
    }
    srcCallSignalAddress = ipAddress {
      ip =  4 octets {
        81 7d 15 f1                                        .}..
      }
      port = 1720
    }
    bandWidth = 100000
    callReferenceValue = 5
    conferenceID =  16 octets {
      9e 9f a0 a1 a2 a3 a4 a5  a6 a7 a8 a9 aa ab ac ad   ................
    }
    activeMC = FALSE
    answerCall = FALSE
    canMapAlias = FALSE
    callIdentifier = {
      guid =  16 octets {
        6f 6f 68 33 32 33 63 2d  6c d0 62 00 00 00 00 fc   ooh323c-l.b.....
      }
    }
    gatekeeperIdentifier =  14 characters {
      0047 004e 0055 0020 0047 0061 0074 0065   GNU Gate
      006b 0065 0065 0070 0065 0072             keeper
    }
    willSupplyUUIEs = FALSE
  }
2007/03/12 14:05:41.883 1             RasSrv.cxx(328)   RAS     ARQ Received
2007/03/12 14:05:41.883 3             RasSrv.cxx(1924)  GK      ARQ will  request bandwith of 100000 
2007/03/12 14:05:41.884 2             RasTbl.cxx(1969)   CallTable::Insert(CALL) Call No. 2, total sessions : 1 
2007/03/12 14:05:41.885 2             RasSrv.cxx(373)         ACF|129.125.21.241:1720|8870_endp|5|601:dialedDigits|502:dialedDigits|false;
2007/03/12 14:05:41.885 3             RasSrv.cxx(219)   RAS     Send to  129.125.21.241:13030 
admissionConfirm {
    requestSeqNum = 5
    bandWidth = 100000
    callModel = gatekeeperRouted <<null>>
    destCallSignalAddress = ipAddress {
      ip =  4 octets {
        81 7d 47 ab                                        .}G.
      }
      port = 1721
    }
    irrFrequency = 120
    willRespondToIRR = FALSE
    uuiesRequested = {
      setup = FALSE
      callProceeding = FALSE
      connect = FALSE
      alerting = FALSE
      information = FALSE
      releaseComplete = FALSE
      facility = FALSE
      progress = FALSE
      empty = FALSE
      status = FALSE
      statusInquiry = FALSE
      setupAcknowledge = FALSE
      notify = FALSE
    }
  }
2007/03/12 14:05:41.893 3       ProxyChannel.cxx(711)   Q931s   Received:  Setup CRV=5 from 129.125.21.241:12033 
2007/03/12 14:05:41.895 2             gkacct.cxx(956)   GKACCT  Successfully  logged event 1 for call no. 2 
2007/03/12 14:05:41.895 3       ProxyChannel.cxx(1679)  GK      Call 2 is NAT  type 0 
2007/03/12 14:05:41.901 3       ProxyChannel.cxx(2162)  Q931    Connect to  129.125.71.172:1720 successful 
2007/03/12 14:05:41.962 3       ProxyChannel.cxx(711)   Q931d   Received:  CallProceeding CRV=5 from 129.125.71.172:1720 
2007/03/12 14:05:41.965 3           yasocket.cxx(528)   Q931s   Delete socket  129.125.21.241:12031 
2007/03/12 14:05:41.966 3           yasocket.cxx(528)   Q931d   Delete socket  129.125.71.172:1720 
2007/03/12 14:05:42.010 2             RasSrv.cxx(168)   RAS     Read from  129.125.71.172:5021 
2007/03/12 14:05:42.011 3             RasSrv.cxx(207)   RAS
admissionRequest {
    requestSeqNum = 16193
    callType = pointToPoint <<null>>
    endpointIdentifier =  9 characters {
      0039 0038 0033 0034 005f 0065 006e 0064   9834_end
      0070                                      p
    }
    destinationInfo = 2 entries {
      [0]=dialedDigits "601"
      [1]=h323_ID  15 characters {
        0041 006e 006e 0065 006b 0065 0020 0050   Anneke P
        0072 0061 0061 0067 006d 0061 006e        raagman
      }
    }
    destCallSignalAddress = ipAddress {
      ip =  4 octets {
        81 7d 47 ac                                        .}G.
      }
      port = 1720
    }
    srcInfo = 1 entries {
      [0]=h323_ID  47 characters {
        0046 0061 0062 0069 006f 0020 0053 0049   Fabio SI
        0050 0020 0066 0072 0065 0065 0050 0042   P freePB
        0058 0020 0028 0035 0030 0032 0029 0020   X (502)
        005b 003a 003a 0066 0066 0066 0066 003a   [::ffff:
        0031 0032 0039 002e 0031 0032 0035 002e   129.125.
        0037 0031 002e 0031 0037 0031 005d        71.171]
      }
    }
    srcCallSignalAddress = ip6Address {
      ip =  16 octets {
        00 00 00 00 00 00 00 00  00 00 ff ff 81 7d 47 ab   .............}G.
      }
      port = 4035
    }
    bandWidth = 100000
    callReferenceValue = 5
    conferenceID =  16 octets {
      9e 9f a0 a1 a2 a3 a4 a5  a6 a7 a8 a9 aa ab ac ad   ................
    }
    activeMC = FALSE
    answerCall = TRUE
    canMapAlias = TRUE
    callIdentifier = {
      guid =  16 octets {
        6f 6f 68 33 32 33 63 2d  6c d0 62 00 00 00 00 fc   ooh323c-l.b.....
      }
    }
    gatekeeperIdentifier =  14 characters {
      0047 004e 0055 0020 0047 0061 0074 0065   GNU Gate
      006b 0065 0065 0070 0065 0072             keeper
    }
    willSupplyUUIEs = TRUE
    canMapSrcAlias = FALSE
  }
2007/03/12 14:05:42.015 1             RasSrv.cxx(328)   RAS     ARQ Received
2007/03/12 14:05:42.015 3             RasSrv.cxx(1924)  GK      ARQ will  request bandwith of 100000 
2007/03/12 14:05:42.016 3             RasSrv.cxx(2027)  GK      ACF: found  existing call no 2 
2007/03/12 14:05:42.017 2             RasSrv.cxx(373)      ACF|129.125.71.172:1720|9834_endp|5|601:dialedDigits=Anneke         Praagman:h323_ID|Fabio SIP freePBX (502) [::ffff:129.125.71.171]:h323_ID|true;
2007/03/12 14:05:42.017 3             RasSrv.cxx(219)   RAS     Send to  129.125.71.172:5021 
admissionConfirm { 
    requestSeqNum = 16193
    bandWidth = 100000
    callModel = gatekeeperRouted <<null>>
    destCallSignalAddress = ipAddress {
      ip =  4 octets {
        81 7d 47 ab                                        .}G.
      }
      port = 1721
    }
    irrFrequency = 120
    willRespondToIRR = FALSE
    uuiesRequested = {
      setup = FALSE
      callProceeding = FALSE
      connect = FALSE
      alerting = FALSE
      information = FALSE
      releaseComplete = FALSE
      facility = FALSE
      progress = FALSE
      empty = FALSE
      status = FALSE
      statusInquiry = FALSE
      setupAcknowledge = FALSE
      notify = FALSE
    }
    serviceControl = 1 entries {
      [0]={
        sessionId = 0
        contents = callCreditServiceControl {
          callStartingPoint = connect <<null>>
        }
        reason = refresh <<null>>
      }
    }
  }
2007/03/12 14:05:42.043 3       ProxyChannel.cxx(711)   Q931d   Received:  Alerting CRV=5 from 129.125.71.172:1720 
2007/03/12 14:05:42.938 3             RasTbl.cxx(1393)  Gk      Delete Call  No. 1 
2007/03/12 14:05:43.456 2             RasSrv.cxx(168)   RAS     Read from  129.125.71.172:5021 
2007/03/12 14:05:43.458 3             RasSrv.cxx(207)   RAS
infoRequestResponse {
    requestSeqNum = 16194
    endpointType = {
      vendor = {
        vendor = {
          t35CountryCode = 9
          t35Extension = 0
          manufacturerCode = 61
        }
        productId =  14 octets {
          67 6e 6f 6d 65 6d 65 65  74 69 6e 67 00 00         gnomemeeting..
        }
        versionId =  26 octets {
          31 2e 32 2e 33 20 28 4f  70 65 6e 48 33 32 33 20   1.2.3 (OpenH323
          76 31 2e 31 38 2e 30 29  00 00                     v1.18.0)..
        }
      }
      terminal = {
      }
      mc = FALSE
      undefinedNode = FALSE
    }
    endpointIdentifier =  9 characters {
      0039 0038 0033 0034 005f 0065 006e 0064   9834_end
      0070                                      p
    }
    rasAddress = ipAddress {
      ip =  4 octets {
        81 7d 47 ac                                        .}G.
      }
      port = 5021
    }
    callSignalAddress = 1 entries {
      [0]=ipAddress {
        ip =  4 octets {
          81 7d 47 ac                                        .}G.
        }
        port = 1720
      }
    }
    endpointAlias = 2 entries {
      [0]=dialedDigits "601"
      [1]=h323_ID  15 characters {
        0041 006e 006e 0065 006b 0065 0020 0050   Anneke P
        0072 0061 0061 0067 006d 0061 006e        raagman
      }
    }
    perCallInfo = 1 entries {
      [0]={
        callReferenceValue = 5
        conferenceID =  16 octets {
          9e 9f a0 a1 a2 a3 a4 a5  a6 a7 a8 a9 aa ab ac ad   ................
        }
        originator = FALSE
        h245 = {
        }
        callSignaling = {
        }
        callType = pointToPoint <<null>>
        bandWidth = 0
        callModel = gatekeeperRouted <<null>>
        callIdentifier = {
          guid =  16 octets {
            6f 6f 68 33 32 33 63 2d  6c d0 62 00 00 00 00 fc    ooh323c-l.b..... 
         } 
       } 
        substituteConfIDs = 0 entries {
        }
        usageInformation = {
          nonStandardUsageFields = 0 entries {
          }
          alertingTime = 1173704696
        }
      }
    }
    needResponse = FALSE
    unsolicited = TRUE
  }
2007/03/12 14:05:43.462 1             RasSrv.cxx(328)   RAS     IRR Received
2007/03/12 14:05:48.043 3           yasocket.cxx(528)   H245d   Delete socket  129.125.71.172:30000 
2007/03/12 14:05:48.043 3           yasocket.cxx(528)   H245s   Delete socket  129.125.21.241:12032 
2007/03/12 14:05:52.421 3       ProxyChannel.cxx(711)   Q931d   Received:  Connect CRV=5 from 129.125.71.172:1720 
2007/03/12 14:05:52.422 2             gkacct.cxx(956)   GKACCT  Successfully  logged event 32 for call no. 2 
2007/03/12 14:05:52.422 3       ProxyChannel.cxx(2425)  H245    Set  h245Address to 129.125.71.171:1554 
2007/03/12 14:05:52.425 3       ProxyChannel.cxx(2376)  H245    Connected from  129.125.21.241:12034 
2007/03/12 14:05:52.426 3       ProxyChannel.cxx(2396)  H245    Connect to  129.125.71.172:30000 successful 
2007/03/12 14:06:04.530 3       ProxyChannel.cxx(711)   Q931d   Received:  ReleaseComplete CRV=5 from 129.125.71.172:1720 
2007/03/12 14:06:04.532 1             RasTbl.cxx(2136)  CDR|2|6f 6f 68 33 32  33 63 2d 6c d0 62 00 00 00 00 fc|12|Mon, 12 Mar 2007 14:05:52 +0100|Mon, 12 Mar  2007 14:06:04         +0100|129.125.21.241:12033|8870_endp|129.125.71.172:1720|9834_endp|601:dialedDigits|502:dialedDigits|GNU Gatekeeper;

2007/03/12 14:06:04.532 2             gkacct.cxx(956)   GKACCT  Successfully  logged event 2 for call no. 2 
2007/03/12 14:06:04.534 2             RasSrv.cxx(168)   RAS     Read from  129.125.21.241:13030 
2007/03/12 14:06:04.534 3             RasSrv.cxx(207)   RAS
disengageRequest {
    requestSeqNum = 6
    endpointIdentifier =  9 characters {
      0038 0038 0037 0030 005f 0065 006e 0064   8870_end
      0070                                      p
    }
    conferenceID =  16 octets {
      9e 9f a0 a1 a2 a3 a4 a5  a6 a7 a8 a9 aa ab ac ad   ................
    }
    callReferenceValue = 5
    disengageReason = normalDrop <<null>>
    callIdentifier = {
      guid =  16 octets {
        6f 6f 68 33 32 33 63 2d  6c d0 62 00 00 00 00 fc   ooh323c-l.b.....
      }
    }
    gatekeeperIdentifier =  14 characters {
      0047 004e 0055 0020 0047 0061 0074 0065   GNU Gate
      006b 0065 0065 0070 0065 0072             keeper
    }
    answeredCall = FALSE
    terminationCause = releaseCompleteCauseIE  10 octets {
      43 61 6c 6c 20 45 6e 64  65 64                     Call Ended
    }
  }
2007/03/12 14:06:04.536 1             RasSrv.cxx(328)   RAS     DRQ Received
2007/03/12 14:06:04.537 2             RasSrv.cxx(373)    DCF|129.125.21.241|8870_endp|5|normalDrop; 
2007/03/12 14:06:04.537 3             RasSrv.cxx(219)   RAS     Send to  129.125.21.241:13030 
disengageConfirm { 
    requestSeqNum = 6
  }
2007/03/12 14:06:04.612 3           yasocket.cxx(758)   ProxyH(0) waiting...
2007/03/12 14:06:04.612 3           yasocket.cxx(758)   ProxyH(0) waiting...
2007/03/12 14:06:04.617 2             RasSrv.cxx(168)   RAS     Read from  129.125.71.172:5021 
2007/03/12 14:06:04.617 3             RasSrv.cxx(207)   RAS
disengageRequest {
    requestSeqNum = 16195
    endpointIdentifier =  9 characters {
      0039 0038 0033 0034 005f 0065 006e 0064   9834_end
      0070                                      p
    }
    conferenceID =  16 octets {
      9e 9f a0 a1 a2 a3 a4 a5  a6 a7 a8 a9 aa ab ac ad   ................
    }
    callReferenceValue = 5
    disengageReason = normalDrop <<null>>
    callIdentifier = {
      guid =  16 octets {
        6f 6f 68 33 32 33 63 2d  6c d0 62 00 00 00 00 fc   ooh323c-l.b.....
      }
    }
    gatekeeperIdentifier =  14 characters {
      0047 004e 0055 0020 0047 0061 0074 0065   GNU Gate
      006b 0065 0065 0070 0065 0072             keeper
    }
    answeredCall = TRUE
    usageInformation = {
      nonStandardUsageFields = 0 entries {
      }
      alertingTime = 1173704696
      connectTime = 1173704707
      endTime = 1173704719
    }
    terminationCause = releaseCompleteCauseIE  2 octets {
      80 90                                              ..
    }
  }
2007/03/12 14:06:04.619 1             RasSrv.cxx(328)   RAS     DRQ Received
2007/03/12 14:06:04.620 2             RasSrv.cxx(373)    DCF|129.125.71.172|9834_endp|5|normalDrop; 
2007/03/12 14:06:04.620 3             RasSrv.cxx(219)   RAS     Send to  129.125.71.172:5021 
disengageConfirm { 
    requestSeqNum = 16195
  }
2007/03/12 14:06:22.974 3             RasTbl.cxx(148)   Gk      Delete  endpoint: 9834_endp 0

Xlite

The Xlite output is from a later call. There was no way of figuring out where the debugging of the actual call started.

SEND TIME: 1181403930
SEND >> 129.125.21.241:5060
INVITE sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK027F2FB6DEAB87C95A8DF619B852FAC5
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47394 INVITE
Max-Forwards: 70
Content-Type: application/sdp
User-Agent: X-Lite release 1105d
Content-Length: 312
v=0
o=502 1181403920 1181403929 IN IP4 129.125.71.173
s=X-Lite
c=IN IP4 129.125.71.173
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 97 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:97 speex/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
RECEIVE TIME: 1181403935
RECEIVE << 129.125.21.241:5060
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK027F2FB6DEAB87C95A8DF619B852FAC5;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>;tag=as3f26b609
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47394 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="01ae543a"
Content-Length: 0
SEND TIME: 1181403936
SEND >> 129.125.21.241:5060
ACK sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK027F2FB6DEAB87C95A8DF619B852FAC5
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>;tag=as3f26b609
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47394 ACK
Max-Forwards: 70
Content-Length: 0
SEND TIME: 1181403939
SEND >> 129.125.21.241:5060
INVITE sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK0B7AA1B8103C43CD6E613A3D3EFA07E1
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47395 INVITE
Proxy-Authorization: Digest         username="502",realm="asterisk",nonce="01ae543a",response="821ee080b48883a5003bcb33980362e1",uri="sip:601@129.125.21.241",algorithm=MD5
Max-Forwards: 70
Content-Type: application/sdp
User-Agent: X-Lite release 1105d
Content-Length: 312
v=0
o=502 1181403920 1181403929 IN IP4 129.125.71.173
s=X-Lite
c=IN IP4 129.125.71.173
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 97 101
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
a=rtpmap:97 speex/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
RECEIVE TIME: 1181403963
RECEIVE << 129.125.21.241:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK0B7AA1B8103C43CD6E613A3D3EFA07E1;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47395 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Content-Length: 0
RECEIVE TIME: 1181404340
RECEIVE << 129.125.21.241:5060
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK0B7AA1B8103C43CD6E613A3D3EFA07E1;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>;tag=as6b0a877c
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47395 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Content-Length: 0

RECEIVE TIME: 1181416593
RECEIVE << 129.125.21.241:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK0B7AA1B8103C43CD6E613A3D3EFA07E1;received=129.125.71.173
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>;tag=as6b0a877c
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47395 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:601@129.125.21.241>
Content-Type: application/sdp
Content-Length: 242
v=0
o=root 9003 9003 IN IP4 129.125.21.241
s=session
c=IN IP4 129.125.21.241
t=0 0
m=audio 17092 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
SEND TIME: 1181416600
SEND >> 129.125.21.241:5060
ACK sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK5DF8F6FCEF39581A6A9A54A7D94EAFBA
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
To: <sip:601@129.125.21.241>;tag=as6b0a877c
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 47395 ACK
Max-Forwards: 70
Content-Length: 0
RECEIVE TIME: 1181420337
RECEIVE << 129.125.21.241:5060
BYE sip:502@129.125.71.173:5060 SIP/2.0
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK455ac8a2
From: <sip:601@129.125.21.241>;tag=as6b0a877c
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
Contact: <sip:601@129.125.21.241>
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 102 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

SEND TIME: 1181420338
SEND >> 129.125.21.241:5060
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK455ac8a2
From: <sip:601@129.125.21.241>;tag=as6b0a877c
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
CSeq: 102 BYE
Server: X-Lite release 1105d
Content-Length: 0

Gnomemeeting

anneke@wingtip112:~$ 2007/03/12 14:04:17.368      0:00.107                  gnomemeeting Detected audio plugins: ALSA,Quicknet
2007/03/12 14:04:17.370   0:00.108                 gnomemeeting Detected video  plugins: Picture,V4L
2007/03/12 14:04:17.380   0:00.119                 H323 Cleaner H323     Started  cleaner thread
2007/03/12 14:04:17.382   0:00.121                 gnomemeeting Detected the  following audio input devices: Intel ICH5,Default with plugin ALSA
2007/03/12 14:04:17.383   0:00.121                 gnomemeeting Detected the  following audio output devices: Intel ICH5,Default with plugin ALSA
2007/03/12 14:04:17.384   0:00.122                 gnomemeeting Detected the  following video input devices: No device found with plugin V4L
2007/03/12 14:04:17.902   0:00.640                 gnomemeeting GnomeMeeting  version 1.2.3
2007/03/12 14:04:17.903   0:00.641                 gnomemeeting OpenH323 version 1.18.0
2007/03/12 14:04:17.903   0:00.641                 gnomemeeting PWLIB version 1.10.2
2007/03/12 14:04:17.904   0:00.642                 gnomemeeting GNOME support  enabled
2007/03/12 14:04:17.904   0:00.642                 gnomemeeting Fullscreen support enabled
2007/03/12 14:04:17.905   0:00.643                 gnomemeeting DBUS support disabled
2007/03/12 14:04:17.905   0:00.643                 gnomemeeting Quicknet  hardware support enabled
2007/03/12 14:04:17.907   0:00.645                 gnomemeeting Set TCP port range to 3000030010
2007/03/12 14:04:17.908   0:00.646                 gnomemeeting Set RTP port range to 50005016
2007/03/12 14:04:17.909   0:00.647                 gnomemeeting Set UDP port range to 50205023
2007/03/12 14:04:17.911   0:00.650                 gnomemeeting H323    Added capability: MS-GSM{sw} <1>
2007/03/12 14:04:17.912   0:00.651                 gnomemeeting H323    Added capability: SpeexNarrow-15k{sw} <2>
2007/03/12 14:04:17.913   0:00.651                 gnomemeeting H323    Added capability: GSM-06.10{sw} <3>
2007/03/12 14:04:17.914   0:00.652                 gnomemeeting H323    Added capability: SpeexNarrow-8k{sw} <4>
2007/03/12 14:04:17.915   0:00.653                 gnomemeeting H323    Added capability: G.726-32k{sw} <5>
2007/03/12 14:04:17.916   0:00.654                 gnomemeeting H323    Added capability: G.711-uLaw-64k <6>
2007/03/12 14:04:17.917   0:00.655                 gnomemeeting H323    Added capability: G.711-ALaw-64k <7>
2007/03/12 14:04:17.917   0:00.655                 gnomemeeting H323    Added capability: LPC-10{sw} <8>
2007/03/12 14:04:17.918   0:00.656                 gnomemeeting H323    Added capability: G.726-40k{sw} <9>
2007/03/12 14:04:17.919   0:00.657                 gnomemeeting H323    Added  capability: G.726-24k{sw} <10>
2007/03/12 14:04:17.920   0:00.658                 gnomemeeting H323    Added  capability: G.726-16k{sw} <11>
2007/03/12 14:04:17.921   0:00.659                 gnomemeeting H323    Added capability: MS-IMA-ADPCM{sw} <12>
2007/03/12 14:04:17.922   0:00.660                 gnomemeeting H323    Added capability: SpeexNarrow-5.95k{sw} <13>
2007/03/12 14:04:17.923   0:00.661                 gnomemeeting H323    Added capability: SpeexNarrow-11k{sw} <14>
2007/03/12 14:04:17.923   0:00.661                 gnomemeeting H323    Added capability: SpeexNarrow-18.2k{sw} <15>
2007/03/12 14:04:17.924   0:00.662                 gnomemeeting H323    Added capability: SpeexNarrow-24.6k{sw} <16>
2007/03/12 14:04:17.925   0:00.663                 gnomemeeting H323    Added capability: SpeexIETFNarrow-5.95k{sw} <17>
2007/03/12 14:04:17.926   0:00.664                 gnomemeeting H323    Added capability: SpeexIETFNarrow-8k{sw} <18>
2007/03/12 14:04:17.927   0:00.665                 gnomemeeting H323    Added capability: SpeexIETFNarrow-11k{sw} <19>
2007/03/12 14:04:17.927   0:00.665                 gnomemeeting H323    Added capability: SpeexIETFNarrow-15k{sw} <20>
2007/03/12 14:04:17.937   0:00.675                 gnomemeeting H323    Added capability: SpeexIETFNarrow-18.2k{sw} <21>
2007/03/12 14:04:17.938   0:00.676                 gnomemeeting H323    Added capability: SpeexIETFNarrow-24.6k{sw} <22>
2007/03/12 14:04:17.938   0:00.676                 gnomemeeting H323    Added capability: SpeexWNarrow-8k{sw} <23>
2007/03/12 14:04:17.939   0:00.677                 gnomemeeting H323    Added capability: SpeexWide-11.55k{sw} <24>
2007/03/12 14:04:17.940   0:00.678                 gnomemeeting H323    Added capability: SpeexWide-17.6k{sw} <25>
2007/03/12 14:04:17.941   0:00.679                 gnomemeeting H323    Added capability: SpeexWide-28.6k{sw} <26>
2007/03/12 14:04:17.942   0:00.680                 gnomemeeting H323    Added capability: SpeexIETFWide-11.55k{sw} <27>
2007/03/12 14:04:17.952   0:00.690                 gnomemeeting H323    Added capability: SpeexIETFWide-17.6k{sw} <28>
2007/03/12 14:04:17.953   0:00.691                 gnomemeeting H323    Added capability: SpeexIETFWide-28.6k{sw} <29>
2007/03/12 14:04:17.954   0:00.692                 gnomemeeting H323    Added capability: H.261-QCIF <30>
2007/03/12 14:04:17.954   0:00.693                 gnomemeeting H323    Added capability: H.261-CIF <31>
2007/03/12 14:04:17.955   0:00.694                 gnomemeeting H323    Added capability: UserInput/hookflash <32>
2007/03/12 14:04:17.956   0:00.694                 gnomemeeting H323    Added capability: UserInput/basicString <33>
2007/03/12 14:04:17.957   0:00.695                 gnomemeeting H323    Added capability: UserInput/dtmf <34>
2007/03/12 14:04:17.958   0:00.696                 gnomemeeting H323    Added capability: UserInput/RFC2833 <35>
2007/03/12 14:04:17.960   0:00.699                 gnomemeeting H323    Started listener Listener[ip$*:1720]
2007/03/12 14:04:18.022   0:00.760      GMH323Gate...r:083c3730 H323UDP Binding to interface: :::5020
2007/03/12 14:04:18.023   0:00.762        H323 Listener:83f9db8 H323    Awaiting TCP connections on port 1720
2007/03/12 14:04:18.027   0:00.765      GMH323Gate...r:083c3730 RAS      Authenticator H235AnnexD_Procedure1<no-pwd> not active during GRQ SetCapability  negotiation
2007/03/12 14:04:18.028   0:00.766      GMH323Gate...r:083c3730 RAS      Authenticator CAT<no-pwd> not active during GRQ SetCapability negotiation
2007/03/12 14:04:18.028   0:00.767      GMH323Gate...r:083c3730 RAS     Authenticator H235AnnexD_Procedure1<no-pwd> not active during GRQ SetCapability negotiation
2007/03/12 14:04:18.029   0:00.767      GMH323Gate...r:083c3730 RAS     Authenticator CAT<no-pwd> not active during GRQ SetCapability negotiation
2007/03/12 14:04:18.030   0:00.768      GMH323Gate...r:083c3730 RAS     Authenticator MD5<no-pwd> not active during GRQ SetCapability negotiation
2007/03/12 14:04:18.031   0:00.769      GMH323Gate...r:083c3730 H225    Started gatekeeper discovery of "ip$129.125.71.171"
2007/03/12 14:04:18.036   0:00.774      GMH323Gate...r:083c3730 RAS     Gatekeeper discovery on interface: 129.125.71.172:5021
2007/03/12 14:04:18.038   0:00.776      GMH323Gate...r:083c3730 Trans   Sending PDU [ip$129.125.71.172:5021/ip$*] : gatekeeperRequest 16191
2007/03/12 14:04:18.039   0:00.777            GkMonitor:83b9300 RAS     Background thread started
2007/03/12 14:04:18.049   0:00.787      GMH323Gate...r:083c3730 H225RAS Receiving PDU [ip$129.125.71.172:5021/ip$129.125.71.171:1719] :  gatekeeperConfirm 16191
2007/03/12 14:04:18.050   0:00.788      GMH323Gate...r:083c3730 RAS      Gatekeeper discovery found ip$129.125.71.171:1719
2007/03/12 14:04:18.051   0:00.790      GMH323Gate...r:083c3730 RAS     Gatekeeper discovered at: 129.125.71.171:1719 (if=129.125.71.172:5021)
2007/03/12 14:04:18.055   0:00.793      GMH323Gate...r:083c3730 Trans   Making request: registrationRequest
2007/03/12 14:04:18.056   0:00.794      GMH323Gate...r:083c3730 Trans   Sending PDU [ip$129.125.71.172:5021/ip$129.125.71.171:1719] :  registrationRequest 16192
2007/03/12 14:04:18.057   0:00.795      GMH323Gate...r:083c3730 Trans   Waiting on response to seqnum=16192 for 3.0 seconds
2007/03/12 14:04:18.058   0:00.796           Transactor:840ed20 Trans   Starting listener thread on Transport[remote=ip$129.125.71.171:1719  if=ip$129.125.71.172:5021]
2007/03/12 14:04:18.081   0:00.819           Transactor:840ed20 H225RAS Receiving PDU [ip$129.125.71.172:5021/ip$129.125.71.171:1719] : registrationConfirm 16192
2007/03/12 14:04:18.082   0:00.820           Transactor:840ed20 RAS     Registered 9834_endp with GNU Gatekeeper
2007/03/12 14:04:18.083   0:00.821           Transactor:840ed20 SvcCtrl OnChange Call Credit service control  debit 0
2007/03/12 14:04:56.719   0:39.457        H323 Listener:83f9db8 H323TCP Started connection:  host=::ffff:129.125.71.171:4035, if=129.125.71.172:1720,  handle=38
2007/03/12 14:04:56.721   0:39.459          H225 Answer:83fc738 H225    Started incoming call thread
2007/03/12 14:04:56.721   0:39.459          H225 Answer:83fc738 H225    Awaiting first PDU
2007/03/12 14:04:56.723   0:39.461          H225 Answer:83fc738 H225    Receiving PDU [ip$129.125.71.172:1720/ip$[::ffff:129.125.71.171]:4035] : setup
2007/03/12 14:04:56.724   0:39.462          H225 Answer:83fc738 H225    Incoming call, first PDU: callReference=5
2007/03/12 14:04:56.725   0:39.463          H225 Answer:83fc738 H323    Added capability: MS-GSM{sw} <1>
2007/03/12 14:04:56.726   0:39.464          H225 Answer:83fc738 H323    Added capability: SpeexNarrow-15k{sw} <2>
2007/03/12 14:04:56.726   0:39.464          H225 Answer:83fc738 H323    Added capability: GSM-06.10{sw} <3>
2007/03/12 14:04:56.727   0:39.465          H225 Answer:83fc738 H323    Added capability: SpeexNarrow-8k{sw} <4>
2007/03/12 14:04:56.728   0:39.466          H225 Answer:83fc738 H323    Added capability: G.726-32k{sw} <5>
2007/03/12 14:04:56.728   0:39.466          H225 Answer:83fc738 H323    Added capability: G.711-uLaw-64k <6>
2007/03/12 14:04:56.729   0:39.467          H225 Answer:83fc738 H323    Added capability: G.711-ALaw-64k <7>
2007/03/12 14:04:56.729   0:39.468          H225 Answer:83fc738 H323    Added capability: LPC-10{sw} <8>
2007/03/12 14:04:56.730   0:39.468          H225 Answer:83fc738 H323    Added capability: G.726-40k{sw} <9>
2007/03/12 14:04:56.731   0:39.469          H225 Answer:83fc738 H323    Added capability: G.726-24k{sw} <10>
2007/03/12 14:04:56.731   0:39.470          H225 Answer:83fc738 H323    Added capability: G.726-16k{sw} <11>
2007/03/12 14:04:56.732   0:39.470          H225 Answer:83fc738 H323    Added capability: MS-IMA-ADPCM{sw} <12>
2007/03/12 14:04:56.733   0:39.471          H225 Answer:83fc738 H323    Added capability: SpeexNarrow-5.95k{sw} <13>
2007/03/12 14:04:56.733   0:39.471          H225 Answer:83fc738 H323    Added capability: SpeexNarrow-11k{sw} <14>
2007/03/12 14:04:56.734   0:39.472          H225 Answer:83fc738 H323    Added capability: SpeexNarrow-18.2k{sw} <15>
2007/03/12 14:04:56.735   0:39.473          H225 Answer:83fc738 H323    Added capability: SpeexNarrow-24.6k{sw} <16>
2007/03/12 14:04:56.735   0:39.473          H225 Answer:83fc738 H323    Added capability: SpeexIETFNarrow-5.95k{sw} <17>
2007/03/12 14:04:56.736   0:39.474          H225 Answer:83fc738 H323    Added capability: SpeexIETFNarrow-8k{sw} <18>
2007/03/12 14:04:56.737   0:39.475          H225 Answer:83fc738 H323    Added capability: SpeexIETFNarrow-11k{sw} <19>
2007/03/12 14:04:56.737   0:39.475          H225 Answer:83fc738 H323    Added capability: SpeexIETFNarrow-15k{sw} <20>
2007/03/12 14:04:56.738   0:39.476          H225 Answer:83fc738 H323    Added capability: SpeexIETFNarrow-18.2k{sw} <21>
2007/03/12 14:04:56.739   0:39.477          H225 Answer:83fc738 H323    Added capability: SpeexIETFNarrow-24.6k{sw} <22>
2007/03/12 14:04:56.739   0:39.477          H225 Answer:83fc738 H323    Added capability: SpeexWNarrow-8k{sw} <23>
2007/03/12 14:04:56.740   0:39.478          H225 Answer:83fc738 H323    Added capability: SpeexWide-11.55k{sw} <24>
2007/03/12 14:04:56.740   0:39.479          H225 Answer:83fc738 H323    Added capability: SpeexWide-17.6k{sw} <25>
2007/03/12 14:04:56.741   0:39.479          H225 Answer:83fc738 H323    Added capability: SpeexWide-28.6k{sw} <26>
2007/03/12 14:04:56.742   0:39.480          H225 Answer:83fc738 H323    Added capability: SpeexIETFWide-11.55k{sw} <27>
2007/03/12 14:04:56.742   0:39.480          H225 Answer:83fc738 H323    Added capability: SpeexIETFWide-17.6k{sw} <28>
2007/03/12 14:04:56.743   0:39.481          H225 Answer:83fc738 H323    Added capability: SpeexIETFWide-28.6k{sw} <29>
2007/03/12 14:04:56.744   0:39.482          H225 Answer:83fc738 H323    Added capability: H.261-QCIF <30>
2007/03/12 14:04:56.745   0:39.483          H225 Answer:83fc738 H323    Added capability: H.261-CIF <31>
2007/03/12 14:04:56.745   0:39.483          H225 Answer:83fc738 H323    Added capability: UserInput/hookflash <32>
2007/03/12 14:04:56.746   0:39.484          H225 Answer:83fc738 H323    Added capability: UserInput/basicString <33>
2007/03/12 14:04:56.747   0:39.485          H225 Answer:83fc738 H323    Added capability: UserInput/dtmf <34>
2007/03/12 14:04:56.747   0:39.485          H225 Answer:83fc738 H323    Added capability: UserInput/RFC2833 <35>
2007/03/12 14:04:56.748   0:39.486          H225 Answer:83fc738 H323    Found capability: MS-GSM{sw} <1>
2007/03/12 14:04:56.749   0:39.487          H225 Answer:83fc738 H323    Found capability: SpeexNarrow-15k{sw} <2>
2007/03/12 14:04:56.749   0:39.487          H225 Answer:83fc738 H323    Found capability: GSM-06.10{sw} <3>
2007/03/12 14:04:56.750   0:39.488          H225 Answer:83fc738 H323    Found capability: SpeexNarrow-8k{sw} <4>
2007/03/12 14:04:56.751   0:39.489          H225 Answer:83fc738 H323    Found capability: G.726-32k{sw} <5>
2007/03/12 14:04:56.751   0:39.489          H225 Answer:83fc738 H323    Found capability: G.711-uLaw-64k <6>
2007/03/12 14:04:56.752   0:39.490          H225 Answer:83fc738 H323    Found capability: G.711-ALaw-64k <7>
2007/03/12 14:04:56.752   0:39.491          H225 Answer:83fc738 H323    Found capability: LPC-10{sw} <8>
2007/03/12 14:04:56.753   0:39.491          H225 Answer:83fc738 H323    Found capability: G.726-40k{sw} <9>
2007/03/12 14:04:56.754   0:39.492          H225 Answer:83fc738 H323    Found  capability: G.726-24k{sw} <10>
2007/03/12 14:04:56.754   0:39.492          H225 Answer:83fc738 H323    Found capability: G.726-16k{sw} <11>
2007/03/12 14:04:56.755   0:39.493          H225 Answer:83fc738 H323    Found capability: MS-IMA-ADPCM{sw} <12>
2007/03/12 14:04:56.756   0:39.494          H225 Answer:83fc738 H323    Found capability: SpeexNarrow-5.95k{sw} <13>
2007/03/12 14:04:56.756   0:39.494          H225 Answer:83fc738 H323    Found capability: SpeexNarrow-11k{sw} <14>
2007/03/12 14:04:56.757   0:39.495          H225 Answer:83fc738 H323    Found capability: SpeexNarrow-18.2k{sw} <15>
2007/03/12 14:04:56.758   0:39.496          H225 Answer:83fc738 H323    Found capability: SpeexNarrow-24.6k{sw} <16>
2007/03/12 14:04:56.758   0:39.496          H225 Answer:83fc738 H323    Found capability: SpeexIETFNarrow-5.95k{sw} <17>
2007/03/12 14:04:56.759   0:39.497          H225 Answer:83fc738 H323    Found capability: SpeexIETFNarrow-8k{sw} <18>
2007/03/12 14:04:56.760   0:39.498          H225 Answer:83fc738 H323    Found capability: SpeexIETFNarrow-11k{sw} <19>
2007/03/12 14:04:56.760   0:39.498          H225 Answer:83fc738 H323    Found capability: SpeexIETFNarrow-15k{sw} <20>
2007/03/12 14:04:56.761   0:39.499          H225 Answer:83fc738 H323    Found capability: SpeexIETFNarrow-18.2k{sw} <21>
2007/03/12 14:04:56.762   0:39.500          H225 Answer:83fc738 H323    Found capability: SpeexIETFNarrow-24.6k{sw} <22>
2007/03/12 14:04:56.762   0:39.500          H225 Answer:83fc738 H323    Found capability: SpeexWNarrow-8k{sw} <23>
2007/03/12 14:04:56.763   0:39.501          H225 Answer:83fc738 H323    Found capability: SpeexWide-11.55k{sw} <24>
2007/03/12 14:04:56.764   0:39.502          H225 Answer:83fc738 H323    Found capability: SpeexWide-17.6k{sw} <25>
2007/03/12 14:04:56.764   0:39.502          H225 Answer:83fc738 H323    Found capability: SpeexWide-28.6k{sw} <26>
2007/03/12 14:04:56.765   0:39.503          H225 Answer:83fc738 H323    Found capability: SpeexIETFWide-11.55k{sw} <27>
2007/03/12 14:04:56.765   0:39.504          H225 Answer:83fc738 H323    Found capability: SpeexIETFWide-17.6k{sw} <28>
2007/03/12 14:04:56.766   0:39.504          H225 Answer:83fc738 H323    Found capability: SpeexIETFWide-28.6k{sw} <29>
2007/03/12 14:04:56.767   0:39.505          H225 Answer:83fc738 H323    Found capability: H.261-QCIF <30>
2007/03/12 14:04:56.767   0:39.506          H225 Answer:83fc738 H323    Found capability: H.261-CIF <31>
2007/03/12 14:04:56.768   0:39.506          H225 Answer:83fc738 H323    Found capability: UserInput/hookflash <32>
2007/03/12 14:04:56.769   0:39.507          H225 Answer:83fc738 H323    Found capability: UserInput/basicString <33>
2007/03/12 14:04:56.769   0:39.507          H225 Answer:83fc738 H323    Found capability: UserInput/dtmf <34>
2007/03/12 14:04:56.770   0:39.508          H225 Answer:83fc738 H323    Found  capability: UserInput/RFC2833 <35>
2007/03/12 14:04:56.771   0:39.509          H225 Answer:83fc738 RFC2833 Handler created
2007/03/12 14:04:56.772   0:39.510          H225 Answer:83fc738 H323    Created new connection: ip$[::ffff:129.125.71.171]:4035/5
2007/03/12 14:04:56.773   0:39.511          H225 Answer:83fc738 H225    Handling PDU: Setup callRef=5
2007/03/12 14:04:56.773   0:39.512          H225 Answer:83fc738 H225    Set protocol version to 4 and implying H.245 version 7
2007/03/12 14:04:56.774   0:39.512          H225 Answer:83fc738 H225    Set remote application name: "objsys    v0.8.1  1/71"
2007/03/12 14:04:56.775   0:39.513          H225 Answer:83fc738 H225    Sending call proceeding PDU
2007/03/12 14:04:56.777   0:39.515          H225 Answer:83fc738 H225    Sending PDU [ip$129.125.71.172:1720/ip$[::ffff:129.125.71.171]:4035] :  callProceeding
2007/03/12 14:04:56.821   0:39.560          H225 Answer:83fc738 H225    Incoming call accepted
2007/03/12 14:04:56.823   0:39.561          H225 Answer:83fc738 Trans   Making request: admissionRequest
2007/03/12 14:04:56.824   0:39.562          H225 Answer:83fc738 Trans   Sending PDU [ip$129.125.71.172:5021/ip$129.125.71.171:1719] : admissionRequest  16193
2007/03/12 14:04:56.825   0:39.563          H225 Answer:83fc738 Trans   Waiting on response to seqnum=16193 for 3.0 seconds
2007/03/12 14:04:56.843   0:39.581           Transactor:840ed20 H225RAS Receiving PDU [ip$129.125.71.172:5021/ip$129.125.71.171:1719] :  admissionConfirm 16193
2007/03/12 14:04:56.844   0:39.583           Transactor:840ed20 SvcCtrl OnChange Call Credit service control  debit 0
2007/03/12 14:04:56.845   0:39.583          H225 Answer:83fc738 H323    Bandwidth used: 0
2007/03/12 14:04:56.856   0:39.594          H225 Answer:83fc738 H323    Answering call: AnswerCallPending
2007/03/12 14:04:56.857   0:39.595          H225 Answer:83fc738 H225    Sending Alerting PDU
2007/03/12 14:04:56.858   0:39.596          H225 Answer:83fc738 H225    Sending PDU [ip$129.125.71.172:1720/ip$[::ffff:129.125.71.171]:4035] : alerting
2007/03/12 14:04:56.859   0:39.597          H225 Answer:83fc738 H323    InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer  fastStartState=FastStartDisabled
2007/03/12 14:04:56.859   0:39.597          H225 Answer:83fc738 H323     InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartDisabled 
2007/03/12 14:04:56.860   0:39.598          H225 Answer:83fc738 H225    Reading PDUs: callRef=5 
2007/03/12 14:04:58.269   0:41.007            GkMonitor:83b9300 H323     Bandwidth used: 0
2007/03/12 14:04:58.270   0:41.008            GkMonitor:83b9300 Trans   Sending PDU [ip$129.125.71.172:5021/ip$129.125.71.171:1719] :  infoRequestResponse 16194
2007/03/12 14:05:07.232   0:49.970        GMURLHandler:08418b50 H323    Answering call: AnswerCallNow
2007/03/12 14:05:07.233   0:49.971        GMURLHandler:08418b50 H225    TCP Listen for H245 on 129.125.71.172:30000
2007/03/12 14:05:07.234   0:49.972        GMURLHandler:08418b50 H225    Sending PDU [ip$129.125.71.172:1720/ip$[::ffff:129.125.71.171]:4035] : connect
2007/03/12 14:05:07.235   0:49.973        GMURLHandler:08418b50 H323    InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect  fastStartState=FastStartDisabled
2007/03/12 14:05:07.239   0:49.977                 H245:8413a80 H245    Started thread
2007/03/12 14:05:07.240   0:49.978                 H245:8413a80 H245    TCP Accept wait
2007/03/12 14:05:07.241   0:49.979                 H245:8413a80 H323TCP Started connection:  host=129.125.71.171:1801, if=129.125.71.172:30000,  handle=47
2007/03/12 14:05:07.242   0:49.980                 H245:8413a80 H245    Started control channel
2007/03/12 14:05:07.242   0:49.980                 H245:8413a80 H245    Sending TerminalCapabilitySet: outSeq=1
2007/03/12 14:05:07.245   0:49.983                 H245:8413a80 H245    Sending PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : request  terminalCapabilitySet
2007/03/12 14:05:07.246   0:49.985                 H245:8413a80 H245    Sending MasterSlaveDetermination
2007/03/12 14:05:07.247   0:49.985                 H245:8413a80 H245    Sending PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : request  masterSlaveDetermination
2007/03/12 14:05:07.356   0:50.094                 H245:8413a80 H245      Receiving PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : request terminalCapabilitySet 
2007/03/12 14:05:07.357   0:50.095                 H245:8413a80 H245    Set protocol version to 8 
2007/03/12 14:05:07.358   0:50.096                 H245:8413a80 H245     Received TerminalCapabilitySet: state=InProgress pduSeq=1 inSeq=4294967295
2007/03/12 14:05:07.358   0:50.096                 H245:8413a80 H323    Added capability: MS-GSM{sw} <1>
2007/03/12 14:05:07.359   0:50.097                 H245:8413a80 H323    Added capability: SpeexNarrow-15k{sw} <2>
2007/03/12 14:05:07.360   0:50.098                 H245:8413a80 H323    Added capability: GSM-06.10{sw} <3>
2007/03/12 14:05:07.360   0:50.098                 H245:8413a80 H323    Added capability: SpeexNarrow-8k{sw} <4>
2007/03/12 14:05:07.361   0:50.099                 H245:8413a80 H323    Added capability: G.726-32k{sw} <5>
2007/03/12 14:05:07.362   0:50.100                 H245:8413a80 H323    Added capability: G.711-uLaw-64k <6>
2007/03/12 14:05:07.362   0:50.100                 H245:8413a80 H323    Added capability: G.711-ALaw-64k <7>
2007/03/12 14:05:07.363   0:50.101                 H245:8413a80 H323    Added capability: LPC-10{sw} <8>
2007/03/12 14:05:07.364   0:50.102                 H245:8413a80 H323    Added capability: G.726-40k{sw} <9>
2007/03/12 14:05:07.364   0:50.102                 H245:8413a80 H323    Added capability: G.726-24k{sw} <10>
2007/03/12 14:05:07.365   0:50.103                 H245:8413a80 H323    Added capability: G.726-16k{sw} <11>
2007/03/12 14:05:07.365   0:50.104                 H245:8413a80 H323    Added capability: MS-IMA-ADPCM{sw} <12>
2007/03/12 14:05:07.366   0:50.104                 H245:8413a80 H323    Added capability: SpeexNarrow-5.95k{sw} <13>
2007/03/12 14:05:07.367   0:50.105                 H245:8413a80 H323    Added capability: SpeexNarrow-11k{sw} <14>
2007/03/12 14:05:07.367   0:50.105                 H245:8413a80 H323    Added capability: SpeexNarrow-18.2k{sw} <15>
2007/03/12 14:05:07.368   0:50.106                 H245:8413a80 H323    Added capability: SpeexNarrow-24.6k{sw} <16>
2007/03/12 14:05:07.369   0:50.107                 H245:8413a80 H323    Added capability: SpeexIETFNarrow-5.95k{sw} <17>
2007/03/12 14:05:07.369   0:50.107                 H245:8413a80 H323    Added capability: SpeexIETFNarrow-8k{sw} <18>
2007/03/12 14:05:07.370   0:50.108                 H245:8413a80 H323    Added capability: SpeexIETFNarrow-11k{sw} <19>
2007/03/12 14:05:07.371   0:50.109                 H245:8413a80 H323    Added capability: SpeexIETFNarrow-15k{sw} <20>
2007/03/12 14:05:07.371   0:50.109                 H245:8413a80 H323    Added capability: SpeexIETFNarrow-18.2k{sw} <21>
2007/03/12 14:05:07.372   0:50.110                 H245:8413a80 H323    Added capability: SpeexIETFNarrow-24.6k{sw} <22>
2007/03/12 14:05:07.373   0:50.111                 H245:8413a80 H323    Added capability: SpeexWNarrow-8k{sw} <23>
2007/03/12 14:05:07.373   0:50.111                 H245:8413a80 H323    Added capability: SpeexWide-11.55k{sw} <24>
2007/03/12 14:05:07.374   0:50.112                 H245:8413a80 H323    Added capability: SpeexWide-17.6k{sw} <25>
2007/03/12 14:05:07.375   0:50.113                 H245:8413a80 H323    Added capability: SpeexWide-28.6k{sw} <26>
2007/03/12 14:05:07.375   0:50.113                 H245:8413a80 H323    Added capability: SpeexIETFWide-11.55k{sw} <27>
2007/03/12 14:05:07.376   0:50.114                 H245:8413a80 H323    Added capability: SpeexIETFWide-17.6k{sw} <28>
2007/03/12 14:05:07.377   0:50.115                 H245:8413a80 H323    Added capability: SpeexIETFWide-28.6k{sw} <29>
2007/03/12 14:05:07.377   0:50.115                 H245:8413a80 H323    Added capability: H.261-QCIF <30>
2007/03/12 14:05:07.378   0:50.116                 H245:8413a80 H323    Added capability: H.261-CIF <31>
2007/03/12 14:05:07.378   0:50.117                 H245:8413a80 H323    Added capability: UserInput/hookflash <32>
2007/03/12 14:05:07.379   0:50.117                 H245:8413a80 H323    Added capability: UserInput/basicString <33>
2007/03/12 14:05:07.380   0:50.118                 H245:8413a80 H323    Added capability: UserInput/dtmf <34>
2007/03/12 14:05:07.380   0:50.118                 H245:8413a80 H323    Added capability: UserInput/RFC2833 <35>
2007/03/12 14:05:07.381   0:50.119                 H245:8413a80 H323    Added capability: G.711-ALaw-64k <36>
2007/03/12 14:05:07.382   0:50.120                 H245:8413a80 H323    Added capability: G.711-uLaw-64k <37>
2007/03/12 14:05:07.383   0:50.121                 H245:8413a80 H323    Found capability: G.726-16k{sw} <11>
2007/03/12 14:05:07.384   0:50.122                 H245:8413a80 H323    Found capability: G.726-24k{sw} <10>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found capability: G.726-32k{sw} <5>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found  capability: G.726-40k{sw} <9>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found  capability: GSM-06.10{sw} <3>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found  capability: LPC-10{sw} <8>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found  capability: MS-GSM{sw} <1>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found capability: MS-IMA-ADPCM{sw} <12>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found capability: SpeexIETFNarrow-11k{sw} <19>
2007/03/12 14:05:07.385   0:50.123                 H245:8413a80 H323    Found capability: SpeexIETFNarrow-15k{sw} <20>
2007/03/12 14:05:07.386   0:50.124                 H245:8413a80 H323    Found capability: SpeexIETFNarrow-18.2k{sw} <21>
2007/03/12 14:05:07.386   0:50.124                 H245:8413a80 H323    Found capability: SpeexIETFNarrow-24.6k{sw} <22>
2007/03/12 14:05:07.386   0:50.124                 H245:8413a80 H323    Found capability: SpeexIETFNarrow-5.95k{sw} <17>
2007/03/12 14:05:07.386   0:50.124                 H245:8413a80 H323    Found capability: SpeexIETFNarrow-8k{sw} <18>
2007/03/12 14:05:07.386   0:50.124                 H245:8413a80 H323    Found capability: SpeexIETFWide-11.55k{sw} <27>
2007/03/12 14:05:07.386   0:50.124                 H245:8413a80 H323    Found capability: SpeexIETFWide-17.6k{sw} <28>
2007/03/12 14:05:07.386   0:50.124                 H245:8413a80 H323    Found capability: SpeexIETFWide-28.6k{sw} <29>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexNarrow-11k{sw} <14>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexNarrow-15k{sw} <2>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexNarrow-18.2k{sw} <15>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexNarrow-24.6k{sw} <16>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexNarrow-5.95k{sw} <13>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability:  SpeexNarrow-8k{sw} <4>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability:  SpeexWNarrow-8k{sw} <23>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexWide-11.55k{sw} <24>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexWide-17.6k{sw} <25>
2007/03/12 14:05:07.389   0:50.127                 H245:8413a80 H323    Found capability: SpeexWide-28.6k{sw} <26>
2007/03/12 14:05:07.390   0:50.128                 H245:8413a80 H323    Added capability: UserInput/hookflash <38>
2007/03/12 14:05:07.390   0:50.128                 H245:8413a80 H323    Added capability: UserInput/basicString <39>
2007/03/12 14:05:07.390   0:50.128                 H245:8413a80 H323    Added capability:  UserInput/dtmf <40>
2007/03/12 14:05:07.390   0:50.128                 H245:8413a80 H323    Added capability: UserInput/RFC2833 <41>
2007/03/12 14:05:07.390   0:50.128                 H245:8413a80 H323    Found capability: GSM-06.10{sw} <3>
2007/03/12 14:05:07.390   0:50.128                 H245:8413a80 H323    Found capability:  G.711-uLaw-64k <6>
2007/03/12 14:05:07.390   0:50.128                 H245:8413a80 H323    Found capability: UserInput/RFC2833 <35>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    Added capability: GSM-06.10{sw} <1>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    Added capability: G.711-uLaw-64k <2>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    Added capability: UserInput/RFC2833 <3>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    Found capability: GSM-06.10{sw} <1>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    Found capability: G.711-uLaw-64k <2>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    Found capability: UserInput/RFC2833 <3>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H245    Received capability set, is accepted
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H245    TerminalCapabilitySet already in progress: outSeq=1
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    Found capability: UserInput/RFC2833 <3>
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    User Input RFC2833 payload type set to [pt=101]
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H245    Sending PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : response terminalCapabilitySetAck
2007/03/12 14:05:07.391   0:50.129                 H245:8413a80 H323    InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect  fastStartState=FastStartDisabled
2007/03/12 14:05:07.392   0:50.130                 H245:8413a80 H245    Receiving PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : request masterSlaveDetermination
2007/03/12 14:05:07.392   0:50.130                 H245:8413a80 H245    Received MasterSlaveDetermination: state=Outgoing
2007/03/12 14:05:07.392   0:50.130                 H245:8413a80 H245    MasterSlaveDetermination: local is master
2007/03/12 14:05:07.392   0:50.130                 H245:8413a80 H245    Sending PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : response masterSlaveDeterminationAck
2007/03/12 14:05:07.392   0:50.130                 H245:8413a80 H323    InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled
2007/03/12 14:05:07.392   0:50.130                 H245:8413a80 H245    Receiving PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : response terminalCapabilitySetAck
2007/03/12 14:05:07.398   0:50.137                 H245:8413a80 H245    Received TerminalCapabilitySetAck: state=InProgress pduSeq=1 outSeq=1
2007/03/12 14:05:07.399   0:50.137                 H245:8413a80 H245    TerminalCapabilitySet Sent.
2007/03/12 14:05:07.400   0:50.138                 H245:8413a80 H323     InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect  fastStartState=FastStartDisabled
2007/03/12 14:05:07.400   0:50.138                 H245:8413a80 H245    Receiving PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : response masterSlaveDeterminationAck
2007/03/12 14:05:07.401   0:50.139                 H245:8413a80 H245    Received MasterSlaveDeterminationAck: state=Incoming
2007/03/12 14:05:07.402   0:50.140                 H245:8413a80 H323    InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect  fastStartState=FastStartDisabled
2007/03/12 14:05:07.402   0:50.140                 H245:8413a80 H245    Default OnSelectLogicalChannels, FastStartDisabled
2007/03/12 14:05:07.402   0:50.140                 H245:8413a80 H323    Found capability: GSM-06.10{sw} <1>
2007/03/12 14:05:07.403   0:50.141                 H245:8413a80 H323    Selecting GSM-06.10{sw} <1>
2007/03/12 14:05:07.403   0:50.141                 H245:8413a80 H245    Opening channel: T-101
2007/03/12 14:05:07.404   0:50.142                 H245:8413a80 RTP_UDP Session 1 created: 129.125.71.172:5000-5001 ssrc=2403563281
2007/03/12 14:05:07.404   0:50.142                 H245:8413a80 RTP     Adding session RTP_UDP
2007/03/12 14:05:07.404   0:50.142                 H245:8413a80 H323RTP Transmitter created using   session 1
2007/03/12 14:05:07.404   0:50.142                 H245:8413a80 H323RTP OnSendingPDU 
2007/03/12 14:05:07.405   0:50.143                 H245:8413a80 RTP     OnSendingPDU
2007/03/12 14:05:07.405   0:50.143                 H245:8413a80 H323PLUGIN      Creating framed audio codec GSM-06.10 from plugin
2007/03/12 14:05:08.636   0:51.374                 H245:8413a80 H323    Started sending logical channel: GSM-06.10{sw} <1>
2007/03/12 14:05:08.652   0:51.391                 H245:8413a80 LogChan Opened using capability GSM-06.10{sw} <1>
2007/03/12 14:05:08.653   0:51.391                 H245:8413a80 LogChan Bandwidth requested/used = 13.2/0.0 kb/s
2007/03/12 14:05:08.654   0:51.392                 H245:8413a80 H323    Bandwidth request: -0.0kb/s, available: 10000.0kb/s
2007/03/12 14:05:08.655   0:51.393                 H245:8413a80 H323    Bandwidth request: +13.2kb/s, available: 10000.0kb/s
2007/03/12 14:05:08.655   0:51.393                 H245:8413a80 H245    Sending PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : request openLogicalChannel
2007/03/12 14:05:08.663   0:51.401                 H245:8413a80 H245    Receiving PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : request openLogicalChannel
2007/03/12 14:05:08.664   0:51.402                 H245:8413a80 H245    Received open channel: R-1005, state=Released
2007/03/12 14:05:08.665   0:51.403                 H245:8413a80 H323    CreateLogicalChannel - forward channel
2007/03/12 14:05:08.665   0:51.403                 H245:8413a80 H323    Found capability: GSM-06.10{sw} <3>
2007/03/12 14:05:08.666   0:51.404                 H245:8413a80 RTP     Found existing session 1
2007/03/12 14:05:08.666   0:51.404                 H245:8413a80 H323RTP Receiver created using  session 1
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 H323PLUGIN      Creating framed audio codec GSM-06.10 from plugin
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 LogChan Bandwidth requested/used = 13.2/0.0 kb/s
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 H323    Bandwidth request: -0.0kb/s, available: 9986.8kb/s
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 H323    Bandwidth request: +13.2kb/s, available: 9986.8kb/s
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 H323RTP OnReceivedPDU for channel:  R-1005
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 RTP_UDP SetRemoteSocketInfo:  session=1 control channel, new=129.125.21.241:14483, local=129.125.71.172:5000-5001,  remote=0.0.0.0:0-0 
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 H323RTP OnSendOpenAck
2007/03/12 14:05:08.667   0:51.405                 H245:8413a80 RTP     OnSendingAckPDU
2007/03/12 14:05:08.668   0:51.406                 H245:8413a80 H323RTP Sending open logical channel  ACK: sessionID=1
2007/03/12 14:05:08.669   0:51.407                 H245:8413a80 H323    Started receiving logical channel: GSM-06.10{sw} <3>
2007/03/12 14:05:08.683   0:51.422                 H245:8413a80 LogChan Opened using capability GSM-06.10{sw} <3>
2007/03/12 14:05:08.684   0:51.422                 H245:8413a80 H245    Sending PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : response openLogicalChannelAck
2007/03/12 14:05:08.685   0:51.424                 H245:8413a80 H323    InternalEstablishedConnectionCheck: connectionState=EstablishedConnection  fastStartState=FastStartDisabled
2007/03/12 14:05:08.686   0:51.425                 H245:8413a80 H245    Receiving PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : response openLogicalChannelAck
2007/03/12 14:05:08.687   0:51.425                 H245:8413a80 H245    Received open channel ack: T-101, state=AwaitingEstablishment
2007/03/12 14:05:08.687   0:51.425                 H245:8413a80 H323RTP OnReceiveOpenAck
2007/03/12 14:05:08.687   0:51.425                 H245:8413a80 RTP_UDP SetRemoteSocketInfo:  session=1 control channel, new=129.125.21.241:14483, local=129.125.71.172:5000-5001, remote=129.125.21.241:14482-14483 
2007/03/12 14:05:08.688   0:51.426                 H245:8413a80 RTP_UDP SetRemoteSocketInfo: session=1 data channel, new=129.125.21.241:14482, local=129.125.71.172:5000-5001,  remote=129.125.21.241:14482-14483
2007/03/12 14:05:08.688   0:51.426                 H245:8413a80 H323    InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled
2007/03/12 14:05:08.693   0:51.431            LogChanRx:83c8600 H323RTP Receive GSM-06.10 thread started.
2007/03/12 14:05:08.694   0:51.432            LogChanRx:83c8600 RTP     Jitter buffer created: size=101 delay=160-4000/160 (20ms) obj=0x8412d10
2007/03/12 14:05:08.697   0:51.435            LogChanTx:841a298 H323RTP Transmit GSM-06.10 thread started: rate=160 time=20ms size=1*33=33
2007/03/12 14:05:08.699   0:51.437           RTP Jitter:8412d10 RTP     Jitter RTP receive thread started: 0x8412d10
2007/03/12 14:05:08.700   0:51.438           RTP Jitter:8412d10 RTP     First data: ver=2 pt=PCMU psz=160 m=0 x=0 seq=48252 ts=0 src=1222764561 ccnt=0
2007/03/12 14:05:08.715   0:51.453            LogChanRx:83c8600 H323RTP Payload type mismatch: expected GSM, got PCMU. Ignoring packet.
2007/03/12 14:05:08.719   0:51.457            LogChanTx:841a298 H323RTP Transmit start of talk burst: 160
2007/03/12 14:05:08.735   0:51.473            LogChanRx:83c8600 H323RTP Payload type mismatch: expected GSM, got PCMU. Ignoring packet.
2007/03/12 14:05:08.755   0:51.493            LogChanRx:83c8600 H323RTP Payload type matched again GSM
2007/03/12 14:05:08.795   0:51.533            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:08.818   0:51.556            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:08.835   0:51.573            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:08.955   0:51.693            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.035   0:51.773            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.055   0:51.793            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.075   0:51.813            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.175   0:51.913            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.275   0:52.013            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.295   0:52.033            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.315   0:52.053            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.415   0:52.153            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.475   0:52.213            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.495   0:52.233            LogChanRx:83c8600 RTP     Jitter buffer size increased to 320 (40ms)
2007/03/12 14:05:09.535   0:52.273            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.555   0:52.293            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.575   0:52.313            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.658   0:52.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:09.675   0:52.413            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:09.775   0:52.513            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:09.795   0:52.533            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:09.815   0:52.553            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:09.914   0:52.653            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.954   0:52.693            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:09.975   0:52.713            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.014   0:52.753            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.034   0:52.773            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.054   0:52.793            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.154   0:52.893            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.214   0:52.953            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.234   0:52.973            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.254   0:52.993            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.274   0:53.013            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.294   0:53.033            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.394   0:53.132            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.454   0:53.192            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.474   0:53.212            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.494   0:53.232            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.514   0:53.252            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.534   0:53.272            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.634   0:53.373            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.658   0:53.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:10.684   0:53.422           RTP Jitter:8412d10 RTP     Receive statistics:   packets=101 octets=3587 lost=0 tooLate=0 order=0 avgTime=19 maxTime=48 minTime=0 jitter=17 maxJitter=23
2007/03/12 14:05:10.719   0:53.457            LogChanTx:841a298 H323RTP Transmitter sent timestamp 16160
2007/03/12 14:05:10.719   0:53.457            LogChanTx:841a298 RTP     Transmit statistics:  packets=101 octets=3333 avgTime=19 maxTime=31 minTime=9
2007/03/12 14:05:10.734   0:53.472            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.754   0:53.492            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.754   0:53.493            LogChanRx:83c8600 H323RTP Receiver written timestamp 16160
2007/03/12 14:05:10.774   0:53.512            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.874   0:53.612            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:10.994   0:53.732            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.014   0:53.752            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.114   0:53.852            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.214   0:53.952            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.234   0:53.972            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.254   0:53.992            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.354   0:54.092            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.454   0:54.192            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.474   0:54.212            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.494   0:54.232            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.594   0:54.332            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.658   0:54.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:11.694   0:54.432            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:11.714   0:54.452            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.734   0:54.472            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.834   0:54.572            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.934   0:54.672            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.954   0:54.692            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:11.994   0:54.732            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.074   0:54.812            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.174   0:54.912            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.194   0:54.932            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.214   0:54.952            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.314   0:55.052            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.414   0:55.152            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.434   0:55.172            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.454   0:55.192            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.474   0:55.212            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.494   0:55.232            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.554   0:55.292            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.654   0:55.392            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.658   0:55.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:12.674   0:55.412            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:12.694   0:55.432            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:12.700   0:55.438           RTP Jitter:8412d10 RTP     Receive statistics:  packets=201 octets=6887 lost=0 tooLate=0 order=0 avgTime=20 maxTime=49 minTime=0 jitter=20  maxJitter=23
2007/03/12 14:05:12.719   0:55.457            LogChanTx:841a298 RTP     Transmit statistics:  packets=201 octets=6633 avgTime=20 maxTime=21 minTime=19
2007/03/12 14:05:12.739   0:55.477            LogChanTx:841a298 H323RTP Transmitter sent timestamp 32320
2007/03/12 14:05:12.774   0:55.512            LogChanRx:83c8600 H323RTP Receiver written timestamp 32320
2007/03/12 14:05:12.794   0:55.532            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.894   0:55.632            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.914   0:55.652            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:12.934   0:55.672            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.034   0:55.772            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.134   0:55.872            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.154   0:55.892            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.174   0:55.912            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.234   0:55.972            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.254   0:55.992            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.274   0:56.012            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.374   0:56.112            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.395   0:56.133            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.414   0:56.152            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.474   0:56.212            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.494   0:56.232            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.514   0:56.252            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.614   0:56.352            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.634   0:56.372            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.654   0:56.392            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.658   0:56.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:13.754   0:56.492            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:13.854   0:56.592            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:13.874   0:56.612            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:13.894   0:56.632            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.094   0:56.832            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.114   0:56.852            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.134   0:56.872            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.234   0:56.972            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.334   0:57.072            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.354   0:57.092            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.374   0:57.112            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.494   0:57.232            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.514   0:57.252            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.554   0:57.292            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.634   0:57.372            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.658   0:57.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:14.708   0:57.446           RTP Jitter:8412d10 RTP     Receive statistics:  packets=301 octets=10187 lost=0 tooLate=0 order=0 avgTime=20 maxTime=49 minTime=0 jitter=17 maxJitter=23
2007/03/12 14:05:14.714   0:57.452            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.719   0:57.457            LogChanTx:841a298 RTP     Transmit statistics:  packets=301 octets=9933 avgTime=20 maxTime=21 minTime=19
2007/03/12 14:05:14.758   0:57.497            LogChanTx:841a298 H323RTP Transmitter sent timestamp 48480
2007/03/12 14:05:14.774   0:57.512            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.794   0:57.532            LogChanRx:83c8600 H323RTP Receiver written timestamp 48480
2007/03/12 14:05:14.854   0:57.592            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.894   0:57.632            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.914   0:57.652            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.934   0:57.672            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:14.974   0:57.712            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:15.034   0:57.772            LogChanRx:83c8600 RTP     Jitter buffer size increased to 480 (60ms)
2007/03/12 14:05:15.514   0:58.252            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:15.658   0:58.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:16.074   0:58.812            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:16.634   0:59.372            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:16.658   0:59.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:16.704   0:59.442           RTP Jitter:8412d10 RTP     Receive statistics:   packets=401 octets=13487 lost=0 tooLate=0 order=0 avgTime=19 maxTime=56 minTime=0 jitter=20 maxJitter=27
2007/03/12 14:05:16.718   0:59.456            LogChanTx:841a298 RTP     Transmit statistics:  packets=401 octets=13233 avgTime=19 maxTime=22 minTime=18
2007/03/12 14:05:16.778   0:59.516            LogChanTx:841a298 H323RTP Transmitter sent timestamp 64640
2007/03/12 14:05:16.854   0:59.592            LogChanRx:83c8600 H323RTP Receiver written timestamp 64640
2007/03/12 14:05:17.194   0:59.932            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:17.658   1:00.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:17.754   1:00.492            LogChanRx:83c8600 RTP     Jitter buffer target  realigned to current jitter buffer
2007/03/12 14:05:17.854   1:00.592            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:18.314   1:01.052            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:18.658   1:01.396                  Housekeeper RTP     Found existing session 1
2007/03/12 14:05:18.718   1:01.456            LogChanTx:841a298 RTP     Transmit statistics:   packets=501 octets=16533 avgTime=20 maxTime=22 minTime=18
2007/03/12 14:05:18.721   1:01.459           RTP Jitter:8412d10 RTP     Receive statistics:  packets=501 octets=16787 lost=0 tooLate=0 order=0 avgTime=20 maxTime=59 minTime=0 jitter=19  maxJitter=27
2007/03/12 14:05:18.798   1:01.536            LogChanTx:841a298 H323RTP Transmitter sent timestamp 80800
2007/03/12 14:05:18.874   1:01.612            LogChanRx:83c8600 RTP     Jitter buffer target realigned to current jitter buffer
2007/03/12 14:05:18.874   1:01.612            LogChanRx:83c8600 H323RTP Receiver written timestamp 80800
2007/03/12 14:05:19.178   1:01.916            LogChanTx:841a298 RTP     SentSenderReport:  ssrc=2403563281 ntp=3382693519.767114512 rtp=83840 psent=524 osent=17292
2007/03/12 14:05:19.178   1:01.916            LogChanTx:841a298 RTP     SentReceiverReport: ssrc=1222764561 fraction=0 lost=0 last_seq=0 jitter=149 lsr=0 dlsr=0
2007/03/12 14:05:19.178   1:01.916            LogChanTx:841a298 RTP     Sending SDES: anneke@wingtip112
2007/03/12 14:05:19.342   1:02.080                 gnomemeeting H323    Call end reason for ip$[::ffff:129.125.71.171]:4035/5 set to EndedByLocalUser
2007/03/12 14:05:19.342   1:02.080                 gnomemeeting H225    Sending release complete PDU: callRef=5
2007/03/12 14:05:19.343   1:02.081                 gnomemeeting H245    Sending PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : command endSessionCommand
2007/03/12 14:05:19.343   1:02.081                 gnomemeeting H225    Sending PDU [ip$129.125.71.172:1720/ip$[::ffff:129.125.71.171]:4035] : releaseComplete
2007/03/12 14:05:19.346   1:02.084                 H245:8413a80 H245    Receiving PDU [ip$129.125.71.172:30000/ip$129.125.71.171:1801] : command endSessionCommand
2007/03/12 14:05:19.347   1:02.085                 H245:8413a80 H323    Clearing connection ip$[::ffff:129.125.71.171]:4035/5 reason=EndedByRemoteUser
2007/03/12 14:05:19.347   1:02.085                 H245:8413a80 H245    Control channel closed.
2007/03/12 14:05:19.347   1:02.085                 H323 Cleaner H323    Cleaning up connections
2007/03/12 14:05:19.348   1:02.086                 H323 Cleaner H323    Connection ip$[::ffff:129.125.71.171]:4035/5 closing: connectionState=EstablishedConnection
2007/03/12 14:05:19.348   1:02.086                 H323 Cleaner H245    Stopping MasterSlaveDetermination: state=Idle
2007/03/12 14:05:19.349   1:02.087                 H323 Cleaner H245    Stopping TerminalCapabilitySet: state=Sent
2007/03/12 14:05:19.349   1:02.087                 H323 Cleaner H323RTP Cleaning up RTP R-1005
2007/03/12 14:05:19.350   1:02.088                 H323 Cleaner RTP_UDP Session 1, Shutting down  read.
2007/03/12 14:05:19.351   1:02.089           RTP Jitter:8412d10 RTP_UDP Session 1, Read shutdown.
2007/03/12 14:05:19.351   1:02.089           RTP Jitter:8412d10 RTP     Jitter RTP receive thread ended
2007/03/12 14:05:19.351   1:02.089                 H323 Cleaner LogChan Cleaning up R-1005
2007/03/12 14:05:19.352   1:02.090          H225 Answer:83fc738 H225    Read error (0):
2007/03/12 14:05:19.352   1:02.090          H225 Answer:83fc738 H323    H323Transport::Close
2007/03/12 14:05:19.354   1:02.092            LogChanRx:83c8600 H323RTP Receive GSM-06.10 thread ended
2007/03/12 14:05:19.357   1:02.095          H225 Answer:83fc738 H225    Signal channel closed.
2007/03/12 14:05:19.358   1:02.097                 H323 Cleaner H323    Stopped receiving logical channel: GSM-06.10{sw} <3>
2007/03/12 14:05:19.381   1:02.119                 H323 Cleaner LogChan Cleaned up R-1005
2007/03/12 14:05:19.382   1:02.120                 H323 Cleaner H323RTP Cleaning up RTP T-101
2007/03/12 14:05:19.382   1:02.120                 H323 Cleaner RTP_UDP Session 1, Shutting down write.
2007/03/12 14:05:19.383   1:02.121                 H323 Cleaner LogChan Cleaning up T-101
2007/03/12 14:05:19.398   1:02.136            LogChanTx:841a298 RTP_UDP Session 1, Write shutdown.
2007/03/12 14:05:19.398   1:02.136            LogChanTx:841a298 H323RTP Transmit GSM-06.10 thread ended
2007/03/12 14:05:19.399   1:02.137                 H323 Cleaner H323    Stopped sending logical  channel: GSM-06.10{sw} <1>
2007/03/12 14:05:19.415   1:02.153                 H323 Cleaner LogChan Cleaned up T-101
2007/03/12 14:05:19.416   1:02.154                 H323 Cleaner RTP     Releasing session 1
2007/03/12 14:05:19.416   1:02.154                 H323 Cleaner H323    Bandwidth request: -13.2kb/s, available: 9973.6kb/s
2007/03/12 14:05:19.417   1:02.155                 H323 Cleaner RTP     Releasing session 1
2007/03/12 14:05:19.418   1:02.156                 H323 Cleaner RTP     Deleting session 1
2007/03/12 14:05:19.418   1:02.156                 H323 Cleaner RTP     Removing jitter buffer 0x8412d10 RTP Jitter:8412d10
2007/03/12 14:05:19.419   1:02.157                 H323 Cleaner RTP_UDP Session 1, Shutting down read.
2007/03/12 14:05:19.420   1:02.158                 H323 Cleaner RTP_UDP Session 1, Shutting down  write.
2007/03/12 14:05:19.420   1:02.158                 H323 Cleaner RTP     Final statistics:  
    packetsSent       = 534
    octetsSent        = 17622
    averageSendTime   = 20
    maximumSendTime   = 22
    minimumSendTime   = 18
    packetsReceived   = 533
    octetsReceived    = 17843
    packetsLost       = 0
    packetsTooLate    = 0
    packetsOutOfOrder = 0
    averageReceiveTime= 20
    maximumReceiveTime= 59
    minimumReceiveTime= 0
    averageJitter     = 19
    maximumJitter     = 27
2007/03/12 14:05:19.423   1:02.161                 H323 Cleaner H323    Bandwidth request:  -13.2kb/s, available: 9986.8kb/s 
2007/03/12 14:05:19.424   1:02.162                 H323 Cleaner H323    H323Transport::Close
2007/03/12 14:05:19.425   1:02.163                 H323 Cleaner H323     H323Transport::CleanUpOnTermination for H245:8413a80 
2007/03/12 14:05:19.426   1:02.164                 H323 Cleaner H323    H323Transport::Close
2007/03/12 14:05:19.426   1:02.164                 H323 Cleaner H323     H323Transport::CleanUpOnTermination for H225 Answer:83fc738 
2007/03/12 14:05:19.427   1:02.165                 H323 Cleaner Trans   Making request:  disengageRequest 
2007/03/12 14:05:19.428   1:02.166                 H323 Cleaner Trans   Sending PDU  [ip$129.125.71.172:5021/ip$129.125.71.171:1719] : disengageRequest 16195 
2007/03/12 14:05:19.429   1:02.167                 H323 Cleaner Trans   Waiting on response to  seqnum=16195 for 3.0 seconds 
2007/03/12 14:05:19.435   1:02.174           Transactor:840ed20 H225RAS Receiving PDU  [ip$129.125.71.172:5021/ip$129.125.71.171:1719] : disengageConfirm 16195 
2007/03/12 14:05:19.437   1:02.175                 H323 Cleaner H323    Connection  ip$[::ffff:129.125.71.171]:4035/5 terminated. 
2007/03/12 14:05:21.234   1:03.972                 H323 Cleaner H323    Connection  ip$[::ffff:129.125.71.171]:4035/5 deleted. 
2007/03/12 14:05:21.234   1:03.972                 H323 Cleaner H323    Cleaning up connections