Script started on Thu Apr 7 12:22:07 2005 minitokyo:~/asterisk # asterisk -r Asterisk CVS-HEAD-04/07/05-11:55:55, Copyright (C) 1999 - 2005 Digium. Written by Mark Spencer ========================================================================= Connected to Asterisk CVS-HEAD-04/07/05-11:55:55 currently running on minitokyo (pid = 32634) minitokyo*CLI> set debuvg 4 minitokyo*CLI> Core debug was 0 and is now 4 minitokyo*CLI> set verbose 4 minitokyo*CLI> Verbosity was 0 and is now 4 minitokyo*CLI> h.323 debug minitokyo*CLI> H323 debug enabled minitokyo*CLI> h.323 trace 4 minitokyo*CLI> H.323 trace set to level 4 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:968 update_max_nontrunk: New max nontrunk callno is 2 Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:1067 find_callno: Creating new call structure 1 Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 0, (6, 1) Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6519 socket_read: IAX subclass 1 received Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 12 on 1/9053 to 163.49.131.89:4569 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 1, (6, 9) Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6422 socket_read: Cancelling transmission of packet 0 Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6519 socket_read: IAX subclass 9 received -- Accepting AUTHENTICATED call from 163.49.131.89: > requested format = ulaw, > requested prefs = (), > actual format = ulaw, > host prefs = (ulaw), > priority = mine Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:8873 iax2_devicestate: Checking device state for device paul@paul Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:8880 iax2_devicestate: Found peer. Now checking device state for peer paul Apr 7 12:22:36 DEBUG[32654]: pbx.c:1810 ast_device_state_changed: Changing state for IAX2/paul@paul - state 1 minitokyo*CLI> Apr 7 12:22:36 DEBUG[213]: app_queue.c:420 changethread: Device 'IAX2/paul@paul' changed to state '1' minitokyo*CLI> Apr 7 12:22:36 DEBUG[213]: app_queue.c:448 changethread: Device 'IAX2/paul@paul' changed to state '1' minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 16 on 1/9053 to 163.49.131.89:4569 minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: pbx.c:1601 pbx_extension_helper: Launching 'Dial' minitokyo*CLI> -- Executing Dial("IAX2/paul@paul-1", "H323/74307") in new stack minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: chan_h323.c:1185 oh323_request: type=H323, format=4, data=74307. minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: chan_h323.c:1220 oh323_request: Extension: Host: 74307 minitokyo*CLI> 26:13.595 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.595 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.595 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.595 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 1 minitokyo*CLI> 26:13.596 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.596 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 2 minitokyo*CLI> 26:13.596 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.596 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 3 minitokyo*CLI> 26:13.597 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.597 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.597 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.597 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 2, (6, 4) Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6422 socket_read: Cancelling transmission of packet 1 Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6519 socket_read: IAX subclass 4 received minitokyo*CLI> 26:13.598 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 1 minitokyo*CLI> 26:13.598 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.598 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 2 minitokyo*CLI> 26:13.599 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.599 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 3 minitokyo*CLI> 26:13.599 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.599 ThreadID=0x00278019 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k" minitokyo*CLI> 26:13.600 ThreadID=0x00278019 h323caps.cxx(2029) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.600 ThreadID=0x00278019 h323caps.cxx(1952) H323 Removing capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.600 ThreadID=0x00278019 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k" minitokyo*CLI> 26:13.600 ThreadID=0x00278019 h323caps.cxx(2020) H323 FindCapability: "UserInput/hookflash" minitokyo*CLI> 26:13.601 ThreadID=0x00278019 h323caps.cxx(2029) H323 Found capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.601 ThreadID=0x00278019 h323caps.cxx(1952) H323 Removing capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.601 ThreadID=0x00278019 h323caps.cxx(2020) H323 FindCapability: "UserInput/hookflash" minitokyo*CLI> 26:13.601 ThreadID=0x00278019 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" minitokyo*CLI> 26:13.602 ThreadID=0x00278019 h323caps.cxx(2029) H323 Found capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.602 ThreadID=0x00278019 h323caps.cxx(1952) H323 Removing capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.602 ThreadID=0x00278019 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" minitokyo*CLI> 26:13.602 ThreadID=0x00278019 h323caps.cxx(1932) H323 Added capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.603 ThreadID=0x00278019 h323caps.cxx(1932) H323 Added capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.603 ThreadID=0x00278019 h323caps.cxx(1932) H323 Added capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.604 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.604 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.604 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.604 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 1 minitokyo*CLI> 26:13.605 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.605 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 2 minitokyo*CLI> 26:13.605 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.605 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 3 minitokyo*CLI> 26:13.606 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> minitokyo*CLI> Allowed Codecs: minitokyo*CLI> Table: minitokyo*CLI> G.711-uLaw-64k <1> minitokyo*CLI> UserInput/hookflash <2> minitokyo*CLI> UserInput/RFC2833 <3> minitokyo*CLI> Set: minitokyo*CLI> 0: minitokyo*CLI> 0: minitokyo*CLI> G.711-uLaw-64k <1> minitokyo*CLI> 1: minitokyo*CLI> UserInput/hookflash <2> minitokyo*CLI> 2: minitokyo*CLI> UserInput/RFC2833 <3> minitokyo*CLI> minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 2, (2, 4) Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6478 socket_read: Ooh, voice format changed to 4 Apr 7 12:22:36 DEBUG[32654]: channel.c:1855 set_format: Set channel IAX2/paul@paul-1 to read format ulaw Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 60 on 1/9053 to 163.49.131.89:4569 Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:3541 calc_rxstamp: calc_rxstamp: call=1: rxcore set to 1112894556.734622 - 60ms Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:3551 calc_rxstamp: calc_rxstamp: call=1: works out as 1112894556.674622 Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=60 minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: channel.c:2379 ast_channel_inherit_variables: Not copying variable STACK-voip-h323-74307-1. minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: chan_h323.c:592 oh323_call: Calling to 74307 on H323/74307 minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: chan_h323.c:627 oh323_call: Placing outgoing call to 74307, 0 minitokyo*CLI> -- Making call to 74307 using gatekeeper. minitokyo*CLI> 26:13.641 ThreadID=0x00278019 h323ep.cxx(1394) H323 Making call to: 74307 minitokyo*CLI> 26:13.642 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.642 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=80 minitokyo*CLI> 26:13.643 ThreadID=0x00278019 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.644 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 1 minitokyo*CLI> 26:13.644 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:13.644 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 2 minitokyo*CLI> 26:13.645 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <2> minitokyo*CLI> 26:13.645 ThreadID=0x00278019 h323caps.cxx(2004) H323 FindCapability: 3 minitokyo*CLI> 26:13.645 ThreadID=0x00278019 h323caps.cxx(2008) H323 Found capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.646 ThreadID=0x00278019 rfc2833.cxx(81) RFC2833 Handler created minitokyo*CLI> == New H.323 Connection created. minitokyo*CLI> 26:13.646 ThreadID=0x00278019 h323ep.cxx(1464) H323 Created new connection: ip$localhost/22417 minitokyo*CLI> -- CNFB is calling host 74307 minitokyo*CLI> --Call token is ip$localhost/22417 minitokyo*CLI> -- Call reference is 22417 minitokyo*CLI> -- DTMF Payload is 0 minitokyo*CLI> -- Called 74307 minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: channel.c:1855 set_format: Set channel H323/74307 to read format slin minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: channel.c:1855 set_format: Set channel IAX2/paul@paul-1 to write format slin minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: channel.c:1855 set_format: Set channel IAX2/paul@paul-1 to read format slin minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: channel.c:1855 set_format: Set channel H323/74307 to write format slin minitokyo*CLI> 26:13.650 H225 Caller:81bc638 h323ep.cxx(762) H225 Started call thread minitokyo*CLI> 26:13.656 H225 Caller:81bc638 h323trans.cxx(522) Trans Making request: admissionRequest minitokyo*CLI> 26:13.656 H225 Caller:81bc638 h323pdu.cxx(517) Trans Sending PDU: minitokyo*CLI> admissionRequest { minitokyo*CLI> requestSeqNum = 56969 minitokyo*CLI> callType = pointToPoint <> minitokyo*CLI> endpointIdentifier = 9 characters { minitokyo*CLI> 0035 0034 0031 0039 005f 0065 006e 0064 5419_end minitokyo*CLI> 0070 p minitokyo*CLI> } minitokyo*CLI> destinationInfo = 1 entries { minitokyo*CLI> [0]=dialedDigits "74307" minitokyo*CLI> } minitokyo*CLI> srcInfo = 1 entries { minitokyo*CLI> [0]=h323_ID 13 characters { minitokyo*CLI> 0050 0061 0075 006c 0020 0044 0061 0076 Paul Dav minitokyo*CLI> 0069 0064 0073 006f 006e idson minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> bandWidth = 100000 minitokyo*CLI> callReferenceValue = 22417 minitokyo*CLI> conferenceID = 16 octets { minitokyo*CLI> 74 cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 t..M..........H. minitokyo*CLI> } minitokyo*CLI> activeMC = FALSE minitokyo*CLI> answerCall = FALSE minitokyo*CLI> canMapAlias = TRUE minitokyo*CLI> callIdentifier = { minitokyo*CLI> guid = 16 octets { minitokyo*CLI> 4c cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 L..M..........H. minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> gatekeeperIdentifier = 9 characters { minitokyo*CLI> 0047 006e 0075 0048 0033 0032 0033 0047 GnuH323G minitokyo*CLI> 004b K minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=100 minitokyo*CLI> } minitokyo*CLI> willSupplyUUIEs = TRUE minitokyo*CLI> canMapSrcAlias = FALSE minitokyo*CLI> } minitokyo*CLI> 26:13.665 H225 Caller:81bc638 h323trans.cxx(653) Trans Waiting on response to seqnum=56969 for 3.0 seconds minitokyo*CLI> 26:13.667 Transactor:8137668 h323pdu.cxx(517) H225RAS Receiving PDU: admissionConfirm { requestSeqNum = 56969 bandWidth = 100000 callModel = direct <> destCallSignalAddress = ipAddress { ip = 4 octets { c0 a8 de 14 .... } port = 1657 } irrFrequency = 120 willRespondToIRR = FALSE uuiesRequested = { setup = FALSE callProceeding = FALSE connect = FALSE alerting = FALSE information = FALSE releaseComplete = FALSE facility = FALSE progress = FALSE empty = FALSE status = FALSE statusInquiry = FALSE setupAcknowledge = FALSE notify = FALSE } } 26:13.668 Transactor:8137668 h323trans.cxx(595) Trans Using credentials from request: H235AnnexD_Procedure1,MD5,CAT minitokyo*CLI> 26:13.669 H225 Caller:81bc638 h323.cxx(4610) H323 Bandwidth used: 0 minitokyo*CLI> 26:13.670 H225 Caller:81bc638 transports.cxx(1600) H323TCP Connecting to 192.168.222.20:1657 (local port=0) minitokyo*CLI> 26:13.671 H225 Caller:81bc638 transports.cxx(1501) H323TCP Started connection: host=192.168.222.20:1657, if=163.49.131.154:34512, handle=45 minitokyo*CLI> 26:13.671 H225 Caller:81bc638 h323.cxx(2945) H225 Sending Setup PDU minitokyo*CLI> 26:13.671 H225 Caller:81bc638 h323.cxx(2960) H225 Check for Fast start by local endpoint minitokyo*CLI> 26:13.672 H225 Caller:81bc638 h323.cxx(4154) H245 Default OnSelectLogicalChannels, FastStartInitiate minitokyo*CLI> Apr 7 12:22:36 DEBUG[216]: chan_h323.c:1315 external_rtp_create: Sending RTP 'US' 163.49.131.154:11100 minitokyo*CLI> Apr 7 12:22:36 DEBUG[216]: chan_h323.c:1315 external_rtp_create: Sending RTP 'US' 163.49.131.154:11100 minitokyo*CLI> 26:13.673 H225 Caller:81bc638 h323.cxx(2966) H225 Fast start begun by local endpoint minitokyo*CLI> 26:13.674 H225 Caller:81bc638 channels.cxx(912) H323RTP OnSendingPDU minitokyo*CLI> 26:13.676 H225 Caller:81bc638 h323.cxx(1970) H225 Build fastStart: minitokyo*CLI> { minitokyo*CLI> forwardLogicalChannelNumber = 1 minitokyo*CLI> forwardLogicalChannelParameters = { minitokyo*CLI> dataType = nullData <> minitokyo*CLI> multiplexParameters = none <> minitokyo*CLI> } minitokyo*CLI> reverseLogicalChannelParameters = { minitokyo*CLI> dataType = audioData g711Ulaw64k 20 minitokyo*CLI> multiplexParameters = h2250LogicalChannelParameters { minitokyo*CLI> sessionID = 1 minitokyo*CLI> mediaChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> a3 31 83 9a .1.. minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 11100 minitokyo*CLI> } minitokyo*CLI> mediaGuaranteedDelivery = FALSE minitokyo*CLI> mediaControlChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> a3 31 83 9a .1.. minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 11101 minitokyo*CLI> } minitokyo*CLI> silenceSuppression = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:13.681 H225 Caller:81bc638 h323.cxx(1975) H225 Built fastStart for G.711-uLaw-64k <1> minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=120 minitokyo*CLI> 26:13.684 H225 Caller:81bc638 channels.cxx(912) H323RTP OnSendingPDU minitokyo*CLI> 26:13.685 H225 Caller:81bc638 h323.cxx(1970) H225 Build fastStart: minitokyo*CLI> { minitokyo*CLI> forwardLogicalChannelNumber = 101 minitokyo*CLI> forwardLogicalChannelParameters = { minitokyo*CLI> dataType = audioData g711Ulaw64k 20 minitokyo*CLI> multiplexParameters = h2250LogicalChannelParameters { minitokyo*CLI> sessionID = 1 minitokyo*CLI> mediaGuaranteedDelivery = FALSE minitokyo*CLI> mediaControlChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> a3 31 83 9a .1.. minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 11101 minitokyo*CLI> } minitokyo*CLI> silenceSuppression = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:13.688 H225 Caller:81bc638 h323.cxx(1975) H225 Built fastStart for G.711-uLaw-64k <1> minitokyo*CLI> -- Sending SETUP message minitokyo*CLI> 26:13.689 H225 Caller:81bc638 h323.cxx(3328) H245 Started control channel minitokyo*CLI> 26:13.689 H225 Caller:81bc638 h323neg.cxx(549) H245 Sending TerminalCapabilitySet: outSeq=1 minitokyo*CLI> 26:13.691 H225 Caller:81bc638 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" minitokyo*CLI> 26:13.692 H225 Caller:81bc638 h323caps.cxx(2029) H323 Found capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:13.692 H225 Caller:81bc638 h323.cxx(3975) H323 User Input RFC2833 payload type set to [pt=101] minitokyo*CLI> Apr 7 12:22:36 DEBUG[216]: chan_h323.c:1712 set_dtmf_payload: Setting DTMF payload to 0 on ip$localhost/22417 minitokyo*CLI> Apr 7 12:22:36 DEBUG[216]: chan_h323.c:1724 set_dtmf_payload: DTMF payload on ip$localhost/22417 set to 0 minitokyo*CLI> -- Transmitting RFC2833 on payload 96 minitokyo*CLI> 26:13.694 H225 Caller:81bc638 h323pdu.cxx(517) H245 Sending PDU: minitokyo*CLI> request terminalCapabilitySet { minitokyo*CLI> sequenceNumber = 1 minitokyo*CLI> protocolIdentifier = 0.0.8.245.0.7 minitokyo*CLI> multiplexCapability = h2250Capability { minitokyo*CLI> maximumAudioDelayJitter = 250 minitokyo*CLI> receiveMultipointCapability = { minitokyo*CLI> multicastCapability = FALSE minitokyo*CLI> multiUniCastConference = FALSE minitokyo*CLI> mediaDistributionCapability = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> centralizedControl = FALSE minitokyo*CLI> distributedControl = FALSE minitokyo*CLI> centralizedAudio = FALSE minitokyo*CLI> distributedAudio = FALSE minitokyo*CLI> centralizedVideo = FALSE minitokyo*CLI> distributedVideo = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> transmitMultipointCapability = { minitokyo*CLI> multicastCapability = FALSE minitokyo*CLI> multiUniCastConference = FALSE minitokyo*CLI> mediaDistributionCapability = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> centralizedControl = FALSE minitokyo*CLI> distributedControl = FALSE minitokyo*CLI> centralizedAudio = FALSE minitokyo*CLI> distributedAudio = FALSE minitokyo*CLI> centralizedVideo = FALSE minitokyo*CLI> distributedVideo = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> receiveAndTransmitMultipointCapability = { minitokyo*CLI> multicastCapability = FALSE minitokyo*CLI> multiUniCastConference = FALSE minitokyo*CLI> mediaDistributionCapability = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> centralizedControl = FALSE minitokyo*CLI> distributedControl = FALSE minitokyo*CLI> centralizedAudio = FALSE minitokyo*CLI> distributedAudio = FALSE minitokyo*CLI> centralizedVideo = FALSE minitokyo*CLI> distributedVideo = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> mcCapability = { minitokyo*CLI> centralizedConferenceMC = FALSE minitokyo*CLI> decentralizedConferenceMC = FALSE minitokyo*CLI> } minitokyo*CLI> rtcpVideoControlCapability = FALSE minitokyo*CLI> mediaPacketizationCapability = { minitokyo*CLI> h261aVideoPacketization = FALSE minitokyo*CLI> } minitokyo*CLI> logicalChannelSwitchingCapability = FALSE minitokyo*CLI> t120DynamicPortCapability = TRUE minitokyo*CLI> } minitokyo*CLI> capabilityTable = 3 entries { minitokyo*CLI> [0]={ minitokyo*CLI> capabilityTableEntryNumber = 1 minitokyo*CLI> capability = receiveAudioCapability g711Ulaw64k 240 minitokyo*CLI> } minitokyo*CLI> [1]={ minitokyo*CLI> capabilityTableEntryNumber = 2 minitokyo*CLI> capability = receiveUserInputCapability hookflash <> minitokyo*CLI> } minitokyo*CLI> [2]={ minitokyo*CLI> capabilityTableEntryNumber = 3 minitokyo*CLI> capability = receiveRTPAudioTelephonyEventCapability { minitokyo*CLI> dynamicRTPPayloadType = 96 minitokyo*CLI> audioTelephoneEvent = "0-16" minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> capabilityDescriptors = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> capabilityDescriptorNumber = 1 minitokyo*CLI> simultaneousCapabilities = 3 entries { minitokyo*CLI> [0]=1 entries { minitokyo*CLI> [0]=1 minitokyo*CLI> } minitokyo*CLI> [1]=1 entries { minitokyo*CLI> [0]=2 minitokyo*CLI> } minitokyo*CLI> [2]=1 entries { minitokyo*CLI> [0]=3 minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=140 minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:13.708 H225 Caller:81bc638 h323neg.cxx(317) H245 Sending MasterSlaveDetermination minitokyo*CLI> 26:13.709 H225 Caller:81bc638 h323pdu.cxx(517) H245 Sending PDU: minitokyo*CLI> request masterSlaveDetermination { minitokyo*CLI> terminalType = 50 minitokyo*CLI> statusDeterminationNumber = 3238669 minitokyo*CLI> } minitokyo*CLI> 26:13.710 H225 Caller:81bc638 h323pdu.cxx(517) H225 Sending PDU: minitokyo*CLI> { minitokyo*CLI> q931pdu = { minitokyo*CLI> protocolDiscriminator = 8 minitokyo*CLI> callReference = 22417 minitokyo*CLI> from = originator minitokyo*CLI> messageType = Setup minitokyo*CLI> IE: Bearer-Capability = { minitokyo*CLI> 80 90 a5 ... minitokyo*CLI> } minitokyo*CLI> IE: Progress-Indicator = { minitokyo*CLI> 80 e4 .. minitokyo*CLI> } minitokyo*CLI> IE: Display = { minitokyo*CLI> 50 61 75 6c 20 44 61 76 69 64 73 6f 6e 00 Paul Davidson. minitokyo*CLI> } minitokyo*CLI> IE: Calling-Party-Number = { minitokyo*CLI> 81 34 33 30 37 .4307 minitokyo*CLI> } minitokyo*CLI> IE: Called-Party-Number = { minitokyo*CLI> 81 37 34 33 30 37 .74307 minitokyo*CLI> } minitokyo*CLI> IE: User-User = { minitokyo*CLI> 20 b8 06 00 08 91 4a 00 04 02 40 03 00 43 00 4e .....J...@..C.N minitokyo*CLI> 00 46 00 42 01 80 77 ba 22 c0 09 00 00 3d 37 54 .F.B..w."....=7T minitokyo*CLI> ... minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> h225pdu = { minitokyo*CLI> h323_uu_pdu = { minitokyo*CLI> h323_message_body = setup { minitokyo*CLI> protocolIdentifier = 0.0.8.2250.0.4 minitokyo*CLI> sourceAddress = 2 entries { minitokyo*CLI> [0]=h323_ID 4 characters { minitokyo*CLI> 0043 004e 0046 0042 CNFB minitokyo*CLI> } minitokyo*CLI> [1]=dialedDigits "4487" minitokyo*CLI> } minitokyo*CLI> sourceInfo = { minitokyo*CLI> vendor = { minitokyo*CLI> vendor = { minitokyo*CLI> t35CountryCode = 9 minitokyo*CLI> t35Extension = 0 minitokyo*CLI> manufacturerCode = 61 minitokyo*CLI> } minitokyo*CLI> productId = 56 octets { minitokyo*CLI> 54 68 65 20 4e 75 46 6f 6e 65 20 4e 65 74 77 6f The NuFone Netwo minitokyo*CLI> 72 6b 27 73 20 48 2e 33 32 33 20 43 68 61 6e 6e rk's H.323 Chann minitokyo*CLI> ... minitokyo*CLI> } minitokyo*CLI> versionId = 26 octets { minitokyo*CLI> 31 2e 30 2e 30 20 28 4f 70 65 6e 48 33 32 33 20 1.0.0 (OpenH323 minitokyo*CLI> 76 31 2e 31 35 2e 31 29 00 00 v1.15.1).. minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> terminal = { minitokyo*CLI> } minitokyo*CLI> mc = FALSE minitokyo*CLI> undefinedNode = FALSE minitokyo*CLI> } minitokyo*CLI> destinationAddress = 1 entries { minitokyo*CLI> [0]=dialedDigits "74307" minitokyo*CLI> } minitokyo*CLI> destCallSignalAddress = ipAddress { minitokyo*CLI> ip = 4 octets { minitokyo*CLI> c0 a8 de 14 .... minitokyo*CLI> } minitokyo*CLI> port = 1657 minitokyo*CLI> } minitokyo*CLI> activeMC = FALSE minitokyo*CLI> conferenceID = 16 octets { minitokyo*CLI> 74 cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 t..M..........H. minitokyo*CLI> } minitokyo*CLI> conferenceGoal = create <> minitokyo*CLI> callType = pointToPoint <> minitokyo*CLI> sourceCallSignalAddress = ipAddress { minitokyo*CLI> ip = 4 octets { minitokyo*CLI> a3 31 83 9a .1.. minitokyo*CLI> } minitokyo*CLI> port = 34512 minitokyo*CLI> } minitokyo*CLI> callIdentifier = { minitokyo*CLI> guid = 16 octets { minitokyo*CLI> 4c cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 L..M..........H. minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> fastStart = 2 entries { minitokyo*CLI> [0]= 30 octets { minitokyo*CLI> 40 00 00 06 04 01 00 4c 60 13 80 12 1d 00 01 00 @......L`....... minitokyo*CLI> a3 31 83 9a 2b 5c 00 a3 31 83 9a 2b 5d 00 .1..+\..1..+]. minitokyo*CLI> } minitokyo*CLI> [1]= 19 octets { minitokyo*CLI> 00 00 64 0c 60 13 80 0b 0d 00 01 00 a3 31 83 9a ..d.`........1.. minitokyo*CLI> 2b 5d 00 +]. minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> mediaWaitForConnect = FALSE minitokyo*CLI> canOverlapSend = FALSE minitokyo*CLI> multipleCalls = FALSE minitokyo*CLI> maintainConnection = FALSE minitokyo*CLI> parallelH245Control = 2 entries { minitokyo*CLI> [0]= 68 octets { minitokyo*CLI> 02 70 01 06 00 08 81 75 00 07 80 13 80 00 fa 00 .p.....u........ minitokyo*CLI> 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 02 ................ minitokyo*CLI> ... minitokyo*CLI> } minitokyo*CLI> [1]= 7 octets { minitokyo*CLI> 01 00 32 80 31 6b 0d ..2.1k. minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> h245Tunneling = TRUE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:13.727 H225 Caller:81bc638 h323.cxx(1705) H225 Reading PDUs: callRef=22417 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=160 minitokyo*CLI> 26:13.744 H225 Caller:81bc638 h323pdu.cxx(517) H225 Receiving PDU: minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=180 minitokyo*CLI> { minitokyo*CLI> q931pdu = { minitokyo*CLI> protocolDiscriminator = 8 minitokyo*CLI> callReference = 22417 minitokyo*CLI> from = destination minitokyo*CLI> messageType = CallProceeding minitokyo*CLI> IE: User-User = { minitokyo*CLI> 21 80 06 00 08 91 4a 00 02 02 01 20 11 00 4c cb !.....J.... ..L. minitokyo*CLI> f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 06 a0 .M..........H... minitokyo*CLI> ... minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> h225pdu = { minitokyo*CLI> h323_uu_pdu = { minitokyo*CLI> h323_message_body = callProceeding { minitokyo*CLI> protocolIdentifier = 0.0.8.2250.0.2 minitokyo*CLI> destinationInfo = { minitokyo*CLI> terminal = { minitokyo*CLI> } minitokyo*CLI> mc = FALSE minitokyo*CLI> undefinedNode = FALSE minitokyo*CLI> } minitokyo*CLI> callIdentifier = { minitokyo*CLI> guid = 16 octets { minitokyo*CLI> 4c cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 L..M..........H. minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> h245Tunneling = FALSE minitokyo*CLI> nonStandardControl = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> nonStandardIdentifier = h221NonStandard { minitokyo*CLI> t35CountryCode = 181 minitokyo*CLI> t35Extension = 0 minitokyo*CLI> manufacturerCode = 18 minitokyo*CLI> } minitokyo*CLI> data = 7 octets { minitokyo*CLI> 80 44 04 00 01 01 00 .D..... minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:13.750 H225 Caller:81bc638 h323.cxx(1758) H225 Handling PDU: CallProceeding callRef=22417 minitokyo*CLI> 26:13.751 H225 Caller:81bc638 h323neg.cxx(334) H245 Stopping MasterSlaveDetermination: state=Outgoing minitokyo*CLI> 26:13.751 H225 Caller:81bc638 h323neg.cxx(561) H245 Stopping TerminalCapabilitySet: state=InProgress minitokyo*CLI> 26:13.751 H225 Caller:81bc638 h323.cxx(1798) H225 Set remote application name: "Cisco IOS 12.x 181/18" minitokyo*CLI> 26:13.752 H225 Caller:81bc638 h323.cxx(2018) H225 Set protocol version to 2 and implying H.245 version 3 minitokyo*CLI> 26:13.752 H225 Caller:81bc638 h323.cxx(2245) H225 Set remote party name: "74307" minitokyo*CLI> 26:13.753 H225 Caller:81bc638 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled minitokyo*CLI> 26:13.756 H225 Caller:81bc638 h323pdu.cxx(517) H225 Receiving PDU: minitokyo*CLI> { minitokyo*CLI> q931pdu = { minitokyo*CLI> protocolDiscriminator = 8 minitokyo*CLI> callReference = 22417 minitokyo*CLI> from = destination minitokyo*CLI> messageType = Alerting minitokyo*CLI> IE: User-User = { minitokyo*CLI> 23 80 06 00 08 91 4a 00 02 02 01 20 11 00 4c cb #.....J.... ..L. minitokyo*CLI> f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 06 a0 .M..........H... minitokyo*CLI> ... minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> h225pdu = { minitokyo*CLI> h323_uu_pdu = { minitokyo*CLI> h323_message_body = alerting { minitokyo*CLI> protocolIdentifier = 0.0.8.2250.0.2 minitokyo*CLI> destinationInfo = { minitokyo*CLI> terminal = { minitokyo*CLI> } minitokyo*CLI> mc = FALSE minitokyo*CLI> undefinedNode = FALSE minitokyo*CLI> } minitokyo*CLI> callIdentifier = { minitokyo*CLI> guid = 16 octets { minitokyo*CLI> 4c cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 L..M..........H. minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> h245Tunneling = FALSE minitokyo*CLI> nonStandardControl = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> nonStandardIdentifier = h221NonStandard { minitokyo*CLI> t35CountryCode = 181 minitokyo*CLI> t35Extension = 0 minitokyo*CLI> manufacturerCode = 18 minitokyo*CLI> } minitokyo*CLI> data = 7 octets { minitokyo*CLI> 80 44 04 00 01 01 00 .D..... minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=200 minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:13.768 H225 Caller:81bc638 h323.cxx(1758) H225 Handling PDU: Alerting callRef=22417 minitokyo*CLI> 26:13.768 H225 Caller:81bc638 h323.cxx(2018) H225 Set protocol version to 2 and implying H.245 version 3 minitokyo*CLI> 26:13.769 H225 Caller:81bc638 h323.cxx(2245) H225 Set remote party name: "74307" minitokyo*CLI> =-= In OnAlerting for call 22417: sessionId=24948 minitokyo*CLI> -- Ringing phone for "74307" minitokyo*CLI> - Progress Indicator: 0 minitokyo*CLI> Apr 7 12:22:36 DEBUG[216]: chan_h323.c:1423 progress: Received ALERT/PROGRESS message for self-generated tones minitokyo*CLI> -- H323/74307 is ringing minitokyo*CLI> Apr 7 12:22:36 DEBUG[214]: chan_iax2.c:3230 iax2_indicate: Indicating condition 3 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 19 on 1/9053 to 163.49.131.89:4569 minitokyo*CLI> Apr 7 12:22:36 DEBUG[216]: chan_h323.c:1609 chan_ringing: Ringing on ip$localhost/22417 minitokyo*CLI> Apr 7 12:22:36 DEBUG[216]: pbx.c:1810 ast_device_state_changed: Changing state for H323/74307 - state 2 minitokyo*CLI> Apr 7 12:22:36 DEBUG[217]: app_queue.c:420 changethread: Device 'H323/74307' changed to state '2' minitokyo*CLI> Apr 7 12:22:36 DEBUG[217]: app_queue.c:448 changethread: Device 'H323/74307' changed to state '2' minitokyo*CLI> -- H323/74307 is ringing minitokyo*CLI> 26:13.773 H225 Caller:81bc638 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled minitokyo*CLI> 26:13.774 H225 Caller:81bc638 h323pdu.cxx(1243) H225 No Q931 User-User Information Element, minitokyo*CLI> Raw PDU: minitokyo*CLI> 08 02 d7 91 6e 27 01 f1 28 0d 50 61 75 6c 20 44 ....n'..(.Paul D minitokyo*CLI> 61 76 69 64 73 6f 6e 4c 05 80 34 33 30 37 avidsonL..4307 minitokyo*CLI> Q.931 PDU: minitokyo*CLI> { minitokyo*CLI> protocolDiscriminator = 8 minitokyo*CLI> callReference = 22417 minitokyo*CLI> from = destination minitokyo*CLI> messageType = <110> minitokyo*CLI> IE: 0x27 (39) = { minitokyo*CLI> f1 . minitokyo*CLI> } minitokyo*CLI> IE: Display = { minitokyo*CLI> 50 61 75 6c 20 44 61 76 69 64 73 6f 6e Paul Davidson minitokyo*CLI> } minitokyo*CLI> IE: Connected-Number = { minitokyo*CLI> 80 34 33 30 37 .4307 minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:13.777 H225 Caller:81bc638 h323.cxx(1758) H225 Handling PDU: <110> callRef=22417 minitokyo*CLI> 26:13.777 H225 Caller:81bc638 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=AwaitingSignalConnect fastStartState=FastStartDisabled minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 3, (6, 4) Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6422 socket_read: Cancelling transmission of packet 2 Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:6519 socket_read: IAX subclass 4 received Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=220 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=240 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=260 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=280 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=300 minitokyo*CLI> Apr 7 12:22:36 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=320 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=340 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=360 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=380 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=400 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=420 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=440 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=460 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=480 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=500 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=520 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=540 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=560 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=580 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=600 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=620 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=640 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=660 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=680 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=700 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=720 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=740 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=760 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=780 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=800 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=820 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=840 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=860 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=880 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=900 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=920 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=940 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=960 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=980 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1000 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1020 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1040 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1060 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1080 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1100 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1120 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1140 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1160 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1180 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1200 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1220 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1240 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1260 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1280 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1300 minitokyo*CLI> Apr 7 12:22:37 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1320 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1340 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1360 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1380 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1400 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1420 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1440 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1460 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1480 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1500 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1520 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1540 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1560 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1580 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1600 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1620 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1640 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1660 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1680 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1700 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1720 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1740 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1760 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1780 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1800 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1820 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1840 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1860 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1880 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1900 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1920 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1940 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1960 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=1980 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 3, (6, 2) Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:6519 socket_read: IAX subclass 2 received Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 2003 on 1/9053 to 163.49.131.89:4569 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 4, (6, 4) Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:6422 socket_read: Cancelling transmission of packet 3 Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:6519 socket_read: IAX subclass 4 received minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2000 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2020 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2040 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2060 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2080 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2100 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2120 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2140 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2160 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2180 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2200 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2220 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2240 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2260 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2280 minitokyo*CLI> Apr 7 12:22:38 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2300 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2320 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2340 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2360 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2380 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2400 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2420 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2440 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2460 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2480 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2500 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2520 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2540 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2560 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2580 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2600 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2620 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2640 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2660 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2680 minitokyo*CLI> 26:16.257 H225 Caller:81bc638 h323pdu.cxx(517) H225 Receiving PDU: { q931pdu = { protocolDiscriminator = 8 callReference = 22417 from = destination messageType = Connect IE: Display = { 50 61 75 6c 20 44 61 76 69 64 73 6f 6e Paul Davidson } IE: User-User = { 22 c0 06 00 08 91 4a 00 02 00 c0 a8 de 14 05 46 ".....J........F 02 00 74 cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 ..t..M.......... ... } } h225pdu = { h323_uu_pdu = { h323_message_body = connect { protocolIdentifier = 0.0.8.2250.0.2 h245Address = ipAddress { ip = 4 octets { c0 a8 de 14 .... } port = 1350 } destinationInfo = { terminal = { } mc = FALSE undefinedNode = FALSE } conferenceID = 16 octets { 74 cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 t..M..........H. } callIdentifier = { guid = 16 octets { 4c cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 L..M..........H. } } } h245Tunneling = FALSE nonStandardControl = 1 entries { [0]={ nonStandardIdentifier = h221NonStandard { t35CountryCode = 181 t35Extension = 0 manufacturerCode = 18 } data = 7 octets { 80 44 04 00 01 01 00 .D..... } } } } } } 26:16.266 H225 Caller:81bc638 h323.cxx(1758) H225 Handling PDU: Connect callRef=22417 26:16.266 H225 Caller:81bc638 h323.cxx(2018) H225 Set protocol version to 2 and implying H.245 version 3 26:16.267 H225 Caller:81bc638 h323.cxx(2245) H225 Set remote party name: "Paul Davidson" 26:16.267 H225 Caller:81bc638 h323ep.cxx(2061) H225 Received connect PDU. 26:16.267 H225 Caller:81bc638 transports.cxx(1600) H323TCP Connecting to 192.168.222.20:1350 (local port=0) minitokyo*CLI> 26:16.267 H225 Caller:81bc638 transports.cxx(1501) H323TCP Started connection: host=192.168.222.20:1350, if=163.49.131.154:34513, handle=46 minitokyo*CLI> ExternalRTPChannel Destroyed 26:16.268 H225 Caller:81bc638 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 10000.0kb/s ExternalRTPChannel Destroyed 26:16.268 H225 Caller:81bc638 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 10000.0kb/s 26:16.268 H225 Caller:81bc638 h323.cxx(3328) H245 Started control channel 26:16.268 H225 Caller:81bc638 h323neg.cxx(549) H245 Sending TerminalCapabilitySet: outSeq=2 26:16.270 H225 Caller:81bc638 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" 26:16.270 H225 Caller:81bc638 h323caps.cxx(2029) H323 Found capability: UserInput/RFC2833 <3> 26:16.271 H225 Caller:81bc638 h323pdu.cxx(517) H245 Sending PDU: request terminalCapabilitySet { sequenceNumber = 2 protocolIdentifier = 0.0.8.245.0.7 multiplexCapability = h2250Capability { maximumAudioDelayJitter = 250 receiveMultipointCapability = { multicastCapability = FALSE minitokyo*CLI> 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 = 2 entries { [0]={ capabilityTableEntryNumber = 1 capability = receiveAudioCapability g711Ulaw64k 240 } [1]={ capabilityTableEntryNumber = 2 capability = receiveUserInputCapability hookflash <> } } capabilityDescriptors = 1 entries { [0]={ capabilityDescriptorNumber = 1 simultaneousCapabilities = 3 entries { [0]=1 entries { [0]=1 } [1]=1 entries { [0]=2 } [2]=1 entries { [0]=1 } } } } } 26:16.274 H225 Caller:81bc638 h323neg.cxx(317) H245 Sending MasterSlaveDetermination 26:16.274 H225 Caller:81bc638 h323pdu.cxx(517) H245 Sending PDU: request masterSlaveDetermination { terminalType = 50 statusDeterminationNumber = 6802294 } 26:16.274 H225 Caller:81bc638 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2700 minitokyo*CLI> 26:16.277 H245:81c1f20 transports.cxx(586) H245 Started thread minitokyo*CLI> 26:16.277 H245:81c1f20 h323.cxx(3328) H245 Started control channel minitokyo*CLI> 26:16.277 H245:81c1f20 h323neg.cxx(535) H245 TerminalCapabilitySet already in progress: outSeq=2 minitokyo*CLI> 26:16.278 H245:81c1f20 h323neg.cxx(303) H245 MasterSlaveDetermination already in progress minitokyo*CLI> 26:16.279 H245:81c1f20 h323.cxx(3371) H245 Received TPKT: size=65 pos=0.0 { minitokyo*CLI> 02 70 01 06 00 08 81 75 00 03 80 0d 00 00 3c 00 p u < minitokyo*CLI> 01 00 00 01 00 00 01 02 80 03 80 00 00 20 c0 27 ' minitokyo*CLI> 80 00 2b 85 01 50 80 00 2c 85 01 10 80 00 2d 85 + P , - minitokyo*CLI> 01 40 00 80 00 02 00 00 00 00 00 2b 01 00 2c 00 @ + , minitokyo*CLI> 2d - minitokyo*CLI> } minitokyo*CLI> 26:16.284 H245:81c1f20 h323pdu.cxx(517) H245 Receiving PDU: minitokyo*CLI> request terminalCapabilitySet { minitokyo*CLI> sequenceNumber = 1 minitokyo*CLI> protocolIdentifier = 0.0.8.245.0.3 minitokyo*CLI> multiplexCapability = h2250Capability { minitokyo*CLI> maximumAudioDelayJitter = 60 minitokyo*CLI> receiveMultipointCapability = { minitokyo*CLI> multicastCapability = FALSE minitokyo*CLI> multiUniCastConference = FALSE minitokyo*CLI> mediaDistributionCapability = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> centralizedControl = FALSE minitokyo*CLI> distributedControl = FALSE minitokyo*CLI> centralizedAudio = FALSE minitokyo*CLI> distributedAudio = FALSE minitokyo*CLI> centralizedVideo = FALSE minitokyo*CLI> distributedVideo = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> transmitMultipointCapability = { minitokyo*CLI> multicastCapability = FALSE minitokyo*CLI> multiUniCastConference = FALSE minitokyo*CLI> mediaDistributionCapability = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> centralizedControl = FALSE minitokyo*CLI> distributedControl = FALSE minitokyo*CLI> centralizedAudio = FALSE minitokyo*CLI> distributedAudio = FALSE minitokyo*CLI> centralizedVideo = FALSE minitokyo*CLI> distributedVideo = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> receiveAndTransmitMultipointCapability = { minitokyo*CLI> multicastCapability = FALSE minitokyo*CLI> multiUniCastConference = FALSE minitokyo*CLI> mediaDistributionCapability = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> centralizedControl = FALSE minitokyo*CLI> distributedControl = FALSE minitokyo*CLI> centralizedAudio = FALSE minitokyo*CLI> distributedAudio = TRUE minitokyo*CLI> centralizedVideo = FALSE minitokyo*CLI> distributedVideo = TRUE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> mcCapability = { minitokyo*CLI> centralizedConferenceMC = FALSE minitokyo*CLI> decentralizedConferenceMC = FALSE minitokyo*CLI> } minitokyo*CLI> rtcpVideoControlCapability = FALSE minitokyo*CLI> mediaPacketizationCapability = { minitokyo*CLI> h261aVideoPacketization = FALSE minitokyo*CLI> } minitokyo*CLI> logicalChannelSwitchingCapability = FALSE minitokyo*CLI> t120DynamicPortCapability = FALSE minitokyo*CLI> } minitokyo*CLI> capabilityTable = 4 entries { minitokyo*CLI> [0]={ minitokyo*CLI> capabilityTableEntryNumber = 1 minitokyo*CLI> capability = receiveAudioCapability g711Ulaw64k 40 minitokyo*CLI> } minitokyo*CLI> [1]={ minitokyo*CLI> capabilityTableEntryNumber = 44 minitokyo*CLI> capability = receiveAndTransmitUserInputCapability hookflash <> minitokyo*CLI> } minitokyo*CLI> [2]={ minitokyo*CLI> capabilityTableEntryNumber = 45 minitokyo*CLI> capability = receiveAndTransmitUserInputCapability basicString <> minitokyo*CLI> } minitokyo*CLI> [3]={ minitokyo*CLI> capabilityTableEntryNumber = 46 minitokyo*CLI> capability = receiveAndTransmitUserInputCapability dtmf <> minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> capabilityDescriptors = 1 entries { minitokyo*CLI> [0]={ minitokyo*CLI> capabilityDescriptorNumber = 0 minitokyo*CLI> simultaneousCapabilities = 3 entries { minitokyo*CLI> [0]=1 entries { minitokyo*CLI> [0]=1 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2720 minitokyo*CLI> } minitokyo*CLI> [1]=1 entries { minitokyo*CLI> [0]=44 minitokyo*CLI> } minitokyo*CLI> [2]=2 entries { minitokyo*CLI> [0]=45 minitokyo*CLI> [1]=46 minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:16.301 H245:81c1f20 h323.cxx(3488) H245 Set protocol version to 3 minitokyo*CLI> 26:16.301 H245:81c1f20 h323neg.cxx(579) H245 Received TerminalCapabilitySet: state=InProgress pduSeq=1 inSeq=4294967295 minitokyo*CLI> 26:16.301 H245:81c1f20 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.302 H245:81c1f20 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <2> minitokyo*CLI> 26:16.302 H245:81c1f20 h323caps.cxx(1942) H323 Added capability: UserInput/RFC2833 <3> minitokyo*CLI> 26:16.302 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.711-ALaw-64k{hw}" minitokyo*CLI> 26:16.303 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.711-ALaw-64k{sw}" minitokyo*CLI> 26:16.303 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: G.711-ALaw-64k <4> minitokyo*CLI> 26:16.304 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k{hw}" minitokyo*CLI> 26:16.304 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.711-uLaw-64k{sw}" minitokyo*CLI> 26:16.305 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: G.711-uLaw-64k <5> minitokyo*CLI> 26:16.305 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.723.1" minitokyo*CLI> 26:16.305 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: G.723.1 <6> minitokyo*CLI> 26:16.306 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.723.1(5.3k){hw}" minitokyo*CLI> 26:16.307 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.723.1A(5.3k){hw}" minitokyo*CLI> 26:16.307 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.723.1A(6.3k)-Cisco{hw}" minitokyo*CLI> 26:16.307 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.723.1A(6.3k){hw}" minitokyo*CLI> 26:16.307 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.723.1{hw}" minitokyo*CLI> 26:16.308 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.728{hw}" minitokyo*CLI> 26:16.308 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.729" minitokyo*CLI> 26:16.309 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: G.729 <7> minitokyo*CLI> 26:16.309 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.729A" minitokyo*CLI> 26:16.310 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: G.729A <8> minitokyo*CLI> 26:16.310 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.729A/B{hw}" minitokyo*CLI> 26:16.311 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.729A{hw}" minitokyo*CLI> 26:16.311 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.729B{hw}" minitokyo*CLI> 26:16.311 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "G.729{hw}" minitokyo*CLI> 26:16.312 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "GSM-06.10{hw}" minitokyo*CLI> 26:16.312 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: UserInput/hookflash <9> minitokyo*CLI> 26:16.312 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: UserInput/basicString <10> minitokyo*CLI> 26:16.313 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: UserInput/dtmf <11> minitokyo*CLI> 26:16.314 H245:81c1f20 h323caps.cxx(1932) H323 Added capability: UserInput/RFC2833 <12> minitokyo*CLI> 26:16.314 H245:81c1f20 h323caps.cxx(2071) H323 FindCapability: receiveAudioCapability minitokyo*CLI> 26:16.315 H245:81c1f20 h323caps.cxx(2284) H323 FindCapability: Audio subtype=3 minitokyo*CLI> 26:16.315 H245:81c1f20 h323caps.cxx(2290) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.315 H245:81c1f20 h323caps.cxx(976) H323 Capability tx frames left at 20 as remote allows 40 minitokyo*CLI> 26:16.316 H245:81c1f20 h323caps.cxx(2071) H323 FindCapability: receiveAndTransmitUserInputCapability minitokyo*CLI> 26:16.316 H245:81c1f20 h323caps.cxx(2284) H323 FindCapability: UserInput subtype=5 minitokyo*CLI> 26:16.316 H245:81c1f20 h323caps.cxx(2290) H323 Found capability: UserInput/hookflash <2> minitokyo*CLI> 26:16.316 H245:81c1f20 h323caps.cxx(2071) H323 FindCapability: receiveAndTransmitUserInputCapability minitokyo*CLI> 26:16.317 H245:81c1f20 h323caps.cxx(2284) H323 FindCapability: UserInput subtype=1 minitokyo*CLI> 26:16.317 H245:81c1f20 h323caps.cxx(2290) H323 Found capability: UserInput/basicString <10> minitokyo*CLI> 26:16.317 H245:81c1f20 h323caps.cxx(2071) H323 FindCapability: receiveAndTransmitUserInputCapability minitokyo*CLI> 26:16.318 H245:81c1f20 h323caps.cxx(2284) H323 FindCapability: UserInput subtype=4 minitokyo*CLI> 26:16.318 H245:81c1f20 h323caps.cxx(2290) H323 Found capability: UserInput/dtmf <11> minitokyo*CLI> 26:16.319 H245:81c1f20 h323caps.cxx(2056) H323 FindCapability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.320 H245:81c1f20 h323caps.cxx(1942) H323 Added capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.320 H245:81c1f20 h323caps.cxx(2056) H323 FindCapability: UserInput/hookflash <44> minitokyo*CLI> 26:16.320 H245:81c1f20 h323caps.cxx(1942) H323 Added capability: UserInput/hookflash <44> minitokyo*CLI> 26:16.320 H245:81c1f20 h323caps.cxx(2056) H323 FindCapability: UserInput/basicString <45> minitokyo*CLI> 26:16.321 H245:81c1f20 h323caps.cxx(1942) H323 Added capability: UserInput/basicString <45> minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2740 minitokyo*CLI> 26:16.321 H245:81c1f20 h323caps.cxx(2056) H323 FindCapability: UserInput/dtmf <46> minitokyo*CLI> 26:16.322 H245:81c1f20 h323caps.cxx(1942) H323 Added capability: UserInput/dtmf <46> minitokyo*CLI> 26:16.322 H245:81c1f20 h323caps.cxx(2004) H323 FindCapability: 1 minitokyo*CLI> 26:16.322 H245:81c1f20 h323caps.cxx(2008) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.323 H245:81c1f20 h323caps.cxx(2004) H323 FindCapability: 44 minitokyo*CLI> 26:16.323 H245:81c1f20 h323caps.cxx(2008) H323 Found capability: UserInput/hookflash <44> minitokyo*CLI> 26:16.323 H245:81c1f20 h323caps.cxx(2004) H323 FindCapability: 45 minitokyo*CLI> 26:16.323 H245:81c1f20 h323caps.cxx(2008) H323 Found capability: UserInput/basicString <45> minitokyo*CLI> 26:16.324 H245:81c1f20 h323caps.cxx(2004) H323 FindCapability: 46 minitokyo*CLI> 26:16.324 H245:81c1f20 h323caps.cxx(2008) H323 Found capability: UserInput/dtmf <46> minitokyo*CLI> 26:16.324 H245:81c1f20 h323caps.cxx(2379) H245 Capability merge result: minitokyo*CLI> Table: minitokyo*CLI> G.711-uLaw-64k <1> minitokyo*CLI> UserInput/hookflash <44> minitokyo*CLI> UserInput/basicString <45> minitokyo*CLI> UserInput/dtmf <46> minitokyo*CLI> Set: minitokyo*CLI> 0: minitokyo*CLI> 0: minitokyo*CLI> G.711-uLaw-64k <1> minitokyo*CLI> 1: minitokyo*CLI> UserInput/hookflash <44> minitokyo*CLI> 2: minitokyo*CLI> UserInput/basicString <45> minitokyo*CLI> UserInput/dtmf <46> minitokyo*CLI> minitokyo*CLI> 26:16.327 H245:81c1f20 h323caps.cxx(2381) H245 Received capability set, is accepted minitokyo*CLI> 26:16.327 H245:81c1f20 h323neg.cxx(535) H245 TerminalCapabilitySet already in progress: outSeq=2 minitokyo*CLI> 26:16.327 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "UserInput/RFC2833" minitokyo*CLI> 26:16.328 H245:81c1f20 h323pdu.cxx(517) H245 Sending PDU: minitokyo*CLI> response terminalCapabilitySetAck { minitokyo*CLI> sequenceNumber = 1 minitokyo*CLI> } minitokyo*CLI> 26:16.329 H245:81c1f20 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled minitokyo*CLI> 26:16.330 H245:81c1f20 h323.cxx(3371) H245 Received TPKT: size=3 pos=0.0 { minitokyo*CLI> 21 80 02 ! minitokyo*CLI> } minitokyo*CLI> 26:16.331 H245:81c1f20 h323pdu.cxx(517) H245 Receiving PDU: minitokyo*CLI> response terminalCapabilitySetAck { minitokyo*CLI> sequenceNumber = 2 minitokyo*CLI> } minitokyo*CLI> 26:16.332 H245:81c1f20 h323neg.cxx(620) H245 Received TerminalCapabilitySetAck: state=InProgress pduSeq=2 outSeq=2 minitokyo*CLI> 26:16.332 H245:81c1f20 h323neg.cxx(630) H245 TerminalCapabilitySet Sent. minitokyo*CLI> 26:16.334 H245:81c1f20 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled minitokyo*CLI> 26:16.334 H245:81c1f20 h323.cxx(3371) H245 Received TPKT: size=2 pos=0.0 { minitokyo*CLI> 20 a0 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2760 minitokyo*CLI> } minitokyo*CLI> 26:16.335 H245:81c1f20 h323pdu.cxx(517) H245 Receiving PDU: minitokyo*CLI> response masterSlaveDeterminationAck { minitokyo*CLI> decision = slave <> minitokyo*CLI> } minitokyo*CLI> 26:16.336 H245:81c1f20 h323neg.cxx(406) H245 Received MasterSlaveDeterminationAck: state=Outgoing minitokyo*CLI> 26:16.337 H245:81c1f20 h323neg.cxx(423) H245 MasterSlaveDetermination: remote is master minitokyo*CLI> 26:16.337 H245:81c1f20 h323pdu.cxx(517) H245 Sending PDU: minitokyo*CLI> response masterSlaveDeterminationAck { minitokyo*CLI> decision = master <> minitokyo*CLI> } minitokyo*CLI> 26:16.338 H245:81c1f20 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect fastStartState=FastStartDisabled minitokyo*CLI> 26:16.338 H245:81c1f20 h323caps.cxx(2020) H323 FindCapability: "T.120" minitokyo*CLI> 26:16.339 H245:81c1f20 h323.cxx(4154) H245 Default OnSelectLogicalChannels, FastStartDisabled minitokyo*CLI> 26:16.339 H245:81c1f20 h323caps.cxx(2056) H323 FindCapability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.339 H245:81c1f20 h323caps.cxx(2060) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.340 H245:81c1f20 h323.cxx(4201) H323 Selecting G.711-uLaw-64k <1> minitokyo*CLI> 26:16.340 H245:81c1f20 h323neg.cxx(743) H245 Opening channel: T-102 minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1315 external_rtp_create: Sending RTP 'US' 163.49.131.154:11100 minitokyo*CLI> 26:16.342 H245:81c1f20 channels.cxx(912) H323RTP OnSendingPDU minitokyo*CLI> -- Started logical channel: sending G.711-uLaw-64k minitokyo*CLI> -- channelsOpen = 1 minitokyo*CLI> 26:16.344 H245:81c1f20 codecs.cxx(1351) Codec G711 uLaw encoder created for at 64k, frame of 160 samples minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2780 minitokyo*CLI> 26:16.354 H245:81c1f20 channels.cxx(796) LogChan Bandwidth requested/used = 64.0/0.0 kb/s minitokyo*CLI> 26:16.354 H245:81c1f20 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 10000.0kb/s minitokyo*CLI> 26:16.354 H245:81c1f20 h323.cxx(4623) H323 Bandwidth request: +64.0kb/s, available: 10000.0kb/s minitokyo*CLI> 26:16.354 H245:81c1f20 h323pdu.cxx(517) H245 Sending PDU: minitokyo*CLI> request openLogicalChannel { minitokyo*CLI> forwardLogicalChannelNumber = 102 minitokyo*CLI> forwardLogicalChannelParameters = { minitokyo*CLI> dataType = audioData g711Ulaw64k 20 minitokyo*CLI> multiplexParameters = h2250LogicalChannelParameters { minitokyo*CLI> sessionID = 1 minitokyo*CLI> mediaGuaranteedDelivery = FALSE minitokyo*CLI> mediaControlChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> a3 31 83 9a .1.. minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 11101 minitokyo*CLI> } minitokyo*CLI> silenceSuppression = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> =-= In OnConnectionEstablished for call 22417 minitokyo*CLI> -- Connection Established with "Paul Davidson" minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1390 connection_made: Call ip$localhost/22417 answered minitokyo*CLI> -- H323/74307 answered IAX2/paul@paul-1 Apr 7 12:22:39 DEBUG[214]: chan_iax2.c:3230 iax2_indicate: Indicating condition -1 Apr 7 12:22:39 DEBUG[214]: channel.c:1855 set_format: Set channel IAX2/paul@paul-1 to read format slin minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 2809 on 1/9053 to 163.49.131.89:4569 minitokyo*CLI> Apr 7 12:22:39 DEBUG[214]: channel.c:1855 set_format: Set channel H323/74307 to write format slin Apr 7 12:22:39 DEBUG[214]: channel.c:1855 set_format: Set channel H323/74307 to read format slin Apr 7 12:22:39 DEBUG[214]: channel.c:1855 set_format: Set channel IAX2/paul@paul-1 to write format slin Apr 7 12:22:39 DEBUG[214]: chan_iax2.c:3222 iax2_answer: Answering Apr 7 12:22:39 DEBUG[214]: channel.c:1480 ast_read: Dropping duplicate answer! minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 2812 on 1/9053 to 163.49.131.89:4569 minitokyo*CLI> 26:16.360 H245:81c1f20 h323.cxx(3371) H245 Received TPKT: size=20 pos=0.0 { minitokyo*CLI> 03 00 00 11 0c 60 13 80 0b 05 00 01 00 c0 a8 de ` minitokyo*CLI> 14 0f a1 00 minitokyo*CLI> } minitokyo*CLI> 26:16.363 H245:81c1f20 h323pdu.cxx(517) H245 Receiving PDU: minitokyo*CLI> request openLogicalChannel { minitokyo*CLI> forwardLogicalChannelNumber = 18 minitokyo*CLI> forwardLogicalChannelParameters = { minitokyo*CLI> dataType = audioData g711Ulaw64k 20 minitokyo*CLI> multiplexParameters = h2250LogicalChannelParameters { minitokyo*CLI> sessionID = 1 minitokyo*CLI> mediaControlChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> c0 a8 de 14 .... minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 4001 minitokyo*CLI> } minitokyo*CLI> silenceSuppression = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:16.366 H245:81c1f20 h323neg.cxx(835) H245 Received open channel: R-18, state=Released minitokyo*CLI> 26:16.367 H245:81c1f20 h323.cxx(4382) H323 CreateLogicalChannel - forward channel minitokyo*CLI> 26:16.367 H245:81c1f20 h323caps.cxx(2119) H323 FindCapability: audioData minitokyo*CLI> 26:16.367 H245:81c1f20 h323caps.cxx(1001) H323 Capability tx frames left at 20 as remote allows 20 minitokyo*CLI> 26:16.368 H245:81c1f20 h323caps.cxx(2164) H323 Found capability: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.368 H245:81c1f20 h323caps.cxx(996) H323 Capability rx frames reduced from 240 to 20 minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1315 external_rtp_create: Sending RTP 'US' 163.49.131.154:11100 minitokyo*CLI> 26:16.369 H245:81c1f20 codecs.cxx(1351) Codec G711 uLaw decoder created for at 64k, frame of 160 samples minitokyo*CLI> 26:16.369 H245:81c1f20 channels.cxx(796) LogChan Bandwidth requested/used = 64.0/0.0 kb/s minitokyo*CLI> 26:16.369 H245:81c1f20 h323.cxx(4623) H323 Bandwidth request: -0.0kb/s, available: 9936.0kb/s minitokyo*CLI> 26:16.370 H245:81c1f20 h323.cxx(4623) H323 Bandwidth request: +64.0kb/s, available: 9936.0kb/s minitokyo*CLI> 26:16.370 H245:81c1f20 channels.cxx(971) H323RTP OnReceivedPDU for channel: R-18 minitokyo*CLI> 26:16.370 H245:81c1f20 h323caps.cxx(1001) H323 Capability rx frames left at 20 as remote allows 20 minitokyo*CLI> 26:16.370 H245:81c1f20 channels.cxx(940) H323RTP OnSendOpenAck minitokyo*CLI> 26:16.371 H245:81c1f20 channels.cxx(961) H323RTP Sending open logical channel ACK: sessionID=1 minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 4, (6, 4) Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:6422 socket_read: Cancelling transmission of packet 4 Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:6422 socket_read: Cancelling transmission of packet 5 Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:6519 socket_read: IAX subclass 4 received minitokyo*CLI> -- Started logical channel: receiving G.711-uLaw-64k minitokyo*CLI> -- channelsOpen = 2 minitokyo*CLI> External RTP Session Starting minitokyo*CLI> RTP channel id 1 parameters: minitokyo*CLI> -- remoteIpAddress: 192.168.222.20 minitokyo*CLI> -- remotePort: 4000 minitokyo*CLI> -- ExternalIpAddress: 163.49.131.154 minitokyo*CLI> -- ExternalPort: 11100 minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1341 setup_rtp_connection: Setting up RTP connection for ip$localhost/22417 minitokyo*CLI> Apr 7 12:22:39 NOTICE[218]: channel.c:1833 set_format: Unable to find a path from g723 to slin minitokyo*CLI> Apr 7 12:22:39 NOTICE[218]: channel.c:1833 set_format: Unable to find a path from g723 to slin minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1375 setup_rtp_connection: RTP connection prepared for ip$localhost/22417 minitokyo*CLI> 26:16.374 H245:81c1f20 h323pdu.cxx(517) H245 Sending PDU: minitokyo*CLI> response openLogicalChannelAck { minitokyo*CLI> forwardLogicalChannelNumber = 18 minitokyo*CLI> forwardMultiplexAckParameters = h2250LogicalChannelAckParameters { minitokyo*CLI> sessionID = 1 minitokyo*CLI> mediaChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> a3 31 83 9a .1.. minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 11100 minitokyo*CLI> } minitokyo*CLI> mediaControlChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> a3 31 83 9a .1.. minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 11101 minitokyo*CLI> } minitokyo*CLI> flowControlToZero = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:16.378 H245:81c1f20 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled minitokyo*CLI> 26:16.378 H245:81c1f20 h323.cxx(3371) H245 Received TPKT: size=23 pos=0.0 { minitokyo*CLI> 22 c0 00 65 04 80 10 1c 00 00 c0 a8 de be 73 0c " e s minitokyo*CLI> Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2800 Apr 7 12:22:39 WARNING[214]: chan_h323.c:805 oh323_write: Asked to transmit frame type 4, while native formats is 1 (read/write = 64/64) Apr 7 12:22:39 WARNING[214]: channel.c:2255 ast_channel_make_compatible: No path to translate from IAX2/paul@paul-1(4) to H323/74307(1) Apr 7 12:22:39 WARNING[214]: channel.c:3074 ast_channel_bridge: Can't make IAX2/paul@paul-1 and H323/74307 compatible Apr 7 12:22:39 WARNING[214]: res_features.c:976 ast_bridge_call: Bridge failed on channels IAX2/paul@paul-1 and H323/74307 Apr 7 12:22:39 DEBUG[214]: channel.c:844 ast_hangup: Hanging up channel 'H323/74307' Apr 7 12:22:39 DEBUG[214]: chan_h323.c:669 oh323_hangup: Hanging up call H323/74307 minitokyo*CLI> 00 c0 a8 de be 73 0d s minitokyo*CLI> } minitokyo*CLI> 26:16.382 H245:81c1f20 h323pdu.cxx(517) H245 Receiving PDU: minitokyo*CLI> response openLogicalChannelAck { minitokyo*CLI> forwardLogicalChannelNumber = 102 minitokyo*CLI> forwardMultiplexAckParameters = h2250LogicalChannelAckParameters { minitokyo*CLI> sessionID = 1 minitokyo*CLI> mediaChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> c0 a8 de be .... minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 29452 minitokyo*CLI> } minitokyo*CLI> mediaControlChannel = unicastAddress iPAddress { minitokyo*CLI> network = 4 octets { minitokyo*CLI> c0 a8 de be .... minitokyo*CLI> } minitokyo*CLI> tsapIdentifier = 29453 minitokyo*CLI> } minitokyo*CLI> flowControlToZero = FALSE minitokyo*CLI> } minitokyo*CLI> } minitokyo*CLI> 26:16.385 H245:81c1f20 h323neg.cxx(897) H245 Received open channel ack: T-102, state=AwaitingEstablishment minitokyo*CLI> 26:16.386 H245:81c1f20 channels.cxx(1017) H323RTP OnReceiveOpenAck minitokyo*CLI> MyH323_ExternalRTPChannel::OnReceivedAckPDU minitokyo*CLI> -- remoteIpAddress: 192.168.222.190 minitokyo*CLI> -- remotePort: 29452 minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1341 setup_rtp_connection: Setting up RTP connection for ip$localhost/22417 minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1375 setup_rtp_connection: RTP connection prepared for ip$localhost/22417 minitokyo*CLI> External RTP Session Starting minitokyo*CLI> RTP channel id 1 parameters: minitokyo*CLI> -- remoteIpAddress: 192.168.222.190 minitokyo*CLI> -- remotePort: 29452 minitokyo*CLI> -- ExternalIpAddress: 163.49.131.154 minitokyo*CLI> -- ExternalPort: 11100 minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1341 setup_rtp_connection: Setting up RTP connection for ip$localhost/22417 minitokyo*CLI> Apr 7 12:22:39 DEBUG[218]: chan_h323.c:1375 setup_rtp_connection: RTP connection prepared for ip$localhost/22417 minitokyo*CLI> 26:16.389 H245:81c1f20 h323.cxx(4088) H323 InternalEstablishedConnectionCheck: connectionState=EstablishedConnection fastStartState=FastStartDisabled minitokyo*CLI> -- ClearCall: Request to clear call with token ip$localhost/22417, cause 3 26:16.390 ThreadID=0x00278019 h323ep.cxx(1777) H323 Clearing connection ip$localhost/22417 reason=EndedByRemoteUser 26:16.390 ThreadID=0x00278019 h323.cxx(1537) H323 Call end reason for ip$localhost/22417 set to EndedByRemoteUser 26:16.390 ThreadID=0x00278019 h323.cxx(1555) H225 Sending release complete PDU: callRef=22417 -- Sending RELEASE COMPLETE 26:16.391 ThreadID=0x00278019 h323pdu.cxx(517) H245 Sending PDU: command endSessionCommand disconnect <> 26:16.392 ThreadID=0x00278019 h323pdu.cxx(517) H225 Sending PDU: { q931pdu = { protocolDiscriminator = 8 callReference = 22417 from = originator messageType = ReleaseComplete IE: Cause - Normal call clearing = { 80 90 .. } IE: User-User = { 25 80 06 00 08 91 4a 00 02 01 11 00 4c cb f4 4d %.0....J.....L..M f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 02 80 01 00 ..........H..... } } h225pdu = { h323_uu_pdu = { h323_message_body = releaseComplete { protocolIdentifier = 0.0.8.2250.0.2 callIdentifier = { guid = 16 octets { 4c cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 L..M..........H. } } } h245Tunneling = FALSE } } } Apr 7 12:22:39 DEBUG[214]: pbx.c:1810 ast_device_state_changed: Changing state for H323/74307 - state 0 26:16.394 H323 Cleaner h323ep.cxx(1834) H323 Cleaning up connections 26:16.394 H323 Cleaner h323.cxx(1592) H323 Connection ip$localhost/22417 closing: connectionState=EstablishedConnection 26:16.394 H323 Cleaner h323neg.cxx(334) H245 Stopping MasterSlaveDetermination: state=Idle 26:16.394 H323 Cleaner h323neg.cxx(561) H245 Stopping TerminalCapabilitySet: state=Sent 26:16.394 H323 Cleaner channels.cxx(679) LogChan Cleaning up T-102 channelsOpen = 1 26:16.395 H323 Cleaner h323ep.cxx(2159) H323 Stopped sending logical channel: G.711-uLaw-64k <1> 26:16.395 H323 Cleaner channels.cxx(707) LogChan Cleaned up T-102 26:16.395 H323 Cleaner channels.cxx(679) LogChan Cleaning up R-18 channelsOpen = 0 26:16.395 H323 Cleaner h323ep.cxx(2159) H323 Stopped receiving logical channel: G.711-uLaw-64k <1> minitokyo*CLI> 26:16.395 H323 Cleaner channels.cxx(707) LogChan Cleaned up R-18 Apr 7 12:22:39 DEBUG[219]: app_queue.c:420 changethread: Device 'H323/74307' changed to state '0' Apr 7 12:22:39 DEBUG[219]: app_queue.c:448 changethread: Device 'H323/74307' changed to state '0' minitokyo*CLI> Apr 7 12:22:39 DEBUG[214]: app_dial.c:1329 dial_exec_full: Exiting with DIALSTATUS=ANSWER. Apr 7 12:22:39 DEBUG[214]: pbx.c:2290 ast_pbx_run: Spawn extension (voip-h323,74307,1) exited non-zero on 'IAX2/paul@paul-1' Apr 7 12:22:39 DEBUG[214]: channel.c:844 ast_hangup: Hanging up channel 'IAX2/paul@paul-1' Apr 7 12:22:39 DEBUG[214]: chan_iax2.c:2971 iax2_hangup: We're hanging up IAX2/paul@paul-1 now... Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:1468 send_packet: Sending 2848 on 1/9053 to 163.49.131.89:4569 -- Hungup 'IAX2/paul@paul-1' Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:7378 socket_read: For call=1, set last=2820 Apr 7 12:22:39 DEBUG[214]: chan_iax2.c:8873 iax2_devicestate: Checking device state for device paul@paul Apr 7 12:22:39 DEBUG[214]: chan_iax2.c:8880 iax2_devicestate: Found peer. Now checking device state for peer paul Apr 7 12:22:39 DEBUG[214]: pbx.c:1810 ast_device_state_changed: Changing state for IAX2/paul@paul - state 1 Apr 7 12:22:39 DEBUG[220]: app_queue.c:420 changethread: Device 'IAX2/paul@paul' changed to state '1' minitokyo*CLI> Apr 7 12:22:39 DEBUG[220]: app_queue.c:448 changethread: Device 'IAX2/paul@paul' changed to state '1' minitokyo*CLI> 26:16.400 H245:81c1f20 h323.cxx(3405) H245 Checking for end session on PDU: command endSessionCommand minitokyo*CLI> 26:16.400 H245:81c1f20 h323.cxx(3391) H245 Control channel closed. minitokyo*CLI> ExternalRTPChannel Destroyed 26:16.401 H323 Cleaner h323.cxx(4623) H323 Bandwidth request: -64.0kb/s, available: 9872.0kb/s minitokyo*CLI> ExternalRTPChannel Destroyed 26:16.401 H323 Cleaner h323.cxx(4623) H323 Bandwidth request: -64.0kb/s, available: 9936.0kb/s 26:16.401 H323 Cleaner h323.cxx(1635) H323 Awaiting end session from remote for 9.989 seconds 26:16.401 H323 Cleaner transports.cxx(1106) H323 H323Transport::Close 26:16.402 H323 Cleaner transports.cxx(1188) H323 H323Transport::CleanUpOnTermination for H245:81c1f20 26:16.402 H323 Cleaner transports.cxx(1106) H323 H323Transport::Close 26:16.402 H225 Caller:81bc638 h323pdu.cxx(1226) H225 Read error (4): Interrupted system call -- ClearCall: Request to clear call with token ip$localhost/22417, cause 7 26:16.402 H225 Caller:81bc638 h323ep.cxx(1777) H323 Clearing connection ip$localhost/22417 reason=EndedByTransportFail 26:16.402 H225 Caller:81bc638 transports.cxx(1106) H323 H323Transport::Close 26:16.402 H225 Caller:81bc638 h323.cxx(1748) H225 Signal channel closed. minitokyo*CLI> 26:16.403 H323 Cleaner transports.cxx(1188) H323 H323Transport::CleanUpOnTermination for H225 Caller:81bc638 26:16.405 H323 Cleaner h323trans.cxx(522) Trans Making request: disengageRequest 26:16.405 H323 Cleaner h323pdu.cxx(517) Trans Sending PDU: disengageRequest { requestSeqNum = 56970 endpointIdentifier = 9 characters { 0035 0034 0031 0039 005f 0065 006e 0064 5419_end 0070 p } conferenceID = 16 octets { 74 cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 t..M..........H. } callReferenceValue = 22417 disengageReason = normalDrop <> callIdentifier = { guid = 16 octets { 4c cb f4 4d f7 a5 d9 11 87 c5 00 0b cd 00 48 e3 L..M..........H. } } gatekeeperIdentifier = 9 characters { 0047 006e 0075 0048 0033 0032 0033 0047 GnuH323G 004b K } answeredCall = FALSE usageInformation = { nonStandardUsageFields = 0 entries { } alertingTime = 1112894556 connectTime = 1112894559 endTime = 1112894559 } terminationCause = releaseCompleteCauseIE 2 octets { 80 90 .. } } 26:16.406 H323 Cleaner h323trans.cxx(653) Trans Waiting on response to seqnum=56970 for 3.0 seconds Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:6332 socket_read: Received packet 4, (6, 4) Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:6422 socket_read: Cancelling transmission of packet 6 Apr 7 12:22:39 DEBUG[32654]: chan_iax2.c:6431 socket_read: Really destroying 1, having been acked on final message 26:16.407 Transactor:8137668 h323pdu.cxx(517) H225RAS Receiving PDU: disengageConfirm { requestSeqNum = 56970 } 26:16.408 Transactor:8137668 h323trans.cxx(595) Trans Using credentials from request: H235AnnexD_Procedure1,MD5,CAT 26:16.408 H323 Cleaner h323.cxx(1656) H323 Connection ip$localhost/22417 terminated. -- Paul Davidson has cleared the call Apr 7 12:22:39 DEBUG[32640]: chan_h323.c:1639 cleanup_connection: Cleaning connection to ip$localhost/22417 == H.323 Connection deleted. 26:16.409 H323 Cleaner h323.cxx(1487) H323 Connection ip$localhost/22417 deleted. 26:16.409 H323 Cleaner h323ep.cxx(1834) H323 Cleaning up connections minitokyo*CLI> quit minitokyo:~/asterisk # Script done on Thu Apr 7 12:22:47 2005