Difference between revisions of "SIP to H323"

From TD-er's Wiki
Jump to navigationJump to search
m
 
(10 intermediate revisions by 2 users not shown)
Line 1: Line 1:
  == '''Asterisk''' ==
+
== Asterisk ==
<-- SIP read from 129.125.71.173:5060:
+
<-- SIP read from 129.125.71.173:5060:
INVITE sip:601@129.125.21.241 SIP/2.0
+
INVITE sip:601@129.125.21.241 SIP/2.0
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK28425BFFA543520B0B9D0FE0CA28AD10
+
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
+
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
To: <sip:601@129.125.21.241>
+
To: <sip:601@129.125.21.241>
Contact: <sip:502@129.125.71.173:5060>
+
Contact: <sip:502@129.125.71.173:5060>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
+
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
CSeq: 24313 INVITE
+
CSeq: 24313 INVITE
Max-Forwards: 70
+
Max-Forwards: 70
Content-Type: application/sdp
+
Content-Type: application/sdp
User-Agent: X-Lite release 1105d
+
User-Agent: X-Lite release 1105d
Content-Length: 312
+
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 ==
  
v=0
+
root@wingtip111:~# 2007/03/12 14:05:41.879      2            RasSrv.cxx(168)    RAS    Read from 129.125.21.241:13030
o=502 1178656389 1178656399 IN IP4 129.125.71.173
+
2007/03/12 14:05:41.880 3            RasSrv.cxx(207)  RAS
s=X-Lite
+
admissionRequest {
c=IN IP4 129.125.71.173
+
    requestSeqNum = 5
t=0 0
+
    callType = pointToPoint <<null>>
m=audio 8000 RTP/AVP 0 8 3 98 97 101
+
    callModel = direct <<null>>
a=rtpmap:0 pcmu/8000
+
    endpointIdentifier =  9 characters {
a=rtpmap:8 pcma/8000
+
      0038 0038 0037 0030 005f 0065 006e 0064  8870_end
a=rtpmap:3 gsm/8000
+
      0070                                      p
a=rtpmap:98 iLBC/8000
+
    }
a=rtpmap:97 speex/8000
+
    destinationInfo = 1 entries {
a=rtpmap:101 telephone-event/8000
+
      [0]=dialedDigits "601"
a=fmtp:101 0-15
+
    }
a=sendrecv
+
    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
  
--- (11 headers 14 lines)---
+
== Xlite ==
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
 
  
 +
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
Scheduling destruction of call '39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173' in 15000 ms
+
SEND >> 129.125.21.241:5060
Found user '502'
+
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
  
<-- SIP read from 129.125.71.173:5060:
+
v=0
ACK sip:601@129.125.21.241 SIP/2.0
+
o=502 1181403920 1181403929 IN IP4 129.125.71.173
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK28425BFFA543520B0B9D0FE0CA28AD10
+
s=X-Lite
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
+
c=IN IP4 129.125.71.173
To: <sip:601@129.125.21.241>;tag=as3321ad7e
+
t=0 0
Contact: <sip:502@129.125.71.173:5060>
+
m=audio 8000 RTP/AVP 0 8 3 98 97 101
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
+
a=rtpmap:0 pcmu/8000
CSeq: 24313 ACK
+
a=rtpmap:8 pcma/8000
Max-Forwards: 70
+
a=rtpmap:3 gsm/8000
Content-Length: 0
+
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
  
--- (9 headers 0 lines)---
+
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
  
<-- SIP read from 129.125.71.173:5060:
+
SEND TIME: 1181403939
INVITE sip:601@129.125.21.241 SIP/2.0
+
SEND >> 129.125.21.241:5060
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK5ECEFA77EC7446BB73C0364FD5D9D9FB
+
INVITE sip:601@129.125.21.241 SIP/2.0
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
+
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK0B7AA1B8103C43CD6E613A3D3EFA07E1
To: <sip:601@129.125.21.241>
+
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
Contact: <sip:502@129.125.71.173:5060>
+
To: <sip:601@129.125.21.241>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
+
Contact: <sip:502@129.125.71.173:5060>
CSeq: 24314 INVITE
+
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
Proxy-Authorization: Digest username="502",realm="asterisk",nonce="18d198f5",response="af4294cd96f7dba63e0fbf3ea9c0e161",uri="sip:601@129.125.21.241",algorithm=MD5
+
CSeq: 47395 INVITE
Max-Forwards: 70
+
Proxy-Authorization: Digest         username="502",realm="asterisk",nonce="01ae543a",response="821ee080b48883a5003bcb33980362e1",uri="sip:601@129.125.21.241",algorithm=MD5
Content-Type: application/sdp
+
Max-Forwards: 70
User-Agent: X-Lite release 1105d
+
Content-Type: application/sdp
Content-Length: 312
+
User-Agent: X-Lite release 1105d
 +
Content-Length: 312
  
v=0
+
v=0
o=502 1178656389 1178656399 IN IP4 129.125.71.173
+
o=502 1181403920 1181403929 IN IP4 129.125.71.173
s=X-Lite
+
s=X-Lite
c=IN IP4 129.125.71.173
+
c=IN IP4 129.125.71.173
t=0 0
+
t=0 0
m=audio 8000 RTP/AVP 0 8 3 98 97 101
+
m=audio 8000 RTP/AVP 0 8 3 98 97 101
a=rtpmap:0 pcmu/8000
+
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
+
a=rtpmap:8 pcma/8000
a=rtpmap:3 gsm/8000
+
a=rtpmap:3 gsm/8000
a=rtpmap:98 iLBC/8000
+
a=rtpmap:98 iLBC/8000
a=rtpmap:97 speex/8000
+
a=rtpmap:97 speex/8000
a=rtpmap:101 telephone-event/8000
+
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
+
a=fmtp:101 0-15
a=sendrecv
+
a=sendrecv
  
--- (12 headers 14 lines)---
+
RECEIVE TIME: 1181403963
Using INVITE request as basis request - 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
+
RECEIVE << 129.125.21.241:5060
Sending to 129.125.71.173 : 5060 (NAT)
+
SIP/2.0 100 Trying
Found user '502'
+
Via: SIP/2.0/UDP 129.125.71.173:5060;rport;branch=z9hG4bK0B7AA1B8103C43CD6E613A3D3EFA07E1;received=129.125.71.173
Found RTP audio format 0
+
From: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
Found RTP audio format 8
+
To: <sip:601@129.125.21.241>
Found RTP audio format 3
+
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
Found RTP audio format 98
+
CSeq: 47395 INVITE
Found RTP audio format 97
+
User-Agent: Asterisk PBX
Found RTP audio format 101
+
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Peer audio RTP is at port 129.125.71.173:8000
+
Contact: <sip:601@129.125.21.241>
Found description format pcmu
+
Content-Length: 0
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
 
  
 +
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
    -- Executing Macro("SIP/502-083b1650", "exten-vm|novm|601") in new stack
+
o=root 9003 9003 IN IP4 129.125.21.241
    -- Executing Macro("SIP/502-083b1650", "user-callerid") in new stack
+
  s=session
    -- Executing GotoIf("SIP/502-083b1650", "0?report") in new stack
+
  c=IN IP4 129.125.21.241
    -- Executing GotoIf("SIP/502-083b1650", "0?start") in new stack
+
  t=0 0
    -- Executing Set("SIP/502-083b1650", "REALCALLERIDNUM=502") in new stack
+
  m=audio 17092 RTP/AVP 0 8 101
    -- Executing NoOp("SIP/502-083b1650", "REALCALLERIDNUM is 502") in new stack
+
a=rtpmap:0 PCMU/8000
    -- Executing Set("SIP/502-083b1650", "AMPUSER=502") in new stack
+
  a=rtpmap:8 PCMA/8000
    -- Executing Set("SIP/502-083b1650", "AMPUSERCIDNAME=Fabio SIP freePBX") in new stack
+
  a=rtpmap:101 telephone-event/8000
    -- Executing GotoIf("SIP/502-083b1650", "0?report") in new stack
+
a=fmtp:101 0-16
    -- Executing Set("SIP/502-083b1650", "CALLERID(all)=Fabio SIP freePBX <502>") in new stack
+
a=silenceSupp:off - - - -
    -- 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
 
  
 +
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
---  onNewCallCreated ooh323c_o_2
+
  RECEIVE << 129.125.21.241:5060
---  find_call
+
BYE sip:502@129.125.71.173:5060 SIP/2.0
+++  find_call
+
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK455ac8a2
setting callid number 502
+
From: <sip:601@129.125.21.241>;tag=as6b0a877c
  Outgoing call 601(ooh323c_o_2) - Codec prefs - (gsm|ulaw)
+
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
        Adding capabilities to call(outgoing, ooh323c_o_2)
+
Contact: <sip:601@129.125.21.241>
        Adding gsm capability to call(outgoing, ooh323c_o_2)
+
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
        Adding g711 ulaw capability to call(outgoing, ooh323c_o_2)
+
CSeq: 102 BYE
---  configure_local_rtp
+
User-Agent: Asterisk PBX
+++  configure_local_rtp
+
Max-Forwards: 70
+++  onNewCallCreated ooh323c_o_2
+
Content-Length: 0
--- onAlerting ooh323c_o_2
+
---  find_call
+
SEND TIME: 1181420338
+++  find_call
+
SEND >> 129.125.21.241:5060
+++ onAlerting ooh323c_o_2
+
SIP/2.0 200 Ok
    -- OOH323/601-489a is ringing
+
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK455ac8a2
---  onCallEstablished ooh323c_o_2
+
From: <sip:601@129.125.21.241>;tag=as6b0a877c
---  find_call
+
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=96016429
+++  find_call
+
Contact: <sip:502@129.125.71.173:5060>
+++  onCallEstablished ooh323c_o_2
+
Call-ID: 01153AB1-744D-4A63-2A44-5883E53A4F10@129.125.71.173
    -- OOH323/601-489a answered SIP/502-083b1650
+
CSeq: 102 BYE
We're at 129.125.21.241 port 15850
+
Server: X-Lite release 1105d
Adding codec 0x4 (ulaw) to SDP
+
Content-Length: 0
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
 
  
 
+
== Gnomemeeting ==
--- (9 headers 0 lines)---
+
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
<-- SIP read from 129.125.71.173:5060:
+
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
--- (0 headers 0 lines) Nat keepalive ---
+
2007/03/12 14:04:17.384   0:00.122                gnomemeeting Detected the  following video input devices: No device found with plugin V4L
---   setup_rtp_connection
+
2007/03/12 14:04:17.902   0:00.640                gnomemeeting GnomeMeeting  version 1.2.3
---   find_call
+
2007/03/12 14:04:17.903   0:00.641                gnomemeeting OpenH323 version 1.18.0
+++   find_call
+
2007/03/12 14:04:17.903   0:00.641                gnomemeeting PWLIB version 1.10.2
+++   setup_rtp_connection
+
2007/03/12 14:04:17.904   0:00.642                gnomemeeting GNOME support  enabled
---   close_rtp_connection
+
2007/03/12 14:04:17.904   0:00.642                gnomemeeting Fullscreen support enabled
---   find_call
+
2007/03/12 14:04:17.905   0:00.643                gnomemeeting DBUS support disabled
+++   find_call
+
2007/03/12 14:04:17.905   0:00.643                gnomemeeting Quicknet  hardware support enabled
+++   close_rtp_connection
+
2007/03/12 14:04:17.907   0:00.645                gnomemeeting Set TCP port range to 3000030010
---   onCallCleared ooh323c_o_2
+
2007/03/12 14:04:17.908   0:00.646                gnomemeeting Set RTP port range to 50005016
---   find_call
+
2007/03/12 14:04:17.909   0:00.647                gnomemeeting Set UDP port range to 50205023
+++   find_call
+
2007/03/12 14:04:17.911   0:00.650                gnomemeeting H323    Added capability: MS-GSM{sw} <1>
---   ooh323_hangup
+
2007/03/12 14:04:17.912  0:00.651                gnomemeeting H323    Added capability: SpeexNarrow-15k{sw} <2>
    hanging 601
+
2007/03/12 14:04:17.913   0:00.651                gnomemeeting H323    Added capability: GSM-06.10{sw} <3>
+++   ooh323_hangup
+
2007/03/12 14:04:17.914  0:00.652                gnomemeeting H323    Added capability: SpeexNarrow-8k{sw} <4>
   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/502-083b1650' in macro 'dial'
+
2007/03/12 14:04:17.915   0:00.653                gnomemeeting H323    Added capability: G.726-32k{sw} <5>
   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/502-083b1650' in macro 'exten-vm'
+
2007/03/12 14:04:17.916  0:00.654                gnomemeeting H323    Added capability: G.711-uLaw-64k <6>
   == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/502-083b1650'
+
2007/03/12 14:04:17.917  0:00.655                gnomemeeting H323    Added capability: G.711-ALaw-64k <7>
Scheduling destruction of call '39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173' in 32000 ms
+
2007/03/12 14:04:17.917  0:00.655                gnomemeeting H323    Added capability: LPC-10{sw} <8>
set_destination: Parsing <sip:502@129.125.71.173:5060> for address/port to send to
+
2007/03/12 14:04:17.918  0:00.656                gnomemeeting H323    Added capability: G.726-40k{sw} <9>
set_destination: set destination to 129.125.71.173, port 5060
+
2007/03/12 14:04:17.919  0:00.657                gnomemeeting H323    Added  capability: G.726-24k{sw} <10>
Reliably Transmitting (no NAT) to 129.125.71.173:5060:
+
2007/03/12 14:04:17.920  0:00.658                gnomemeeting H323    Added  capability: G.726-16k{sw} <11>
BYE sip:502@129.125.71.173:5060 SIP/2.0
+
2007/03/12 14:04:17.921  0:00.659                gnomemeeting H323    Added capability: MS-IMA-ADPCM{sw} <12>
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK221b90c0
+
2007/03/12 14:04:17.922  0:00.660                gnomemeeting H323    Added capability: SpeexNarrow-5.95k{sw} <13>
From: <sip:601@129.125.21.241>;tag=as4618ff70
+
2007/03/12 14:04:17.923  0:00.661                gnomemeeting H323    Added capability: SpeexNarrow-11k{sw} <14>
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
+
2007/03/12 14:04:17.923  0:00.661                gnomemeeting H323    Added capability: SpeexNarrow-18.2k{sw} <15>
Contact: <sip:601@129.125.21.241>
+
2007/03/12 14:04:17.924  0:00.662                gnomemeeting H323    Added capability: SpeexNarrow-24.6k{sw} <16>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
+
2007/03/12 14:04:17.925  0:00.663                gnomemeeting H323    Added capability: SpeexIETFNarrow-5.95k{sw} <17>
CSeq: 102 BYE
+
2007/03/12 14:04:17.926  0:00.664                gnomemeeting H323    Added capability: SpeexIETFNarrow-8k{sw} <18>
User-Agent: Asterisk PBX
+
2007/03/12 14:04:17.927  0:00.665                gnomemeeting H323    Added capability: SpeexIETFNarrow-11k{sw} <19>
Max-Forwards: 70
+
2007/03/12 14:04:17.927  0:00.665                gnomemeeting H323    Added capability: SpeexIETFNarrow-15k{sw} <20>
Content-Length: 0
+
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>
<-- SIP read from 129.125.71.173:5060:
+
2007/03/12 14:04:17.941  0:00.679                gnomemeeting H323    Added capability: SpeexWide-28.6k{sw} <26>
SIP/2.0 200 Ok
+
2007/03/12 14:04:17.942  0:00.680                gnomemeeting H323    Added capability: SpeexIETFWide-11.55k{sw} <27>
Via: SIP/2.0/UDP 129.125.21.241:5060;branch=z9hG4bK221b90c0
+
  2007/03/12 14:04:17.952   0:00.690                gnomemeeting H323    Added capability: SpeexIETFWide-17.6k{sw} <28>
From: <sip:601@129.125.21.241>;tag=as4618ff70
+
  2007/03/12 14:04:17.953  0:00.691                gnomemeeting H323    Added capability: SpeexIETFWide-28.6k{sw} <29>
To: "Fabio - xlite@wingtip113" <sip:502@129.125.21.241>;tag=1975923204
+
  2007/03/12 14:04:17.954  0:00.692                gnomemeeting H323    Added capability: H.261-QCIF <30>
Contact: <sip:502@129.125.71.173:5060>
+
  2007/03/12 14:04:17.954  0:00.693                gnomemeeting H323    Added capability: H.261-CIF <31>
Call-ID: 39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173
+
2007/03/12 14:04:17.955  0:00.694                gnomemeeting H323    Added capability: UserInput/hookflash <32>
CSeq: 102 BYE
+
  2007/03/12 14:04:17.956  0:00.694                gnomemeeting H323    Added capability: UserInput/basicString <33>
Server: X-Lite release 1105d
+
2007/03/12 14:04:17.957  0:00.695                gnomemeeting H323    Added capability: UserInput/dtmf <34>
Content-Length: 0
+
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
--- (9 headers 0 lines)---
+
2007/03/12 14:04:18.023  0:00.762        H323 Listener:83f9db8 H323    Awaiting TCP connections on port 1720
Destroying call '39573BC3-AB5C-189A-DB2D-EFD3CDA07489@129.125.71.173'
+
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
---  ooh323_destroy
+
2007/03/12 14:04:18.028  0:00.766      GMH323Gate...r:083c3730 RAS      Authenticator CAT<no-pwd> not active during GRQ SetCapability negotiation
  Destroying 601
+
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
+++   ooh323_destroy
+
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
  == '''Gatekeeper''' ==
+
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
  == '''Xlite''' ==
+
2007/03/12 14:04:18.050  0:00.788      GMH323Gate...r:083c3730 RAS      Gatekeeper discovery found ip$129.125.71.171:1719
  == '''Gnomemeeting''' ==
+
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

Latest revision as of 16:02, 12 March 2007

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