Apr 7 14:08:23 VERBOSE[839] logger.c: Asterisk Event Logger restarted Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: New max nontrunk callno is 3 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Creating new call structure 2 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Received packet 0, (6, 1) Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: IAX subclass 1 received Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Sending 16 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Received packet 1, (6, 9) Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 0 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: IAX subclass 9 received Apr 7 14:08:44 VERBOSE[32654] logger.c: -- Accepting AUTHENTICATED call from 163.49.131.89: > requested format = ulaw, > requested prefs = (), > actual format = ulaw, > host prefs = (ulaw), > priority = mine Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Checking device state for device paul@paul Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Found peer. Now checking device state for peer paul Apr 7 14:08:44 DEBUG[32654] pbx.c: Changing state for IAX2/paul@paul - state 1 Apr 7 14:08:44 DEBUG[841] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' Apr 7 14:08:44 DEBUG[841] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Sending 19 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:44 DEBUG[842] pbx.c: Launching 'Dial' Apr 7 14:08:44 VERBOSE[842] logger.c: -- Executing Dial("IAX2/paul@paul-2", "H323/74307") in new stack Apr 7 14:08:44 DEBUG[842] chan_h323.c: type=H323, format=4, data=74307. Apr 7 14:08:44 DEBUG[842] chan_h323.c: Extension: Host: 74307 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.058 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.058 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.059 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.059 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 1 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.059 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.059 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 2 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.060 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.060 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 3 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.060 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.060 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.061 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.061 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.061 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 1 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.061 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.061 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 2 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.062 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.062 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 3 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.062 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.062 ThreadID=0x0062c019 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k" Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.063 ThreadID=0x0062c019 h323caps.cxx(2029) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.064 ThreadID=0x0062c019 h323caps.cxx(1952) H323 Removing capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.064 ThreadID=0x0062c019 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k" Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.064 ThreadID=0x0062c019 h323caps.cxx(2020) H323 FindCapability: "UserInput/hookflash" Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.064 ThreadID=0x0062c019 h323caps.cxx(2029) H323 Found capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.065 ThreadID=0x0062c019 h323caps.cxx(1952) H323 Removing capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.065 ThreadID=0x0062c019 h323caps.cxx(2020) H323 FindCapability: "UserInput/hookflash" Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.065 ThreadID=0x0062c019 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.065 ThreadID=0x0062c019 h323caps.cxx(2029) H323 Found capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.066 ThreadID=0x0062c019 h323caps.cxx(1952) H323 Removing capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.066 ThreadID=0x0062c019 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.066 ThreadID=0x0062c019 h323caps.cxx(1932) H323 Added capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.066 ThreadID=0x0062c019 h323caps.cxx(1932) H323 Added capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.067 ThreadID=0x0062c019 h323caps.cxx(1932) H323 Added capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.067 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.067 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.068 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.068 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 1 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.068 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.069 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 2 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.069 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Received packet 2, (6, 4) Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 1 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: IAX subclass 4 received Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.069 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 3 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.070 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: Allowed Codecs: Apr 7 14:08:44 VERBOSE[842] logger.c: Table: Apr 7 14:08:44 VERBOSE[842] logger.c: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: Set: Apr 7 14:08:44 VERBOSE[842] logger.c: 0: Apr 7 14:08:44 VERBOSE[842] logger.c: 0: Apr 7 14:08:44 VERBOSE[842] logger.c: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 1: Apr 7 14:08:44 VERBOSE[842] logger.c: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2: Apr 7 14:08:44 VERBOSE[842] logger.c: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Received packet 2, (2, 4) Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Ooh, voice format changed to 4 Apr 7 14:08:44 DEBUG[32654] channel.c: Set channel IAX2/paul@paul-2 to read format ulaw Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Sending 80 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: calc_rxstamp: call=2: rxcore set to 1112900924.205394 - 80ms Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: calc_rxstamp: call=2: works out as 1112900924.125394 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=80 Apr 7 14:08:44 DEBUG[842] channel.c: Not copying variable STACK-voip-h323-74307-1. Apr 7 14:08:44 DEBUG[842] chan_h323.c: Calling to 74307 on H323/74307 Apr 7 14:08:44 DEBUG[842] chan_h323.c: Placing outgoing call to 74307, 0 Apr 7 14:08:44 VERBOSE[842] logger.c: -- Making call to 74307 using gatekeeper. Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.106 ThreadID=0x0062c019 h323ep.cxx(1394) H323 Making call to: 74307 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.106 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.107 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.107 ThreadID=0x0062c019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.107 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 1 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.108 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.108 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 2 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.108 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.108 ThreadID=0x0062c019 h323caps.cxx(2004) H323 FindCapability: 3 Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.109 ThreadID=0x0062c019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.109 ThreadID=0x0062c019 rfc2833.cxx(81) RFC2833 Handler created Apr 7 14:08:44 VERBOSE[842] logger.c: == New H.323 Connection created. Apr 7 14:08:44 VERBOSE[842] logger.c: 2:12:21.110 ThreadID=0x0062c019 h323ep.cxx(1464) H323 Created new connection: ip$localhost/22421 Apr 7 14:08:44 VERBOSE[842] logger.c: -- CNFB is calling host 74307 Apr 7 14:08:44 VERBOSE[842] logger.c: --Call token is ip$localhost/22421 Apr 7 14:08:44 VERBOSE[842] logger.c: -- Call reference is 22421 Apr 7 14:08:44 VERBOSE[842] logger.c: -- DTMF Payload is 0 Apr 7 14:08:44 VERBOSE[842] logger.c: -- Called 74307 Apr 7 14:08:44 DEBUG[842] channel.c: Set channel H323/74307 to read format slin Apr 7 14:08:44 DEBUG[842] channel.c: Set channel IAX2/paul@paul-2 to write format slin Apr 7 14:08:44 DEBUG[842] channel.c: Set channel IAX2/paul@paul-2 to read format slin Apr 7 14:08:44 DEBUG[842] channel.c: Set channel H323/74307 to write format slin Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.112 H225 Caller:81c1e90 h323ep.cxx(762) H225 Started call thread Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=100 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.117 H225 Caller:81c1e90 h323trans.cxx(522) Trans Making request: admissionRequest Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.117 H225 Caller:81c1e90 h323pdu.cxx(517) Trans Sending PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: admissionRequest { Apr 7 14:08:44 VERBOSE[844] logger.c: requestSeqNum = 56977 Apr 7 14:08:44 VERBOSE[844] logger.c: callType = pointToPoint <> Apr 7 14:08:44 VERBOSE[844] logger.c: endpointIdentifier = 9 characters { Apr 7 14:08:44 VERBOSE[844] logger.c: 0035 0034 0031 0039 005f 0065 006e 0064 5419_end Apr 7 14:08:44 VERBOSE[844] logger.c: 0070 p Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: destinationInfo = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=dialedDigits "74307" Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: srcInfo = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=h323_ID 13 characters { Apr 7 14:08:44 VERBOSE[844] logger.c: 0050 0061 0075 006c 0020 0044 0061 0076 Paul Dav Apr 7 14:08:44 VERBOSE[844] logger.c: 0069 0064 0073 006f 006e idson Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: bandWidth = 100000 Apr 7 14:08:44 VERBOSE[844] logger.c: callReferenceValue = 22421 Apr 7 14:08:44 VERBOSE[844] logger.c: conferenceID = 16 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 3e c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 >.B!..........H. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: activeMC = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: answerCall = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: canMapAlias = TRUE Apr 7 14:08:44 VERBOSE[844] logger.c: callIdentifier = { Apr 7 14:08:44 VERBOSE[844] logger.c: guid = 16 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: gatekeeperIdentifier = 9 characters { Apr 7 14:08:44 VERBOSE[844] logger.c: 0047 006e 0075 0048 0033 0032 0033 0047 GnuH323G Apr 7 14:08:44 VERBOSE[844] logger.c: 004b K Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: willSupplyUUIEs = TRUE Apr 7 14:08:44 VERBOSE[844] logger.c: canMapSrcAlias = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.123 H225 Caller:81c1e90 h323trans.cxx(653) Trans Waiting on response to seqnum=56977 for 3.0 seconds Apr 7 14:08:44 VERBOSE[32643] logger.c: 2:12:21.125 Transactor:8137668 h323pdu.cxx(517) H225RAS Receiving PDU: Apr 7 14:08:44 VERBOSE[32643] logger.c: admissionConfirm { Apr 7 14:08:44 VERBOSE[32643] logger.c: requestSeqNum = 56977 Apr 7 14:08:44 VERBOSE[32643] logger.c: bandWidth = 100000 Apr 7 14:08:44 VERBOSE[32643] logger.c: callModel = direct <> Apr 7 14:08:44 VERBOSE[32643] logger.c: destCallSignalAddress = ipAddress { Apr 7 14:08:44 VERBOSE[32643] logger.c: ip = 4 octets { Apr 7 14:08:44 VERBOSE[32643] logger.c: c0 a8 de 14 .... Apr 7 14:08:44 VERBOSE[32643] logger.c: } Apr 7 14:08:44 VERBOSE[32643] logger.c: port = 1657 Apr 7 14:08:44 VERBOSE[32643] logger.c: } Apr 7 14:08:44 VERBOSE[32643] logger.c: irrFrequency = 120 Apr 7 14:08:44 VERBOSE[32643] logger.c: willRespondToIRR = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: uuiesRequested = { Apr 7 14:08:44 VERBOSE[32643] logger.c: setup = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: callProceeding = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: connect = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: alerting = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: information = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: releaseComplete = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: facility = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: progress = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: empty = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: status = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: statusInquiry = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: setupAcknowledge = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: notify = FALSE Apr 7 14:08:44 VERBOSE[32643] logger.c: } Apr 7 14:08:44 VERBOSE[32643] logger.c: } Apr 7 14:08:44 VERBOSE[32643] logger.c: 2:12:21.127 Transactor:8137668 h323trans.cxx(595) Trans Using credentials from request: H235AnnexD_Procedure1,MD5,CAT Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.129 H225 Caller:81c1e90 h323.cxx(4610) H323 Bandwidth used: 0 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.130 H225 Caller:81c1e90 transports.cxx(1600) H323TCP Connecting to 192.168.222.20:1657 (local port=0) Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.131 H225 Caller:81c1e90 transports.cxx(1501) H323TCP Started connection: host=192.168.222.20:1657, if=163.49.131.154:34526, handle=46 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.131 H225 Caller:81c1e90 h323.cxx(2945) H225 Sending Setup PDU Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.131 H225 Caller:81c1e90 h323.cxx(2960) H225 Check for Fast start by local endpoint Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.132 H225 Caller:81c1e90 h323.cxx(4154) H245 Default OnSelectLogicalChannels, FastStartInitiate Apr 7 14:08:44 DEBUG[844] chan_h323.c: Sending RTP 'US' 163.49.131.154:12004 Apr 7 14:08:44 DEBUG[844] chan_h323.c: Sending RTP 'US' 163.49.131.154:12004 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.133 H225 Caller:81c1e90 h323.cxx(2966) H225 Fast start begun by local endpoint Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.134 H225 Caller:81c1e90 channels.cxx(912) H323RTP OnSendingPDU Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.135 H225 Caller:81c1e90 h323.cxx(1970) H225 Build fastStart: Apr 7 14:08:44 VERBOSE[844] logger.c: { Apr 7 14:08:44 VERBOSE[844] logger.c: forwardLogicalChannelNumber = 1 Apr 7 14:08:44 VERBOSE[844] logger.c: forwardLogicalChannelParameters = { Apr 7 14:08:44 VERBOSE[844] logger.c: dataType = nullData <> Apr 7 14:08:44 VERBOSE[844] logger.c: multiplexParameters = none <> Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: reverseLogicalChannelParameters = { Apr 7 14:08:44 VERBOSE[844] logger.c: dataType = audioData g711Ulaw64k 20 Apr 7 14:08:44 VERBOSE[844] logger.c: multiplexParameters = h2250LogicalChannelParameters { Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=120 Apr 7 14:08:44 VERBOSE[844] logger.c: sessionID = 1 Apr 7 14:08:44 VERBOSE[844] logger.c: mediaChannel = unicastAddress iPAddress { Apr 7 14:08:44 VERBOSE[844] logger.c: network = 4 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: a3 31 83 9a .1.. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: tsapIdentifier = 12004 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: mediaGuaranteedDelivery = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: mediaControlChannel = unicastAddress iPAddress { Apr 7 14:08:44 VERBOSE[844] logger.c: network = 4 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: a3 31 83 9a .1.. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: tsapIdentifier = 12005 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: silenceSuppression = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.140 H225 Caller:81c1e90 h323.cxx(1975) H225 Built fastStart for G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.141 H225 Caller:81c1e90 channels.cxx(912) H323RTP OnSendingPDU Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.142 H225 Caller:81c1e90 h323.cxx(1970) H225 Build fastStart: Apr 7 14:08:44 VERBOSE[844] logger.c: { Apr 7 14:08:44 VERBOSE[844] logger.c: forwardLogicalChannelNumber = 101 Apr 7 14:08:44 VERBOSE[844] logger.c: forwardLogicalChannelParameters = { Apr 7 14:08:44 VERBOSE[844] logger.c: dataType = audioData g711Ulaw64k 20 Apr 7 14:08:44 VERBOSE[844] logger.c: multiplexParameters = h2250LogicalChannelParameters { Apr 7 14:08:44 VERBOSE[844] logger.c: sessionID = 1 Apr 7 14:08:44 VERBOSE[844] logger.c: mediaGuaranteedDelivery = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: mediaControlChannel = unicastAddress iPAddress { Apr 7 14:08:44 VERBOSE[844] logger.c: network = 4 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: a3 31 83 9a .1.. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: tsapIdentifier = 12005 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: silenceSuppression = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.145 H225 Caller:81c1e90 h323.cxx(1975) H225 Built fastStart for G.711-uLaw-64k <1> Apr 7 14:08:44 VERBOSE[844] logger.c: -- Sending SETUP message Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.146 H225 Caller:81c1e90 h323.cxx(3328) H245 Started control channel Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.146 H225 Caller:81c1e90 h323neg.cxx(549) H245 Sending TerminalCapabilitySet: outSeq=1 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.149 H225 Caller:81c1e90 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.149 H225 Caller:81c1e90 h323caps.cxx(2029) H323 Found capability: UserInput/RFC2833 <3> Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.149 H225 Caller:81c1e90 h323.cxx(3975) H323 User Input RFC2833 payload type set to [pt=101] Apr 7 14:08:44 DEBUG[844] chan_h323.c: Setting DTMF payload to 0 on ip$localhost/22421 Apr 7 14:08:44 DEBUG[844] chan_h323.c: DTMF payload on ip$localhost/22421 set to 0 Apr 7 14:08:44 VERBOSE[844] logger.c: -- Transmitting RFC2833 on payload 96 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.150 H225 Caller:81c1e90 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: request terminalCapabilitySet { Apr 7 14:08:44 VERBOSE[844] logger.c: sequenceNumber = 1 Apr 7 14:08:44 VERBOSE[844] logger.c: protocolIdentifier = 0.0.8.245.0.7 Apr 7 14:08:44 VERBOSE[844] logger.c: multiplexCapability = h2250Capability { Apr 7 14:08:44 VERBOSE[844] logger.c: maximumAudioDelayJitter = 250 Apr 7 14:08:44 VERBOSE[844] logger.c: receiveMultipointCapability = { Apr 7 14:08:44 VERBOSE[844] logger.c: multicastCapability = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: multiUniCastConference = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedControl = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedControl = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedAudio = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedAudio = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedVideo = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedVideo = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: transmitMultipointCapability = { Apr 7 14:08:44 VERBOSE[844] logger.c: multicastCapability = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: multiUniCastConference = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedControl = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedControl = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedAudio = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedAudio = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedVideo = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedVideo = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: receiveAndTransmitMultipointCapability = { Apr 7 14:08:44 VERBOSE[844] logger.c: multicastCapability = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: multiUniCastConference = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedControl = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedControl = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedAudio = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: distributedAudio = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedVideo = FALSE Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=140 Apr 7 14:08:44 VERBOSE[844] logger.c: distributedVideo = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: mcCapability = { Apr 7 14:08:44 VERBOSE[844] logger.c: centralizedConferenceMC = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: decentralizedConferenceMC = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: rtcpVideoControlCapability = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: mediaPacketizationCapability = { Apr 7 14:08:44 VERBOSE[844] logger.c: h261aVideoPacketization = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: logicalChannelSwitchingCapability = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: t120DynamicPortCapability = TRUE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: capabilityTable = 3 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:44 VERBOSE[844] logger.c: capabilityTableEntryNumber = 1 Apr 7 14:08:44 VERBOSE[844] logger.c: capability = receiveAudioCapability g711Ulaw64k 240 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: [1]={ Apr 7 14:08:44 VERBOSE[844] logger.c: capabilityTableEntryNumber = 2 Apr 7 14:08:44 VERBOSE[844] logger.c: capability = receiveUserInputCapability hookflash <> Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: [2]={ Apr 7 14:08:44 VERBOSE[844] logger.c: capabilityTableEntryNumber = 3 Apr 7 14:08:44 VERBOSE[844] logger.c: capability = receiveRTPAudioTelephonyEventCapability { Apr 7 14:08:44 VERBOSE[844] logger.c: dynamicRTPPayloadType = 96 Apr 7 14:08:44 VERBOSE[844] logger.c: audioTelephoneEvent = "0-16" Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: capabilityDescriptors = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:44 VERBOSE[844] logger.c: capabilityDescriptorNumber = 1 Apr 7 14:08:44 VERBOSE[844] logger.c: simultaneousCapabilities = 3 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=1 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: [1]=1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=2 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: [2]=1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=3 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.166 H225 Caller:81c1e90 h323neg.cxx(317) H245 Sending MasterSlaveDetermination Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.167 H225 Caller:81c1e90 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: request masterSlaveDetermination { Apr 7 14:08:44 VERBOSE[844] logger.c: terminalType = 50 Apr 7 14:08:44 VERBOSE[844] logger.c: statusDeterminationNumber = 4013988 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.168 H225 Caller:81c1e90 h323pdu.cxx(517) H225 Sending PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: { Apr 7 14:08:44 VERBOSE[844] logger.c: q931pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: protocolDiscriminator = 8 Apr 7 14:08:44 VERBOSE[844] logger.c: callReference = 22421 Apr 7 14:08:44 VERBOSE[844] logger.c: from = originator Apr 7 14:08:44 VERBOSE[844] logger.c: messageType = Setup Apr 7 14:08:44 VERBOSE[844] logger.c: IE: Bearer-Capability = { Apr 7 14:08:44 VERBOSE[844] logger.c: 80 90 a5 ... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: IE: Progress-Indicator = { Apr 7 14:08:44 VERBOSE[844] logger.c: 80 e4 .. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: IE: Display = { Apr 7 14:08:44 VERBOSE[844] logger.c: 50 61 75 6c 20 44 61 76 69 64 73 6f 6e 00 Paul Davidson. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: IE: Calling-Party-Number = { Apr 7 14:08:44 VERBOSE[844] logger.c: 81 34 33 30 37 .4307 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: IE: Called-Party-Number = { Apr 7 14:08:44 VERBOSE[844] logger.c: 81 37 34 33 30 37 .74307 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: IE: User-User = { Apr 7 14:08:44 VERBOSE[844] logger.c: 20 b8 06 00 08 91 4a 00 04 02 40 03 00 43 00 4e .....J...@..C.N Apr 7 14:08:44 VERBOSE[844] logger.c: 00 46 00 42 01 80 77 ba 22 c0 09 00 00 3d 37 54 .F.B..w."....=7T Apr 7 14:08:44 VERBOSE[844] logger.c: ... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: h225pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: h323_uu_pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: h323_message_body = setup { Apr 7 14:08:44 VERBOSE[844] logger.c: protocolIdentifier = 0.0.8.2250.0.4 Apr 7 14:08:44 VERBOSE[844] logger.c: sourceAddress = 2 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=h323_ID 4 characters { Apr 7 14:08:44 VERBOSE[844] logger.c: 0043 004e 0046 0042 CNFB Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: [1]=dialedDigits "4487" Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: sourceInfo = { Apr 7 14:08:44 VERBOSE[844] logger.c: vendor = { Apr 7 14:08:44 VERBOSE[844] logger.c: vendor = { Apr 7 14:08:44 VERBOSE[844] logger.c: t35CountryCode = 9 Apr 7 14:08:44 VERBOSE[844] logger.c: t35Extension = 0 Apr 7 14:08:44 VERBOSE[844] logger.c: manufacturerCode = 61 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: productId = 56 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo Apr 7 14:08:44 VERBOSE[844] logger.c: 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann Apr 7 14:08:44 VERBOSE[844] logger.c: ... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: versionId = 26 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 31 2e 30 2e 30 20 28 4f 70 65 6e 48 33 32 33 20 1.0.0 (OpenH323 Apr 7 14:08:44 VERBOSE[844] logger.c: 76 31 2e 31 35 2e 31 29 00 00 v1.15.1).. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: terminal = { Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: mc = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: undefinedNode = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=160 Apr 7 14:08:44 VERBOSE[844] logger.c: destinationAddress = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]=dialedDigits "74307" Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: destCallSignalAddress = ipAddress { Apr 7 14:08:44 VERBOSE[844] logger.c: ip = 4 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: c0 a8 de 14 .... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: port = 1657 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: activeMC = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: conferenceID = 16 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 3e c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 >.B!..........H. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: conferenceGoal = create <> Apr 7 14:08:44 VERBOSE[844] logger.c: callType = pointToPoint <> Apr 7 14:08:44 VERBOSE[844] logger.c: sourceCallSignalAddress = ipAddress { Apr 7 14:08:44 VERBOSE[844] logger.c: ip = 4 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: a3 31 83 9a .1.. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: port = 34526 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: callIdentifier = { Apr 7 14:08:44 VERBOSE[844] logger.c: guid = 16 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: fastStart = 2 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]= 30 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 40 00 00 06 04 01 00 4c 60 13 80 12 1d 00 01 00 @......L`....... Apr 7 14:08:44 VERBOSE[844] logger.c: a3 31 83 9a 2e e4 00 a3 31 83 9a 2e e5 00 .1......1..... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: [1]= 19 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 00 00 64 0c 60 13 80 0b 0d 00 01 00 a3 31 83 9a ..d.`........1.. Apr 7 14:08:44 VERBOSE[844] logger.c: 2e e5 00 ... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: mediaWaitForConnect = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: canOverlapSend = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: multipleCalls = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: maintainConnection = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: parallelH245Control = 2 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]= 68 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 02 70 01 06 00 08 81 75 00 07 80 13 80 00 fa 00 .p.....u........ Apr 7 14:08:44 VERBOSE[844] logger.c: 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 02 ................ Apr 7 14:08:44 VERBOSE[844] logger.c: ... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: [1]= 7 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 01 00 32 80 3d 3f a4 ..2.=?. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: h245Tunneling = TRUE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.186 H225 Caller:81c1e90 h323.cxx(1705) H225 Reading PDUs: callRef=22421 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=180 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=200 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=220 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=240 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=260 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.291 H225 Caller:81c1e90 h323pdu.cxx(517) H225 Receiving PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: { Apr 7 14:08:44 VERBOSE[844] logger.c: q931pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: protocolDiscriminator = 8 Apr 7 14:08:44 VERBOSE[844] logger.c: callReference = 22421 Apr 7 14:08:44 VERBOSE[844] logger.c: from = destination Apr 7 14:08:44 VERBOSE[844] logger.c: messageType = CallProceeding Apr 7 14:08:44 VERBOSE[844] logger.c: IE: User-User = { Apr 7 14:08:44 VERBOSE[844] logger.c: 21 80 06 00 08 91 4a 00 02 02 01 20 11 00 20 c7 !.....J.... .. . Apr 7 14:08:44 VERBOSE[844] logger.c: 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 06 a0 B!..........H... Apr 7 14:08:44 VERBOSE[844] logger.c: ... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: h225pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: h323_uu_pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: h323_message_body = callProceeding { Apr 7 14:08:44 VERBOSE[844] logger.c: protocolIdentifier = 0.0.8.2250.0.2 Apr 7 14:08:44 VERBOSE[844] logger.c: destinationInfo = { Apr 7 14:08:44 VERBOSE[844] logger.c: terminal = { Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: mc = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: undefinedNode = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: callIdentifier = { Apr 7 14:08:44 VERBOSE[844] logger.c: guid = 16 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: h245Tunneling = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: nonStandardControl = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:44 VERBOSE[844] logger.c: nonStandardIdentifier = h221NonStandard { Apr 7 14:08:44 VERBOSE[844] logger.c: t35CountryCode = 181 Apr 7 14:08:44 VERBOSE[844] logger.c: t35Extension = 0 Apr 7 14:08:44 VERBOSE[844] logger.c: manufacturerCode = 18 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: data = 7 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 80 44 04 00 01 01 00 .D..... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.298 H225 Caller:81c1e90 h323.cxx(1758) H225 Handling PDU: CallProceeding callRef=22421 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=280 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.299 H225 Caller:81c1e90 h323neg.cxx(334) H245 Stopping MasterSlaveDetermination: state=Outgoing Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.299 H225 Caller:81c1e90 h323neg.cxx(561) H245 Stopping TerminalCapabilitySet: state=InProgress Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.299 H225 Caller:81c1e90 h323.cxx(1798) H225 Set remote application name: "Cisco IOS 12.x 181/18" Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.300 H225 Caller:81c1e90 h323.cxx(2018) H225 Set protocol version to 2 and implying H.245 version 3 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.300 H225 Caller:81c1e90 h323.cxx(2245) H225 Set remote party name: "74307" Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.300 H225 Caller:81c1e90 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.302 H225 Caller:81c1e90 h323pdu.cxx(517) H225 Receiving PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: { Apr 7 14:08:44 VERBOSE[844] logger.c: q931pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: protocolDiscriminator = 8 Apr 7 14:08:44 VERBOSE[844] logger.c: callReference = 22421 Apr 7 14:08:44 VERBOSE[844] logger.c: from = destination Apr 7 14:08:44 VERBOSE[844] logger.c: messageType = Alerting Apr 7 14:08:44 VERBOSE[844] logger.c: IE: User-User = { Apr 7 14:08:44 VERBOSE[844] logger.c: 23 80 06 00 08 91 4a 00 02 02 01 20 11 00 20 c7 #.....J.... .. . Apr 7 14:08:44 VERBOSE[844] logger.c: 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 06 a0 B!..........H... Apr 7 14:08:44 VERBOSE[844] logger.c: ... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: h225pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: h323_uu_pdu = { Apr 7 14:08:44 VERBOSE[844] logger.c: h323_message_body = alerting { Apr 7 14:08:44 VERBOSE[844] logger.c: protocolIdentifier = 0.0.8.2250.0.2 Apr 7 14:08:44 VERBOSE[844] logger.c: destinationInfo = { Apr 7 14:08:44 VERBOSE[844] logger.c: terminal = { Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: mc = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: undefinedNode = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: callIdentifier = { Apr 7 14:08:44 VERBOSE[844] logger.c: guid = 16 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: h245Tunneling = FALSE Apr 7 14:08:44 VERBOSE[844] logger.c: nonStandardControl = 1 entries { Apr 7 14:08:44 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:44 VERBOSE[844] logger.c: nonStandardIdentifier = h221NonStandard { Apr 7 14:08:44 VERBOSE[844] logger.c: t35CountryCode = 181 Apr 7 14:08:44 VERBOSE[844] logger.c: t35Extension = 0 Apr 7 14:08:44 VERBOSE[844] logger.c: manufacturerCode = 18 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: data = 7 octets { Apr 7 14:08:44 VERBOSE[844] logger.c: 80 44 04 00 01 01 00 .D..... Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.310 H225 Caller:81c1e90 h323.cxx(1758) H225 Handling PDU: Alerting callRef=22421 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.311 H225 Caller:81c1e90 h323.cxx(2018) H225 Set protocol version to 2 and implying H.245 version 3 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.311 H225 Caller:81c1e90 h323.cxx(2245) H225 Set remote party name: "74307" Apr 7 14:08:44 VERBOSE[844] logger.c: =-= In OnAlerting for call 22421: sessionId=24948 Apr 7 14:08:44 VERBOSE[844] logger.c: -- Ringing phone for "74307" Apr 7 14:08:44 VERBOSE[844] logger.c: - Progress Indicator: 0 Apr 7 14:08:44 DEBUG[844] chan_h323.c: Received ALERT/PROGRESS message for self-generated tones Apr 7 14:08:44 VERBOSE[842] logger.c: -- H323/74307 is ringing Apr 7 14:08:44 DEBUG[842] chan_iax2.c: Indicating condition 3 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Sending 22 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:44 DEBUG[844] chan_h323.c: Ringing on ip$localhost/22421 Apr 7 14:08:44 DEBUG[844] pbx.c: Changing state for H323/74307 - state 2 Apr 7 14:08:44 DEBUG[845] app_queue.c: Device 'H323/74307' changed to state '2' Apr 7 14:08:44 VERBOSE[842] logger.c: -- H323/74307 is ringing Apr 7 14:08:44 DEBUG[845] app_queue.c: Device 'H323/74307' changed to state '2' Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.313 H225 Caller:81c1e90 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.315 H225 Caller:81c1e90 h323pdu.cxx(1243) H225 No Q931 User-User Information Element, Apr 7 14:08:44 VERBOSE[844] logger.c: Raw PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: 08 02 d7 95 6e 27 01 f1 28 0d 50 61 75 6c 20 44 ....n'..(.Paul D Apr 7 14:08:44 VERBOSE[844] logger.c: 61 76 69 64 73 6f 6e 4c 05 80 34 33 30 37 avidsonL..4307 Apr 7 14:08:44 VERBOSE[844] logger.c: Q.931 PDU: Apr 7 14:08:44 VERBOSE[844] logger.c: { Apr 7 14:08:44 VERBOSE[844] logger.c: protocolDiscriminator = 8 Apr 7 14:08:44 VERBOSE[844] logger.c: callReference = 22421 Apr 7 14:08:44 VERBOSE[844] logger.c: from = destination Apr 7 14:08:44 VERBOSE[844] logger.c: messageType = <110> Apr 7 14:08:44 VERBOSE[844] logger.c: IE: 0x27 (39) = { Apr 7 14:08:44 VERBOSE[844] logger.c: f1 . Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=300 Apr 7 14:08:44 VERBOSE[844] logger.c: IE: Display = { Apr 7 14:08:44 VERBOSE[844] logger.c: 50 61 75 6c 20 44 61 76 69 64 73 6f 6e Paul Davidson Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: IE: Connected-Number = { Apr 7 14:08:44 VERBOSE[844] logger.c: 80 34 33 30 37 .4307 Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: } Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.319 H225 Caller:81c1e90 h323.cxx(1758) H225 Handling PDU: <110> callRef=22421 Apr 7 14:08:44 VERBOSE[844] logger.c: 2:12:21.319 H225 Caller:81c1e90 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Received packet 3, (6, 4) Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 2 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: IAX subclass 4 received Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=320 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=340 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=360 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=380 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=400 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=420 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=440 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=460 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=480 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=500 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=520 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=540 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=560 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=580 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=600 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=620 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=640 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=660 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=680 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=700 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=720 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=740 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=760 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=780 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=800 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=820 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=840 Apr 7 14:08:44 DEBUG[32654] chan_iax2.c: For call=2, set last=860 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=880 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=900 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=920 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=940 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=960 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=980 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1000 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1020 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1040 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1060 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1080 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1100 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1120 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1140 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1160 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1180 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1200 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1220 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1240 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1260 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1280 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1300 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1320 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1340 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1360 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1380 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1400 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1420 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1440 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1460 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1480 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1500 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1520 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1540 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1560 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1580 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1600 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1620 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1640 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1660 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1680 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1700 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1720 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1740 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1760 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1780 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1800 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1820 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1840 Apr 7 14:08:45 DEBUG[32654] chan_iax2.c: For call=2, set last=1860 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=1880 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=1900 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=1920 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=1940 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=1960 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=1980 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2000 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: Received packet 3, (6, 2) Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: IAX subclass 2 received Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: Sending 2004 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: Received packet 4, (6, 4) Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 3 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: IAX subclass 4 received Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2020 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2040 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2060 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2080 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2100 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2120 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2140 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2160 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2180 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2200 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2220 Apr 7 14:08:46 VERBOSE[32642] logger.c: 2:12:23.254 GkMonitor:8137f78 transports.cxx(989) TCP Appending H.225 transport ip$163.49.131.154:1720 using associated transport Transport[remote=ip$163.49.131.112:1719 if=ip$127.0.0.1:33302] Apr 7 14:08:46 VERBOSE[32642] logger.c: 2:12:23.255 GkMonitor:8137f78 h323.cxx(4610) H323 Bandwidth used: 0 Apr 7 14:08:46 VERBOSE[32642] logger.c: 2:12:23.255 GkMonitor:8137f78 gkclient.cxx(1772) RAS Sending unsolicited IRR and without acknowledgement Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2240 Apr 7 14:08:46 VERBOSE[32642] logger.c: 2:12:23.255 GkMonitor:8137f78 h323pdu.cxx(517) Trans Sending PDU: Apr 7 14:08:46 VERBOSE[32642] logger.c: infoRequestResponse { Apr 7 14:08:46 VERBOSE[32642] logger.c: requestSeqNum = 56978 Apr 7 14:08:46 VERBOSE[32642] logger.c: endpointType = { Apr 7 14:08:46 VERBOSE[32642] logger.c: vendor = { Apr 7 14:08:46 VERBOSE[32642] logger.c: vendor = { Apr 7 14:08:46 VERBOSE[32642] logger.c: t35CountryCode = 9 Apr 7 14:08:46 VERBOSE[32642] logger.c: t35Extension = 0 Apr 7 14:08:46 VERBOSE[32642] logger.c: manufacturerCode = 61 Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: productId = 56 octets { Apr 7 14:08:46 VERBOSE[32642] logger.c: 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo Apr 7 14:08:46 VERBOSE[32642] logger.c: 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann Apr 7 14:08:46 VERBOSE[32642] logger.c: ... Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: versionId = 26 octets { Apr 7 14:08:46 VERBOSE[32642] logger.c: 31 2e 30 2e 30 20 28 4f 70 65 6e 48 33 32 33 20 1.0.0 (OpenH323 Apr 7 14:08:46 VERBOSE[32642] logger.c: 76 31 2e 31 35 2e 31 29 00 00 v1.15.1).. Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: terminal = { Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: mc = FALSE Apr 7 14:08:46 VERBOSE[32642] logger.c: undefinedNode = FALSE Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: endpointIdentifier = 9 characters { Apr 7 14:08:46 VERBOSE[32642] logger.c: 0035 0034 0031 0039 005f 0065 006e 0064 5419_end Apr 7 14:08:46 VERBOSE[32642] logger.c: 0070 p Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: rasAddress = ipAddress { Apr 7 14:08:46 VERBOSE[32642] logger.c: ip = 4 octets { Apr 7 14:08:46 VERBOSE[32642] logger.c: a3 31 83 9a .1.. Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: port = 33302 Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: callSignalAddress = 1 entries { Apr 7 14:08:46 VERBOSE[32642] logger.c: [0]=ipAddress { Apr 7 14:08:46 VERBOSE[32642] logger.c: ip = 4 octets { Apr 7 14:08:46 VERBOSE[32642] logger.c: a3 31 83 9a .1.. Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: port = 1720 Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: endpointAlias = 2 entries { Apr 7 14:08:46 VERBOSE[32642] logger.c: [0]=h323_ID 4 characters { Apr 7 14:08:46 VERBOSE[32642] logger.c: 0043 004e 0046 0042 CNFB Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: [1]=dialedDigits "4487" Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: perCallInfo = 1 entries { Apr 7 14:08:46 VERBOSE[32642] logger.c: [0]={ Apr 7 14:08:46 VERBOSE[32642] logger.c: callReferenceValue = 22421 Apr 7 14:08:46 VERBOSE[32642] logger.c: conferenceID = 16 octets { Apr 7 14:08:46 VERBOSE[32642] logger.c: 3e c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 >.B!..........H. Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: originator = TRUE Apr 7 14:08:46 VERBOSE[32642] logger.c: h245 = { Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: callSignaling = { Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: callType = pointToPoint <> Apr 7 14:08:46 VERBOSE[32642] logger.c: bandWidth = 0 Apr 7 14:08:46 VERBOSE[32642] logger.c: callModel = direct <> Apr 7 14:08:46 VERBOSE[32642] logger.c: callIdentifier = { Apr 7 14:08:46 VERBOSE[32642] logger.c: guid = 16 octets { Apr 7 14:08:46 VERBOSE[32642] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: substituteConfIDs = 0 entries { Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: usageInformation = { Apr 7 14:08:46 VERBOSE[32642] logger.c: nonStandardUsageFields = 0 entries { Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: alertingTime = 1112900924 Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 VERBOSE[32642] logger.c: needResponse = FALSE Apr 7 14:08:46 VERBOSE[32642] logger.c: unsolicited = TRUE Apr 7 14:08:46 VERBOSE[32642] logger.c: } Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2260 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2280 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2300 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2320 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2340 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2360 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2380 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2400 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2420 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2440 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2460 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2480 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2500 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2520 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2540 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2560 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2580 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2600 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2620 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2640 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2660 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2680 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2700 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2720 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2740 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2760 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2780 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2800 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2820 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2840 Apr 7 14:08:46 DEBUG[32654] chan_iax2.c: For call=2, set last=2860 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=2880 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=2900 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=2920 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=2940 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=2960 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=2980 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3000 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3020 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3040 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3060 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3080 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3100 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3120 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3140 Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.172 H225 Caller:81c1e90 h323pdu.cxx(517) H225 Receiving PDU: Apr 7 14:08:47 VERBOSE[844] logger.c: { Apr 7 14:08:47 VERBOSE[844] logger.c: q931pdu = { Apr 7 14:08:47 VERBOSE[844] logger.c: protocolDiscriminator = 8 Apr 7 14:08:47 VERBOSE[844] logger.c: callReference = 22421 Apr 7 14:08:47 VERBOSE[844] logger.c: from = destination Apr 7 14:08:47 VERBOSE[844] logger.c: messageType = Connect Apr 7 14:08:47 VERBOSE[844] logger.c: IE: Display = { Apr 7 14:08:47 VERBOSE[844] logger.c: 50 61 75 6c 20 44 61 76 69 64 73 6f 6e Paul Davidson Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: IE: User-User = { Apr 7 14:08:47 VERBOSE[844] logger.c: 22 c0 06 00 08 91 4a 00 02 00 c0 a8 de 14 06 97 ".....J......... Apr 7 14:08:47 VERBOSE[844] logger.c: 02 00 3e c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 ..>.B!.......... Apr 7 14:08:47 VERBOSE[844] logger.c: ... Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: h225pdu = { Apr 7 14:08:47 VERBOSE[844] logger.c: h323_uu_pdu = { Apr 7 14:08:47 VERBOSE[844] logger.c: h323_message_body = connect { Apr 7 14:08:47 VERBOSE[844] logger.c: protocolIdentifier = 0.0.8.2250.0.2 Apr 7 14:08:47 VERBOSE[844] logger.c: h245Address = ipAddress { Apr 7 14:08:47 VERBOSE[844] logger.c: ip = 4 octets { Apr 7 14:08:47 VERBOSE[844] logger.c: c0 a8 de 14 .... Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: port = 1687 Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: destinationInfo = { Apr 7 14:08:47 VERBOSE[844] logger.c: terminal = { Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: mc = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: undefinedNode = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: conferenceID = 16 octets { Apr 7 14:08:47 VERBOSE[844] logger.c: 3e c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 >.B!..........H. Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: callIdentifier = { Apr 7 14:08:47 VERBOSE[844] logger.c: guid = 16 octets { Apr 7 14:08:47 VERBOSE[844] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: h245Tunneling = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: nonStandardControl = 1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[844] logger.c: nonStandardIdentifier = h221NonStandard { Apr 7 14:08:47 VERBOSE[844] logger.c: t35CountryCode = 181 Apr 7 14:08:47 VERBOSE[844] logger.c: t35Extension = 0 Apr 7 14:08:47 VERBOSE[844] logger.c: manufacturerCode = 18 Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: data = 7 octets { Apr 7 14:08:47 VERBOSE[844] logger.c: 80 44 04 00 01 01 00 .D..... Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.175 H225 Caller:81c1e90 h323.cxx(1758) H225 Handling PDU: Connect callRef=22421 Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.175 H225 Caller:81c1e90 h323.cxx(2018) H225 Set protocol version to 2 and implying H.245 version 3 Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.175 H225 Caller:81c1e90 h323.cxx(2245) H225 Set remote party name: "Paul Davidson" Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.175 H225 Caller:81c1e90 h323ep.cxx(2061) H225 Received connect PDU. Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.175 H225 Caller:81c1e90 transports.cxx(1600) H323TCP Connecting to 192.168.222.20:1687 (local port=0) Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.176 H225 Caller:81c1e90 transports.cxx(1501) H323TCP Started connection: host=192.168.222.20:1687, if=163.49.131.154:34527, handle=47 Apr 7 14:08:47 VERBOSE[844] logger.c: ExternalRTPChannel Destroyed Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.177 H225 Caller:81c1e90 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 10000.0kb/s Apr 7 14:08:47 VERBOSE[844] logger.c: ExternalRTPChannel Destroyed Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.177 H225 Caller:81c1e90 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 10000.0kb/s Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.177 H225 Caller:81c1e90 h323.cxx(3328) H245 Started control channel Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.177 H225 Caller:81c1e90 h323neg.cxx(549) H245 Sending TerminalCapabilitySet: outSeq=2 Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.179 H225 Caller:81c1e90 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.179 H225 Caller:81c1e90 h323caps.cxx(2029) H323 Found capability: UserInput/RFC2833 <3> Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.180 H225 Caller:81c1e90 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:47 VERBOSE[844] logger.c: request terminalCapabilitySet { Apr 7 14:08:47 VERBOSE[844] logger.c: sequenceNumber = 2 Apr 7 14:08:47 VERBOSE[844] logger.c: protocolIdentifier = 0.0.8.245.0.7 Apr 7 14:08:47 VERBOSE[844] logger.c: multiplexCapability = h2250Capability { Apr 7 14:08:47 VERBOSE[844] logger.c: maximumAudioDelayJitter = 250 Apr 7 14:08:47 VERBOSE[844] logger.c: receiveMultipointCapability = { Apr 7 14:08:47 VERBOSE[844] logger.c: multicastCapability = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: multiUniCastConference = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedControl = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedControl = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedAudio = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedAudio = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedVideo = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedVideo = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: transmitMultipointCapability = { Apr 7 14:08:47 VERBOSE[844] logger.c: multicastCapability = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: multiUniCastConference = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedControl = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedControl = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedAudio = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedAudio = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedVideo = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedVideo = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: receiveAndTransmitMultipointCapability = { Apr 7 14:08:47 VERBOSE[844] logger.c: multicastCapability = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: multiUniCastConference = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedControl = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedControl = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedAudio = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedAudio = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedVideo = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: distributedVideo = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: mcCapability = { Apr 7 14:08:47 VERBOSE[844] logger.c: centralizedConferenceMC = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: decentralizedConferenceMC = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: rtcpVideoControlCapability = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: mediaPacketizationCapability = { Apr 7 14:08:47 VERBOSE[844] logger.c: h261aVideoPacketization = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: logicalChannelSwitchingCapability = FALSE Apr 7 14:08:47 VERBOSE[844] logger.c: t120DynamicPortCapability = TRUE Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: capabilityTable = 2 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[844] logger.c: capabilityTableEntryNumber = 1 Apr 7 14:08:47 VERBOSE[844] logger.c: capability = receiveAudioCapability g711Ulaw64k 240 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3160 Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: [1]={ Apr 7 14:08:47 VERBOSE[844] logger.c: capabilityTableEntryNumber = 2 Apr 7 14:08:47 VERBOSE[844] logger.c: capability = receiveUserInputCapability hookflash <> Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: capabilityDescriptors = 1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[844] logger.c: capabilityDescriptorNumber = 1 Apr 7 14:08:47 VERBOSE[844] logger.c: simultaneousCapabilities = 3 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]=1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]=1 Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: [1]=1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]=2 Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: [2]=1 entries { Apr 7 14:08:47 VERBOSE[844] logger.c: [0]=1 Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.184 H225 Caller:81c1e90 h323neg.cxx(317) H245 Sending MasterSlaveDetermination Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.185 H225 Caller:81c1e90 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:47 VERBOSE[844] logger.c: request masterSlaveDetermination { Apr 7 14:08:47 VERBOSE[844] logger.c: terminalType = 50 Apr 7 14:08:47 VERBOSE[844] logger.c: statusDeterminationNumber = 5162421 Apr 7 14:08:47 VERBOSE[844] logger.c: } Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.185 H225 Caller:81c1e90 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.187 H245:81c71d8 transports.cxx(586) H245 Started thread Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.187 H245:81c71d8 h323.cxx(3328) H245 Started control channel Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.187 H245:81c71d8 h323neg.cxx(535) H245 TerminalCapabilitySet already in progress: outSeq=2 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.188 H245:81c71d8 h323neg.cxx(303) H245 MasterSlaveDetermination already in progress Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.189 H245:81c71d8 h323.cxx(3371) H245 Received TPKT: size=65 pos=0.0 { Apr 7 14:08:47 VERBOSE[846] logger.c: 02 70 01 06 00 08 81 75 00 03 80 0d 00 00 3c 00 p u < Apr 7 14:08:47 VERBOSE[846] logger.c: 01 00 00 01 00 00 01 02 80 03 80 00 00 20 c0 27 ' Apr 7 14:08:47 VERBOSE[846] logger.c: 80 00 2b 85 01 50 80 00 2c 85 01 10 80 00 2d 85 + P , - Apr 7 14:08:47 VERBOSE[846] logger.c: 01 40 00 80 00 02 00 00 00 00 00 2b 01 00 2c 00 @ + , Apr 7 14:08:47 VERBOSE[846] logger.c: 2d - Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.193 H245:81c71d8 h323pdu.cxx(517) H245 Receiving PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: request terminalCapabilitySet { Apr 7 14:08:47 VERBOSE[846] logger.c: sequenceNumber = 1 Apr 7 14:08:47 VERBOSE[846] logger.c: protocolIdentifier = 0.0.8.245.0.3 Apr 7 14:08:47 VERBOSE[846] logger.c: multiplexCapability = h2250Capability { Apr 7 14:08:47 VERBOSE[846] logger.c: maximumAudioDelayJitter = 60 Apr 7 14:08:47 VERBOSE[846] logger.c: receiveMultipointCapability = { Apr 7 14:08:47 VERBOSE[846] logger.c: multicastCapability = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: multiUniCastConference = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedControl = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedControl = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedAudio = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedAudio = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedVideo = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedVideo = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: transmitMultipointCapability = { Apr 7 14:08:47 VERBOSE[846] logger.c: multicastCapability = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: multiUniCastConference = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedControl = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedControl = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedAudio = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedAudio = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedVideo = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedVideo = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: receiveAndTransmitMultipointCapability = { Apr 7 14:08:47 VERBOSE[846] logger.c: multicastCapability = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: multiUniCastConference = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: mediaDistributionCapability = 1 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedControl = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedControl = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedAudio = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedAudio = TRUE Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedVideo = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: distributedVideo = TRUE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: mcCapability = { Apr 7 14:08:47 VERBOSE[846] logger.c: centralizedConferenceMC = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: decentralizedConferenceMC = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: rtcpVideoControlCapability = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: mediaPacketizationCapability = { Apr 7 14:08:47 VERBOSE[846] logger.c: h261aVideoPacketization = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: logicalChannelSwitchingCapability = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: t120DynamicPortCapability = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: capabilityTable = 4 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[846] logger.c: capabilityTableEntryNumber = 1 Apr 7 14:08:47 VERBOSE[846] logger.c: capability = receiveAudioCapability g711Ulaw64k 40 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: [1]={ Apr 7 14:08:47 VERBOSE[846] logger.c: capabilityTableEntryNumber = 44 Apr 7 14:08:47 VERBOSE[846] logger.c: capability = receiveAndTransmitUserInputCapability hookflash <> Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: [2]={ Apr 7 14:08:47 VERBOSE[846] logger.c: capabilityTableEntryNumber = 45 Apr 7 14:08:47 VERBOSE[846] logger.c: capability = receiveAndTransmitUserInputCapability basicString <> Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: [3]={ Apr 7 14:08:47 VERBOSE[846] logger.c: capabilityTableEntryNumber = 46 Apr 7 14:08:47 VERBOSE[846] logger.c: capability = receiveAndTransmitUserInputCapability dtmf <> Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3180 Apr 7 14:08:47 VERBOSE[846] logger.c: capabilityDescriptors = 1 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]={ Apr 7 14:08:47 VERBOSE[846] logger.c: capabilityDescriptorNumber = 0 Apr 7 14:08:47 VERBOSE[846] logger.c: simultaneousCapabilities = 3 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]=1 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]=1 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: [1]=1 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]=44 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: [2]=2 entries { Apr 7 14:08:47 VERBOSE[846] logger.c: [0]=45 Apr 7 14:08:47 VERBOSE[846] logger.c: [1]=46 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.210 H245:81c71d8 h323.cxx(3488) H245 Set protocol version to 3 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.211 H245:81c71d8 h323neg.cxx(579) H245 Received TerminalCapabilitySet: state=InProgress pduSeq=1 inSeq=4294967295 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.211 H245:81c71d8 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.212 H245:81c71d8 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.212 H245:81c71d8 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.213 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.711-ALaw-64k{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.214 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.711-ALaw-64k{sw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.214 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: G.711-ALaw-64k <4> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.214 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.215 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k{sw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.215 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: G.711-uLaw-64k <5> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.215 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.723.1" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.215 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: G.723.1 <6> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.216 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.723.1(5.3k){hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.216 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.723.1A(5.3k){hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.216 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.723.1A(6.3k)-Cisco{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.216 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.723.1A(6.3k){hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.217 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.723.1{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.217 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.728{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.217 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.729" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.217 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: G.729 <7> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.218 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.729A" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.218 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: G.729A <8> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.218 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.729A/B{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.218 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.729A{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.219 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.729B{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.219 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "G.729{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.219 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "GSM-06.10{hw}" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.219 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: UserInput/hookflash <9> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.220 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: UserInput/basicString <10> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.220 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: UserInput/dtmf <11> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.220 H245:81c71d8 h323caps.cxx(1932) H323 Added capability: UserInput/RFC2833 <12> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.220 H245:81c71d8 h323caps.cxx(2071) H323 FindCapability: receiveAudioCapability Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.221 H245:81c71d8 h323caps.cxx(2284) H323 FindCapability: Audio subtype=3 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.221 H245:81c71d8 h323caps.cxx(2290) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.221 H245:81c71d8 h323caps.cxx(976) H323 Capability tx frames left at 20 as remote allows 40 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.221 H245:81c71d8 h323caps.cxx(2071) H323 FindCapability: receiveAndTransmitUserInputCapability Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.221 H245:81c71d8 h323caps.cxx(2284) H323 FindCapability: UserInput subtype=5 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.221 H245:81c71d8 h323caps.cxx(2290) H323 Found capability: UserInput/hookflash <2> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.222 H245:81c71d8 h323caps.cxx(2071) H323 FindCapability: receiveAndTransmitUserInputCapability Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.222 H245:81c71d8 h323caps.cxx(2284) H323 FindCapability: UserInput subtype=1 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.222 H245:81c71d8 h323caps.cxx(2290) H323 Found capability: UserInput/basicString <10> Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3200 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.222 H245:81c71d8 h323caps.cxx(2071) H323 FindCapability: receiveAndTransmitUserInputCapability Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.223 H245:81c71d8 h323caps.cxx(2284) H323 FindCapability: UserInput subtype=4 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.223 H245:81c71d8 h323caps.cxx(2290) H323 Found capability: UserInput/dtmf <11> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.224 H245:81c71d8 h323caps.cxx(2056) H323 FindCapability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.224 H245:81c71d8 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.225 H245:81c71d8 h323caps.cxx(2056) H323 FindCapability: UserInput/hookflash <44> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.225 H245:81c71d8 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <44> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.225 H245:81c71d8 h323caps.cxx(2056) H323 FindCapability: UserInput/basicString <45> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.225 H245:81c71d8 h323caps.cxx(1942) H323 Added capability: UserInput/basicString <45> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.225 H245:81c71d8 h323caps.cxx(2056) H323 FindCapability: UserInput/dtmf <46> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.226 H245:81c71d8 h323caps.cxx(1942) H323 Added capability: UserInput/dtmf <46> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.226 H245:81c71d8 h323caps.cxx(2004) H323 FindCapability: 1 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.226 H245:81c71d8 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.226 H245:81c71d8 h323caps.cxx(2004) H323 FindCapability: 44 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.226 H245:81c71d8 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <44> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.227 H245:81c71d8 h323caps.cxx(2004) H323 FindCapability: 45 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.227 H245:81c71d8 h323caps.cxx(2008) H323 Found capability: UserInput/basicString <45> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.227 H245:81c71d8 h323caps.cxx(2004) H323 FindCapability: 46 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.227 H245:81c71d8 h323caps.cxx(2008) H323 Found capability: UserInput/dtmf <46> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.227 H245:81c71d8 h323caps.cxx(2379) H245 Capability merge result: Apr 7 14:08:47 VERBOSE[846] logger.c: Table: Apr 7 14:08:47 VERBOSE[846] logger.c: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: UserInput/hookflash <44> Apr 7 14:08:47 VERBOSE[846] logger.c: UserInput/basicString <45> Apr 7 14:08:47 VERBOSE[846] logger.c: UserInput/dtmf <46> Apr 7 14:08:47 VERBOSE[846] logger.c: Set: Apr 7 14:08:47 VERBOSE[846] logger.c: 0: Apr 7 14:08:47 VERBOSE[846] logger.c: 0: Apr 7 14:08:47 VERBOSE[846] logger.c: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 1: Apr 7 14:08:47 VERBOSE[846] logger.c: UserInput/hookflash <44> Apr 7 14:08:47 VERBOSE[846] logger.c: 2: Apr 7 14:08:47 VERBOSE[846] logger.c: UserInput/basicString <45> Apr 7 14:08:47 VERBOSE[846] logger.c: UserInput/dtmf <46> Apr 7 14:08:47 VERBOSE[846] logger.c: Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.229 H245:81c71d8 h323caps.cxx(2381) H245 Received capability set, is accepted Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.230 H245:81c71d8 h323neg.cxx(535) H245 TerminalCapabilitySet already in progress: outSeq=2 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.230 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.230 H245:81c71d8 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: response terminalCapabilitySetAck { Apr 7 14:08:47 VERBOSE[846] logger.c: sequenceNumber = 1 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.231 H245:81c71d8 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.233 H245:81c71d8 h323.cxx(3371) H245 Received TPKT: size=3 pos=0.0 { Apr 7 14:08:47 VERBOSE[846] logger.c: 21 80 02 ! Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.235 H245:81c71d8 h323pdu.cxx(517) H245 Receiving PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: response terminalCapabilitySetAck { Apr 7 14:08:47 VERBOSE[846] logger.c: sequenceNumber = 2 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.236 H245:81c71d8 h323neg.cxx(620) H245 Received TerminalCapabilitySetAck: state=InProgress pduSeq=2 outSeq=2 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.236 H245:81c71d8 h323neg.cxx(630) H245 TerminalCapabilitySet Sent. Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.236 H245:81c71d8 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.236 H245:81c71d8 h323.cxx(3371) H245 Received TPKT: size=2 pos=0.0 { Apr 7 14:08:47 VERBOSE[846] logger.c: 20 a0 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.237 H245:81c71d8 h323pdu.cxx(517) H245 Receiving PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: response masterSlaveDeterminationAck { Apr 7 14:08:47 VERBOSE[846] logger.c: decision = slave <> Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.238 H245:81c71d8 h323neg.cxx(406) H245 Received MasterSlaveDeterminationAck: state=Outgoing Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.238 H245:81c71d8 h323neg.cxx(423) H245 MasterSlaveDetermination: remote is master Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.239 H245:81c71d8 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: response masterSlaveDeterminationAck { Apr 7 14:08:47 VERBOSE[846] logger.c: decision = master <> Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.240 H245:81c71d8 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.240 H245:81c71d8 h323caps.cxx(2020) H323 FindCapability: "T.120" Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.240 H245:81c71d8 h323.cxx(4154) H245 Default OnSelectLogicalChannels, FastStartDisabled Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.240 H245:81c71d8 h323caps.cxx(2056) H323 FindCapability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.241 H245:81c71d8 h323caps.cxx(2060) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.241 H245:81c71d8 h323.cxx(4201) H323 Selecting G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.241 H245:81c71d8 h323neg.cxx(743) H245 Opening channel: T-102 Apr 7 14:08:47 DEBUG[846] chan_h323.c: Sending RTP 'US' 163.49.131.154:12004 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.243 H245:81c71d8 channels.cxx(912) H323RTP OnSendingPDU Apr 7 14:08:47 VERBOSE[846] logger.c: -- Started logical channel: sending G.711-uLaw-64k Apr 7 14:08:47 VERBOSE[846] logger.c: -- channelsOpen = 1 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.244 H245:81c71d8 codecs.cxx(1351) Codec G711 uLaw encoder created for at 64k, frame of 160 samples Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.244 H245:81c71d8 channels.cxx(796) LogChan Bandwidth requested/used = 64.0/0.0 kb/s Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.245 H245:81c71d8 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 10000.0kb/s Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.245 H245:81c71d8 h323.cxx(4623) H323 Bandwidth request: +64.0kb/s, available: 10000.0kb/s Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.245 H245:81c71d8 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: request openLogicalChannel { Apr 7 14:08:47 VERBOSE[846] logger.c: forwardLogicalChannelNumber = 102 Apr 7 14:08:47 VERBOSE[846] logger.c: forwardLogicalChannelParameters = { Apr 7 14:08:47 VERBOSE[846] logger.c: dataType = audioData g711Ulaw64k 20 Apr 7 14:08:47 VERBOSE[846] logger.c: multiplexParameters = h2250LogicalChannelParameters { Apr 7 14:08:47 VERBOSE[846] logger.c: sessionID = 1 Apr 7 14:08:47 VERBOSE[846] logger.c: mediaGuaranteedDelivery = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: mediaControlChannel = unicastAddress iPAddress { Apr 7 14:08:47 VERBOSE[846] logger.c: network = 4 octets { Apr 7 14:08:47 VERBOSE[846] logger.c: a3 31 83 9a .1.. Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: tsapIdentifier = 12005 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: silenceSuppression = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: =-= In OnConnectionEstablished for call 22421 Apr 7 14:08:47 VERBOSE[846] logger.c: -- Connection Established with "Paul Davidson" Apr 7 14:08:47 DEBUG[846] chan_h323.c: Call ip$localhost/22421 answered Apr 7 14:08:47 VERBOSE[842] logger.c: -- H323/74307 answered IAX2/paul@paul-2 Apr 7 14:08:47 DEBUG[842] chan_iax2.c: Indicating condition -1 Apr 7 14:08:47 DEBUG[842] channel.c: Set channel IAX2/paul@paul-2 to read format slin Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3220 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Sending 3240 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:47 DEBUG[842] channel.c: Set channel H323/74307 to write format slin Apr 7 14:08:47 DEBUG[842] channel.c: Set channel H323/74307 to read format slin Apr 7 14:08:47 DEBUG[842] channel.c: Set channel IAX2/paul@paul-2 to write format slin Apr 7 14:08:47 DEBUG[842] chan_iax2.c: Answering Apr 7 14:08:47 DEBUG[842] channel.c: Dropping duplicate answer! Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Sending 3243 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.250 H245:81c71d8 h323.cxx(3371) H245 Received TPKT: size=20 pos=0.0 { Apr 7 14:08:47 VERBOSE[846] logger.c: 03 00 00 12 0c 60 13 80 0b 05 00 01 00 c0 a8 de ` Apr 7 14:08:47 VERBOSE[846] logger.c: 14 0f a1 00 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.253 H245:81c71d8 h323pdu.cxx(517) H245 Receiving PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: request openLogicalChannel { Apr 7 14:08:47 VERBOSE[846] logger.c: forwardLogicalChannelNumber = 19 Apr 7 14:08:47 VERBOSE[846] logger.c: forwardLogicalChannelParameters = { Apr 7 14:08:47 VERBOSE[846] logger.c: dataType = audioData g711Ulaw64k 20 Apr 7 14:08:47 VERBOSE[846] logger.c: multiplexParameters = h2250LogicalChannelParameters { Apr 7 14:08:47 VERBOSE[846] logger.c: sessionID = 1 Apr 7 14:08:47 VERBOSE[846] logger.c: mediaControlChannel = unicastAddress iPAddress { Apr 7 14:08:47 VERBOSE[846] logger.c: network = 4 octets { Apr 7 14:08:47 VERBOSE[846] logger.c: c0 a8 de 14 .... Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: tsapIdentifier = 4001 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: silenceSuppression = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.254 H245:81c71d8 h323neg.cxx(835) H245 Received open channel: R-19, state=Released Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.255 H245:81c71d8 h323.cxx(4382) H323 CreateLogicalChannel - forward channel Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.255 H245:81c71d8 h323caps.cxx(2119) H323 FindCapability: audioData Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.255 H245:81c71d8 h323caps.cxx(1001) H323 Capability tx frames left at 20 as remote allows 20 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.256 H245:81c71d8 h323caps.cxx(2164) H323 Found capability: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.256 H245:81c71d8 h323caps.cxx(996) H323 Capability rx frames reduced from 240 to 20 Apr 7 14:08:47 DEBUG[846] chan_h323.c: Sending RTP 'US' 163.49.131.154:12004 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.256 H245:81c71d8 codecs.cxx(1351) Codec G711 uLaw decoder created for at 64k, frame of 160 samples Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.256 H245:81c71d8 channels.cxx(796) LogChan Bandwidth requested/used = 64.0/0.0 kb/s Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.256 H245:81c71d8 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 9936.0kb/s Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.256 H245:81c71d8 h323.cxx(4623) H323 Bandwidth request: +64.0kb/s, available: 9936.0kb/s Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.257 H245:81c71d8 channels.cxx(971) H323RTP OnReceivedPDU for channel: R-19 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.257 H245:81c71d8 h323caps.cxx(1001) H323 Capability rx frames left at 20 as remote allows 20 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.257 H245:81c71d8 channels.cxx(940) H323RTP OnSendOpenAck Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.258 H245:81c71d8 channels.cxx(961) H323RTP Sending open logical channel ACK: sessionID=1 Apr 7 14:08:47 VERBOSE[846] logger.c: -- Started logical channel: receiving G.711-uLaw-64k Apr 7 14:08:47 VERBOSE[846] logger.c: -- channelsOpen = 2 Apr 7 14:08:47 VERBOSE[846] logger.c: External RTP Session Starting Apr 7 14:08:47 VERBOSE[846] logger.c: RTP channel id 1 parameters: Apr 7 14:08:47 VERBOSE[846] logger.c: -- remoteIpAddress: 192.168.222.20 Apr 7 14:08:47 VERBOSE[846] logger.c: -- remotePort: 4000 Apr 7 14:08:47 VERBOSE[846] logger.c: -- ExternalIpAddress: 163.49.131.154 Apr 7 14:08:47 VERBOSE[846] logger.c: -- ExternalPort: 12004 Apr 7 14:08:47 DEBUG[846] chan_h323.c: Setting up RTP connection for ip$localhost/22421 Apr 7 14:08:47 NOTICE[846] channel.c: Unable to find a path from g723 to slin Apr 7 14:08:47 NOTICE[846] channel.c: Unable to find a path from g723 to slin Apr 7 14:08:47 DEBUG[846] chan_h323.c: RTP connection prepared for ip$localhost/22421 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.260 H245:81c71d8 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:47 VERBOSE[846] logger.c: response openLogicalChannelAck { Apr 7 14:08:47 VERBOSE[846] logger.c: forwardLogicalChannelNumber = 19 Apr 7 14:08:47 VERBOSE[846] logger.c: forwardMultiplexAckParameters = h2250LogicalChannelAckParameters { Apr 7 14:08:47 VERBOSE[846] logger.c: sessionID = 1 Apr 7 14:08:47 VERBOSE[846] logger.c: mediaChannel = unicastAddress iPAddress { Apr 7 14:08:47 VERBOSE[846] logger.c: network = 4 octets { Apr 7 14:08:47 VERBOSE[846] logger.c: a3 31 83 9a .1.. Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: tsapIdentifier = 12004 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: mediaControlChannel = unicastAddress iPAddress { Apr 7 14:08:47 VERBOSE[846] logger.c: network = 4 octets { Apr 7 14:08:47 VERBOSE[846] logger.c: a3 31 83 9a .1.. Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: tsapIdentifier = 12005 Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: flowControlToZero = FALSE Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 VERBOSE[846] logger.c: } Apr 7 14:08:47 WARNING[842] chan_h323.c: Asked to transmit frame type 4, while native formats is 1 (read/write = 64/64) Apr 7 14:08:47 WARNING[842] channel.c: No path to translate from IAX2/paul@paul-2(4) to H323/74307(1) Apr 7 14:08:47 WARNING[842] channel.c: Can't make IAX2/paul@paul-2 and H323/74307 compatible Apr 7 14:08:47 WARNING[842] res_features.c: Bridge failed on channels IAX2/paul@paul-2 and H323/74307 Apr 7 14:08:47 DEBUG[842] channel.c: Hanging up channel 'H323/74307' Apr 7 14:08:47 DEBUG[842] chan_h323.c: Hanging up call H323/74307 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Received packet 4, (6, 4) Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 4 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 5 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: IAX subclass 4 received Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: For call=2, set last=3240 Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.265 H245:81c71d8 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled Apr 7 14:08:47 VERBOSE[842] logger.c: -- ClearCall: Request to clear call with token ip$localhost/22421, cause 3 Apr 7 14:08:47 VERBOSE[842] logger.c: 2:12:24.265 ThreadID=0x0062c019 h323ep.cxx(1777) H323 Clearing connection ip$localhost/22421 reason=EndedByRemoteUser Apr 7 14:08:47 VERBOSE[842] logger.c: 2:12:24.265 ThreadID=0x0062c019 h323.cxx(1537) H323 Call end reason for ip$localhost/22421 set to EndedByRemoteUser Apr 7 14:08:47 VERBOSE[842] logger.c: 2:12:24.266 ThreadID=0x0062c019 h323.cxx(1555) H225 Sending release complete PDU: callRef=22421 Apr 7 14:08:47 VERBOSE[842] logger.c: -- Sending RELEASE COMPLETE Apr 7 14:08:47 VERBOSE[842] logger.c: 2:12:24.267 ThreadID=0x0062c019 h323pdu.cxx(517) H245 Sending PDU: Apr 7 14:08:47 VERBOSE[842] logger.c: command endSessionCommand disconnect <> Apr 7 14:08:47 VERBOSE[842] logger.c: 2:12:24.267 ThreadID=0x0062c019 h323pdu.cxx(517) H225 Sending PDU: Apr 7 14:08:47 VERBOSE[842] logger.c: { Apr 7 14:08:47 VERBOSE[842] logger.c: q931pdu = { Apr 7 14:08:47 VERBOSE[842] logger.c: protocolDiscriminator = 8 Apr 7 14:08:47 VERBOSE[842] logger.c: callReference = 22421 Apr 7 14:08:47 VERBOSE[842] logger.c: from = originator Apr 7 14:08:47 VERBOSE[842] logger.c: messageType = ReleaseComplete Apr 7 14:08:47 VERBOSE[842] logger.c: IE: Cause - Normal call clearing = { Apr 7 14:08:47 VERBOSE[842] logger.c: 80 90 .. Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: IE: User-User = { Apr 7 14:08:47 VERBOSE[842] logger.c: 25 80 06 00 08 91 4a 00 02 01 11 00 20 c7 42 21 %.0....J..... .B! Apr 7 14:08:47 VERBOSE[842] logger.c: 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 02 80 01 00 ..........H..... Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: h225pdu = { Apr 7 14:08:47 VERBOSE[842] logger.c: h323_uu_pdu = { Apr 7 14:08:47 VERBOSE[842] logger.c: h323_message_body = releaseComplete { Apr 7 14:08:47 VERBOSE[842] logger.c: protocolIdentifier = 0.0.8.2250.0.2 Apr 7 14:08:47 VERBOSE[842] logger.c: callIdentifier = { Apr 7 14:08:47 VERBOSE[842] logger.c: guid = 16 octets { Apr 7 14:08:47 VERBOSE[842] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: h245Tunneling = FALSE Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 VERBOSE[842] logger.c: } Apr 7 14:08:47 DEBUG[842] pbx.c: Changing state for H323/74307 - state 0 Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.269 H323 Cleaner h323ep.cxx(1834) H323 Cleaning up connections Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner h323.cxx(1592) H323 Connection ip$localhost/22421 closing: connectionState=EstablishedConnection Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner h323neg.cxx(334) H245 Stopping MasterSlaveDetermination: state=Idle Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner h323neg.cxx(561) H245 Stopping TerminalCapabilitySet: state=Sent Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner channels.cxx(679) LogChan Cleaning up T-102 Apr 7 14:08:47 VERBOSE[32640] logger.c: channelsOpen = 1 Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner h323ep.cxx(2159) H323 Stopped sending logical channel: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner channels.cxx(707) LogChan Cleaned up T-102 Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner channels.cxx(679) LogChan Cleaning up R-19 Apr 7 14:08:47 VERBOSE[32640] logger.c: channelsOpen = 0 Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner h323ep.cxx(2159) H323 Stopped receiving logical channel: G.711-uLaw-64k <1> Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.270 H323 Cleaner channels.cxx(707) LogChan Cleaned up R-19 Apr 7 14:08:47 DEBUG[847] app_queue.c: Device 'H323/74307' changed to state '0' Apr 7 14:08:47 DEBUG[842] app_dial.c: Exiting with DIALSTATUS=ANSWER. Apr 7 14:08:47 DEBUG[842] pbx.c: Spawn extension (voip-h323,74307,1) exited non-zero on 'IAX2/paul@paul-2' Apr 7 14:08:47 DEBUG[842] channel.c: Hanging up channel 'IAX2/paul@paul-2' Apr 7 14:08:47 DEBUG[842] chan_iax2.c: We're hanging up IAX2/paul@paul-2 now... Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Sending 3266 on 2/9269 to 163.49.131.89:4569 Apr 7 14:08:47 DEBUG[847] app_queue.c: Device 'H323/74307' changed to state '0' Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.275 H245:81c71d8 h323.cxx(3405) H245 Checking for end session on PDU: response openLogicalChannelAck Apr 7 14:08:47 VERBOSE[842] logger.c: -- Hungup 'IAX2/paul@paul-2' Apr 7 14:08:47 DEBUG[842] chan_iax2.c: Checking device state for device paul@paul Apr 7 14:08:47 DEBUG[842] chan_iax2.c: Found peer. Now checking device state for peer paul Apr 7 14:08:47 DEBUG[842] pbx.c: Changing state for IAX2/paul@paul - state 1 Apr 7 14:08:47 DEBUG[848] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' Apr 7 14:08:47 DEBUG[848] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.278 H245:81c71d8 h323.cxx(3405) H245 Checking for end session on PDU: command endSessionCommand Apr 7 14:08:47 VERBOSE[846] logger.c: 2:12:24.278 H245:81c71d8 h323.cxx(3391) H245 Control channel closed. Apr 7 14:08:47 VERBOSE[32640] logger.c: ExternalRTPChannel Destroyed Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.279 H323 Cleaner h323.cxx(4623) H323 Bandwidth request: -64.0kb/s, available: 9872.0kb/s Apr 7 14:08:47 VERBOSE[32640] logger.c: ExternalRTPChannel Destroyed Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.279 H323 Cleaner h323.cxx(4623) H323 Bandwidth request: -64.0kb/s, available: 9936.0kb/s Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.279 H323 Cleaner h323.cxx(1635) H323 Awaiting end session from remote for 9.987 seconds Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.279 H323 Cleaner transports.cxx(1106) H323 H323Transport::Close Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.279 H323 Cleaner transports.cxx(1188) H323 H323Transport::CleanUpOnTermination for H245:81c71d8 Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.280 H323 Cleaner transports.cxx(1106) H323 H323Transport::Close Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.280 H225 Caller:81c1e90 h323pdu.cxx(1226) H225 Read error (4): Interrupted system call Apr 7 14:08:47 VERBOSE[844] logger.c: -- ClearCall: Request to clear call with token ip$localhost/22421, cause 7 Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.280 H225 Caller:81c1e90 h323ep.cxx(1777) H323 Clearing connection ip$localhost/22421 reason=EndedByTransportFail Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.280 H225 Caller:81c1e90 transports.cxx(1106) H323 H323Transport::Close Apr 7 14:08:47 VERBOSE[844] logger.c: 2:12:24.280 H225 Caller:81c1e90 h323.cxx(1748) H225 Signal channel closed. Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.281 H323 Cleaner transports.cxx(1188) H323 H323Transport::CleanUpOnTermination for H225 Caller:81c1e90 Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.282 H323 Cleaner h323trans.cxx(522) Trans Making request: disengageRequest Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.283 H323 Cleaner h323pdu.cxx(517) Trans Sending PDU: Apr 7 14:08:47 VERBOSE[32640] logger.c: disengageRequest { Apr 7 14:08:47 VERBOSE[32640] logger.c: requestSeqNum = 56979 Apr 7 14:08:47 VERBOSE[32640] logger.c: endpointIdentifier = 9 characters { Apr 7 14:08:47 VERBOSE[32640] logger.c: 0035 0034 0031 0039 005f 0065 006e 0064 5419_end Apr 7 14:08:47 VERBOSE[32640] logger.c: 0070 p Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: conferenceID = 16 octets { Apr 7 14:08:47 VERBOSE[32640] logger.c: 3e c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 >.B!..........H. Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: callReferenceValue = 22421 Apr 7 14:08:47 VERBOSE[32640] logger.c: disengageReason = normalDrop <> Apr 7 14:08:47 VERBOSE[32640] logger.c: callIdentifier = { Apr 7 14:08:47 VERBOSE[32640] logger.c: guid = 16 octets { Apr 7 14:08:47 VERBOSE[32640] logger.c: 20 c7 42 21 06 a6 d9 11 87 c5 00 0b cd 00 48 e3 .B!..........H. Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: gatekeeperIdentifier = 9 characters { Apr 7 14:08:47 VERBOSE[32640] logger.c: 0047 006e 0075 0048 0033 0032 0033 0047 GnuH323G Apr 7 14:08:47 VERBOSE[32640] logger.c: 004b K Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: answeredCall = FALSE Apr 7 14:08:47 VERBOSE[32640] logger.c: usageInformation = { Apr 7 14:08:47 VERBOSE[32640] logger.c: nonStandardUsageFields = 0 entries { Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: alertingTime = 1112900924 Apr 7 14:08:47 VERBOSE[32640] logger.c: connectTime = 1112900927 Apr 7 14:08:47 VERBOSE[32640] logger.c: endTime = 1112900927 Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: terminationCause = releaseCompleteCauseIE 2 octets { Apr 7 14:08:47 VERBOSE[32640] logger.c: 80 90 .. Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: } Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.284 H323 Cleaner h323trans.cxx(653) Trans Waiting on response to seqnum=56979 for 3.0 seconds Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Received packet 4, (6, 4) Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 6 Apr 7 14:08:47 DEBUG[32654] chan_iax2.c: Really destroying 2, having been acked on final message Apr 7 14:08:47 VERBOSE[32643] logger.c: 2:12:24.286 Transactor:8137668 h323pdu.cxx(517) H225RAS Receiving PDU: Apr 7 14:08:47 VERBOSE[32643] logger.c: disengageConfirm { Apr 7 14:08:47 VERBOSE[32643] logger.c: requestSeqNum = 56979 Apr 7 14:08:47 VERBOSE[32643] logger.c: } Apr 7 14:08:47 VERBOSE[32643] logger.c: 2:12:24.286 Transactor:8137668 h323trans.cxx(595) Trans Using credentials from request: H235AnnexD_Procedure1,MD5,CAT Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.287 H323 Cleaner h323.cxx(1656) H323 Connection ip$localhost/22421 terminated. Apr 7 14:08:47 VERBOSE[32640] logger.c: -- Paul Davidson has cleared the call Apr 7 14:08:47 DEBUG[32640] chan_h323.c: Cleaning connection to ip$localhost/22421 Apr 7 14:08:47 VERBOSE[32640] logger.c: == H.323 Connection deleted. Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.288 H323 Cleaner h323.cxx(1487) H323 Connection ip$localhost/22421 deleted. Apr 7 14:08:47 VERBOSE[32640] logger.c: 2:12:24.288 H323 Cleaner h323ep.cxx(1834) H323 Cleaning up connections Apr 7 14:08:53 VERBOSE[839] logger.c: -- Remote UNIX connection disconnected Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: New max nontrunk callno is 2 Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Creating new call structure 1 Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Received packet 0, (6, 13) Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: IAX subclass 13 received Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Checking device state for device paul Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Found peer. Now checking device state for peer paul Apr 7 14:09:07 DEBUG[32654] pbx.c: Changing state for IAX2/paul - state 1 Apr 7 14:09:07 DEBUG[854] app_queue.c: Device 'IAX2/paul' changed to state '1' Apr 7 14:09:07 DEBUG[854] app_queue.c: Device 'IAX2/paul' changed to state '1' Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Sending 15 on 1/9271 to 163.49.131.89:4569 Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Received packet 1, (6, 13) Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 0 Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: IAX subclass 13 received Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Checking device state for device paul Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Found peer. Now checking device state for peer paul Apr 7 14:09:07 DEBUG[32654] pbx.c: Changing state for IAX2/paul - state 1 Apr 7 14:09:07 DEBUG[855] app_queue.c: Device 'IAX2/paul' changed to state '1' Apr 7 14:09:07 DEBUG[855] app_queue.c: Device 'IAX2/paul' changed to state '1' Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Sending 20 on 1/9271 to 163.49.131.89:4569 Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Received packet 2, (6, 4) Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Cancelling transmission of packet 1 Apr 7 14:09:07 DEBUG[32654] chan_iax2.c: Really destroying 1, having been acked on final message