Debian 6.0.5 64bit
Vici 2.4rc2 via source
Asterisk 1.4.39.1
Dahdi 2.4.0
All PSTN connect is SIP
Quad Core Xeon E5530 2.4 ghz with 16gb of ram
We are having issues when transferring calls from a agent to another ingroup. Calls die as soon as we park or transfer, in the CLI we see the calls going to a 990009xxxxxxx extension, this calls the following code: AGI(agi-VDAD_ALL_inbound.agi,CLOSER-----LB-----CL_TESTCAMP-----7275551212-----Closer-----park----------999-----1)
We see the the AGI execute then the calls is hung up on the person calling in and the agent loses the call, the CLI shows nothing excpet the AGI being executed.
Ive looked in the agiout log and see no errors or anything.
Any ideal what our next steps are to check?
Thanks
Transferring calls from ingroup to another ingroup
Moderators: gerski, enjay, williamconley, Op3r, Staydog, gardo, mflorell, MJCoate, mcargile, Kumba, Michael_N
5 posts
• Page 1 of 1
Transferring calls from ingroup to another ingroup
by mms1 » Sun Oct 07, 2012 1:51 pm
- mms1
- Posts: 34
- Joined: Mon Jun 30, 2008 9:46 am
Re: Transferring calls from ingroup to another ingroup
by williamconley » Sun Oct 07, 2012 4:55 pm
It is a requirement to post your Vicidial Version with Build (not 2.4rc2, but the full version with build). Also you should post a link to your installation instructions as you are not using a standard system (and there always exists the possibility you are using a flawed installation ... that "link" could link you and another user and find said flaw).
That being said: You should post the Asterisk CLI output from a failed attempt (not 3000 lines of unrelated code, of course, just the code generated at the moment of the attempted transfer).
You can also look at the logs in /var/log/astguiclient and /var/log/asterisk and see what happens at the moment of transfer that may be going awry. All those logs are disposable, so you can dump them just before pressing the button and then copy them to an outside location immediately after the fail ... then harvest your results and see if any contain useful information.
Logging for /var/log/asterisk is controlled by /etc/asterisk/logger.conf and requires "logger rotate" to take effect after changes are made. Logging for /var/log/astguiclient is controlled by your Admin->Servers logging setting on each server.
Happy Hunting! Welcome to the Party!
That being said: You should post the Asterisk CLI output from a failed attempt (not 3000 lines of unrelated code, of course, just the code generated at the moment of the attempted transfer).
You can also look at the logs in /var/log/astguiclient and /var/log/asterisk and see what happens at the moment of transfer that may be going awry. All those logs are disposable, so you can dump them just before pressing the button and then copy them to an outside location immediately after the fail ... then harvest your results and see if any contain useful information.
Logging for /var/log/asterisk is controlled by /etc/asterisk/logger.conf and requires "logger rotate" to take effect after changes are made. Logging for /var/log/astguiclient is controlled by your Admin->Servers logging setting on each server.
Happy Hunting! Welcome to the Party!
Vicidial Installation and Repair, plus Hosting and Colocation
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
- williamconley
- Posts: 20258
- Joined: Wed Oct 31, 2007 4:17 pm
- Location: Davenport, FL (By Disney!)
Re: Transferring calls from ingroup to another ingroup
by mms1 » Sat Jan 05, 2013 10:41 pm
VERSION: 2.6-392a
BUILD: 130102-1135
Ok, so we still have not gotten this functionality to work so any further help is much appreciated as we really need to get this functionality working. We upgraded to the latest svn version and still no luck.
We have found that we can successfully transfer an outbound call to an ingroup but using the same campaign we are unable to transfer an inbound call to that same ingroup. The call drops.
Here is the output from the cli: 757####### is the phone number
Working for transferring an outbound call
[Jan 5 21:52:19] -- Executing [990009*Radius_in**1325339**757#######*0693**@default:1] AGI("SIP/benga-2-in-000094f5", "agi-VDAD_ALL_inbound.agi|CLOSER-----LB-----CL_TESTCAMP-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 21:52:19] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/8600069@default-b196,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----15") in new stack
[Jan 5 21:52:19] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-227a,2'
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/58600069@default-227a,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----15 completed, returning 0
[Jan 5 21:52:19] == Spawn extension (default, 71757#######, 2) exited non-zero on 'Local/8600069@default-b196,1'
[Jan 5 21:52:19] == Spawn extension (default, 8600069, 1) exited non-zero on 'Local/8600069@default-b196,2'
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/8600069@default-b196,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 21:52:19] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-227a,1'
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/58600069@default-227a,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 21:52:19] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jan 5 21:52:19] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jan 5 21:52:19] -- Playing 'stayonline' (escape_digits=) (sample_offset 0)
Not working for an inbound call:
[Jan 5 22:04:01] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 0) exited non-zero on 'SIP/benga-2-in-00009508'
[Jan 5 22:04:01] -- Executing [990009*Radius_in**2647696**757#######*0693**@default:1] AGI("SIP/benga-2-in-00009508", "agi-VDAD_ALL_inbound.agi|CID-----LB-----Radius_elevated_in-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 22:04:01] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 22:04:01] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 1) exited non-zero on 'SIP/benga-2-in-00009508'
[Jan 5 22:04:01] -- Executing [h@default:1] DeadAGI("SIP/benga-2-in-00009508", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:04:01] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:04:01] Found
[Jan 5 22:04:01] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:04:01] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-746d,2'
[Jan 5 22:04:01] -- Executing [h@default:1] DeadAGI("Local/58600069@default-746d,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:04:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:04:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:04:01] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-746d,1'
[Jan 5 22:04:01] -- Executing [h@default:1] DeadAGI("Local/58600069@default-746d,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
Has anyone else had issues with the latest svn or 2.4 build transferring inbound calls to a different ingroup? We had this working on previous versions.
Thanks again for any assistance.
In case it is helpful, here is the log for these instances of drops.
[Jan 5 22:13:06] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 9 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 8 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 7 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 6 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 3f36bf66698fb2127022d3ce24260230@192.168.20.40 Their Tag Our tag: as0c339cbd
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '3f36bf66698fb2127022d3ce24260230@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:09] Really destroying SIP dialog '3f36bf66698fb2127022d3ce24260230@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2
[Jan 5 22:13:09] Really destroying SIP dialog '034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2
[Jan 5 22:13:10] Really destroying SIP dialog '21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[17852]: channel.c:3106 ast_write: Deadlock avoided for write to channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 0f79503d21384d97345b3b6079d500df@192.168.20.40 Their Tag Our tag: as3421adc1
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '0f79503d21384d97345b3b6079d500df@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:14] Really destroying SIP dialog '0f79503d21384d97345b3b6079d500df@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:2215 __sip_autodestruct: Auto destroying SIP dialog '9cd09ccce346becf@192.168.20.133'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 9cd09ccce346becf@192.168.20.133
[Jan 5 22:13:18] Really destroying SIP dialog '9cd09ccce346becf@192.168.20.133' Method: REGISTER
[Jan 5 22:13:18] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1ddaf897362be5296464011f288f61c9@192.168.20.40 Their Tag Our tag: as4db2c39f
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '1ddaf897362be5296464011f288f61c9@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:22] Really destroying SIP dialog '1ddaf897362be5296464011f288f61c9@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2394e6a67b733d853f21c4a17b6fca88@2.2.2.2
[Jan 5 22:13:23] Really destroying SIP dialog '2394e6a67b733d853f21c4a17b6fca88@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 0803a6de4575a79a30231b1077a87933@2.2.2.2 Their Tag Our tag: as77b6fbcc
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 66f648bc09304ec032414508116256d2@1.1.1.1 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 - OPTIONS (No RTP)
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:17252 sipsock_read: SIP message could not be handled, bad request: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 0803a6de4575a79a30231b1077a87933@2.2.2.2
[Jan 5 22:13:24] Really destroying SIP dialog '0803a6de4575a79a30231b1077a87933@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17994]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Redirect'
[Jan 5 22:13:28] DEBUG[17994]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'AGI'
[Jan 5 22:13:28] -- Executing [990009*Radius_in**2647696**757#######*0693**@default:1] AGI("SIP/inbound-carrier-0000950c", "agi-VDAD_ALL_inbound.agi|CID-----LB-----Radius_elevated_in-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 22:13:28] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:1925 run_agi: SIP/inbound-carrier-0000950c hungup
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("SIP/inbound-carrier-0000950c", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17997]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Hangup'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1681 ast_hangup: Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17997]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17798]: chan_sip.c:3851 sip_hangup: Hangup call SIP/inbound-carrier-0000950c, SIP callid 66f648bc09304ec032414508116256d2@1.1.1.1)
[Jan 5 22:13:28] DEBUG[17798]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/inbound-carrier - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/inbound-carrier' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,58600069,1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17852]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,8309,3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17852]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 2 (In use)
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17853]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17853]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 66f648bc09304ec032414508116256d2@1.1.1.1 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '66f648bc09304ec032414508116256d2@1.1.1.1' of Request 102: Match Found
[Jan 5 22:13:06] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 9 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 8 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 7 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 6 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 3f36bf66698fb2127022d3ce24260230@192.168.20.40 Their Tag Our tag: as0c339cbd
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '3f36bf66698fb2127022d3ce24260230@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:09] Really destroying SIP dialog '3f36bf66698fb2127022d3ce24260230@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2
[Jan 5 22:13:09] Really destroying SIP dialog '034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2
[Jan 5 22:13:10] Really destroying SIP dialog '21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[17852]: channel.c:3106 ast_write: Deadlock avoided for write to channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 0f79503d21384d97345b3b6079d500df@192.168.20.40 Their Tag Our tag: as3421adc1
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '0f79503d21384d97345b3b6079d500df@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:14] Really destroying SIP dialog '0f79503d21384d97345b3b6079d500df@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:2215 __sip_autodestruct: Auto destroying SIP dialog '9cd09ccce346becf@192.168.20.133'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 9cd09ccce346becf@192.168.20.133
[Jan 5 22:13:18] Really destroying SIP dialog '9cd09ccce346becf@192.168.20.133' Method: REGISTER
[Jan 5 22:13:18] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1ddaf897362be5296464011f288f61c9@192.168.20.40 Their Tag Our tag: as4db2c39f
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '1ddaf897362be5296464011f288f61c9@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:22] Really destroying SIP dialog '1ddaf897362be5296464011f288f61c9@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2394e6a67b733d853f21c4a17b6fca88@2.2.2.2
[Jan 5 22:13:23] Really destroying SIP dialog '2394e6a67b733d853f21c4a17b6fca88@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 0803a6de4575a79a30231b1077a87933@2.2.2.2 Their Tag Our tag: as77b6fbcc
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 66f648bc09304ec032414508116256d2@69.160.41.36 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 - OPTIONS (No RTP)
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:17252 sipsock_read: SIP message could not be handled, bad request: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 0803a6de4575a79a30231b1077a87933@2.2.2.2
[Jan 5 22:13:24] Really destroying SIP dialog '0803a6de4575a79a30231b1077a87933@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17994]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Redirect'
[Jan 5 22:13:28] DEBUG[17994]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'AGI'
[Jan 5 22:13:28] -- Executing [990009*Radius_in**2647696**757#######*0693**@default:1] AGI("SIP/inbound-carrier-0000950c", "agi-VDAD_ALL_inbound.agi|CID-----LB-----Radius_elevated_in-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 22:13:28] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:1925 run_agi: SIP/inbound-carrier-0000950c hungup
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("SIP/inbound-carrier-0000950c", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17997]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Hangup'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1681 ast_hangup: Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17997]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17798]: chan_sip.c:3851 sip_hangup: Hangup call SIP/inbound-carrier-0000950c, SIP callid 66f648bc09304ec032414508116256d2@69.160.41.36)
[Jan 5 22:13:28] DEBUG[17798]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/inbound-carrier - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/inbound-carrier' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,58600069,1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17852]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,8309,3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17852]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 2 (In use)
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17853]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17853]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 66f648bc09304ec032414508116256d2@69.160.41.36 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '66f648bc09304ec032414508116256d2@69.160.41.36' of Request 102: Match Found
[Jan 5 22:13:29] Really destroying SIP dialog '66f648bc09304ec032414508116256d2@69.160.41.36' Method: ACK
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:30] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] -- Remote UNIX connection disconnected
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2 Their Tag Our tag: as1c7954ae
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2 Their Tag Our tag: as2dcd65b9
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 1e83ca875b7b2d9d@192.168.20.115 - REGISTER (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1e83ca875b7b2d9d@192.168.20.115 Their Tag 16a486d6b5426055 Our tag: as13e042b8
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - a13
[Jan 5 22:13:36] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/a13 - state 1 (Not in use)
[Jan 5 22:13:36] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/a13' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:36] DEBUG[16745]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 77e353470e7b4c1b321032c12d837046@192.168.20.40 Their Tag Our tag: as42073d24
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '77e353470e7b4c1b321032c12d837046@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:36] Really destroying SIP dialog '77e353470e7b4c1b321032c12d837046@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2
[Jan 5 22:13:37] Really destroying SIP dialog '2c6f1275146c2e3816a006e25bd6133f@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2
[Jan 5 22:13:38] Really destroying SIP dialog '6cf6c65614e968563e8d6aea2d5741df@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 2d1f571455af290f5db2cfed23287787@192.168.20.40 Their Tag Our tag: as7642ab1c
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '2d1f571455af290f5db2cfed23287787@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:38] Really destroying SIP dialog '2d1f571455af290f5db2cfed23287787@192.168.20.40' Method: NOTIFY
[Jan 5 22:13:29] Really destroying SIP dialog '66f648bc09304ec032414508116256d2@1.1.1.1' Method: ACK
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:30] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] -- Remote UNIX connection disconnected
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2 Their Tag Our tag: as1c7954ae
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2 Their Tag Our tag: as2dcd65b9
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 1e83ca875b7b2d9d@192.168.20.115 - REGISTER (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1e83ca875b7b2d9d@192.168.20.115 Their Tag 16a486d6b5426055 Our tag: as13e042b8
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - a13
[Jan 5 22:13:36] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/a13 - state 1 (Not in use)
[Jan 5 22:13:36] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/a13' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:36] DEBUG[16745]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 77e353470e7b4c1b321032c12d837046@192.168.20.40 Their Tag Our tag: as42073d24
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '77e353470e7b4c1b321032c12d837046@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:36] Really destroying SIP dialog '77e353470e7b4c1b321032c12d837046@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2
[Jan 5 22:13:37] Really destroying SIP dialog '2c6f1275146c2e3816a006e25bd6133f@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2
[Jan 5 22:13:38] Really destroying SIP dialog '6cf6c65614e968563e8d6aea2d5741df@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 2d1f571455af290f5db2cfed23287787@192.168.20.40 Their Tag Our tag: as7642ab1c
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '2d1f571455af290f5db2cfed23287787@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:38] Really destroying SIP dialog '2d1f571455af290f5db2cfed23287787@192.168.20.40' Method: NOTIFY
BUILD: 130102-1135
Ok, so we still have not gotten this functionality to work so any further help is much appreciated as we really need to get this functionality working. We upgraded to the latest svn version and still no luck.
We have found that we can successfully transfer an outbound call to an ingroup but using the same campaign we are unable to transfer an inbound call to that same ingroup. The call drops.
Here is the output from the cli: 757####### is the phone number
Working for transferring an outbound call
[Jan 5 21:52:19] -- Executing [990009*Radius_in**1325339**757#######*0693**@default:1] AGI("SIP/benga-2-in-000094f5", "agi-VDAD_ALL_inbound.agi|CLOSER-----LB-----CL_TESTCAMP-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 21:52:19] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/8600069@default-b196,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----15") in new stack
[Jan 5 21:52:19] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-227a,2'
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/58600069@default-227a,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----20-----15 completed, returning 0
[Jan 5 21:52:19] == Spawn extension (default, 71757#######, 2) exited non-zero on 'Local/8600069@default-b196,1'
[Jan 5 21:52:19] == Spawn extension (default, 8600069, 1) exited non-zero on 'Local/8600069@default-b196,2'
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/8600069@default-b196,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 21:52:19] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-227a,1'
[Jan 5 21:52:19] -- Executing [h@default:1] DeadAGI("Local/58600069@default-227a,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 21:52:19] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 21:52:19] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jan 5 21:52:19] -- Playing 'sip-silence' (escape_digits=) (sample_offset 0)
[Jan 5 21:52:19] -- Playing 'stayonline' (escape_digits=) (sample_offset 0)
Not working for an inbound call:
[Jan 5 22:04:01] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 0) exited non-zero on 'SIP/benga-2-in-00009508'
[Jan 5 22:04:01] -- Executing [990009*Radius_in**2647696**757#######*0693**@default:1] AGI("SIP/benga-2-in-00009508", "agi-VDAD_ALL_inbound.agi|CID-----LB-----Radius_elevated_in-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 22:04:01] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 22:04:01] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 1) exited non-zero on 'SIP/benga-2-in-00009508'
[Jan 5 22:04:01] -- Executing [h@default:1] DeadAGI("SIP/benga-2-in-00009508", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:04:01] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:04:01] Found
[Jan 5 22:04:01] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:04:01] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-746d,2'
[Jan 5 22:04:01] -- Executing [h@default:1] DeadAGI("Local/58600069@default-746d,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:04:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:04:01] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:04:01] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-746d,1'
[Jan 5 22:04:01] -- Executing [h@default:1] DeadAGI("Local/58600069@default-746d,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
Has anyone else had issues with the latest svn or 2.4 build transferring inbound calls to a different ingroup? We had this working on previous versions.
Thanks again for any assistance.
In case it is helpful, here is the log for these instances of drops.
[Jan 5 22:13:06] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 9 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 8 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 7 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 6 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 3f36bf66698fb2127022d3ce24260230@192.168.20.40 Their Tag Our tag: as0c339cbd
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '3f36bf66698fb2127022d3ce24260230@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:09] Really destroying SIP dialog '3f36bf66698fb2127022d3ce24260230@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2
[Jan 5 22:13:09] Really destroying SIP dialog '034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2
[Jan 5 22:13:10] Really destroying SIP dialog '21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[17852]: channel.c:3106 ast_write: Deadlock avoided for write to channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 0f79503d21384d97345b3b6079d500df@192.168.20.40 Their Tag Our tag: as3421adc1
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '0f79503d21384d97345b3b6079d500df@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:14] Really destroying SIP dialog '0f79503d21384d97345b3b6079d500df@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:2215 __sip_autodestruct: Auto destroying SIP dialog '9cd09ccce346becf@192.168.20.133'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 9cd09ccce346becf@192.168.20.133
[Jan 5 22:13:18] Really destroying SIP dialog '9cd09ccce346becf@192.168.20.133' Method: REGISTER
[Jan 5 22:13:18] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1ddaf897362be5296464011f288f61c9@192.168.20.40 Their Tag Our tag: as4db2c39f
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '1ddaf897362be5296464011f288f61c9@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:22] Really destroying SIP dialog '1ddaf897362be5296464011f288f61c9@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2394e6a67b733d853f21c4a17b6fca88@2.2.2.2
[Jan 5 22:13:23] Really destroying SIP dialog '2394e6a67b733d853f21c4a17b6fca88@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 0803a6de4575a79a30231b1077a87933@2.2.2.2 Their Tag Our tag: as77b6fbcc
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 66f648bc09304ec032414508116256d2@1.1.1.1 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 - OPTIONS (No RTP)
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:17252 sipsock_read: SIP message could not be handled, bad request: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 0803a6de4575a79a30231b1077a87933@2.2.2.2
[Jan 5 22:13:24] Really destroying SIP dialog '0803a6de4575a79a30231b1077a87933@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17994]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Redirect'
[Jan 5 22:13:28] DEBUG[17994]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'AGI'
[Jan 5 22:13:28] -- Executing [990009*Radius_in**2647696**757#######*0693**@default:1] AGI("SIP/inbound-carrier-0000950c", "agi-VDAD_ALL_inbound.agi|CID-----LB-----Radius_elevated_in-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 22:13:28] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:1925 run_agi: SIP/inbound-carrier-0000950c hungup
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("SIP/inbound-carrier-0000950c", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17997]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Hangup'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1681 ast_hangup: Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17997]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17798]: chan_sip.c:3851 sip_hangup: Hangup call SIP/inbound-carrier-0000950c, SIP callid 66f648bc09304ec032414508116256d2@1.1.1.1)
[Jan 5 22:13:28] DEBUG[17798]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/inbound-carrier - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/inbound-carrier' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,58600069,1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17852]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,8309,3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17852]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 2 (In use)
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17853]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17853]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 66f648bc09304ec032414508116256d2@1.1.1.1 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '66f648bc09304ec032414508116256d2@1.1.1.1' of Request 102: Match Found
[Jan 5 22:13:06] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 9 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 8 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 7 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[1544]: chan_iax2.c:2076 peercnt_remove: ip callno count decremented to 6 for 127.0.0.1
[Jan 5 22:13:07] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:08] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:08] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 3f36bf66698fb2127022d3ce24260230@192.168.20.40 Their Tag Our tag: as0c339cbd
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '3f36bf66698fb2127022d3ce24260230@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:09] Really destroying SIP dialog '3f36bf66698fb2127022d3ce24260230@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:09] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:09] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2
[Jan 5 22:13:09] Really destroying SIP dialog '034a9cde0d9ac00e4524ed7f4f194085@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2
[Jan 5 22:13:10] Really destroying SIP dialog '21a79ee03a4dac2338d59a3b4cfc1378@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:10] DEBUG[17852]: channel.c:3106 ast_write: Deadlock avoided for write to channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:11] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:12] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:13] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:13] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 0f79503d21384d97345b3b6079d500df@192.168.20.40 Their Tag Our tag: as3421adc1
[Jan 5 22:13:14] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '0f79503d21384d97345b3b6079d500df@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:14] Really destroying SIP dialog '0f79503d21384d97345b3b6079d500df@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:14] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:15] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:16] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:17] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:2215 __sip_autodestruct: Auto destroying SIP dialog '9cd09ccce346becf@192.168.20.133'
[Jan 5 22:13:18] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 9cd09ccce346becf@192.168.20.133
[Jan 5 22:13:18] Really destroying SIP dialog '9cd09ccce346becf@192.168.20.133' Method: REGISTER
[Jan 5 22:13:18] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:18] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:19] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:20] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:21] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1ddaf897362be5296464011f288f61c9@192.168.20.40 Their Tag Our tag: as4db2c39f
[Jan 5 22:13:22] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '1ddaf897362be5296464011f288f61c9@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:22] Really destroying SIP dialog '1ddaf897362be5296464011f288f61c9@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:23] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2394e6a67b733d853f21c4a17b6fca88@2.2.2.2
[Jan 5 22:13:23] Really destroying SIP dialog '2394e6a67b733d853f21c4a17b6fca88@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 0803a6de4575a79a30231b1077a87933@2.2.2.2 Their Tag Our tag: as77b6fbcc
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 66f648bc09304ec032414508116256d2@69.160.41.36 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 - OPTIONS (No RTP)
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received OPTIONS (3) - Command in SIP OPTIONS
[Jan 5 22:13:23] DEBUG[1487]: chan_sip.c:17252 sipsock_read: SIP message could not be handled, bad request: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 0803a6de4575a79a30231b1077a87933@2.2.2.2
[Jan 5 22:13:24] Really destroying SIP dialog '0803a6de4575a79a30231b1077a87933@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:24] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:25] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:26] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:27] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17798]: rtp.c:941 ast_rtcp_read: Got RTCP report of 64 bytes
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17994]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Redirect'
[Jan 5 22:13:28] DEBUG[17994]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 0) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'AGI'
[Jan 5 22:13:28] -- Executing [990009*Radius_in**2647696**757#######*0693**@default:1] AGI("SIP/inbound-carrier-0000950c", "agi-VDAD_ALL_inbound.agi|CID-----LB-----Radius_elevated_in-----7275551212-----Closer-----park----------999-----1") in new stack
[Jan 5 22:13:28] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_inbound.agi
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:1925 run_agi: SIP/inbound-carrier-0000950c hungup
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,990009*Radius_in**2647696**757#######*0693**,1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] == Spawn extension (default, 990009*Radius_in**2647696**757#######*0693**, 1) exited non-zero on 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17798]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("SIP/inbound-carrier-0000950c", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17798]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Login'
[Jan 5 22:13:28] == Parsing '/etc/asterisk/manager.conf': [Jan 5 22:13:28] DEBUG[17997]: config.c:895 config_text_file_load: Parsing /etc/asterisk/manager.conf
[Jan 5 22:13:28] Found
[Jan 5 22:13:28] == Manager 'sendcron' logged on from 127.0.0.1
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Hangup'
[Jan 5 22:13:28] DEBUG[17798]: channel.c:1681 ast_hangup: Hanging up channel 'SIP/inbound-carrier-0000950c'
[Jan 5 22:13:28] DEBUG[17997]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17798]: chan_sip.c:3851 sip_hangup: Hangup call SIP/inbound-carrier-0000950c, SIP callid 66f648bc09304ec032414508116256d2@69.160.41.36)
[Jan 5 22:13:28] DEBUG[17798]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer inbound-carrier
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/inbound-carrier - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/inbound-carrier' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,58600069,1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] == Spawn extension (default, 58600069, 1) exited non-zero on 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17852]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[17852]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17852]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,2'
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:2473 __ast_pbx_run: Spawn extension (default,8309,3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] == Spawn extension (default, 8309, 3) exited non-zero on 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17852]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1578 ast_softhangup_nolock: Soft-Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 2 (In use)
[Jan 5 22:13:28] DEBUG[17853]: pbx.c:1866 pbx_extension_helper: Launching 'DeadAGI'
[Jan 5 22:13:28] -- Executing [h@default:1] DeadAGI("Local/58600069@default-ec68,1", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:28] DEBUG[17853]: res_agi.c:249 launch_netscript: Wow, connected!
[Jan 5 22:13:28] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:28] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0
[Jan 5 22:13:28] DEBUG[17853]: channel.c:1681 ast_hangup: Hanging up channel 'Local/58600069@default-ec68,1'
[Jan 5 22:13:28] DEBUG[17853]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel Local/58600069@default
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for Local - 58600069@default
[Jan 5 22:13:28] DEBUG[1479]: chan_local.c:216 local_devicestate: Checking if extension 58600069@default exists (devicestate)
[Jan 5 22:13:28] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for Local/58600069@default - state 1 (Not in use)
[Jan 5 22:13:28] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'Local/58600069@default' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 66f648bc09304ec032414508116256d2@69.160.41.36 Their Tag as595e8507 Our tag: as41a65e09
[Jan 5 22:13:29] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '66f648bc09304ec032414508116256d2@69.160.41.36' of Request 102: Match Found
[Jan 5 22:13:29] Really destroying SIP dialog '66f648bc09304ec032414508116256d2@69.160.41.36' Method: ACK
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:30] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] -- Remote UNIX connection disconnected
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2 Their Tag Our tag: as1c7954ae
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2 Their Tag Our tag: as2dcd65b9
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 1e83ca875b7b2d9d@192.168.20.115 - REGISTER (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1e83ca875b7b2d9d@192.168.20.115 Their Tag 16a486d6b5426055 Our tag: as13e042b8
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - a13
[Jan 5 22:13:36] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/a13 - state 1 (Not in use)
[Jan 5 22:13:36] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/a13' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:36] DEBUG[16745]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 77e353470e7b4c1b321032c12d837046@192.168.20.40 Their Tag Our tag: as42073d24
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '77e353470e7b4c1b321032c12d837046@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:36] Really destroying SIP dialog '77e353470e7b4c1b321032c12d837046@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2
[Jan 5 22:13:37] Really destroying SIP dialog '2c6f1275146c2e3816a006e25bd6133f@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2
[Jan 5 22:13:38] Really destroying SIP dialog '6cf6c65614e968563e8d6aea2d5741df@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 2d1f571455af290f5db2cfed23287787@192.168.20.40 Their Tag Our tag: as7642ab1c
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '2d1f571455af290f5db2cfed23287787@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:38] Really destroying SIP dialog '2d1f571455af290f5db2cfed23287787@192.168.20.40' Method: NOTIFY
[Jan 5 22:13:29] Really destroying SIP dialog '66f648bc09304ec032414508116256d2@1.1.1.1' Method: ACK
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:29] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:30] DEBUG[17994]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:30] DEBUG[17997]: manager.c:2278 process_message: Manager received command 'Logoff'
[Jan 5 22:13:30] == Manager 'sendcron' logged off from 127.0.0.1
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:31] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:32] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:33] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:33] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:34] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] -- Remote UNIX connection disconnected
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:35] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2 Their Tag Our tag: as1c7954ae
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2 Their Tag Our tag: as2dcd65b9
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 3fdb57af6ea95433558774320ba4f42b@192.168.20.104 Their Tag as65c6eec7 Our tag: as3d5a4708
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 1bb9e95540cc00a737ad35001f9c9e15@192.168.20.40 Their Tag b79f11e69ec87ee4 Our tag: as41231d6c
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = No match Their Call ID: 2d614d9056d6e868364abe777618b34f@192.168.20.40 Their Tag 580adcfc2854e5f5 Our tag: as3bb60f15
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for 1e83ca875b7b2d9d@192.168.20.115 - REGISTER (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 1e83ca875b7b2d9d@192.168.20.115 Their Tag 16a486d6b5426055 Our tag: as13e042b8
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:16919 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - OPTIONS (No RTP)
[Jan 5 22:13:36] DEBUG[1487]: devicestate.c:302 ast_device_state_changed_literal: Notification of state change to be queued on device/channel SIP/a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:161 ast_device_state: No provider found, checking channel drivers for SIP - a13
[Jan 5 22:13:36] DEBUG[1479]: chan_sip.c:17722 sip_devicestate: Checking device state for peer a13
[Jan 5 22:13:36] DEBUG[1479]: devicestate.c:287 do_state_change: Changing state for SIP/a13 - state 1 (Not in use)
[Jan 5 22:13:36] DEBUG[1489]: app_queue.c:710 handle_statechange: Device 'SIP/a13' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[Jan 5 22:13:36] DEBUG[16745]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 77e353470e7b4c1b321032c12d837046@192.168.20.40 Their Tag Our tag: as42073d24
[Jan 5 22:13:36] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '77e353470e7b4c1b321032c12d837046@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:36] Really destroying SIP dialog '77e353470e7b4c1b321032c12d837046@192.168.20.40' Method: OPTIONS
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:37] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 2c6f1275146c2e3816a006e25bd6133f@2.2.2.2
[Jan 5 22:13:37] Really destroying SIP dialog '2c6f1275146c2e3816a006e25bd6133f@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[15990]: manager.c:2278 process_message: Manager received command 'Command'
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:3642 sip_destroy: Destroying SIP dialog 6cf6c65614e968563e8d6aea2d5741df@2.2.2.2
[Jan 5 22:13:38] Really destroying SIP dialog '6cf6c65614e968563e8d6aea2d5741df@2.2.2.2' Method: OPTIONS
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:4946 sip_alloc: Allocating new SIP dialog for (No Call-ID) - NOTIFY (No RTP)
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:5002 find_call: = Found Their Call ID: 2d1f571455af290f5db2cfed23287787@192.168.20.40 Their Tag Our tag: as7642ab1c
[Jan 5 22:13:38] DEBUG[1487]: chan_sip.c:2325 __sip_ack: Stopping retransmission on '2d1f571455af290f5db2cfed23287787@192.168.20.40' of Request 102: Match Found
[Jan 5 22:13:38] Really destroying SIP dialog '2d1f571455af290f5db2cfed23287787@192.168.20.40' Method: NOTIFY
- mms1
- Posts: 34
- Joined: Mon Jun 30, 2008 9:46 am
Re: Transferring calls from ingroup to another ingroup
by mms1 » Mon Jan 07, 2013 8:21 pm
Anyone have any suggestions to try?
To summarize:
We can successfully transfer an outbound call to a specified ingroup.
When we try to transfer an inbound call to a different ingroup then the call originated from, the call drops.
To summarize:
We can successfully transfer an outbound call to a specified ingroup.
When we try to transfer an inbound call to a different ingroup then the call originated from, the call drops.
- mms1
- Posts: 34
- Joined: Mon Jun 30, 2008 9:46 am
Re: Transferring calls from ingroup to another ingroup
by williamconley » Thu Jan 31, 2013 12:44 am
perhaps it would help if you described the precise method you are using (button for button) for this transfer process.
also you skipped this "Also you should post a link to your installation instructions as you are not using a standard system." It is entirely possible your system is just built wrong ... and without your installation instruction set it's hard not to write this off as "broken".
Why are you not using Vicibox or Goautodial for your installation? (Not an attack, curiosity ...hoping to understand the situation).
also you skipped this "Also you should post a link to your installation instructions as you are not using a standard system." It is entirely possible your system is just built wrong ... and without your installation instruction set it's hard not to write this off as "broken".
Why are you not using Vicibox or Goautodial for your installation? (Not an attack, curiosity ...hoping to understand the situation).
Vicidial Installation and Repair, plus Hosting and Colocation
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
Newest Product: Vicidial Agent Only Beep - Beta
http://www.PoundTeam.com # 352-269-0000 # +44(203) 769-2294
- williamconley
- Posts: 20258
- Joined: Wed Oct 31, 2007 4:17 pm
- Location: Davenport, FL (By Disney!)
5 posts
• Page 1 of 1
Who is online
Users browsing this forum: Bing [Bot], Google [Bot] and 114 guests