[Mar 12 17:51:04] -- 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 17:51:04] DEBUG[10674]: pbx.c:1791 pbx_extension_helper: Launching 'Set' [Mar 12 17:51:04] -- Executing [492211625699979@from-pbx:1] Set("IAX2/217.24.217.52:4569-2", "origid=") in new stack [Mar 12 17:51:04] DEBUG[10674]: pbx.c:1791 pbx_extension_helper: Launching 'Goto' [Mar 12 17:51:04] -- Executing [492211625699979@from-pbx:2] Goto("IAX2/217.24.217.52:4569-2", "wrn|79|1") in new stack [Mar 12 17:51:04] -- Goto (wrn,79,1) [Mar 12 17:51:04] DEBUG[10674]: pbx.c:1791 pbx_extension_helper: Launching 'Ringing' [Mar 12 17:51:04] -- Executing [79@wrn:1] Ringing("IAX2/217.24.217.52:4569-2", "") in new stack [Mar 12 17:51:04] DEBUG[10674]: pbx.c:1791 pbx_extension_helper: Launching 'Wait' [Mar 12 17:51:04] -- Executing [79@wrn:2] Wait("IAX2/217.24.217.52:4569-2", "90") in new stack [Mar 12 17:51:04] DEBUG[10653]: chan_iax2.c:6649 socket_process: Ooh, voice format changed to 8 [Mar 12 17:51:04] DEBUG[10653]: channel.c:2845 set_format: Set channel IAX2/217.24.217.52:4569-2 to read format alaw [Mar 12 17:51:07] DEBUG[10641]: chan_sip.c:4345 find_call: = Looking for Call ID: 5e802f0020b623bf7e9f3cee373ba83e@127.0.0.1 (Checking From) --From tag as283d737d --To-tag [Mar 12 17:51:07] DEBUG[10641]: chan_sip.c:4359 find_call: = No match Their Call ID: 09ad23b70ac4f3590491dadb23586356@193.201.52.127 Their Tag b11cb9bb270104b49a99a995b8c68544.078d Our tag: as006c20a0 [Mar 12 17:51:07] DEBUG[10641]: chan_sip.c:4359 find_call: = No match Their Call ID: 5794dac5367165527ea9a4060e8018e5@193.201.52.127 Their Tag ffc5cabd Our tag: as0a5d3bc4 [Mar 12 17:51:07] DEBUG[10641]: chan_sip.c:4359 find_call: = No match Their Call ID: 5054e6e70420159466ba4fe53c193167@193.201.52.127 Their Tag d737b8ad Our tag: as3ff3f0fb [Mar 12 17:51:07] DEBUG[10641]: chan_sip.c:4359 find_call: = No match Their Call ID: 3b42febf553caa5c4170b19c7bb4c3f5@212.202.78.44 Their Tag as42b5a0d1 Our tag: as7b551b4b [Mar 12 17:51:07] DEBUG[10641]: chan_sip.c:4308 sip_alloc: Allocating new SIP dialog for 5e802f0020b623bf7e9f3cee373ba83e@127.0.0.1 - REGISTER (No RTP) Channel Location State Application(Data) IAX2/217.24.217.52:4 79@wrn:2 Ring Wait(90) 1 active channel 1 active call *CLI> [Mar 12 17:51:09] DEBUG[10641]: chan_sip.c:2008 __sip_autodestruct: Auto destroying SIP dialog '3b42febf553caa5c4170b19c7bb4c3f5@212.202.78.44' [Mar 12 17:51:09] DEBUG[10641]: chan_sip.c:3107 sip_destroy: Destroying SIP dialog 3b42febf553caa5c4170b19c7bb4c3f5@212.202.78.44 [Mar 12 17:51:09] Really destroying SIP dialog '3b42febf553caa5c4170b19c7bb4c3f5@212.202.78.44' Method: OPTIONS [Mar 12 17:51:14] DEBUG[10641]: chan_sip.c:2008 __sip_autodestruct: Auto destroying SIP dialog '09ad23b70ac4f3590491dadb23586356@193.201.52.127' [Mar 12 17:51:14] DEBUG[10641]: chan_sip.c:3107 sip_destroy: Destroying SIP dialog 09ad23b70ac4f3590491dadb23586356@193.201.52.127 [Mar 12 17:51:14] Really destroying SIP dialog '09ad23b70ac4f3590491dadb23586356@193.201.52.127' Method: REGISTER [Mar 12 17:51:14] DEBUG[10641]: chan_sip.c:2008 __sip_autodestruct: Auto destroying SIP dialog '5054e6e70420159466ba4fe53c193167@193.201.52.127' [Mar 12 17:51:14] DEBUG[10641]: chan_sip.c:3107 sip_destroy: Destroying SIP dialog 5054e6e70420159466ba4fe53c193167@193.201.52.127 [Mar 12 17:51:14] Really destroying SIP dialog '5054e6e70420159466ba4fe53c193167@193.201.52.127' Method: REGISTER [Mar 12 17:51:14] DEBUG[10641]: chan_sip.c:2008 __sip_autodestruct: Auto destroying SIP dialog '5794dac5367165527ea9a4060e8018e5@193.201.52.127' [Mar 12 17:51:14] DEBUG[10641]: chan_sip.c:3107 sip_destroy: Destroying SIP dialog 5794dac5367165527ea9a4060e8018e5@193.201.52.127 [Mar 12 17:51:14] Really destroying SIP dialog '5794dac5367165527ea9a4060e8018e5@193.201.52.127' Method: REGISTER core show channels[Mar 12 17:51:22] -- 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 17:51:22] DEBUG[10676]: pbx.c:1791 pbx_extension_helper: Launching 'Set' [Mar 12 17:51:22] -- Executing [492211625699980@from-pbx:1] Set("IAX2/217.24.217.52:4569-3", "origid=") in new stack [Mar 12 17:51:22] DEBUG[10676]: pbx.c:1791 pbx_extension_helper: Launching 'Goto' [Mar 12 17:51:22] -- Executing [492211625699980@from-pbx:2] Goto("IAX2/217.24.217.52:4569-3", "wrn|80|1") in new stack [Mar 12 17:51:22] -- Goto (wrn,80,1) [Mar 12 17:51:22] DEBUG[10676]: pbx.c:1791 pbx_extension_helper: Launching 'Wait' [Mar 12 17:51:22] -- Executing [80@wrn:1] Wait("IAX2/217.24.217.52:4569-3", "1") in new stack [Mar 12 17:51:22] DEBUG[10649]: chan_iax2.c:6649 socket_process: Ooh, voice format changed to 8 [Mar 12 17:51:22] DEBUG[10649]: channel.c:2845 set_format: Set channel IAX2/217.24.217.52:4569-3 to read format alaw Channel Location State Application(Data) IAX2/217.24.217.52:4 80@wrn:1 Ring Wait(1) IAX2/217.24.217.52:4 79@wrn:2 Ring Wait(90) 2 active channels 2 active calls *CLI> [Mar 12 17:51:23] DEBUG[10676]: pbx.c:1791 pbx_extension_helper: Launching 'Pickup' [Mar 12 17:51:23] -- Executing [80@wrn:2] Pickup("IAX2/217.24.217.52:4569-3", "79@wrn") in new stack [Mar 12 17:51:23] NOTICE[10676]: app_directed_pickup.c:159 pickup_exec: No target channel found for 79. [Mar 12 17:51:23] DEBUG[10676]: pbx.c:1791 pbx_extension_helper: Launching 'Hangup' [Mar 12 17:51:23] -- Executing [80@wrn:3] Hangup("IAX2/217.24.217.52:4569-3", "") in new stack [Mar 12 17:51:23] DEBUG[10676]: pbx.c:2389 __ast_pbx_run: Spawn extension (wrn,80,3) exited non-zero on 'IAX2/217.24.217.52:4569-3' [Mar 12 17:51:23] == Spawn extension (wrn, 80, 3) exited non-zero on 'IAX2/217.24.217.52:4569-3' [Mar 12 17:51:23] DEBUG[10676]: cdr_addon_mysql.c:210 mysql_log: cdr_mysql: inserting a CDR record.