[Mar 12 16:27:23]     -- Accepting UNAUTHENTICATED call from 217.24.217.52:
       > requested format = alaw,
       > requested prefs = (g726|alaw|g723|gsm|ulaw|ilbc),
       > actual format = alaw,
       > host prefs = (alaw|ulaw),
       > priority = mine
[Mar 12 16:27:23] DEBUG[7130]: pbx.c:1791 pbx_extension_helper: Launching 'Set'
[Mar 12 16:27:23]     -- Executing [492211625699979@from-pbx:1] Set("IAX2/217.24.217.52:4569-2", "origid=") in new stack
[Mar 12 16:27:23] DEBUG[7130]: pbx.c:1791 pbx_extension_helper: Launching 'Goto'
[Mar 12 16:27:23]     -- Executing [492211625699979@from-pbx:2] Goto("IAX2/217.24.217.52:4569-2", "wrn|79|1") in new stack
[Mar 12 16:27:23]     -- Goto (wrn,79,1)
[Mar 12 16:27:23] DEBUG[7130]: pbx.c:1791 pbx_extension_helper: Launching 'Ringing'
[Mar 12 16:27:23]     -- Executing [79@wrn:1] Ringing("IAX2/217.24.217.52:4569-2", "") in new stack
[Mar 12 16:27:23] DEBUG[7130]: pbx.c:1791 pbx_extension_helper: Launching 'Wait'
[Mar 12 16:27:23]     -- Executing [79@wrn:2] Wait("IAX2/217.24.217.52:4569-2", "90") in new stack
[Mar 12 16:27:23] DEBUG[7112]: chan_iax2.c:6649 socket_process: Ooh, voice format changed to 8
[Mar 12 16:27:23] DEBUG[7112]: channel.c:2845 set_format: Set channel IAX2/217.24.217.52:4569-2 to read format alaw
[Mar 12 16:27:37] DEBUG[7103]: chan_sip.c:4345 find_call: = Looking for  Call ID: 6c0c3ee7246140e021e4e5476c74ec1c@212.202.78.44 (Checking From) --From tag as128117bd --To-tag   
[Mar 12 16:27:37] DEBUG[7103]: chan_sip.c:4308 sip_alloc: Allocating new SIP dialog for 6c0c3ee7246140e021e4e5476c74ec1c@212.202.78.44 - OPTIONS (No RTP)
[Mar 12 16:27:37] DEBUG[7103]: chan_sip.c:14590 handle_request: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Mar 12 16:27:37] DEBUG[7103]: chan_sip.c:14807 sipsock_read: SIP message could not be handled, bad request: 6c0c3ee7246140e021e4e5476c74ec1c@212.202.78.44                        
[Mar 12 16:27:40] DEBUG[7113]: chan_iax2.c:7725 iax2_do_register: Allocate call number
[Mar 12 16:27:40] DEBUG[7113]: chan_iax2.c:7731 iax2_do_register: Registration created on call 3
[Mar 12 16:27:41]     -- Accepting UNAUTHENTICATED call from 217.24.217.52:
       > requested format = alaw,
       > requested prefs = (g726|alaw|g723|gsm|ulaw|ilbc),
       > actual format = alaw,
       > host prefs = (alaw|ulaw),
       > priority = mine
[Mar 12 16:27:41] DEBUG[7131]: pbx.c:1791 pbx_extension_helper: Launching 'Set'
[Mar 12 16:27:41]     -- Executing [492211625699980@from-pbx:1] Set("IAX2/217.24.217.52:4569-4", "origid=") in new stack
[Mar 12 16:27:41] DEBUG[7131]: pbx.c:1791 pbx_extension_helper: Launching 'Goto'
[Mar 12 16:27:41]     -- Executing [492211625699980@from-pbx:2] Goto("IAX2/217.24.217.52:4569-4", "wrn|80|1") in new stack
[Mar 12 16:27:41]     -- Goto (wrn,80,1)
[Mar 12 16:27:41] DEBUG[7131]: pbx.c:1791 pbx_extension_helper: Launching 'Wait'
[Mar 12 16:27:41]     -- Executing [80@wrn:1] Wait("IAX2/217.24.217.52:4569-4", "1") in new stack
[Mar 12 16:27:41] DEBUG[7112]: chan_iax2.c:6649 socket_process: Ooh, voice format changed to 8
[Mar 12 16:27:41] DEBUG[7112]: channel.c:2845 set_format: Set channel IAX2/217.24.217.52:4569-4 to read format alaw
[Mar 12 16:27:42] DEBUG[7131]: pbx.c:1791 pbx_extension_helper: Launching 'Pickup'
[Mar 12 16:27:42]     -- Executing [80@wrn:2] Pickup("IAX2/217.24.217.52:4569-4", "79@wrn") in new stack
[Mar 12 16:27:42] NOTICE[7131]: app_directed_pickup.c:99 pickup_by_exten: exten:  80 - context: wrn in .
[Mar 12 16:27:42] NOTICE[7131]: app_directed_pickup.c:99 pickup_by_exten: exten:  79 - context: wrn in .
[Mar 12 16:27:42] NOTICE[7131]: app_directed_pickup.c:162 pickup_exec: No target channel found for 79.
[Mar 12 16:27:42] DEBUG[7131]: pbx.c:1791 pbx_extension_helper: Launching 'Hangup'
[Mar 12 16:27:42]     -- Executing [80@wrn:3] Hangup("IAX2/217.24.217.52:4569-4", "") in new stack
[Mar 12 16:27:42] DEBUG[7131]: pbx.c:2389 __ast_pbx_run: Spawn extension (wrn,80,3) exited non-zero on 'IAX2/217.24.217.52:4569-4'
[