Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:3166 sip_alloc: Allocating new SIP dialog for 7d4dac7bb876e930MWY4ZDhmMWM2NDE5MDQyZjg2ZGJlMWMwZjhiZTI2ZmE. - INVITE (With RTP)
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:11178 handle_request: **** Received INVITE (5) - Command in SIP INVITE
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:7174 check_user_full: Setting NAT on RTP to 524288
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:11178 handle_request: **** Received ACK (6) - Command in SIP ACK
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:1410 __sip_ack: Stopping retransmission on '7d4dac7bb876e930MWY4ZDhmMWM2NDE5MDQyZjg2ZGJlMWMwZjhiZTI2ZmE.' of Response 1: Match Found
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:11178 handle_request: **** Received INVITE (5) - Command in SIP INVITE
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:7174 check_user_full: Setting NAT on RTP to 524288
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:10530 handle_request_invite: Checking SIP call limits for device festrntb
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:2215 update_call_counter: Updating call counter for incoming call
Sep 28 15:33:51 DEBUG[2354]: chan_sip.c:6152 build_route: build_route: Contact hop: <sip:festrntb@212.71.160.23:36826>
Sep 28 15:33:51 DEBUG[2332]: chan_sip.c:11719 sip_devicestate: Checking device state for peer festrntb
Sep 28 15:33:51 DEBUG[2332]: devicestate.c:187 do_state_change: Changing state for SIP/festrntb - state 2 (In use)
Sep 28 15:33:51 DEBUG[2393]: pbx.c:1677 pbx_extension_helper: Launching 'Dial'
    -- Executing Dial("SIP/festrntb-0823f7f8", "OH323/605267610") in new stack
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:2937 oh323_request: In oh323_request: type=OH323, format=256, data=605267610.
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:2661 new_oh323: Player fds 46,47 - Recorder fds 48,49 - Event pipe 50,51.
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:2993 oh323_request: Created new call structure 0 (9948 bytes).
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:2815 ast_oh323_new: OH323/605267610-3f89c926: Raw format set to g729.
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:2876 ast_oh323_new: Context is 'h323-default-answer', extension is 's'.
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:2877 ast_oh323_new: CID name 'UNKNOWN', CID num 'UNKNOWN', ANI 'UNKNOWN', DNID 'UNKNOWN'.
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:3010 oh323_request: OH323/605267610-3f89c926: Native format changed to g729.
Sep 28 15:33:51 DEBUG[2393]: channel.c:2902 ast_channel_inherit_variables: Not copying variable STACK-testcontext-605267610-1.
Sep 28 15:33:51 DEBUG[2393]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPCALLID.
Sep 28 15:33:51 DEBUG[2393]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT.
Sep 28 15:33:51 DEBUG[2393]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
Sep 28 15:33:51 DEBUG[2393]: channel.c:2902 ast_channel_inherit_variables: Not copying variable SIPURI.
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:1858 oh323_call: In oh323_call (OH323/605267610-3f89c926, dest=605267610, timeout=0).
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:1894 oh323_call: OH323/605267610-3f89c926: Target '(null)' is not a configured H.323 endpoint
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:1938 oh323_call: OH323/605267610-3f89c926: No ${OH323_OUTCODEC}.
    -- H.323 call to 605267610 with codec(s) g729
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:1948 oh323_call: NEW STATE: NULL --> INIT
    -- Outbound H.323 call to destination '605267610', channel 'OH323/605267610-3f89c926'.
Sep 28 15:33:51 DEBUG[2393]: chan_oh323.c:1969 oh323_call: OH323/605267610-3f89c926: Call to 605267610 initiated successfully.
    -- Called 605267610