0:11.743 H323 Listener:81e3da0 transports.cxx(1559) H323TCPStarted connection: host=10.174.254.1:3835, if=10.174.1.251:1720, handle=29 0:11.743 H323 Listener:81e3da0 transports.cxx(1356) TCP Waiting on socket accept on ip$10.174.1.251:1720 0:11.744 H225 Answer:81e0a30 transports.cxx(595) H225 Started incoming call thread 0:11.744 H225 Answer:81e0a30 transports.cxx(1161) H225 Awaiting first PDU 0:11.778 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Receiving PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = originator messageType = Setup IE: Bearer-Capability = { 88 93 a5 ... } IE: Display = { 50 43 61 64 61 63 68 00 PCadach. } IE: Calling-Party-Number = { 81 33 32 33 32 32 30 30 39 35 31 .3232200951 } IE: Called-Party-Number = { 81 32 .2 } IE: User-User = { 20 b8 06 00 08 91 4a 00 04 02 40 06 00 50 00 43 .....J...@..P.C 00 61 00 64 00 61 00 63 00 68 04 80 65 65 53 3c .a.d.a.c.h..eeS< 84 22 c0 09 00 00 3d 16 45 71 75 69 76 61 6c 65 ."....=.Equivale 6e 63 65 20 4f 70 65 6e 50 68 6f 6e 65 00 00 19 nce OpenPhone... 31 2e 38 2e 31 20 28 4f 70 65 6e 48 33 32 33 20 1.8.1 (OpenH323 76 31 2e 31 31 2e 37 29 00 00 00 01 00 00 50 0a v1.11.7)......P. ae 01 fb 06 b8 00 4d 94 ad 73 92 f6 18 10 8f 55 ......M..s.....U 00 02 44 83 d0 59 00 5d 0d 80 07 00 0a ae fe 01 ..D..Y.]........ 0e fb 11 00 4d 94 ad 73 92 f6 18 10 8f 54 00 02 ....M..s.....T.. 44 83 d0 59 01 00 01 00 01 00 01 00 04 c0 01 80 D..Y............ 7a 02 70 02 70 01 06 00 08 81 75 00 07 80 13 80 z.p.p.....u..... 00 64 00 01 00 00 01 00 00 01 00 00 0c c0 01 00 .d.............. 01 80 06 80 00 01 20 40 ef 80 00 00 08 d0 18 48 ...... @.......H 00 80 01 00 80 00 02 08 e0 18 48 00 80 01 00 80 ..........H..... 00 03 83 01 50 80 00 04 83 01 10 80 00 05 83 01 ....P........... 40 80 00 06 8a 06 14 04 30 2d 31 36 00 80 01 04 @.......0-16.... 00 00 01 00 00 00 00 00 02 00 00 03 02 00 04 00 ................ 05 00 06 07 01 00 32 80 c1 b4 94 ......2.... } } h225pdu = { h323_uu_pdu = { h323_message_body = setup { protocolIdentifier = 0.0.8.2250.0.4 sourceAddress = 2 entries { [0]=h323_ID 7 characters { 0050 0043 0061 0064 0061 0063 0068 PCadach } [1]=dialedDigits "3232200951" } sourceInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } productId = 23 octets { 45 71 75 69 76 61 6c 65 6e 63 65 20 4f 70 65 6e Equivalence Open 50 68 6f 6e 65 00 00 Phone.. } versionId = 26 octets { 31 2e 38 2e 31 20 28 4f 70 65 6e 48 33 32 33 20 1.8.1 (OpenH323 76 31 2e 31 31 2e 37 29 00 00 v1.11.7).. } } terminal = { } mc = FALSE undefinedNode = FALSE } destinationAddress = 1 entries { [0]=dialedDigits "2" } destCallSignalAddress = ipAddress { ip = 4 octets { 0a ae 01 fb .... } port = 1720 } activeMC = FALSE conferenceID = 16 octets { 4d 94 ad 73 92 f6 18 10 8f 55 00 02 44 83 d0 59 M..s.....U..D..Y } conferenceGoal = create <> callType = pointToPoint <> sourceCallSignalAddress = ipAddress { ip = 4 octets { 0a ae fe 01 .... } port = 3835 } callIdentifier = { guid = 16 octets { 4d 94 ad 73 92 f6 18 10 8f 54 00 02 44 83 d0 59 M..s.....T..D..Y } } mediaWaitForConnect = FALSE canOverlapSend = FALSE multipleCalls = FALSE maintainConnection = FALSE } h245Tunneling = TRUE h245Control = 2 entries { [0]= 112 octets { 02 70 01 06 00 08 81 75 00 07 80 13 80 00 64 00 .p.....u......d. 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 06 ................ 80 00 01 20 40 ef 80 00 00 08 d0 18 48 00 80 01 ... @.......H... 00 80 00 02 08 e0 18 48 00 80 01 00 80 00 03 83 .......H........ 01 50 80 00 04 83 01 10 80 00 05 83 01 40 80 00 .P...........@.. 06 8a 06 14 04 30 2d 31 36 00 80 01 04 00 00 01 .....0-16....... 00 00 00 00 00 02 00 00 03 02 00 04 00 05 00 06 ................ } [1]= 7 octets { 01 00 32 80 c1 b4 94 ..2.... } } } } } 0:11.808 H225 Answer:81e0a30 transports.cxx(1170) H225 Incoming call, first PDU: callReference=15612 0:11.823 H225 Answer:81e0a30 rfc2833.cxx(81) RFC2833 Handler created == New H.323 Connection created. 0:11.823 H225 Answer:81e0a30 h323ep.cxx(2456) H323 Created new connection: ip$10.174.254.1:3835/15612 0:11.823 H225 Answer:81e0a30 h323.cxx(1876) H225 Handling PDU: Setup callRef=15612 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1363 setup_incoming_call: Setting up incoming call for ip$10.174.254.1:3835/15612 -- Setting up Call -- Call token: [ip$10.174.254.1:3835/15612] -- Calling party name: [PCadach] -- Calling party number: [3232200951] -- Called party name: [2] -- Called party number: [2] -- Calling party IP: [10.174.254.1] [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1417 setup_incoming_call: Sending PCadach to context [111] --Received SETUP message 0:11.824 H225 Answer:81e0a30 h323.cxx(2142) H225 Set protocol version to 4 and implying H.245 version 7 0:11.828 H225 Answer:81e0a30 h323.cxx(2401) H225 Set remote application name: "Equivalence OpenPhone 1.8.1 (OpenH323 v1.11.7) 9/61" [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1648 set_local_capabilities: Setting capabilities for connection ip$10.174.254.1:3835/15612 Setting capabilities to 0xe (gsm|ulaw|alaw) 0:11.829 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: G.711-ALaw-64k <1> 0:11.829 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: G.711-uLaw-64k <2> 0:11.829 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: GSM-06.10 <3> 0:11.829 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: UserInput/hookflash <4> 0:11.829 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: UserInput/RFC2833 <5> Allowed Codecs: Table: G.711-ALaw-64k <1> G.711-uLaw-64k <2> GSM-06.10 <3> UserInput/hookflash <4> UserInput/RFC2833 <5> Set: 0: 0: G.711-ALaw-64k <1> G.711-uLaw-64k <2> GSM-06.10 <3> 1: UserInput/hookflash <4> 2: UserInput/RFC2833 <5> [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1661 set_local_capabilities: Capabilities for connection ip$10.174.254.1:3835/15612 is set 0:11.830 H225 Answer:81e0a30 h323.cxx(2249) H225 Sending call proceeding PDU 0:11.831 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Sending PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = destination messageType = CallProceeding IE: Display = { 72 6f 6f 74 00 root. } IE: User-User = { 21 80 06 00 08 91 4a 00 04 22 c0 09 00 00 3d 37 !.....J.."....=7 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann 65 6c 20 44 72 69 76 65 72 20 66 6f 72 20 41 73 el Driver for As 74 65 72 69 73 6b 00 00 19 31 2e 30 2e 30 20 28 terisk...1.0.0 ( 4f 70 65 6e 48 33 32 33 20 76 31 2e 31 38 2e 30 OpenH323 v1.18.0 29 00 00 00 d0 c0 11 00 4d 94 ad 73 92 f6 18 10 ).......M..s.... 8f 54 00 02 44 83 d0 59 01 00 01 00 02 80 01 80 .T..D..Y........ } } h225pdu = { h323_uu_pdu = { h323_message_body = callProceeding { protocolIdentifier = 0.0.8.2250.0.4 destinationInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } productId = 56 octets { 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann 65 6c 20 44 72 69 76 65 72 20 66 6f 72 20 41 73 el Driver for As 74 65 72 69 73 6b 00 00 terisk.. } versionId = 26 octets { 31 2e 30 2e 30 20 28 4f 70 65 6e 48 33 32 33 20 1.0.0 (OpenH323 76 31 2e 31 38 2e 30 29 00 00 v1.18.0).. } } terminal = { } mc = FALSE undefinedNode = FALSE } callIdentifier = { guid = 16 octets { 4d 94 ad 73 92 f6 18 10 8f 54 00 02 44 83 d0 59 M..s.....T..D..Y } } multipleCalls = FALSE maintainConnection = FALSE } h245Tunneling = TRUE } } } 0:11.834 H225 Answer:81e0a30 h323.cxx(2288) H225 Incoming call accepted =-= In OnAnswerCall for call 15612 - Progress Indicator: 0 - Inserting PI of 0 into ALERTING message [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1470 answer_call: Preparing Asterisk to answer for ip$10.174.254.1:3835/15612 0:11.835 H225 Answer:81e0a30 h323.cxx(2792) H323 Answering call: AnswerCallDeferredWithMedia 0:11.836 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartDisabled -- Sent into invalid extension 's' in context 'default' on H323/ip$10.174.254.1:3835/15612 -- Executing [i@default:1] Playback("H323/ip$10.174.254.1:3835/15612", "invalid") in new stack [Aug 19 12:34:25] DEBUG[15328]: chan_h323.c:493 oh323_answer: Answering on H323/ip$10.174.254.1:3835/15612 0:11.921 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Receiving PDU [(noaddr)/(noaddr)] : request terminalCapabilitySet { sequenceNumber = 1 protocolIdentifier = 0.0.8.245.0.7 multiplexCapability = h2250Capability { maximumAudioDelayJitter = 100 receiveMultipointCapability = { multicastCapability = FALSE multiUniCastConference = FALSE mediaDistributionCapability = 1 entries { [0]={ centralizedControl = FALSE distributedControl = FALSE centralizedAudio = FALSE distributedAudio = FALSE centralizedVideo = FALSE distributedVideo = FALSE } } } transmitMultipointCapability = { multicastCapability = FALSE multiUniCastConference = FALSE mediaDistributionCapability = 1 entries { [0]={ centralizedControl = FALSE distributedControl = FALSE centralizedAudio = FALSE distributedAudio = FALSE centralizedVideo = FALSE distributedVideo = FALSE } } } receiveAndTransmitMultipointCapability = { multicastCapability = FALSE multiUniCastConference = FALSE mediaDistributionCapability = 1 entries { [0]={ centralizedControl = FALSE distributedControl = FALSE centralizedAudio = FALSE distributedAudio = FALSE centralizedVideo = FALSE distributedVideo = FALSE } } } mcCapability = { centralizedConferenceMC = FALSE decentralizedConferenceMC = FALSE } rtcpVideoControlCapability = FALSE mediaPacketizationCapability = { h261aVideoPacketization = FALSE } logicalChannelSwitchingCapability = FALSE t120DynamicPortCapability = TRUE } capabilityTable = 7 entries { [0]={ capabilityTableEntryNumber = 2 capability = receiveAudioCapability g711Alaw64k 240 } [1]={ capabilityTableEntryNumber = 1 capability = receiveVideoCapability h261VideoCapability { cifMPI = 1 temporalSpatialTradeOffCapability = FALSE maxBitRate = 6217 stillImageTransmission = FALSE videoBadMBsCap = FALSE } } [2]={ capabilityTableEntryNumber = 3 capability = receiveVideoCapability h261VideoCapability { qcifMPI = 1 temporalSpatialTradeOffCapability = FALSE maxBitRate = 6217 stillImageTransmission = FALSE videoBadMBsCap = FALSE } } [3]={ capabilityTableEntryNumber = 4 capability = receiveUserInputCapability hookflash <> } [4]={ capabilityTableEntryNumber = 5 capability = receiveUserInputCapability basicString <> } [5]={ capabilityTableEntryNumber = 6 capability = receiveUserInputCapability dtmf <> } [6]={ capabilityTableEntryNumber = 7 capability = receiveRTPAudioTelephonyEventCapability { dynamicRTPPayloadType = 101 audioTelephoneEvent = "0-16" } } } capabilityDescriptors = 1 entries { [0]={ capabilityDescriptorNumber = 1 simultaneousCapabilities = 5 entries { [0]=1 entries { [0]=2 } [1]=1 entries { [0]=1 } [2]=1 entries { [0]=3 } [3]=1 entries { [0]=4 } [4]=3 entries { [0]=5 [1]=6 [2]=7 } } } } } 0:11.926 H225 Answer:81e0a30 h323.cxx(3767) H245 Set protocol version to 7 0:11.926 H225 Answer:81e0a30 h323neg.cxx(591) H245 Received TerminalCapabilitySet: state=Idle pduSeq=1 inSeq=4294967295 0:11.926 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: G.711-ALaw-64k <1> 0:11.926 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: G.711-uLaw-64k <2> 0:11.926 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: GSM-06.10 <3> 0:11.926 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: UserInput/hookflash <4> 0:11.926 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: UserInput/RFC2833 <5> 0:11.927 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.711-ALaw-64k{hw}" 0:11.927 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.711-ALaw-64k{sw}" 0:11.927 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: G.711-ALaw-64k <6> 0:11.927 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.711-uLaw-64k{hw}" 0:11.927 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.711-uLaw-64k{sw}" 0:11.927 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: G.711-uLaw-64k <7> 0:11.927 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.723.1" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: G.723.1 <8> 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.723.1(5.3k){hw}" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.723.1A(5.3k){hw}" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.723.1A(6.3k)-Cisco{hw}" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.723.1A(6.3k){hw}" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.723.1{hw}" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.728{hw}" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.729" 0:11.928 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: G.729 <9> 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.729A" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: G.729A <10> 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.729A/B{hw}" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.729A{hw}" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.729B{hw}" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "G.729{hw}" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "GSM-06.10" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2240) H323 Found capability: GSM-06.10 <3> 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "GSM-06.10{hw}" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "GSM-06.10{sw}" 0:11.929 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: GSM-06.10{sw} <11> 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "MS-GSM{sw}" 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: MS-GSM{sw} <12> 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: UserInput/hookflash <13> 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: UserInput/basicString <14> 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: UserInput/dtmf <15> 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2143) H323 Added capability: UserInput/RFC2833 <16> 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2282) H323 FindCapability: receiveAudioCapability 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2533) H323 FindCapability: Audio subtype=1 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2539) H323 Found capability: G.711-ALaw-64k <1> 0:11.930 H225 Answer:81e0a30 h323caps.cxx(997) H323 Capability tx frames left at 20 as remote allows 240 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2282) H323 FindCapability: receiveVideoCapability 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2533) H323 FindCapability: Video subtype=1 0:11.930 H225 Answer:81e0a30 h323caps.cxx(2282) H323 FindCapability: receiveVideoCapability 0:11.931 H225 Answer:81e0a30 h323caps.cxx(2533) H323 FindCapability: Video subtype=1 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2282) H323 FindCapability: receiveUserInputCapability 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2533) H323 FindCapability: UserInput subtype=5 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2539) H323 Found capability: UserInput/hookflash <4> 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2282) H323 FindCapability: receiveUserInputCapability 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2533) H323 FindCapability: UserInput subtype=1 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2539) H323 Found capability: UserInput/basicString <14> 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2282) H323 FindCapability: receiveUserInputCapability 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2533) H323 FindCapability: UserInput subtype=4 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2539) H323 Found capability: UserInput/dtmf <15> 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2282) H323 FindCapability: receiveRTPAudioTelephonyEventCapability 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2533) H323 FindCapability: UserInput subtype=10000 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2539) H323 Found capability: UserInput/RFC2833 <5> 0:11.932 H225 Answer:81e0a30 h323caps.cxx(2267) H323 FindCapability: G.711-ALaw-64k <2> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: G.711-ALaw-64k <2> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2267) H323 FindCapability: UserInput/hookflash <4> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: UserInput/hookflash <4> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2267) H323 FindCapability: UserInput/basicString <5> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: UserInput/basicString <5> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2267) H323 FindCapability: UserInput/dtmf <6> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: UserInput/dtmf <6> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2267) H323 FindCapability: UserInput/RFC2833 <7> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2153) H323 Added capability: UserInput/RFC2833 <7> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2215) H323 FindCapability: 2 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2219) H323 Found capability: G.711-ALaw-64k <2> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2215) H323 FindCapability: 4 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2219) H323 Found capability: UserInput/hookflash <4> 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2215) H323 FindCapability: 5 0:11.933 H225 Answer:81e0a30 h323caps.cxx(2219) H323 Found capability: UserInput/basicString <5> 0:11.934 H225 Answer:81e0a30 h323caps.cxx(2215) H323 FindCapability: 6 0:11.934 H225 Answer:81e0a30 h323caps.cxx(2219) H323 Found capability: UserInput/dtmf <6> 0:11.934 H225 Answer:81e0a30 h323caps.cxx(2215) H323 FindCapability: 7 0:11.934 H225 Answer:81e0a30 h323caps.cxx(2219) H323 Found capability: UserInput/RFC2833 <7> 0:11.934 H225 Answer:81e0a30 h323caps.cxx(2628) H245 Capability merge result: Table: G.711-ALaw-64k <2> UserInput/hookflash <4> UserInput/basicString <5> UserInput/dtmf <6> UserInput/RFC2833 <7> Set: 0: 0: G.711-ALaw-64k <2> 1: 2: 3: UserInput/hookflash <4> 4: UserInput/basicString <5> UserInput/dtmf <6> UserInput/RFC2833 <7> 0:11.935 H225 Answer:81e0a30 h323caps.cxx(2630) H245 Received capability set, is accepted 0:11.935 H225 Answer:81e0a30 h323neg.cxx(560) H245 Sending TerminalCapabilitySet: outSeq=1 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1612 set_dtmf_payload: Setting DTMF payload to 101 on ip$10.174.254.1:3835/15612 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1623 set_dtmf_payload: DTMF payload on ip$10.174.254.1:3835/15612 set to 101 -- Transmitting RFC2833 on payload 101 0:11.943 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Sending PDU [(noaddr)/(noaddr)] : request terminalCapabilitySet { sequenceNumber = 1 protocolIdentifier = 0.0.8.245.0.11 multiplexCapability = h2250Capability { maximumAudioDelayJitter = 250 receiveMultipointCapability = { multicastCapability = FALSE multiUniCastConference = FALSE mediaDistributionCapability = 1 entries { [0]={ centralizedControl = FALSE distributedControl = FALSE centralizedAudio = FALSE distributedAudio = FALSE centralizedVideo = FALSE distributedVideo = FALSE } } } transmitMultipointCapability = { multicastCapability = FALSE multiUniCastConference = FALSE mediaDistributionCapability = 1 entries { [0]={ centralizedControl = FALSE distributedControl = FALSE centralizedAudio = FALSE distributedAudio = FALSE centralizedVideo = FALSE distributedVideo = FALSE } } } receiveAndTransmitMultipointCapability = { multicastCapability = FALSE multiUniCastConference = FALSE mediaDistributionCapability = 1 entries { [0]={ centralizedControl = FALSE distributedControl = FALSE centralizedAudio = FALSE distributedAudio = FALSE centralizedVideo = FALSE distributedVideo = FALSE } } } mcCapability = { centralizedConferenceMC = FALSE decentralizedConferenceMC = FALSE } rtcpVideoControlCapability = FALSE mediaPacketizationCapability = { h261aVideoPacketization = FALSE } logicalChannelSwitchingCapability = FALSE t120DynamicPortCapability = TRUE } capabilityTable = 5 entries { [0]={ capabilityTableEntryNumber = 1 capability = receiveAudioCapability g711Alaw64k 240 } [1]={ capabilityTableEntryNumber = 2 capability = receiveAudioCapability g711Ulaw64k 240 } [2]={ capabilityTableEntryNumber = 3 capability = receiveAudioCapability gsmFullRate { audioUnitSize = 24 comfortNoise = FALSE scrambled = FALSE } } [3]={ capabilityTableEntryNumber = 4 capability = receiveUserInputCapability hookflash <> } [4]={ capabilityTableEntryNumber = 5 capability = receiveRTPAudioTelephonyEventCapability { dynamicRTPPayloadType = 101 audioTelephoneEvent = "0-16" } } } capabilityDescriptors = 1 entries { [0]={ capabilityDescriptorNumber = 1 simultaneousCapabilities = 3 entries { [0]=3 entries { [0]=1 [1]=2 [2]=3 } [1]=1 entries { [0]=4 } [2]=1 entries { [0]=5 } } } } } 0:11.950 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "UserInput/RFC2833" 0:11.950 H225 Answer:81e0a30 h323caps.cxx(2240) H323 Found capability: UserInput/RFC2833 <7> 0:11.950 H225 Answer:81e0a30 h323.cxx(4257) H323 User Input RFC2833 payload type set to [pt=101] 0:11.950 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "UserInput/RFC2833" 0:11.951 H225 Answer:81e0a30 h323caps.cxx(2240) H323 Found capability: UserInput/RFC2833 <7> [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1612 set_dtmf_payload: Setting DTMF payload to 101 on ip$10.174.254.1:3835/15612 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1623 set_dtmf_payload: DTMF payload on ip$10.174.254.1:3835/15612 set to 101 -- Inbound RFC2833 on payload [pt=101] Peer capability is G.711-ALaw-64k <2> Found peer capability G.711-ALaw-64k <2>, Asterisk code is 8 Peer capability is UserInput/hookflash <4> Peer capability is UserInput/basicString <5> Peer capability is UserInput/dtmf <6> Peer capability is UserInput/RFC2833 <7> Peer capabilities = 0x8 (alaw) [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1631 set_peer_capabilities: Got remote capabilities from connection ip$10.174.254.1:3835/15612 0:11.951 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Sending PDU [(noaddr)/(noaddr)] : response terminalCapabilitySetAck { sequenceNumber = 1 } 0:11.952 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartDisabled 0:11.952 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Receiving PDU [(noaddr)/(noaddr)] : request masterSlaveDetermination { terminalType = 50 statusDeterminationNumber = 12694676 } 0:11.953 H225 Answer:81e0a30 h323neg.cxx(355) H245 Received MasterSlaveDetermination: state=Idle 0:11.953 H225 Answer:81e0a30 h323neg.cxx(386) H245 MasterSlaveDetermination: local is slave 0:11.953 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Sending PDU [(noaddr)/(noaddr)] : response masterSlaveDeterminationAck { decision = master <> } 0:11.953 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartDisabled 0:11.954 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Sending PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = destination messageType = Facility IE: Facility = { } IE: User-User = { 28 10 01 00 04 c0 01 80 60 03 57 02 70 01 06 00 (.......`.W.p... 08 81 75 00 0b 80 13 80 00 fa 00 01 00 00 01 00 ..u............. 00 01 00 00 0c c0 01 00 01 80 04 80 00 00 20 40 .............. @ ef 80 00 01 20 c0 ef 80 00 02 24 18 03 00 17 00 .... .....$..... 80 00 03 83 01 50 80 00 04 8a 06 14 04 30 2d 31 .....P.......0-1 36 00 80 01 02 02 00 00 00 01 00 02 00 00 03 00 6............... 00 04 03 21 80 01 02 20 80 ...!... . } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 3 entries { [0]= 87 octets { 02 70 01 06 00 08 81 75 00 0b 80 13 80 00 fa 00 .p.....u........ 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 04 ................ 80 00 00 20 40 ef 80 00 01 20 c0 ef 80 00 02 24 ... @.... .....$ 18 03 00 17 00 80 00 03 83 01 50 80 00 04 8a 06 ..........P..... 14 04 30 2d 31 36 00 80 01 02 02 00 00 00 01 00 ..0-16.......... 02 00 00 03 00 00 04 ....... } [1]= 3 octets { 21 80 01 !.. } [2]= 2 octets { 20 80 . } } } } } 0:11.956 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartDisabled 0:11.957 H225 Answer:81e0a30 h323.cxx(1816) H225 Reading PDUs: callRef=15612 Answering call ip$10.174.254.1:3835/15612 0:11.957 ThreadID=0xb5abcbb0 h323.cxx(2792) H323 Answering call: AnswerCallNow 0:11.957 ThreadID=0xb5abcbb0 h323.cxx(3592) H245 Started control channel 0:11.957 ThreadID=0xb5abcbb0 h323neg.cxx(546) H245 TerminalCapabilitySet already in progress: outSeq=1 0:11.957 ThreadID=0xb5abcbb0 h323neg.cxx(309) H245 MasterSlaveDetermination already in progress 0:11.958 ThreadID=0xb5abcbb0 h323pdu.cxx(553) H225 Sending PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = destination messageType = Connect IE: Bearer-Capability = { 80 90 a5 ... } IE: Display = { 72 6f 6f 74 00 root. } IE: User-User = { 22 80 06 00 08 91 4a 00 04 22 c0 09 00 00 3d 37 ".....J.."....=7 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann 65 6c 20 44 72 69 76 65 72 20 66 6f 72 20 41 73 el Driver for As 74 65 72 69 73 6b 00 00 19 31 2e 30 2e 30 20 28 terisk...1.0.0 ( 4f 70 65 6e 48 33 32 33 20 76 31 2e 31 38 2e 30 OpenH323 v1.18.0 29 00 00 00 4d 94 ad 73 92 f6 18 10 8f 55 00 02 )...M..s.....U.. 44 83 d0 59 17 0c 20 11 00 4d 94 ad 73 92 f6 18 D..Y.. ..M..s... 10 8f 54 00 02 44 83 d0 59 01 00 01 00 01 00 02 ..T..D..Y....... 80 01 80 ... } } h225pdu = { h323_uu_pdu = { h323_message_body = connect { protocolIdentifier = 0.0.8.2250.0.4 destinationInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } productId = 56 octets { 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann 65 6c 20 44 72 69 76 65 72 20 66 6f 72 20 41 73 el Driver for As 74 65 72 69 73 6b 00 00 terisk.. } versionId = 26 octets { 31 2e 30 2e 30 20 28 4f 70 65 6e 48 33 32 33 20 1.0.0 (OpenH323 76 31 2e 31 38 2e 30 29 00 00 v1.18.0).. } } terminal = { } mc = FALSE undefinedNode = FALSE } conferenceID = 16 octets { 4d 94 ad 73 92 f6 18 10 8f 55 00 02 44 83 d0 59 M..s.....U..D..Y } callIdentifier = { guid = 16 octets { 4d 94 ad 73 92 f6 18 10 8f 54 00 02 44 83 d0 59 M..s.....T..D..Y } } multipleCalls = FALSE maintainConnection = FALSE fastConnectRefused = <> } h245Tunneling = TRUE } } } 0:11.962 ThreadID=0xb5abcbb0 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled [Aug 19 12:34:25] DEBUG[15328]: channel.c:1819 ast_settimeout: Scheduling timer at 160 sample intervals -- Playing 'invalid' (language 'en') 0:12.048 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Receiving PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = originator messageType = Facility IE: Facility = { } IE: User-User = { 28 10 00 04 c0 01 80 08 02 03 21 80 01 02 20 a0 (.........!... . } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 2 entries { [0]= 3 octets { 21 80 01 !.. } [1]= 2 octets { 20 a0 . } } } } } 0:12.049 H225 Answer:81e0a30 h323.cxx(1876) H225 Handling PDU: Facility callRef=15612 -- Received Facility message... 0:12.050 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Receiving PDU [(noaddr)/(noaddr)] : response terminalCapabilitySetAck { sequenceNumber = 1 } 0:12.050 H225 Answer:81e0a30 h323neg.cxx(633) H245 Received TerminalCapabilitySetAck: state=InProgress pduSeq=1 outSeq=1 0:12.050 H225 Answer:81e0a30 h323neg.cxx(643) H245 TerminalCapabilitySet Sent. 0:12.050 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled 0:12.050 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Receiving PDU [(noaddr)/(noaddr)] : response masterSlaveDeterminationAck { decision = slave <> } 0:12.050 H225 Answer:81e0a30 h323neg.cxx(414) H245 Received MasterSlaveDeterminationAck: state=Incoming 0:12.051 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled 0:12.051 H225 Answer:81e0a30 h323.cxx(4445) H245 Default OnSelectLogicalChannels, FastStartDisabled 0:12.051 H225 Answer:81e0a30 h323caps.cxx(2267) H323 FindCapability: G.711-ALaw-64k <1> 0:12.051 H225 Answer:81e0a30 h323caps.cxx(2271) H323 Found capability: G.711-ALaw-64k <2> 0:12.051 H225 Answer:81e0a30 h323.cxx(4510) H323 Selecting G.711-ALaw-64k <2> 0:12.051 H225 Answer:81e0a30 h323neg.cxx(759) H245 Opening channel: T-101 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1233 external_rtp_create: Sending RTP 'US' 10.174.1.251:12448 0:12.069 H225 Answer:81e0a30 channels.cxx(920) H323RTP OnSendingPDU -- Started logical channel: sending G.711-ALaw-64k 0:12.079 H225 Answer:81e0a30 codecs.cxx(1331) Codec G711 ALaw encoder created for at 64k, 160 samples 0:12.079 H225 Answer:81e0a30 channels.cxx(802) LogChan Bandwidth requested/used = 64.0/0.0 kb/s 0:12.079 H225 Answer:81e0a30 h323.cxx(4932) H323 Bandwidth request: -0.0kb/s, available: 128.0kb/s 0:12.079 H225 Answer:81e0a30 h323.cxx(4932) H323 Bandwidth request: +64.0kb/s, available: 128.0kb/s 0:12.079 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Sending PDU [(noaddr)/(noaddr)] : request openLogicalChannel { forwardLogicalChannelNumber = 101 forwardLogicalChannelParameters = { dataType = audioData g711Alaw64k 20 multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaGuaranteedDelivery = FALSE mediaControlChannel = unicastAddress iPAddress { network = 4 octets { 0a ae 01 fb .... } tsapIdentifier = 12449 } silenceSuppression = FALSE } } } 0:12.084 H225 Answer:81e0a30 h323caps.cxx(2231) H323 FindCapability: "T.120" =-= In OnConnectionEstablished for call 15612 -- Connection Established with "PCadach (3232200951) [10.174.254.1]" [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1309 connection_made: Call ip$10.174.254.1:3835/15612 answered 0:12.084 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Sending PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = destination messageType = Facility IE: Facility = { } IE: User-User = { 28 10 01 00 04 c0 01 80 16 01 14 03 00 00 64 0c (.............d. 20 13 80 0b 0d 00 01 00 0a ae 01 fb 30 a1 00 ...........0.. } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 1 entries { [0]= 20 octets { 03 00 00 64 0c 20 13 80 0b 0d 00 01 00 0a ae 01 ...d. .......... fb 30 a1 00 .0.. } } } } } 0:12.086 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled 0:12.086 H225 Answer:81e0a30 h323neg.cxx(1634) H245 Started round trip delay: seq=1 awaitingResponse 0:12.086 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Sending PDU [(noaddr)/(noaddr)] : request roundTripDelayRequest { sequenceNumber = 1 } 0:12.086 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Sending PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = destination messageType = Facility IE: Facility = { } IE: User-User = { 28 10 01 00 04 c0 01 80 05 01 03 09 00 01 (............. } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 1 entries { [0]= 3 octets { 09 00 01 ... } } } } } 0:12.088 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Receiving PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = originator messageType = Facility IE: Facility = { } IE: User-User = { 28 10 00 04 c0 01 80 16 01 14 03 00 00 64 0c 20 (............d. 13 80 0b 0d 00 01 00 0a ae fe 01 13 8f 00 .............. } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 1 entries { [0]= 20 octets { 03 00 00 64 0c 20 13 80 0b 0d 00 01 00 0a ae fe ...d. .......... 01 13 8f 00 .... } } } } } 0:12.090 H225 Answer:81e0a30 h323.cxx(1876) H225 Handling PDU: Facility callRef=15612 -- Received Facility message... 0:12.090 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Receiving PDU [(noaddr)/(noaddr)] : request openLogicalChannel { forwardLogicalChannelNumber = 101 forwardLogicalChannelParameters = { dataType = audioData g711Alaw64k 20 multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaGuaranteedDelivery = FALSE mediaControlChannel = unicastAddress iPAddress { network = 4 octets { 0a ae fe 01 .... } tsapIdentifier = 5007 } silenceSuppression = FALSE } } } 0:12.091 H225 Answer:81e0a30 h323neg.cxx(851) H245 Received open channel: R-101, state=Released 0:12.091 H225 Answer:81e0a30 h323.cxx(4691) H323 CreateLogicalChannel - forward channel 0:12.091 H225 Answer:81e0a30 h323caps.cxx(2330) H323 FindCapability: audioData 0:12.091 H225 Answer:81e0a30 h323caps.cxx(1022) H323 Capability tx frames left at 20 as remote allows 20 0:12.091 H225 Answer:81e0a30 h323caps.cxx(2375) H323 Found capability: G.711-ALaw-64k <1> 0:12.091 H225 Answer:81e0a30 h323caps.cxx(1017) H323 Capability rx frames reduced from 240 to 20 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1233 external_rtp_create: Sending RTP 'US' 10.174.1.251:12448 0:12.091 H225 Answer:81e0a30 codecs.cxx(1331) Codec G711 ALaw decoder created for at 64k, 160 samples 0:12.092 H225 Answer:81e0a30 channels.cxx(802) LogChan Bandwidth requested/used = 64.0/0.0 kb/s 0:12.092 H225 Answer:81e0a30 h323.cxx(4932) H323 Bandwidth request: -0.0kb/s, available: 64.0kb/s 0:12.092 H225 Answer:81e0a30 h323.cxx(4932) H323 Bandwidth request: +64.0kb/s, available: 64.0kb/s 0:12.092 H225 Answer:81e0a30 channels.cxx(979) H323RTP OnReceivedPDU for channel: R-101 0:12.092 H225 Answer:81e0a30 h323caps.cxx(1022) H323 Capability rx frames left at 20 as remote allows 20 0:12.092 H225 Answer:81e0a30 channels.cxx(948) H323RTP OnSendOpenAck 0:12.092 H225 Answer:81e0a30 channels.cxx(969) H323RTP Sending open logical channel ACK: sessionID=1 -- Started logical channel: receiving G.711-ALaw-64k External RTP Session Starting RTP channel id 1 parameters: -- remoteIpAddress: 10.174.254.1 -- remotePort: 5006 -- ExternalIpAddress: 10.174.1.251 -- ExternalPort: 12448 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1257 setup_rtp_connection: Setting up RTP connection for ip$10.174.254.1:3835/15612 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1295 setup_rtp_connection: RTP connection prepared for ip$10.174.254.1:3835/15612 0:12.095 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Sending PDU [(noaddr)/(noaddr)] : response openLogicalChannelAck { forwardLogicalChannelNumber = 101 forwardMultiplexAckParameters = h2250LogicalChannelAckParameters { sessionID = 1 mediaChannel = unicastAddress iPAddress { network = 4 octets { 0a ae 01 fb .... } tsapIdentifier = 12448 } mediaControlChannel = unicastAddress iPAddress { network = 4 octets { 0a ae 01 fb .... } tsapIdentifier = 12449 } flowControlToZero = FALSE } } 0:12.096 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled 0:12.096 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Sending PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = destination messageType = Facility IE: Facility = { } IE: User-User = { 28 10 01 00 04 c0 01 80 1c 01 1a 22 c0 00 64 02 (.........."..d. 80 13 5c 00 00 0a ae 01 fb 30 a0 00 0a ae 01 fb ..\......0...... 30 a1 01 01 00 0.... } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 1 entries { [0]= 26 octets { 22 c0 00 64 02 80 13 5c 00 00 0a ae 01 fb 30 a0 "..d...\......0. 00 0a ae 01 fb 30 a1 01 01 00 .....0.... } } } } } 0:12.098 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled 0:12.171 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Receiving PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = originator messageType = Facility IE: Facility = { } IE: User-User = { 28 10 00 04 c0 01 80 1c 01 1a 22 c0 00 64 02 80 (........."..d.. 13 5c 00 00 0a ae fe 01 13 8e 00 0a ae fe 01 13 .\.............. 8f 01 01 00 .... } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 1 entries { [0]= 26 octets { 22 c0 00 64 02 80 13 5c 00 00 0a ae fe 01 13 8e "..d...\........ 00 0a ae fe 01 13 8f 01 01 00 .......... } } } } } 0:12.172 H225 Answer:81e0a30 h323.cxx(1876) H225 Handling PDU: Facility callRef=15612 -- Received Facility message... 0:12.172 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Receiving PDU [(noaddr)/(noaddr)] : response openLogicalChannelAck { forwardLogicalChannelNumber = 101 forwardMultiplexAckParameters = h2250LogicalChannelAckParameters { sessionID = 1 mediaChannel = unicastAddress iPAddress { network = 4 octets { 0a ae fe 01 .... } tsapIdentifier = 5006 } mediaControlChannel = unicastAddress iPAddress { network = 4 octets { 0a ae fe 01 .... } tsapIdentifier = 5007 } flowControlToZero = FALSE } } 0:12.173 H225 Answer:81e0a30 h323neg.cxx(914) H245 Received open channel ack: T-101, state=AwaitingEstablishment 0:12.173 H225 Answer:81e0a30 channels.cxx(1025) H323RTP OnReceiveOpenAck MyH323_ExternalRTPChannel::OnReceivedAckPDU -- remoteIpAddress: 10.174.254.1 -- remotePort: 5006 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1257 setup_rtp_connection: Setting up RTP connection for ip$10.174.254.1:3835/15612 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1295 setup_rtp_connection: RTP connection prepared for ip$10.174.254.1:3835/15612 External RTP Session Starting RTP channel id 1 parameters: -- remoteIpAddress: 10.174.254.1 -- remotePort: 5006 -- ExternalIpAddress: 10.174.1.251 -- ExternalPort: 12448 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1257 setup_rtp_connection: Setting up RTP connection for ip$10.174.254.1:3835/15612 [Aug 19 12:34:25] DEBUG[15327]: chan_h323.c:1295 setup_rtp_connection: RTP connection prepared for ip$10.174.254.1:3835/15612 0:12.175 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled 0:12.175 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled 0:12.175 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Receiving PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = originator messageType = Facility IE: Facility = { } IE: User-User = { 28 10 00 04 c0 01 80 05 01 03 28 00 01 (.........(.. } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <> h245Tunneling = TRUE h245Control = 1 entries { [0]= 3 octets { 28 00 01 (.. } } } } } 0:12.176 H225 Answer:81e0a30 h323.cxx(1876) H225 Handling PDU: Facility callRef=15612 -- Received Facility message... 0:12.176 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Receiving PDU [(noaddr)/(noaddr)] : response roundTripDelayResponse { sequenceNumber = 1 } 0:12.176 H225 Answer:81e0a30 h323neg.cxx(1667) H245 Handling round trip delay: seq=1 awaitingResponse 0:12.176 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled 0:12.177 H225 Answer:81e0a30 h323.cxx(4374) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled 0:14.728 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Receiving PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = originator messageType = ReleaseComplete IE: Cause - Normal call clearing = { 80 90 .. } IE: User-User = { 25 80 06 00 08 91 4a 00 04 01 11 00 4d 94 ad 73 %.....J.....M..s 92 f6 18 10 8f 54 00 02 44 83 d0 59 04 c0 01 80 .....T..D..Y.... 04 01 02 4a 40 ...J@ } } h225pdu = { h323_uu_pdu = { h323_message_body = releaseComplete { protocolIdentifier = 0.0.8.2250.0.4 callIdentifier = { guid = 16 octets { 4d 94 ad 73 92 f6 18 10 8f 54 00 02 44 83 d0 59 M..s.....T..D..Y } } } h245Tunneling = TRUE h245Control = 1 entries { [0]= 2 octets { 4a 40 J@ } } } } } 0:14.730 H225 Answer:81e0a30 h323.cxx(1876) H225 Handling PDU: ReleaseComplete callRef=15612 -- Received RELEASE COMPLETE message... [Aug 19 12:34:28] DEBUG[15327]: chan_h323.c:1587 hangup_connection: Hanging up connection to ip$10.174.254.1:3835/15612 with cause 16 -- ClearCall: Request to clear call with token ip$10.174.254.1:3835/15612, cause EndedByRemoteUser [Aug 19 12:34:28] DEBUG[15328]: channel.c:1819 ast_settimeout: Scheduling timer at 0 sample intervals == Spawn extension (default, i, 1) exited non-zero on 'H323/ip$10.174.254.1:3835/15612' [Aug 19 12:34:28] DEBUG[15328]: chan_h323.c:518 oh323_hangup: Hanging up call H323/ip$10.174.254.1:3835/15612 0:14.732 H225 Answer:81e0a30 h323ep.cxx(2119) H323 Clearing connection ip$10.174.254.1:3835/15612 reason=EndedByRemoteUser 0:14.732 H225 Answer:81e0a30 h323.cxx(1629) H323 Call end reason for ip$10.174.254.1:3835/15612 set to EndedByRemoteUser 0:14.732 H225 Answer:81e0a30 h323.cxx(1647) H225 Sending release complete PDU: callRef=15612 -- Sending RELEASE COMPLETE 0:14.732 H225 Answer:81e0a30 h323pdu.cxx(553) H245 Sending PDU [(noaddr)/(noaddr)] : command endSessionCommand disconnect <> 0:14.733 H225 Answer:81e0a30 h323pdu.cxx(553) H225 Sending PDU [ip$10.174.1.251:1720/ip$10.174.254.1:3835] : { q931pdu = { protocolDiscriminator = 8 callReference = 15612 from = destination messageType = ReleaseComplete IE: Cause - Normal call clearing = { 80 90 .. } IE: User-User = { 25 80 06 00 08 91 4a 00 04 01 11 00 4d 94 ad 73 %.....J.....M..s 92 f6 18 10 8f 54 00 02 44 83 d0 59 04 c0 01 80 .....T..D..Y.... 04 01 02 4a 40 ...J@ } } h225pdu = { h323_uu_pdu = { h323_message_body = releaseComplete { protocolIdentifier = 0.0.8.2250.0.4 callIdentifier = { guid = 16 octets { 4d 94 ad 73 92 f6 18 10 8f 54 00 02 44 83 d0 59 M..s.....T..D..Y } } } h245Tunneling = TRUE h245Control = 1 entries { [0]= 2 octets { 4a 40 J@ } } } } } 14.735 H323 Cleaner h323ep.cxx(2176) H323 Cleaning up connections 0:14.737 H225 Answer:81e0a30 h323ep.cxx(2119) H323 Clearing connection ip$10.174.254.1:3835/15612 reason=EndedByTransportFail 0:14.737 H323 Cleaner h323.cxx(1689) H323 Connection ip$10.174.254.1:3835/15612 closing: connectionState=EstablishedConnection 0:14.737 H225 Answer:81e0a30 h323.cxx(1866) H225 Signal channel closed. 0:14.737 H323 Cleaner h323neg.cxx(340) H245 Stopping MasterSlaveDetermination: state=Idle 0:14.737 H323 Cleaner h323neg.cxx(572) H245 Stopping TerminalCapabilitySet: state=Sent 0:14.738 H323 Cleaner channels.cxx(685) LogChan Cleaning up T-101 0:14.738 H323 Cleaner h323ep.cxx(2509) H323 Stopped sending logical channel: G.711-ALaw-64k <2> 0:14.738 H323 Cleaner channels.cxx(713) LogChan Cleaned up T-101 0:14.738 H323 Cleaner channels.cxx(685) LogChan Cleaning up R-101 0:14.738 H323 Cleaner h323ep.cxx(2509) H323 Stopped receiving logical channel: G.711-ALaw-64k <1> 0:14.738 H323 Cleaner channels.cxx(713) LogChan Cleaned up R-101 0:14.738 H323 Cleaner h323.cxx(4932) H323 Bandwidth request: -64.0kb/s, available: 0.0kb/s 0:14.738 H323 Cleaner h323.cxx(4932) H323 Bandwidth request: -64.0kb/s, available: 64.0kb/s 0:14.738 H323 Cleaner h323.cxx(1732) H323 Awaiting end session from remote for 9.992 seconds 0:14.738 H323 Cleaner transports.cxx(1143) H323 H323Transport::Close 0:14.761 H323 Cleaner transports.cxx(1240) H323 H323Transport::CleanUpOnTermination for H225 Answer:81e0a30 0:14.761 H323 Cleaner h323.cxx(1757) H323 Connection ip$10.174.254.1:3835/15612 terminated. -- PCadach (3232200951) [10.174.254.1] has cleared the call == H.323 Connection deleted. 0:14.774 H323 Cleaner h323.cxx(1579) H323 Connection ip$10.174.254.1:3835/15612 deleted. 0:14.774 H323 Cleaner h323ep.cxx(2176) H323 Cleaning up connections