May 2 14:56:18 VERBOSE[18828] logger.c: Asterisk Event Logger restarted May 2 14:56:41 DEBUG[18621] chan_sip.c: Allocating new SIP call for (null) May 2 14:56:41 DEBUG[18621] chan_sip.c: Allocating new SIP call for (null) May 2 14:56:41 DEBUG[18621] chan_sip.c: Stopping retransmission on '3667a9bd3933b3170379395f02f23d68@163.49.131.154' of Request 102: Found May 2 14:56:41 DEBUG[18621] chan_sip.c: Stopping retransmission on '7527bab87018bb820dd0bf26320ed06d@163.49.131.154' of Request 102: Found May 2 14:56:41 DEBUG[18617] chan_iax2.c: New max nontrunk callno is 2 May 2 14:56:41 DEBUG[18617] chan_iax2.c: Creating new call structure 1 May 2 14:56:41 DEBUG[18617] chan_iax2.c: Received packet 0, (6, 13) May 2 14:56:41 DEBUG[18617] chan_iax2.c: IAX subclass 13 received May 2 14:56:41 DEBUG[18829] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:56:41 DEBUG[18617] chan_iax2.c: Sending 6 on 1/27025 to 163.49.131.89:4569 May 2 14:56:41 DEBUG[18829] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:56:41 DEBUG[18617] chan_iax2.c: Received packet 1, (6, 13) May 2 14:56:41 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 0 May 2 14:56:41 DEBUG[18617] chan_iax2.c: IAX subclass 13 received May 2 14:56:41 DEBUG[18830] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:56:41 DEBUG[18617] chan_iax2.c: Sending 10 on 1/27025 to 163.49.131.89:4569 May 2 14:56:41 DEBUG[18830] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:56:41 DEBUG[18617] chan_iax2.c: Received packet 2, (6, 4) May 2 14:56:41 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 1 May 2 14:56:41 DEBUG[18617] chan_iax2.c: Really destroying 1, having been acked on final message May 2 14:57:01 DEBUG[18617] chan_iax2.c: New max nontrunk callno is 3 May 2 14:57:01 DEBUG[18617] chan_iax2.c: Creating new call structure 2 May 2 14:57:01 DEBUG[18617] chan_iax2.c: Received packet 0, (6, 1) May 2 14:57:01 DEBUG[18617] chan_iax2.c: IAX subclass 1 received May 2 14:57:01 DEBUG[18617] chan_iax2.c: Sending 11 on 2/27026 to 163.49.131.89:4569 May 2 14:57:01 DEBUG[18617] chan_iax2.c: Received packet 1, (6, 9) May 2 14:57:01 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 0 May 2 14:57:01 DEBUG[18617] chan_iax2.c: IAX subclass 9 received May 2 14:57:01 DEBUG[18617] chan_iax2.c: We don't do requested format ulaw, falling back to peer capability 4 May 2 14:57:01 DEBUG[18833] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' May 2 14:57:01 DEBUG[18833] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' May 2 14:57:01 DEBUG[18617] chan_iax2.c: Sending 103 on 2/27026 to 163.49.131.89:4569 May 2 14:57:01 DEBUG[18834] pbx.c: Launching 'Dial' May 2 14:57:01 DEBUG[18834] chan_h323.c: type=H323, format=4, data=74307. May 2 14:57:01 DEBUG[18834] chan_h323.c: Extension: Host: 74307 May 2 14:57:01 DEBUG[18617] chan_iax2.c: Received packet 2, (6, 4) May 2 14:57:01 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 1 May 2 14:57:01 DEBUG[18617] chan_iax2.c: IAX subclass 4 received May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.953 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.954 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.954 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.954 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 1 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.955 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.955 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 2 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.955 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.955 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 3 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.956 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.956 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.956 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.956 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.957 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 1 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.957 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.957 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 2 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.957 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.957 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 3 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.958 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.958 ThreadID=0x001d8019 h323caps.cxx(2045) H323 FindCapability: "G.711-uLaw-64k" May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.958 ThreadID=0x001d8019 h323caps.cxx(2054) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.958 ThreadID=0x001d8019 h323caps.cxx(1977) H323 Removing capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.959 ThreadID=0x001d8019 h323caps.cxx(2045) H323 FindCapability: "G.711-uLaw-64k" May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.959 ThreadID=0x001d8019 h323caps.cxx(2045) H323 FindCapability: "UserInput/hookflash" May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.959 ThreadID=0x001d8019 h323caps.cxx(2054) H323 Found capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.959 ThreadID=0x001d8019 h323caps.cxx(1977) H323 Removing capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.960 ThreadID=0x001d8019 h323caps.cxx(2045) H323 FindCapability: "UserInput/hookflash" May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.960 ThreadID=0x001d8019 h323caps.cxx(2045) H323 FindCapability: "UserInput/RFC2833" May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.960 ThreadID=0x001d8019 h323caps.cxx(2054) H323 Found capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.960 ThreadID=0x001d8019 h323caps.cxx(1977) H323 Removing capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.961 ThreadID=0x001d8019 h323caps.cxx(2045) H323 FindCapability: "UserInput/RFC2833" May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.961 ThreadID=0x001d8019 h323caps.cxx(1957) H323 Added capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.961 ThreadID=0x001d8019 h323caps.cxx(1957) H323 Added capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.962 ThreadID=0x001d8019 h323caps.cxx(1957) H323 Added capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: Allowed Codecs: May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.962 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.962 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.962 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.963 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 1 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.963 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.963 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 2 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.963 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.964 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 3 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.964 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: Table: May 2 14:57:01 VERBOSE[18834] logger.c: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: Set: May 2 14:57:01 VERBOSE[18834] logger.c: 0: May 2 14:57:01 VERBOSE[18834] logger.c: 0: May 2 14:57:01 VERBOSE[18834] logger.c: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 1: May 2 14:57:01 VERBOSE[18834] logger.c: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 2: May 2 14:57:01 VERBOSE[18834] logger.c: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: May 2 14:57:01 DEBUG[18834] channel.c: Not copying variable STACK-voip-h323-74307-1. May 2 14:57:01 DEBUG[18834] chan_h323.c: Calling to 74307 on H323/74307 May 2 14:57:01 DEBUG[18834] chan_h323.c: Placing outgoing call to 74307, 101 May 2 14:57:01 VERBOSE[18834] logger.c: -- Making call to 74307 using gatekeeper. May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.998 ThreadID=0x001d8019 h323ep.cxx(1534) H323 Making call to: 74307 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.998 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.999 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.999 ThreadID=0x001d8019 h323caps.cxx(1967) H323 Added capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.999 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 1 May 2 14:57:01 VERBOSE[18834] logger.c: 28:20.999 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18834] logger.c: 28:21.000 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 2 May 2 14:57:01 VERBOSE[18834] logger.c: 28:21.000 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/hookflash <2> May 2 14:57:01 VERBOSE[18834] logger.c: 28:21.000 ThreadID=0x001d8019 h323caps.cxx(2029) H323 FindCapability: 3 May 2 14:57:01 VERBOSE[18834] logger.c: 28:21.000 ThreadID=0x001d8019 h323caps.cxx(2033) H323 Found capability: UserInput/RFC2833 <3> May 2 14:57:01 VERBOSE[18834] logger.c: 28:21.001 ThreadID=0x001d8019 rfc2833.cxx(81) RFC2833 Handler created May 2 14:57:01 VERBOSE[18834] logger.c: == New H.323 Connection created. May 2 14:57:01 VERBOSE[18834] logger.c: 28:21.001 ThreadID=0x001d8019 h323ep.cxx(1621) H323 Created new connection: ip$localhost/30191 May 2 14:57:01 VERBOSE[18834] logger.c: -- CNFB is calling host 74307 May 2 14:57:01 VERBOSE[18834] logger.c: --Call token is ip$localhost/30191 May 2 14:57:01 VERBOSE[18834] logger.c: -- Call reference is 30191 May 2 14:57:01 VERBOSE[18834] logger.c: -- DTMF Payload is [pt=101] May 2 14:57:01 DEBUG[18834] channel.c: Set channel H323/74307 to read format ulaw May 2 14:57:01 DEBUG[18834] channel.c: Set channel IAX2/paul@paul-2 to write format ulaw May 2 14:57:01 DEBUG[18834] channel.c: Set channel IAX2/paul@paul-2 to read format ulaw May 2 14:57:01 DEBUG[18834] channel.c: Set channel H323/74307 to write format ulaw May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.004 H225 Caller:8132fe0 h323ep.cxx(843) H225 Started call thread May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.008 H225 Caller:8132fe0 h323trans.cxx(530) Trans Making request: admissionRequest May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.009 H225 Caller:8132fe0 h323pdu.cxx(541) Trans Sending PDU [ip$163.49.131.154:33945/ip$163.49.131.112:1719] : May 2 14:57:01 VERBOSE[18836] logger.c: admissionRequest { May 2 14:57:01 VERBOSE[18836] logger.c: requestSeqNum = 26265 May 2 14:57:01 VERBOSE[18836] logger.c: callType = pointToPoint <> May 2 14:57:01 VERBOSE[18836] logger.c: endpointIdentifier = 9 characters { May 2 14:57:01 VERBOSE[18836] logger.c: 0037 0035 0032 0036 005f 0065 006e 0064 7526_end May 2 14:57:01 VERBOSE[18836] logger.c: 0070 p May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: destinationInfo = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]=dialedDigits "74307" May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: srcInfo = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]=h323_ID 13 characters { May 2 14:57:01 VERBOSE[18836] logger.c: 0050 0061 0075 006c 0020 0044 0061 0076 Paul Dav May 2 14:57:01 VERBOSE[18836] logger.c: 0069 0064 0073 006f 006e idson May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: bandWidth = 100000 May 2 14:57:01 VERBOSE[18836] logger.c: callReferenceValue = 30191 May 2 14:57:01 VERBOSE[18836] logger.c: conferenceID = 16 octets { May 2 14:57:01 VERBOSE[18836] logger.c: e2 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: activeMC = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: answerCall = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: canMapAlias = TRUE May 2 14:57:01 VERBOSE[18836] logger.c: callIdentifier = { May 2 14:57:01 VERBOSE[18836] logger.c: guid = 16 octets { May 2 14:57:01 VERBOSE[18836] logger.c: c4 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: gatekeeperIdentifier = 9 characters { May 2 14:57:01 VERBOSE[18836] logger.c: 0047 006e 0075 0048 0033 0032 0033 0047 GnuH323G May 2 14:57:01 VERBOSE[18836] logger.c: 004b K May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: willSupplyUUIEs = TRUE May 2 14:57:01 VERBOSE[18836] logger.c: canMapSrcAlias = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.014 H225 Caller:8132fe0 h323trans.cxx(661) Trans Waiting on response to seqnum=26265 for 3.0 seconds May 2 14:57:01 VERBOSE[18606] logger.c: 28:21.017 Transactor:81356f8 h323pdu.cxx(541) H225RAS Receiving PDU [ip$163.49.131.154:33945/ip$163.49.131.112:1719] : May 2 14:57:01 VERBOSE[18606] logger.c: admissionConfirm { May 2 14:57:01 VERBOSE[18606] logger.c: requestSeqNum = 26265 May 2 14:57:01 VERBOSE[18606] logger.c: bandWidth = 100000 May 2 14:57:01 VERBOSE[18606] logger.c: callModel = direct <> May 2 14:57:01 VERBOSE[18606] logger.c: destCallSignalAddress = ipAddress { May 2 14:57:01 VERBOSE[18606] logger.c: ip = 4 octets { May 2 14:57:01 VERBOSE[18606] logger.c: c0 a8 de 01 .... May 2 14:57:01 VERBOSE[18606] logger.c: } May 2 14:57:01 VERBOSE[18606] logger.c: port = 49829 May 2 14:57:01 VERBOSE[18606] logger.c: } May 2 14:57:01 VERBOSE[18606] logger.c: irrFrequency = 120 May 2 14:57:01 VERBOSE[18606] logger.c: willRespondToIRR = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: uuiesRequested = { May 2 14:57:01 VERBOSE[18606] logger.c: setup = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: callProceeding = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: connect = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: alerting = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: information = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: releaseComplete = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: facility = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: progress = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: empty = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: status = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: statusInquiry = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: setupAcknowledge = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: notify = FALSE May 2 14:57:01 VERBOSE[18606] logger.c: } May 2 14:57:01 VERBOSE[18606] logger.c: } May 2 14:57:01 VERBOSE[18606] logger.c: 28:21.018 Transactor:81356f8 h323trans.cxx(603) Trans Using credentials from request: H235AnnexD_Procedure1,CAT,MD5 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.020 H225 Caller:8132fe0 h323.cxx(4846) H323 Bandwidth used: 0 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.020 H225 Caller:8132fe0 transports.cxx(1628) H323TCP Connecting to 192.168.222.1:49829 (local port=0) May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.021 H225 Caller:8132fe0 transports.cxx(1529) H323TCP Started connection: host=192.168.222.1:49829, if=163.49.131.154:36247, handle=45 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.022 H225 Caller:8132fe0 h323.cxx(3146) H225 Sending Setup PDU May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.022 H225 Caller:8132fe0 h323.cxx(3161) H225 Check for Fast start by local endpoint May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.022 H225 Caller:8132fe0 h323.cxx(4372) H245 Default OnSelectLogicalChannels, FastStartDisabled May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.023 H225 Caller:8132fe0 h323caps.cxx(2081) H323 FindCapability: G.711-uLaw-64k <1> May 2 14:57:01 VERBOSE[18836] logger.c: -- Sending SETUP message May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.023 H225 Caller:8132fe0 h323pdu.cxx(541) H225 Sending PDU [ip$163.49.131.154:36247/ip$192.168.222.1:49829] : May 2 14:57:01 VERBOSE[18836] logger.c: { May 2 14:57:01 VERBOSE[18836] logger.c: q931pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: protocolDiscriminator = 8 May 2 14:57:01 VERBOSE[18836] logger.c: callReference = 30191 May 2 14:57:01 VERBOSE[18836] logger.c: from = originator May 2 14:57:01 VERBOSE[18836] logger.c: messageType = Setup May 2 14:57:01 VERBOSE[18836] logger.c: IE: Bearer-Capability = { May 2 14:57:01 VERBOSE[18836] logger.c: 80 90 a5 ... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: IE: Display = { May 2 14:57:01 VERBOSE[18836] logger.c: 50 61 75 6c 20 44 61 76 69 64 73 6f 6e 00 Paul Davidson. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: IE: Calling-Party-Number = { May 2 14:57:01 VERBOSE[18836] logger.c: 81 31 34 33 30 37 .14307 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: IE: Called-Party-Number = { May 2 14:57:01 VERBOSE[18836] logger.c: 81 37 34 33 30 37 .74307 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: IE: User-User = { May 2 14:57:01 VERBOSE[18836] logger.c: 20 b8 06 00 08 91 4a 00 04 01 40 0c 00 50 00 61 .....J...@..P.a May 2 14:57:01 VERBOSE[18836] logger.c: 00 75 00 6c 00 20 00 44 00 61 00 76 00 69 00 64 .u.l. .D.a.v.i.d May 2 14:57:01 VERBOSE[18836] logger.c: ... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: h225pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: h323_uu_pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: h323_message_body = setup { May 2 14:57:01 VERBOSE[18836] logger.c: protocolIdentifier = 0.0.8.2250.0.4 May 2 14:57:01 VERBOSE[18836] logger.c: sourceAddress = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]=h323_ID 13 characters { May 2 14:57:01 VERBOSE[18836] logger.c: 0050 0061 0075 006c 0020 0044 0061 0076 Paul Dav May 2 14:57:01 VERBOSE[18836] logger.c: 0069 0064 0073 006f 006e idson May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: sourceInfo = { May 2 14:57:01 VERBOSE[18836] logger.c: vendor = { May 2 14:57:01 VERBOSE[18836] logger.c: vendor = { May 2 14:57:01 VERBOSE[18836] logger.c: t35CountryCode = 9 May 2 14:57:01 VERBOSE[18836] logger.c: t35Extension = 0 May 2 14:57:01 VERBOSE[18836] logger.c: manufacturerCode = 61 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: productId = 56 octets { May 2 14:57:01 VERBOSE[18836] logger.c: 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo May 2 14:57:01 VERBOSE[18836] logger.c: 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann May 2 14:57:01 VERBOSE[18836] logger.c: ... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: versionId = 26 octets { May 2 14:57:01 VERBOSE[18836] logger.c: 31 2e 30 2e 30 20 28 4f 70 65 6e 48 33 32 33 20 1.0.0 (OpenH323 May 2 14:57:01 VERBOSE[18836] logger.c: 76 31 2e 31 37 2e 31 29 00 00 v1.17.1).. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: gateway = { May 2 14:57:01 VERBOSE[18836] logger.c: protocol = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]=voice { May 2 14:57:01 VERBOSE[18836] logger.c: supportedPrefixes = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]={ May 2 14:57:01 VERBOSE[18836] logger.c: prefix = dialedDigits "14307" May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: mc = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: undefinedNode = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: destinationAddress = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]=dialedDigits "74307" May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: destCallSignalAddress = ipAddress { May 2 14:57:01 VERBOSE[18836] logger.c: ip = 4 octets { May 2 14:57:01 VERBOSE[18836] logger.c: c0 a8 de 01 .... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: port = 49829 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: activeMC = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: conferenceID = 16 octets { May 2 14:57:01 VERBOSE[18836] logger.c: e2 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: conferenceGoal = create <> May 2 14:57:01 VERBOSE[18836] logger.c: callType = pointToPoint <> May 2 14:57:01 VERBOSE[18836] logger.c: sourceCallSignalAddress = ipAddress { May 2 14:57:01 VERBOSE[18836] logger.c: ip = 4 octets { May 2 14:57:01 VERBOSE[18836] logger.c: a3 31 83 9a .1.. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: port = 36247 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: callIdentifier = { May 2 14:57:01 VERBOSE[18836] logger.c: guid = 16 octets { May 2 14:57:01 VERBOSE[18836] logger.c: c4 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: mediaWaitForConnect = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: canOverlapSend = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: multipleCalls = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: maintainConnection = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: h245Tunneling = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.039 H225 Caller:8132fe0 h323.cxx(1784) H225 Reading PDUs: callRef=30191 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.054 H225 Caller:8132fe0 h323pdu.cxx(541) H225 Receiving PDU [ip$163.49.131.154:36247/ip$192.168.222.1:49829] : May 2 14:57:01 VERBOSE[18836] logger.c: { May 2 14:57:01 VERBOSE[18836] logger.c: q931pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: protocolDiscriminator = 8 May 2 14:57:01 VERBOSE[18836] logger.c: callReference = 30191 May 2 14:57:01 VERBOSE[18836] logger.c: from = destination May 2 14:57:01 VERBOSE[18836] logger.c: messageType = CallProceeding May 2 14:57:01 VERBOSE[18836] logger.c: IE: User-User = { May 2 14:57:01 VERBOSE[18836] logger.c: 21 80 06 00 08 91 4a 00 02 02 01 20 11 00 c4 60 !.....J.... ...` May 2 14:57:01 VERBOSE[18836] logger.c: 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 06 a0 ............H... May 2 14:57:01 VERBOSE[18836] logger.c: ... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: h225pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: h323_uu_pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: h323_message_body = callProceeding { May 2 14:57:01 VERBOSE[18836] logger.c: protocolIdentifier = 0.0.8.2250.0.2 May 2 14:57:01 VERBOSE[18836] logger.c: destinationInfo = { May 2 14:57:01 VERBOSE[18836] logger.c: terminal = { May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: mc = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: undefinedNode = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: callIdentifier = { May 2 14:57:01 VERBOSE[18836] logger.c: guid = 16 octets { May 2 14:57:01 VERBOSE[18836] logger.c: c4 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: h245Tunneling = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: nonStandardControl = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]={ May 2 14:57:01 VERBOSE[18836] logger.c: nonStandardIdentifier = h221NonStandard { May 2 14:57:01 VERBOSE[18836] logger.c: t35CountryCode = 181 May 2 14:57:01 VERBOSE[18836] logger.c: t35Extension = 0 May 2 14:57:01 VERBOSE[18836] logger.c: manufacturerCode = 18 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: data = 7 octets { May 2 14:57:01 VERBOSE[18836] logger.c: 80 44 04 00 01 01 00 .D..... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.060 H225 Caller:8132fe0 h323.cxx(1837) H225 Handling PDU: CallProceeding callRef=30191 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.061 H225 Caller:8132fe0 h323.cxx(1879) H225 Set remote application name: "Cisco IOS 12.x 181/18" May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.061 H225 Caller:8132fe0 h323.cxx(2099) H225 Set protocol version to 2 and implying H.245 version 3 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.061 H225 Caller:8132fe0 h323.cxx(2338) H225 Set remote party name: "74307" May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.061 H225 Caller:8132fe0 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.064 H225 Caller:8132fe0 h323pdu.cxx(541) H225 Receiving PDU [ip$163.49.131.154:36247/ip$192.168.222.1:49829] : May 2 14:57:01 VERBOSE[18836] logger.c: { May 2 14:57:01 VERBOSE[18836] logger.c: q931pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: protocolDiscriminator = 8 May 2 14:57:01 VERBOSE[18836] logger.c: callReference = 30191 May 2 14:57:01 VERBOSE[18836] logger.c: from = destination May 2 14:57:01 VERBOSE[18836] logger.c: messageType = Alerting May 2 14:57:01 VERBOSE[18836] logger.c: IE: User-User = { May 2 14:57:01 VERBOSE[18836] logger.c: 23 80 06 00 08 91 4a 00 02 02 01 20 11 00 c4 60 #.....J.... ...` May 2 14:57:01 VERBOSE[18836] logger.c: 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 06 a0 ............H... May 2 14:57:01 VERBOSE[18836] logger.c: ... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: h225pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: h323_uu_pdu = { May 2 14:57:01 VERBOSE[18836] logger.c: h323_message_body = alerting { May 2 14:57:01 VERBOSE[18836] logger.c: protocolIdentifier = 0.0.8.2250.0.2 May 2 14:57:01 VERBOSE[18836] logger.c: destinationInfo = { May 2 14:57:01 VERBOSE[18836] logger.c: terminal = { May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: mc = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: undefinedNode = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: callIdentifier = { May 2 14:57:01 VERBOSE[18836] logger.c: guid = 16 octets { May 2 14:57:01 VERBOSE[18836] logger.c: c4 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: h245Tunneling = FALSE May 2 14:57:01 VERBOSE[18836] logger.c: nonStandardControl = 1 entries { May 2 14:57:01 VERBOSE[18836] logger.c: [0]={ May 2 14:57:01 VERBOSE[18836] logger.c: nonStandardIdentifier = h221NonStandard { May 2 14:57:01 VERBOSE[18836] logger.c: t35CountryCode = 181 May 2 14:57:01 VERBOSE[18836] logger.c: t35Extension = 0 May 2 14:57:01 VERBOSE[18836] logger.c: manufacturerCode = 18 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: data = 7 octets { May 2 14:57:01 VERBOSE[18836] logger.c: 80 44 04 00 01 01 00 .D..... May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.071 H225 Caller:8132fe0 h323.cxx(1837) H225 Handling PDU: Alerting callRef=30191 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.071 H225 Caller:8132fe0 h323.cxx(2099) H225 Set protocol version to 2 and implying H.245 version 3 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.071 H225 Caller:8132fe0 h323.cxx(2338) H225 Set remote party name: "74307" May 2 14:57:01 VERBOSE[18836] logger.c: =-= In OnAlerting for call 30191: sessionId=1 May 2 14:57:01 VERBOSE[18836] logger.c: -- Ringing phone for "74307" May 2 14:57:01 VERBOSE[18836] logger.c: - Progress Indicator: 0 May 2 14:57:01 DEBUG[18836] chan_h323.c: Received ALERT/PROGRESS message for self-generated tones May 2 14:57:01 DEBUG[18836] chan_h323.c: Ringing on ip$localhost/30191 May 2 14:57:01 DEBUG[18837] app_queue.c: Device 'H323/74307' changed to state '2' May 2 14:57:01 DEBUG[18837] app_queue.c: Device 'H323/74307' changed to state '2' May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.073 H225 Caller:8132fe0 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.076 H225 Caller:8132fe0 h323pdu.cxx(1295) H225 No Q931 User-User Information Element, May 2 14:57:01 VERBOSE[18836] logger.c: Raw PDU: May 2 14:57:01 VERBOSE[18836] logger.c: 08 02 f5 ef 6e 27 01 f1 28 0d 50 61 75 6c 20 44 ....n'..(.Paul D May 2 14:57:01 VERBOSE[18836] logger.c: 61 76 69 64 73 6f 6e 4c 05 80 34 33 30 37 avidsonL..4307 May 2 14:57:01 VERBOSE[18836] logger.c: Q.931 PDU: May 2 14:57:01 VERBOSE[18836] logger.c: { May 2 14:57:01 VERBOSE[18836] logger.c: protocolDiscriminator = 8 May 2 14:57:01 VERBOSE[18836] logger.c: callReference = 30191 May 2 14:57:01 VERBOSE[18836] logger.c: from = destination May 2 14:57:01 VERBOSE[18836] logger.c: messageType = <110> May 2 14:57:01 VERBOSE[18836] logger.c: IE: 0x27 (39) = { May 2 14:57:01 VERBOSE[18836] logger.c: f1 . May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: IE: Display = { May 2 14:57:01 VERBOSE[18836] logger.c: 50 61 75 6c 20 44 61 76 69 64 73 6f 6e Paul Davidson May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: IE: Connected-Number = { May 2 14:57:01 VERBOSE[18836] logger.c: 80 34 33 30 37 .4307 May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: } May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.079 H225 Caller:8132fe0 h323.cxx(1837) H225 Handling PDU: <110> callRef=30191 May 2 14:57:01 VERBOSE[18836] logger.c: 28:21.079 H225 Caller:8132fe0 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled May 2 14:57:01 DEBUG[18834] chan_iax2.c: Indicating condition 3 May 2 14:57:01 DEBUG[18617] chan_iax2.c: Sending 106 on 2/27026 to 163.49.131.89:4569 May 2 14:57:01 DEBUG[18617] chan_iax2.c: Received packet 2, (6, 4) May 2 14:57:01 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 2 May 2 14:57:01 DEBUG[18617] chan_iax2.c: IAX subclass 4 received May 2 14:57:03 DEBUG[18617] chan_iax2.c: Received packet 2, (6, 2) May 2 14:57:03 DEBUG[18617] chan_iax2.c: IAX subclass 2 received May 2 14:57:03 DEBUG[18617] chan_iax2.c: Sending 1993 on 2/27026 to 163.49.131.89:4569 May 2 14:57:03 DEBUG[18617] chan_iax2.c: Received packet 3, (6, 4) May 2 14:57:03 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 3 May 2 14:57:03 DEBUG[18617] chan_iax2.c: IAX subclass 4 received May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.681 H225 Caller:8132fe0 h323pdu.cxx(541) H225 Receiving PDU [ip$163.49.131.154:36247/ip$192.168.222.1:49829] : May 2 14:57:05 VERBOSE[18836] logger.c: { May 2 14:57:05 VERBOSE[18836] logger.c: q931pdu = { May 2 14:57:05 VERBOSE[18836] logger.c: protocolDiscriminator = 8 May 2 14:57:05 VERBOSE[18836] logger.c: callReference = 30191 May 2 14:57:05 VERBOSE[18836] logger.c: from = destination May 2 14:57:05 VERBOSE[18836] logger.c: messageType = Connect May 2 14:57:05 VERBOSE[18836] logger.c: IE: Bearer-Capability = { May 2 14:57:05 VERBOSE[18836] logger.c: 80 90 a2 ... May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: IE: Display = { May 2 14:57:05 VERBOSE[18836] logger.c: 50 61 75 6c 20 44 61 76 69 64 73 6f 6e Paul Davidson May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: IE: User-User = { May 2 14:57:05 VERBOSE[18836] logger.c: 22 c0 06 00 08 91 4a 00 02 00 c0 a8 de 01 c3 c1 ".....J......... May 2 14:57:05 VERBOSE[18836] logger.c: 02 00 e2 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 ...`............ May 2 14:57:05 VERBOSE[18836] logger.c: ... May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: h225pdu = { May 2 14:57:05 VERBOSE[18836] logger.c: h323_uu_pdu = { May 2 14:57:05 VERBOSE[18836] logger.c: h323_message_body = connect { May 2 14:57:05 VERBOSE[18836] logger.c: protocolIdentifier = 0.0.8.2250.0.2 May 2 14:57:05 VERBOSE[18836] logger.c: h245Address = ipAddress { May 2 14:57:05 VERBOSE[18836] logger.c: ip = 4 octets { May 2 14:57:05 VERBOSE[18836] logger.c: c0 a8 de 01 .... May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: port = 50113 May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: destinationInfo = { May 2 14:57:05 VERBOSE[18836] logger.c: terminal = { May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: mc = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: undefinedNode = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: conferenceID = 16 octets { May 2 14:57:05 VERBOSE[18836] logger.c: e2 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: callIdentifier = { May 2 14:57:05 VERBOSE[18836] logger.c: guid = 16 octets { May 2 14:57:05 VERBOSE[18836] logger.c: c4 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: h245Tunneling = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: nonStandardControl = 1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]={ May 2 14:57:05 VERBOSE[18836] logger.c: nonStandardIdentifier = h221NonStandard { May 2 14:57:05 VERBOSE[18836] logger.c: t35CountryCode = 181 May 2 14:57:05 VERBOSE[18836] logger.c: t35Extension = 0 May 2 14:57:05 VERBOSE[18836] logger.c: manufacturerCode = 18 May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: data = 7 octets { May 2 14:57:05 VERBOSE[18836] logger.c: 80 44 04 00 01 01 00 .D..... May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.683 H225 Caller:8132fe0 h323.cxx(1837) H225 Handling PDU: Connect callRef=30191 May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.683 H225 Caller:8132fe0 h323.cxx(2099) H225 Set protocol version to 2 and implying H.245 version 3 May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.683 H225 Caller:8132fe0 h323.cxx(2338) H225 Set remote party name: "Paul Davidson" May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.683 H225 Caller:8132fe0 h323ep.cxx(2343) H225 Received connect PDU. May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.683 H225 Caller:8132fe0 transports.cxx(1628) H323TCP Connecting to 192.168.222.1:50113 (local port=0) May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.684 H225 Caller:8132fe0 transports.cxx(1529) H323TCP Started connection: host=192.168.222.1:50113, if=163.49.131.154:36248, handle=46 May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.685 H225 Caller:8132fe0 h323.cxx(3532) H245 Started control channel May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.685 H225 Caller:8132fe0 h323neg.cxx(549) H245 Sending TerminalCapabilitySet: outSeq=1 May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.687 H225 Caller:8132fe0 h323caps.cxx(2045) H323 FindCapability: "UserInput/RFC2833" May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.688 H225 Caller:8132fe0 h323caps.cxx(2054) H323 Found capability: UserInput/RFC2833 <3> May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.688 H225 Caller:8132fe0 h323.cxx(4188) H323 User Input RFC2833 payload type set to [pt=101] May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.688 H225 Caller:8132fe0 h323pdu.cxx(541) H245 Sending PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18836] logger.c: request terminalCapabilitySet { May 2 14:57:05 VERBOSE[18836] logger.c: sequenceNumber = 1 May 2 14:57:05 VERBOSE[18836] logger.c: protocolIdentifier = 0.0.8.245.0.7 May 2 14:57:05 VERBOSE[18836] logger.c: multiplexCapability = h2250Capability { May 2 14:57:05 VERBOSE[18836] logger.c: maximumAudioDelayJitter = 250 May 2 14:57:05 VERBOSE[18836] logger.c: receiveMultipointCapability = { May 2 14:57:05 VERBOSE[18836] logger.c: multicastCapability = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: multiUniCastConference = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: mediaDistributionCapability = 1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]={ May 2 14:57:05 VERBOSE[18836] logger.c: centralizedControl = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedControl = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: centralizedAudio = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedAudio = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: centralizedVideo = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedVideo = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: transmitMultipointCapability = { May 2 14:57:05 VERBOSE[18836] logger.c: multicastCapability = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: multiUniCastConference = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: mediaDistributionCapability = 1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]={ May 2 14:57:05 VERBOSE[18836] logger.c: centralizedControl = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedControl = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: centralizedAudio = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedAudio = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: centralizedVideo = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedVideo = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: receiveAndTransmitMultipointCapability = { May 2 14:57:05 VERBOSE[18836] logger.c: multicastCapability = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: multiUniCastConference = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: mediaDistributionCapability = 1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]={ May 2 14:57:05 VERBOSE[18836] logger.c: centralizedControl = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedControl = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: centralizedAudio = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedAudio = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: centralizedVideo = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: distributedVideo = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: mcCapability = { May 2 14:57:05 VERBOSE[18836] logger.c: centralizedConferenceMC = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: decentralizedConferenceMC = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: rtcpVideoControlCapability = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: mediaPacketizationCapability = { May 2 14:57:05 VERBOSE[18836] logger.c: h261aVideoPacketization = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: logicalChannelSwitchingCapability = FALSE May 2 14:57:05 VERBOSE[18836] logger.c: t120DynamicPortCapability = TRUE May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: capabilityTable = 2 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]={ May 2 14:57:05 VERBOSE[18836] logger.c: capabilityTableEntryNumber = 1 May 2 14:57:05 VERBOSE[18836] logger.c: capability = receiveAudioCapability g711Ulaw64k 240 May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: [1]={ May 2 14:57:05 VERBOSE[18836] logger.c: capabilityTableEntryNumber = 2 May 2 14:57:05 VERBOSE[18836] logger.c: capability = receiveUserInputCapability hookflash <> May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: capabilityDescriptors = 1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]={ May 2 14:57:05 VERBOSE[18836] logger.c: capabilityDescriptorNumber = 1 May 2 14:57:05 VERBOSE[18836] logger.c: simultaneousCapabilities = 3 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]=1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]=1 May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: [1]=1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]=2 May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: [2]=1 entries { May 2 14:57:05 VERBOSE[18836] logger.c: [0]=1 May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.698 H225 Caller:8132fe0 h323neg.cxx(317) H245 Sending MasterSlaveDetermination May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.699 H225 Caller:8132fe0 h323pdu.cxx(541) H245 Sending PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18836] logger.c: request masterSlaveDetermination { May 2 14:57:05 VERBOSE[18836] logger.c: terminalType = 60 May 2 14:57:05 VERBOSE[18836] logger.c: statusDeterminationNumber = 6695285 May 2 14:57:05 VERBOSE[18836] logger.c: } May 2 14:57:05 VERBOSE[18836] logger.c: 28:24.699 H225 Caller:8132fe0 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.701 H245:8133a08 transports.cxx(609) H245 Started thread May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.701 H245:8133a08 h323.cxx(3532) H245 Started control channel May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.701 H245:8133a08 h323neg.cxx(535) H245 TerminalCapabilitySet already in progress: outSeq=1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.702 H245:8133a08 h323neg.cxx(303) H245 MasterSlaveDetermination already in progress May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.702 H245:8133a08 h323.cxx(3578) H245 Received TPKT: size=113 pos=0.0 { May 2 14:57:05 VERBOSE[18838] logger.c: 02 70 01 06 00 08 81 75 00 03 80 13 80 00 3c 00 p u < May 2 14:57:05 VERBOSE[18838] logger.c: 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 00 08 May 2 14:57:05 VERBOSE[18838] logger.c: 80 00 00 20 c0 27 80 00 01 20 40 27 80 00 02 24 ' @' $ May 2 14:57:05 VERBOSE[18838] logger.c: 00 01 05 80 00 03 24 08 01 05 80 00 04 22 80 05 $ " May 2 14:57:05 VERBOSE[18838] logger.c: 80 00 05 22 c0 05 80 00 2b 85 01 50 80 00 2c 85 " + P , May 2 14:57:05 VERBOSE[18838] logger.c: 01 10 80 00 2d 85 01 40 00 80 00 02 05 00 00 00 - @ May 2 14:57:05 VERBOSE[18838] logger.c: 01 00 02 00 03 00 04 00 05 00 00 2b 01 00 2c 00 + , May 2 14:57:05 VERBOSE[18838] logger.c: 2d - May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.711 H245:8133a08 h323pdu.cxx(541) H245 Receiving PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: request terminalCapabilitySet { May 2 14:57:05 VERBOSE[18838] logger.c: sequenceNumber = 1 May 2 14:57:05 VERBOSE[18838] logger.c: protocolIdentifier = 0.0.8.245.0.3 May 2 14:57:05 VERBOSE[18838] logger.c: multiplexCapability = h2250Capability { May 2 14:57:05 VERBOSE[18838] logger.c: maximumAudioDelayJitter = 60 May 2 14:57:05 VERBOSE[18838] logger.c: receiveMultipointCapability = { May 2 14:57:05 VERBOSE[18838] logger.c: multicastCapability = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: multiUniCastConference = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: mediaDistributionCapability = 1 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]={ May 2 14:57:05 VERBOSE[18838] logger.c: centralizedControl = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedControl = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: centralizedAudio = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedAudio = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: centralizedVideo = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedVideo = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: transmitMultipointCapability = { May 2 14:57:05 VERBOSE[18838] logger.c: multicastCapability = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: multiUniCastConference = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: mediaDistributionCapability = 1 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]={ May 2 14:57:05 VERBOSE[18838] logger.c: centralizedControl = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedControl = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: centralizedAudio = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedAudio = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: centralizedVideo = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedVideo = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: receiveAndTransmitMultipointCapability = { May 2 14:57:05 VERBOSE[18838] logger.c: multicastCapability = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: multiUniCastConference = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: mediaDistributionCapability = 1 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]={ May 2 14:57:05 VERBOSE[18838] logger.c: centralizedControl = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedControl = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: centralizedAudio = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedAudio = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: centralizedVideo = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: distributedVideo = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: mcCapability = { May 2 14:57:05 VERBOSE[18838] logger.c: centralizedConferenceMC = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: decentralizedConferenceMC = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: rtcpVideoControlCapability = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: mediaPacketizationCapability = { May 2 14:57:05 VERBOSE[18838] logger.c: h261aVideoPacketization = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: logicalChannelSwitchingCapability = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: t120DynamicPortCapability = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTable = 9 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 1 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAudioCapability g711Ulaw64k 40 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [1]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 2 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAudioCapability g711Alaw64k 40 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [2]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 3 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAudioCapability g729wAnnexB 6 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [3]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 4 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAudioCapability g729AnnexAwAnnexB 6 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [4]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 5 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAudioCapability g729 6 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [5]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 6 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAudioCapability g729AnnexA 6 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [6]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 44 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAndTransmitUserInputCapability hookflash <> May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [7]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 45 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAndTransmitUserInputCapability basicString <> May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [8]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityTableEntryNumber = 46 May 2 14:57:05 VERBOSE[18838] logger.c: capability = receiveAndTransmitUserInputCapability dtmf <> May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: capabilityDescriptors = 1 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]={ May 2 14:57:05 VERBOSE[18838] logger.c: capabilityDescriptorNumber = 0 May 2 14:57:05 VERBOSE[18838] logger.c: simultaneousCapabilities = 3 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]=6 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]=1 May 2 14:57:05 VERBOSE[18838] logger.c: [1]=2 May 2 14:57:05 VERBOSE[18838] logger.c: [2]=3 May 2 14:57:05 VERBOSE[18838] logger.c: [3]=4 May 2 14:57:05 VERBOSE[18838] logger.c: [4]=5 May 2 14:57:05 VERBOSE[18838] logger.c: [5]=6 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [1]=1 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]=44 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: [2]=2 entries { May 2 14:57:05 VERBOSE[18838] logger.c: [0]=45 May 2 14:57:05 VERBOSE[18838] logger.c: [1]=46 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.729 H245:8133a08 h323.cxx(3698) H245 Set protocol version to 3 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.729 H245:8133a08 h323neg.cxx(579) H245 Received TerminalCapabilitySet: state=InProgress pduSeq=1 inSeq=4294967295 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.729 H245:8133a08 h323caps.cxx(1967) H323 Added capability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.730 H245:8133a08 h323caps.cxx(1967) H323 Added capability: UserInput/hookflash <2> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.730 H245:8133a08 h323caps.cxx(1967) H323 Added capability: UserInput/RFC2833 <3> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.730 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.711-ALaw-64k{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.731 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.711-ALaw-64k{sw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.731 H245:8133a08 h323caps.cxx(1957) H323 Added capability: G.711-ALaw-64k <4> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.732 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.711-uLaw-64k{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.732 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.711-uLaw-64k{sw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.732 H245:8133a08 h323caps.cxx(1957) H323 Added capability: G.711-uLaw-64k <5> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.733 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.723.1" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.733 H245:8133a08 h323caps.cxx(1957) H323 Added capability: G.723.1 <6> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.734 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.723.1(5.3k){hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.734 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.723.1A(5.3k){hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.734 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.723.1A(6.3k)-Cisco{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.735 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.723.1A(6.3k){hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.735 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.723.1{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.736 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.728{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.736 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.729" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.736 H245:8133a08 h323caps.cxx(1957) H323 Added capability: G.729 <7> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.737 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.729A" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.737 H245:8133a08 h323caps.cxx(1957) H323 Added capability: G.729A <8> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.737 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.729A/B{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.737 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.729A{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.738 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.729B{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.738 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "G.729{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.739 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "GSM-06.10{hw}" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.739 H245:8133a08 h323caps.cxx(1957) H323 Added capability: UserInput/hookflash <9> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.739 H245:8133a08 h323caps.cxx(1957) H323 Added capability: UserInput/basicString <10> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.740 H245:8133a08 h323caps.cxx(1957) H323 Added capability: UserInput/dtmf <11> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.740 H245:8133a08 h323caps.cxx(1957) H323 Added capability: UserInput/RFC2833 <12> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.741 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAudioCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.741 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: Audio subtype=3 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.741 H245:8133a08 h323caps.cxx(2315) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.741 H245:8133a08 h323caps.cxx(988) H323 Capability tx frames left at 20 as remote allows 40 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.742 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAudioCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.742 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: Audio subtype=1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.742 H245:8133a08 h323caps.cxx(2315) H323 Found capability: G.711-ALaw-64k <4> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.742 H245:8133a08 h323caps.cxx(988) H323 Capability tx frames left at 30 as remote allows 40 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.743 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAudioCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.743 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: Audio subtype=14 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.743 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAudioCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.743 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: Audio subtype=15 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.744 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAudioCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.744 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: Audio subtype=10 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.744 H245:8133a08 h323caps.cxx(2315) H323 Found capability: G.729 <7> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.745 H245:8133a08 h323caps.cxx(988) H323 Capability tx frames left at 2 as remote allows 6 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.745 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAudioCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.746 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: Audio subtype=11 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.746 H245:8133a08 h323caps.cxx(2315) H323 Found capability: G.729A <8> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.746 H245:8133a08 h323caps.cxx(988) H323 Capability tx frames left at 6 as remote allows 6 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.746 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAndTransmitUserInputCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.747 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: UserInput subtype=5 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.747 H245:8133a08 h323caps.cxx(2315) H323 Found capability: UserInput/hookflash <2> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.747 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAndTransmitUserInputCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.747 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: UserInput subtype=1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.748 H245:8133a08 h323caps.cxx(2315) H323 Found capability: UserInput/basicString <10> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.748 H245:8133a08 h323caps.cxx(2096) H323 FindCapability: receiveAndTransmitUserInputCapability May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.748 H245:8133a08 h323caps.cxx(2309) H323 FindCapability: UserInput subtype=4 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.748 H245:8133a08 h323caps.cxx(2315) H323 Found capability: UserInput/dtmf <11> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.749 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.750 H245:8133a08 h323caps.cxx(1967) H323 Added capability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.750 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: G.711-ALaw-64k <2> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.750 H245:8133a08 h323caps.cxx(1967) H323 Added capability: G.711-ALaw-64k <2> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.750 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: G.729 <5> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.751 H245:8133a08 h323caps.cxx(1967) H323 Added capability: G.729 <5> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.751 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: G.729A <6> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.751 H245:8133a08 h323caps.cxx(1967) H323 Added capability: G.729A <6> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.752 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: UserInput/hookflash <44> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.752 H245:8133a08 h323caps.cxx(1967) H323 Added capability: UserInput/hookflash <44> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.752 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: UserInput/basicString <45> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.752 H245:8133a08 h323caps.cxx(1967) H323 Added capability: UserInput/basicString <45> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.752 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: UserInput/dtmf <46> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.753 H245:8133a08 h323caps.cxx(1967) H323 Added capability: UserInput/dtmf <46> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.753 H245:8133a08 h323caps.cxx(2029) H323 FindCapability: 1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.753 H245:8133a08 h323caps.cxx(2033) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.753 H245:8133a08 h323caps.cxx(2029) H323 FindCapability: 2 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.754 H245:8133a08 h323caps.cxx(2033) H323 Found capability: G.711-ALaw-64k <2> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.754 H245:8133a08 h323caps.cxx(2029) H323 FindCapability: 5 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.754 H245:8133a08 h323caps.cxx(2033) H323 Found capability: G.729 <5> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.754 H245:8133a08 h323caps.cxx(2029) H323 FindCapability: 6 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.754 H245:8133a08 h323caps.cxx(2033) H323 Found capability: G.729A <6> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.755 H245:8133a08 h323caps.cxx(2029) H323 FindCapability: 44 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.755 H245:8133a08 h323caps.cxx(2033) H323 Found capability: UserInput/hookflash <44> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.756 H245:8133a08 h323caps.cxx(2029) H323 FindCapability: 45 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.756 H245:8133a08 h323caps.cxx(2033) H323 Found capability: UserInput/basicString <45> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.756 H245:8133a08 h323caps.cxx(2029) H323 FindCapability: 46 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.756 H245:8133a08 h323caps.cxx(2033) H323 Found capability: UserInput/dtmf <46> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.757 H245:8133a08 h323caps.cxx(2404) H245 Capability merge result: May 2 14:57:05 VERBOSE[18838] logger.c: Table: May 2 14:57:05 VERBOSE[18838] logger.c: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: G.711-ALaw-64k <2> May 2 14:57:05 VERBOSE[18838] logger.c: G.729 <5> May 2 14:57:05 VERBOSE[18838] logger.c: G.729A <6> May 2 14:57:05 VERBOSE[18838] logger.c: UserInput/hookflash <44> May 2 14:57:05 VERBOSE[18838] logger.c: UserInput/basicString <45> May 2 14:57:05 VERBOSE[18838] logger.c: UserInput/dtmf <46> May 2 14:57:05 VERBOSE[18838] logger.c: Set: May 2 14:57:05 VERBOSE[18838] logger.c: 0: May 2 14:57:05 VERBOSE[18838] logger.c: 0: May 2 14:57:05 VERBOSE[18838] logger.c: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: G.711-ALaw-64k <2> May 2 14:57:05 VERBOSE[18838] logger.c: G.729 <5> May 2 14:57:05 VERBOSE[18838] logger.c: G.729A <6> May 2 14:57:05 VERBOSE[18838] logger.c: 1: May 2 14:57:05 VERBOSE[18838] logger.c: UserInput/hookflash <44> May 2 14:57:05 VERBOSE[18838] logger.c: 2: May 2 14:57:05 VERBOSE[18838] logger.c: UserInput/basicString <45> May 2 14:57:05 VERBOSE[18838] logger.c: UserInput/dtmf <46> May 2 14:57:05 VERBOSE[18838] logger.c: May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.760 H245:8133a08 h323caps.cxx(2406) H245 Received capability set, is accepted May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.760 H245:8133a08 h323neg.cxx(535) H245 TerminalCapabilitySet already in progress: outSeq=1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.760 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "UserInput/RFC2833" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.761 H245:8133a08 h323pdu.cxx(541) H245 Sending PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: response terminalCapabilitySetAck { May 2 14:57:05 VERBOSE[18838] logger.c: sequenceNumber = 1 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.762 H245:8133a08 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.763 H245:8133a08 h323.cxx(3578) H245 Received TPKT: size=3 pos=0.0 { May 2 14:57:05 VERBOSE[18838] logger.c: 21 80 01 ! May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.765 H245:8133a08 h323pdu.cxx(541) H245 Receiving PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: response terminalCapabilitySetAck { May 2 14:57:05 VERBOSE[18838] logger.c: sequenceNumber = 1 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.766 H245:8133a08 h323neg.cxx(620) H245 Received TerminalCapabilitySetAck: state=InProgress pduSeq=1 outSeq=1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.766 H245:8133a08 h323neg.cxx(630) H245 TerminalCapabilitySet Sent. May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.766 H245:8133a08 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.767 H245:8133a08 h323.cxx(3578) H245 Received TPKT: size=2 pos=0.0 { May 2 14:57:05 VERBOSE[18838] logger.c: 20 80 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.768 H245:8133a08 h323pdu.cxx(541) H245 Receiving PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: response masterSlaveDeterminationAck { May 2 14:57:05 VERBOSE[18838] logger.c: decision = master <> May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.769 H245:8133a08 h323neg.cxx(406) H245 Received MasterSlaveDeterminationAck: state=Outgoing May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.769 H245:8133a08 h323neg.cxx(423) H245 MasterSlaveDetermination: remote is slave May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.770 H245:8133a08 h323pdu.cxx(541) H245 Sending PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: response masterSlaveDeterminationAck { May 2 14:57:05 VERBOSE[18838] logger.c: decision = slave <> May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.771 H245:8133a08 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.771 H245:8133a08 h323caps.cxx(2045) H323 FindCapability: "T.120" May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.771 H245:8133a08 h323.cxx(4372) H245 Default OnSelectLogicalChannels, FastStartDisabled May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.772 H245:8133a08 h323caps.cxx(2081) H323 FindCapability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.772 H245:8133a08 h323caps.cxx(2085) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.772 H245:8133a08 h323.cxx(4437) H323 Selecting G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.773 H245:8133a08 h323neg.cxx(743) H245 Opening channel: T-101 May 2 14:57:05 DEBUG[18838] chan_h323.c: Sending RTP 'US' 163.49.131.154:17260 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.775 H245:8133a08 channels.cxx(917) H323RTP OnSendingPDU May 2 14:57:05 VERBOSE[18838] logger.c: -- Started logical channel: sending G.711-uLaw-64k May 2 14:57:05 VERBOSE[18838] logger.c: -- channelsOpen = 1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.777 H245:8133a08 codecs.cxx(1359) Codec G711 uLaw encoder created for at 64k, frame of 160 samples May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.777 H245:8133a08 channels.cxx(799) LogChan Bandwidth requested/used = 64.0/0.0 kb/s May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.778 H245:8133a08 h323.cxx(4859) H323 Bandwidth request: -0.0kb/s, available: 10000.0kb/s May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.778 H245:8133a08 h323.cxx(4859) H323 Bandwidth request: +64.0kb/s, available: 10000.0kb/s May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.778 H245:8133a08 h323pdu.cxx(541) H245 Sending PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: request openLogicalChannel { May 2 14:57:05 VERBOSE[18838] logger.c: forwardLogicalChannelNumber = 101 May 2 14:57:05 VERBOSE[18838] logger.c: forwardLogicalChannelParameters = { May 2 14:57:05 VERBOSE[18838] logger.c: dataType = audioData g711Ulaw64k 20 May 2 14:57:05 VERBOSE[18838] logger.c: multiplexParameters = h2250LogicalChannelParameters { May 2 14:57:05 VERBOSE[18838] logger.c: sessionID = 1 May 2 14:57:05 VERBOSE[18838] logger.c: mediaGuaranteedDelivery = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: mediaControlChannel = unicastAddress iPAddress { May 2 14:57:05 VERBOSE[18838] logger.c: network = 4 octets { May 2 14:57:05 VERBOSE[18838] logger.c: 00 00 00 00 .... May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: tsapIdentifier = 17261 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: silenceSuppression = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: =-= In OnConnectionEstablished for call 30191 May 2 14:57:05 VERBOSE[18838] logger.c: -- Connection Established with "Paul Davidson" May 2 14:57:05 DEBUG[18838] chan_h323.c: Call ip$localhost/30191 answered May 2 14:57:05 DEBUG[18834] chan_iax2.c: Indicating condition -1 May 2 14:57:05 DEBUG[18834] channel.c: Set channel IAX2/paul@paul-2 to read format ulaw May 2 14:57:05 DEBUG[18617] chan_iax2.c: Sending 3962 on 2/27026 to 163.49.131.89:4569 May 2 14:57:05 DEBUG[18834] channel.c: Set channel H323/74307 to write format ulaw May 2 14:57:05 DEBUG[18834] channel.c: Set channel H323/74307 to read format ulaw May 2 14:57:05 DEBUG[18834] channel.c: Set channel IAX2/paul@paul-2 to write format ulaw May 2 14:57:05 DEBUG[18834] chan_iax2.c: Answering May 2 14:57:05 DEBUG[18834] channel.c: Dropping duplicate answer! May 2 14:57:05 DEBUG[18617] chan_iax2.c: Sending 3965 on 2/27026 to 163.49.131.89:4569 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.784 H245:8133a08 h323.cxx(3578) H245 Received TPKT: size=20 pos=0.0 { May 2 14:57:05 VERBOSE[18838] logger.c: 03 00 00 00 0c 60 13 80 0b 05 00 01 00 c0 a8 de ` May 2 14:57:05 VERBOSE[18838] logger.c: 01 0f a1 00 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.787 H245:8133a08 h323pdu.cxx(541) H245 Receiving PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: request openLogicalChannel { May 2 14:57:05 VERBOSE[18838] logger.c: forwardLogicalChannelNumber = 1 May 2 14:57:05 VERBOSE[18838] logger.c: forwardLogicalChannelParameters = { May 2 14:57:05 VERBOSE[18838] logger.c: dataType = audioData g711Ulaw64k 20 May 2 14:57:05 VERBOSE[18838] logger.c: multiplexParameters = h2250LogicalChannelParameters { May 2 14:57:05 VERBOSE[18838] logger.c: sessionID = 1 May 2 14:57:05 VERBOSE[18838] logger.c: mediaControlChannel = unicastAddress iPAddress { May 2 14:57:05 VERBOSE[18838] logger.c: network = 4 octets { May 2 14:57:05 DEBUG[18617] chan_iax2.c: Received packet 3, (6, 4) May 2 14:57:05 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 4 May 2 14:57:05 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 5 May 2 14:57:05 DEBUG[18617] chan_iax2.c: IAX subclass 4 received May 2 14:57:05 VERBOSE[18838] logger.c: c0 a8 de 01 .... May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: tsapIdentifier = 4001 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: silenceSuppression = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.790 H245:8133a08 h323neg.cxx(835) H245 Received open channel: R-1, state=Released May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.791 H245:8133a08 h323.cxx(4618) H323 CreateLogicalChannel - forward channel May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.792 H245:8133a08 h323caps.cxx(2144) H323 FindCapability: audioData May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.792 H245:8133a08 h323caps.cxx(1013) H323 Capability tx frames left at 20 as remote allows 20 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.792 H245:8133a08 h323caps.cxx(2189) H323 Found capability: G.711-uLaw-64k <1> May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.793 H245:8133a08 h323caps.cxx(1008) H323 Capability rx frames reduced from 240 to 20 May 2 14:57:05 DEBUG[18838] chan_h323.c: Sending RTP 'US' 163.49.131.154:17260 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.793 H245:8133a08 codecs.cxx(1359) Codec G711 uLaw decoder created for at 64k, frame of 160 samples May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.794 H245:8133a08 channels.cxx(799) LogChan Bandwidth requested/used = 64.0/0.0 kb/s May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.794 H245:8133a08 h323.cxx(4859) H323 Bandwidth request: -0.0kb/s, available: 9936.0kb/s May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.794 H245:8133a08 h323.cxx(4859) H323 Bandwidth request: +64.0kb/s, available: 9936.0kb/s May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.795 H245:8133a08 channels.cxx(976) H323RTP OnReceivedPDU for channel: R-1 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.795 H245:8133a08 h323caps.cxx(1013) H323 Capability rx frames left at 20 as remote allows 20 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.795 H245:8133a08 channels.cxx(945) H323RTP OnSendOpenAck May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.796 H245:8133a08 channels.cxx(966) H323RTP Sending open logical channel ACK: sessionID=1 May 2 14:57:05 VERBOSE[18838] logger.c: -- Started logical channel: receiving G.711-uLaw-64k May 2 14:57:05 VERBOSE[18838] logger.c: -- channelsOpen = 2 May 2 14:57:05 VERBOSE[18838] logger.c: External RTP Session Starting May 2 14:57:05 VERBOSE[18838] logger.c: RTP channel id 1 parameters: May 2 14:57:05 VERBOSE[18838] logger.c: -- remoteIpAddress: 192.168.222.1 May 2 14:57:05 VERBOSE[18838] logger.c: -- remotePort: 4000 May 2 14:57:05 VERBOSE[18838] logger.c: -- ExternalIpAddress: 127.0.0.1 May 2 14:57:05 VERBOSE[18838] logger.c: -- ExternalPort: 17260 May 2 14:57:05 DEBUG[18838] chan_h323.c: Setting up RTP connection for ip$localhost/30191 May 2 14:57:05 DEBUG[18838] channel.c: Set channel H323/74307 to read format ulaw May 2 14:57:05 DEBUG[18838] channel.c: Set channel H323/74307 to write format ulaw May 2 14:57:05 DEBUG[18838] chan_h323.c: RTP connection prepared for ip$localhost/30191 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.799 H245:8133a08 h323pdu.cxx(541) H245 Sending PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: response openLogicalChannelAck { May 2 14:57:05 VERBOSE[18838] logger.c: forwardLogicalChannelNumber = 1 May 2 14:57:05 VERBOSE[18838] logger.c: forwardMultiplexAckParameters = h2250LogicalChannelAckParameters { May 2 14:57:05 VERBOSE[18838] logger.c: sessionID = 1 May 2 14:57:05 VERBOSE[18838] logger.c: mediaChannel = unicastAddress iPAddress { May 2 14:57:05 VERBOSE[18838] logger.c: network = 4 octets { May 2 14:57:05 VERBOSE[18838] logger.c: 00 00 00 00 .... May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: tsapIdentifier = 17260 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: mediaControlChannel = unicastAddress iPAddress { May 2 14:57:05 VERBOSE[18838] logger.c: network = 4 octets { May 2 14:57:05 VERBOSE[18838] logger.c: 00 00 00 00 .... May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: tsapIdentifier = 17261 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: flowControlToZero = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.802 H245:8133a08 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.802 H245:8133a08 h323.cxx(3578) H245 Received TPKT: size=23 pos=0.0 { May 2 14:57:05 VERBOSE[18838] logger.c: 22 c0 00 64 04 80 10 1c 00 00 c0 a8 de be 6d 86 " d m May 2 14:57:05 VERBOSE[18838] logger.c: 00 c0 a8 de be 6d 87 m May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.805 H245:8133a08 h323pdu.cxx(541) H245 Receiving PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:05 VERBOSE[18838] logger.c: response openLogicalChannelAck { May 2 14:57:05 VERBOSE[18838] logger.c: forwardLogicalChannelNumber = 101 May 2 14:57:05 VERBOSE[18838] logger.c: forwardMultiplexAckParameters = h2250LogicalChannelAckParameters { May 2 14:57:05 VERBOSE[18838] logger.c: sessionID = 1 May 2 14:57:05 VERBOSE[18838] logger.c: mediaChannel = unicastAddress iPAddress { May 2 14:57:05 VERBOSE[18838] logger.c: network = 4 octets { May 2 14:57:05 VERBOSE[18838] logger.c: c0 a8 de be .... May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: tsapIdentifier = 28038 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: mediaControlChannel = unicastAddress iPAddress { May 2 14:57:05 VERBOSE[18838] logger.c: network = 4 octets { May 2 14:57:05 VERBOSE[18838] logger.c: c0 a8 de be .... May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: tsapIdentifier = 28039 May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: flowControlToZero = FALSE May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: } May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.808 H245:8133a08 h323neg.cxx(897) H245 Received open channel ack: T-101, state=AwaitingEstablishment May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.809 H245:8133a08 channels.cxx(1022) H323RTP OnReceiveOpenAck May 2 14:57:05 VERBOSE[18838] logger.c: MyH323_ExternalRTPChannel::OnReceivedAckPDU May 2 14:57:05 VERBOSE[18838] logger.c: -- remoteIpAddress: 192.168.222.190 May 2 14:57:05 VERBOSE[18838] logger.c: -- remotePort: 28038 May 2 14:57:05 DEBUG[18838] chan_h323.c: Setting up RTP connection for ip$localhost/30191 May 2 14:57:05 DEBUG[18838] channel.c: Set channel H323/74307 to read format ulaw May 2 14:57:05 DEBUG[18838] channel.c: Set channel H323/74307 to write format ulaw May 2 14:57:05 DEBUG[18838] chan_h323.c: RTP connection prepared for ip$localhost/30191 May 2 14:57:05 VERBOSE[18838] logger.c: External RTP Session Starting May 2 14:57:05 VERBOSE[18838] logger.c: RTP channel id 1 parameters: May 2 14:57:05 VERBOSE[18838] logger.c: -- remoteIpAddress: 192.168.222.190 May 2 14:57:05 VERBOSE[18838] logger.c: -- remotePort: 28038 May 2 14:57:05 VERBOSE[18838] logger.c: -- ExternalIpAddress: 127.0.0.1 May 2 14:57:05 VERBOSE[18838] logger.c: -- ExternalPort: 17260 May 2 14:57:05 DEBUG[18838] chan_h323.c: Setting up RTP connection for ip$localhost/30191 May 2 14:57:05 DEBUG[18838] channel.c: Set channel H323/74307 to read format ulaw May 2 14:57:05 DEBUG[18838] channel.c: Set channel H323/74307 to write format ulaw May 2 14:57:05 DEBUG[18838] chan_h323.c: RTP connection prepared for ip$localhost/30191 May 2 14:57:05 VERBOSE[18838] logger.c: 28:24.813 H245:8133a08 h323.cxx(4301) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled May 2 14:57:09 DEBUG[18617] chan_iax2.c: Received packet 3, (2, 4) May 2 14:57:09 DEBUG[18617] chan_iax2.c: Ooh, voice format changed to 4 May 2 14:57:09 DEBUG[18617] channel.c: Set channel IAX2/paul@paul-2 to read format ulaw May 2 14:57:09 DEBUG[18617] chan_iax2.c: Sending 8500 on 2/27026 to 163.49.131.89:4569 May 2 14:57:09 DEBUG[18617] chan_iax2.c: schedule_delivery: call=2: TS jumped. resyncing rxcore (ts=8500, last=0) May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8500 May 2 14:57:09 DEBUG[18834] rtp.c: Ooh, format changed from unknown to ulaw May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8520 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8540 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8560 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8580 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8600 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8620 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8640 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8660 May 2 14:57:09 DEBUG[18617] chan_iax2.c: For call=2, set last=8680 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8700 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8720 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8740 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8760 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8780 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8800 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8820 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8840 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8860 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8880 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8900 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8920 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8940 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8960 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=8980 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9000 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9020 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9040 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9060 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9080 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9100 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9120 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9140 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9160 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9180 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9200 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9220 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9240 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9260 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9280 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9300 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9320 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9340 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9360 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9380 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9400 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9420 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9440 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9460 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9480 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9500 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9520 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9540 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9560 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9580 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9600 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9620 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9640 May 2 14:57:10 DEBUG[18617] chan_iax2.c: For call=2, set last=9660 May 2 14:57:11 DEBUG[18617] chan_iax2.c: For call=2, set last=9680 May 2 14:57:11 DEBUG[18617] chan_iax2.c: For call=2, set last=9700 May 2 14:57:11 DEBUG[18617] chan_iax2.c: For call=2, set last=9720 May 2 14:57:11 DEBUG[18617] chan_iax2.c: For call=2, set last=9740 May 2 14:57:11 DEBUG[18617] chan_iax2.c: For call=2, set last=9760 May 2 14:57:11 DEBUG[18617] chan_iax2.c: Sending 10014 on 2/27026 to 163.49.131.89:4569 May 2 14:57:11 DEBUG[18617] chan_iax2.c: Received packet 4, (6, 4) May 2 14:57:11 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 6 May 2 14:57:11 DEBUG[18617] chan_iax2.c: IAX subclass 4 received May 2 14:57:11 DEBUG[18617] chan_iax2.c: Received packet 4, (6, 12) May 2 14:57:11 DEBUG[18617] chan_iax2.c: IAX subclass 12 received May 2 14:57:11 DEBUG[18617] chan_iax2.c: Peer 163.49.131.89 lag measured as 133ms May 2 14:57:11 DEBUG[18617] chan_iax2.c: Sending 10014 on 2/27026 to 163.49.131.89:4569 May 2 14:57:12 DEBUG[18617] chan_iax2.c: Received packet 5, (6, 5) May 2 14:57:12 DEBUG[18617] chan_iax2.c: IAX subclass 5 received May 2 14:57:12 DEBUG[18617] chan_iax2.c: Immediately destroying 2, having received hangup May 2 14:57:12 DEBUG[18617] chan_iax2.c: Sending 11004 on 2/27026 to 163.49.131.89:4569 May 2 14:57:12 DEBUG[18834] channel.c: Didn't get a frame from channel: IAX2/paul@paul-2 May 2 14:57:12 DEBUG[18834] channel.c: Bridge stops bridging channels IAX2/paul@paul-2 and H323/74307 May 2 14:57:12 DEBUG[18834] channel.c: Hanging up channel 'H323/74307' May 2 14:57:12 DEBUG[18834] chan_h323.c: Hanging up call H323/74307 May 2 14:57:12 VERBOSE[18834] logger.c: -- ClearCall: Request to clear call with token ip$localhost/30191, cause EndedByRemoteUser May 2 14:57:12 VERBOSE[18834] logger.c: 28:31.829 ThreadID=0x001d8019 h323ep.cxx(2059) H323 Clearing connection ip$localhost/30191 reason=EndedByRemoteUser May 2 14:57:12 VERBOSE[18834] logger.c: 28:31.829 ThreadID=0x001d8019 h323.cxx(1606) H323 Call end reason for ip$localhost/30191 set to EndedByRemoteUser May 2 14:57:12 VERBOSE[18834] logger.c: 28:31.829 ThreadID=0x001d8019 h323.cxx(1624) H225 Sending release complete PDU: callRef=30191 May 2 14:57:12 VERBOSE[18834] logger.c: -- Sending RELEASE COMPLETE May 2 14:57:12 VERBOSE[18834] logger.c: 28:31.830 ThreadID=0x001d8019 h323pdu.cxx(541) H245 Sending PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:12 VERBOSE[18834] logger.c: command endSessionCommand disconnect <> May 2 14:57:12 VERBOSE[18834] logger.c: 28:31.831 ThreadID=0x001d8019 h323pdu.cxx(541) H225 Sending PDU [ip$163.49.131.154:36247/ip$192.168.222.1:49829] : May 2 14:57:12 VERBOSE[18834] logger.c: { May 2 14:57:12 VERBOSE[18834] logger.c: q931pdu = { May 2 14:57:12 VERBOSE[18834] logger.c: protocolDiscriminator = 8 May 2 14:57:12 VERBOSE[18834] logger.c: callReference = 30191 May 2 14:57:12 VERBOSE[18834] logger.c: from = originator May 2 14:57:12 VERBOSE[18834] logger.c: messageType = ReleaseComplete May 2 14:57:12 VERBOSE[18834] logger.c: IE: Cause - Normal call clearing = { May 2 14:57:12 VERBOSE[18834] logger.c: 80 90 .. May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: IE: User-User = { May 2 14:57:12 VERBOSE[18834] logger.c: 25 80 06 00 08 91 4a 00 02 01 11 00 c4 60 80 04 %.0....J......`.. May 2 14:57:12 VERBOSE[18834] logger.c: b2 b9 d9 11 87 05 00 0b cd 00 48 e3 02 80 01 00 ..........H..... May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: h225pdu = { May 2 14:57:12 VERBOSE[18834] logger.c: h323_uu_pdu = { May 2 14:57:12 VERBOSE[18834] logger.c: h323_message_body = releaseComplete { May 2 14:57:12 VERBOSE[18834] logger.c: protocolIdentifier = 0.0.8.2250.0.2 May 2 14:57:12 VERBOSE[18834] logger.c: callIdentifier = { May 2 14:57:12 VERBOSE[18834] logger.c: guid = 16 octets { May 2 14:57:12 VERBOSE[18834] logger.c: c4 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: h245Tunneling = FALSE May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18834] logger.c: } May 2 14:57:12 VERBOSE[18838] logger.c: 28:31.832 H245:8133a08 h323.cxx(3578) H245 Received TPKT: size=2 pos=0.0 { May 2 14:57:12 VERBOSE[18838] logger.c: 4a 40 J@ May 2 14:57:12 VERBOSE[18838] logger.c: } May 2 14:57:12 VERBOSE[18838] logger.c: 28:31.833 H245:8133a08 h323pdu.cxx(541) H245 Receiving PDU [ip$163.49.131.154:36248/ip$192.168.222.1:50113] : May 2 14:57:12 VERBOSE[18838] logger.c: command endSessionCommand disconnect <> May 2 14:57:12 VERBOSE[18838] logger.c: -- ClearCall: Request to clear call with token ip$localhost/30191, cause EndedByRemoteUser May 2 14:57:12 VERBOSE[18838] logger.c: 28:31.833 H245:8133a08 h323ep.cxx(2059) H323 Clearing connection ip$localhost/30191 reason=EndedByRemoteUser May 2 14:57:12 VERBOSE[18838] logger.c: 28:31.833 H245:8133a08 h323.cxx(3598) H245 Control channel closed. May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner h323ep.cxx(2116) H323 Cleaning up connections May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner h323.cxx(1664) H323 Connection ip$localhost/30191 closing: connectionState=EstablishedConnection May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner h323neg.cxx(334) H245 Stopping MasterSlaveDetermination: state=Idle May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner h323neg.cxx(561) H245 Stopping TerminalCapabilitySet: state=Sent May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner channels.cxx(682) LogChan Cleaning up R-1 May 2 14:57:12 VERBOSE[18603] logger.c: channelsOpen = 1 May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner h323ep.cxx(2441) H323 Stopped receiving logical channel: G.711-uLaw-64k <1> May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner channels.cxx(710) LogChan Cleaned up R-1 May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner channels.cxx(682) LogChan Cleaning up T-101 May 2 14:57:12 VERBOSE[18603] logger.c: channelsOpen = 0 May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner h323ep.cxx(2441) H323 Stopped sending logical channel: G.711-uLaw-64k <1> May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.834 H323 Cleaner channels.cxx(710) LogChan Cleaned up T-101 May 2 14:57:12 DEBUG[18839] app_queue.c: Device 'H323/74307' changed to state '0' May 2 14:57:12 DEBUG[18834] app_dial.c: Exiting with DIALSTATUS=ANSWER. May 2 14:57:12 DEBUG[18834] pbx.c: Spawn extension (voip-h323,74307,1) exited non-zero on 'IAX2/paul@paul-2' May 2 14:57:12 DEBUG[18834] channel.c: Hanging up channel 'IAX2/paul@paul-2' May 2 14:57:12 DEBUG[18834] chan_iax2.c: We're hanging up IAX2/paul@paul-2 now... May 2 14:57:12 DEBUG[18834] chan_iax2.c: Really destroying IAX2/paul@paul-2 now... May 2 14:57:12 DEBUG[18840] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' May 2 14:57:12 DEBUG[18840] app_queue.c: Device 'IAX2/paul@paul' changed to state '1' May 2 14:57:12 DEBUG[18839] app_queue.c: Device 'H323/74307' changed to state '0' May 2 14:57:12 VERBOSE[18603] logger.c: ExternalRTPChannel Destroyed May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.837 H323 Cleaner h323.cxx(4859) H323 Bandwidth request: -64.0kb/s, available: 9872.0kb/s May 2 14:57:12 VERBOSE[18603] logger.c: ExternalRTPChannel Destroyed May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.838 H323 Cleaner h323.cxx(4859) H323 Bandwidth request: -64.0kb/s, available: 9936.0kb/s May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.838 H323 Cleaner h323.cxx(1707) H323 Awaiting end session from remote for 9.992 seconds May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.838 H323 Cleaner transports.cxx(1132) H323 H323Transport::Close May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.838 H323 Cleaner transports.cxx(1214) H323 H323Transport::CleanUpOnTermination for H245:8133a08 May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.838 H323 Cleaner transports.cxx(1132) H323 H323Transport::Close May 2 14:57:12 VERBOSE[18836] logger.c: 28:31.838 H225 Caller:8132fe0 h323pdu.cxx(1278) H225 Read error (4): Interrupted system call May 2 14:57:12 VERBOSE[18836] logger.c: -- ClearCall: Request to clear call with token ip$localhost/30191, cause EndedByTransportFail May 2 14:57:12 VERBOSE[18836] logger.c: 28:31.839 H225 Caller:8132fe0 h323ep.cxx(2059) H323 Clearing connection ip$localhost/30191 reason=EndedByTransportFail May 2 14:57:12 VERBOSE[18836] logger.c: 28:31.839 H225 Caller:8132fe0 transports.cxx(1132) H323 H323Transport::Close May 2 14:57:12 VERBOSE[18836] logger.c: 28:31.839 H225 Caller:8132fe0 h323.cxx(1827) H225 Signal channel closed. May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.840 H323 Cleaner transports.cxx(1214) H323 H323Transport::CleanUpOnTermination for H225 Caller:8132fe0 May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.841 H323 Cleaner h323trans.cxx(530) Trans Making request: disengageRequest May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.841 H323 Cleaner h323pdu.cxx(541) Trans Sending PDU [ip$163.49.131.154:33945/ip$163.49.131.112:1719] : May 2 14:57:12 VERBOSE[18603] logger.c: disengageRequest { May 2 14:57:12 VERBOSE[18603] logger.c: requestSeqNum = 26266 May 2 14:57:12 VERBOSE[18603] logger.c: endpointIdentifier = 9 characters { May 2 14:57:12 VERBOSE[18603] logger.c: 0037 0035 0032 0036 005f 0065 006e 0064 7526_end May 2 14:57:12 VERBOSE[18603] logger.c: 0070 p May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: conferenceID = 16 octets { May 2 14:57:12 VERBOSE[18603] logger.c: e2 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: callReferenceValue = 30191 May 2 14:57:12 VERBOSE[18603] logger.c: disengageReason = normalDrop <> May 2 14:57:12 VERBOSE[18603] logger.c: callIdentifier = { May 2 14:57:12 VERBOSE[18603] logger.c: guid = 16 octets { May 2 14:57:12 VERBOSE[18603] logger.c: c4 60 80 04 b2 b9 d9 11 87 05 00 0b cd 00 48 e3 .`............H. May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: gatekeeperIdentifier = 9 characters { May 2 14:57:12 VERBOSE[18603] logger.c: 0047 006e 0075 0048 0033 0032 0033 0047 GnuH323G May 2 14:57:12 VERBOSE[18603] logger.c: 004b K May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: answeredCall = FALSE May 2 14:57:12 VERBOSE[18603] logger.c: usageInformation = { May 2 14:57:12 VERBOSE[18603] logger.c: nonStandardUsageFields = 0 entries { May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: alertingTime = 1115063821 May 2 14:57:12 VERBOSE[18603] logger.c: connectTime = 1115063825 May 2 14:57:12 VERBOSE[18603] logger.c: endTime = 1115063832 May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: terminationCause = releaseCompleteCauseIE 2 octets { May 2 14:57:12 VERBOSE[18603] logger.c: 80 90 .. May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: } May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.842 H323 Cleaner h323trans.cxx(661) Trans Waiting on response to seqnum=26266 for 3.0 seconds May 2 14:57:12 VERBOSE[18606] logger.c: 28:31.843 Transactor:81356f8 h323pdu.cxx(541) H225RAS Receiving PDU [ip$163.49.131.154:33945/ip$163.49.131.112:1719] : May 2 14:57:12 VERBOSE[18606] logger.c: disengageConfirm { May 2 14:57:12 VERBOSE[18606] logger.c: requestSeqNum = 26266 May 2 14:57:12 VERBOSE[18606] logger.c: } May 2 14:57:12 VERBOSE[18606] logger.c: 28:31.844 Transactor:81356f8 h323trans.cxx(603) Trans Using credentials from request: H235AnnexD_Procedure1,CAT,MD5 May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.845 H323 Cleaner h323.cxx(1735) H323 Connection ip$localhost/30191 terminated. May 2 14:57:12 VERBOSE[18603] logger.c: -- Paul Davidson has cleared the call May 2 14:57:12 DEBUG[18603] chan_h323.c: Cleaning connection to ip$localhost/30191 May 2 14:57:12 VERBOSE[18603] logger.c: == H.323 Connection deleted. May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.845 H323 Cleaner h323.cxx(1556) H323 Connection ip$localhost/30191 deleted. May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.845 H323 Cleaner h323ep.cxx(2116) H323 Cleaning up connections May 2 14:57:12 VERBOSE[18603] logger.c: 28:31.845 H323 Cleaner h323ep.cxx(2116) H323 Cleaning up connections May 2 14:57:41 DEBUG[18621] chan_sip.c: Allocating new SIP call for (null) May 2 14:57:41 DEBUG[18621] chan_sip.c: Allocating new SIP call for (null) May 2 14:57:41 DEBUG[18621] chan_sip.c: Stopping retransmission on '7b787e7960802e4b00dcafc817d3a14c@163.49.131.154' of Request 102: Found May 2 14:57:41 DEBUG[18621] chan_sip.c: Stopping retransmission on '59738ceb71ac0535406a29e55233c55a@163.49.131.154' of Request 102: Found May 2 14:57:41 DEBUG[18617] chan_iax2.c: New max nontrunk callno is 4 May 2 14:57:41 DEBUG[18617] chan_iax2.c: Creating new call structure 3 May 2 14:57:41 DEBUG[18617] chan_iax2.c: Received packet 0, (6, 13) May 2 14:57:41 DEBUG[18617] chan_iax2.c: IAX subclass 13 received May 2 14:57:41 DEBUG[18842] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:57:41 DEBUG[18842] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:57:41 DEBUG[18617] chan_iax2.c: Sending 8 on 3/27027 to 163.49.131.89:4569 May 2 14:57:41 DEBUG[18617] chan_iax2.c: Received packet 1, (6, 13) May 2 14:57:41 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 0 May 2 14:57:41 DEBUG[18617] chan_iax2.c: IAX subclass 13 received May 2 14:57:41 DEBUG[18843] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:57:41 DEBUG[18843] app_queue.c: Device 'IAX2/paul' changed to state '1' May 2 14:57:41 DEBUG[18617] chan_iax2.c: Sending 12 on 3/27027 to 163.49.131.89:4569 May 2 14:57:41 DEBUG[18617] chan_iax2.c: Received packet 2, (6, 4) May 2 14:57:41 DEBUG[18617] chan_iax2.c: Cancelling transmission of packet 1 May 2 14:57:41 DEBUG[18617] chan_iax2.c: Really destroying 3, having been acked on final message