I have two SIP lines both with the same dial plans and providers. I have one outbound route set up and if SIP line 1 is full or busy the pbx should use the next SIP line as i have it set up in the trunk sequence. This does not happen however it just gives you the busy sound when more then 2 people try to use the pbx. Any idea whats going on??
Asterisk CLI output?
Are the SIP registrations the same username and password?
Are they registering to the same server?
Try switching it so the 2nd one goes out first and see if it still works…
what output would you need??
No they do not have the same password or username
they both register to the same IP but on different ports
I have done switching the trunks in the sequence but that didnt help also both lines work fine.
cli output can get gotten this way. Go into the asterisk CLI and type: set verbose 5
then when the problem happens copy from the time the extension in question connects to the server until the call is hung up and post that output here.
That will tell us every thing that happened and in the order it happened. Without the call trace we can’t tell.
ok heres some of the log file, i know its a lot of info but i wasnt sure what you needed.
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “DIAL_NUMBER=3343509”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “GROUP()=OUT_2”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?nomax”) in new stack
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Goto (macro-dialout-trunk,s,9)
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '0’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “0?skipoutcid”) in new stack
Oct 9 10:21:05 DEBUG[25848] pbx.c: Not taking any branch
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “DIAL_TRUNK_OPTIONS=”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Macro(“SIP/107-08207d10”, “outbound-callerid|2”) in new stack
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '0’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing ExecIf(“SIP/107-08207d10”, “0|SetCallerPres|”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: ExecIf
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '0’
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?start”) in new stack
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Goto (macro-outbound-callerid,s,4)
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing NoOp(“SIP/107-08207d10”, “REALCALLERIDNUM is 107”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Noop
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?normcid”) in new stack
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Goto (macro-outbound-callerid,s,10)
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 DEBUG[25848] pbx.c: Function result is ''
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “USEROUTCID=”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 DEBUG[25848] db.c: Unable to find key ‘107/emergency_cid’ in family 'DEVICE’
Oct 9 10:21:05 DEBUG[25848] func_db.c: DB: DEVICE/107/emergency_cid not found in database.
Oct 9 10:21:05 DEBUG[25848] pbx.c: Function result is ''
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “EMERGENCYCID=”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “TRUNKOUTCID=“GrandCare Systems” <2623386147>”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?trunkcid”) in new stack
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Goto (macro-outbound-callerid,s,17)
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '0’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “0?usercid”) in new stack
Oct 9 10:21:05 DEBUG[25848] pbx.c: Not taking any branch
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “CALLERID(all)=“GrandCare Systems” <2623386147>”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?report”) in new stack
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Goto (macro-outbound-callerid,s,23)
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 DEBUG[25848] pbx.c: Function result is '“GrandCare Systems” <2623386147>'
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing NoOp(“SIP/107-08207d10”, “CallerID set to “GrandCare Systems” <2623386147>”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Noop
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Macro
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing AGI(“SIP/107-08207d10”, “fixlocalprefix”) in new stack
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Oct 9 10:21:05 VERBOSE[25848] logger.c: > fixlocalprefix: Using pattern 1262|NXXXXXX
Oct 9 10:21:05 VERBOSE[25848] logger.c: – AGI Script fixlocalprefix completed, returning 0
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: AGI
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “OUTNUM=3343509”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 DEBUG[25848] pbx.c: Function result is 'SIP/SIPLine1’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Set(“SIP/107-08207d10”, “custom=SIP/SIPLine1”) in new stack
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Set
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '0’
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?gocall”) in new stack
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Goto (macro-dialout-trunk,s,17)
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Macro(“SIP/107-08207d10”, “dialout-trunk-predial-hook|”) in new stack
Oct 9 10:21:05 WARNING[25848] app_macro.c: Context ‘macro-dialout-trunk-predial-hook’ for macro ‘dialout-trunk-predial-hook’ lacks ‘s’ extension, priority 1
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: Macro
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '0’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “0?bypass|1”) in new stack
Oct 9 10:21:05 DEBUG[25848] pbx.c: Not taking any branch
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 DEBUG[25848] pbx.c: Expression result is '0’
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “0?customtrunk”) in new stack
Oct 9 10:21:05 DEBUG[25848] pbx.c: Not taking any branch
Oct 9 10:21:05 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Executing Dial(“SIP/107-08207d10”, “SIP/SIPLine1/3343509|300|”) in new stack
Oct 9 10:21:05 DEBUG[25848] chan_sip.c: Setting NAT on RTP to 524288
Oct 9 10:21:05 DEBUG[25848] acl.c: ##### Testing 64.47.33.25 with 10.10.5.0
Oct 9 10:21:05 DEBUG[25848] chan_sip.c: Target address 64.47.33.25 is not local, substituting externip
Oct 9 10:21:05 DEBUG[25848] chan_sip.c: Outgoing Call for 3343509
Oct 9 10:21:05 VERBOSE[25848] logger.c: – Called SIPLine1/3343509
Oct 9 10:21:05 DEBUG[4869] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on ‘5c[email protected]’ Request 102: Found
Oct 9 10:21:05 DEBUG[4869] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on ‘5c[email protected]’ Request 102: Found
Oct 9 10:21:05 DEBUG[4869] chan_sip.c: Oooh, we need to change our formats since our peer supports only 0x4 (ulaw) and not 0x8 (alaw)
Oct 9 10:21:05 VERBOSE[25848] logger.c: – SIP/SIPLine1-0823de80 is making progress passing it to SIP/107-08207d10
Oct 9 10:21:06 DEBUG[25848] channel.c: Building translator from ulaw to SLINEAR for spies on channel SIP/107-08207d10
Oct 9 10:21:06 DEBUG[25847] manager.c: Manager received command 'Command’
Oct 9 10:21:06 DEBUG[25847] manager.c: Manager received command 'Command’
Oct 9 10:21:06 DEBUG[25847] manager.c: Manager received command 'Command’
Oct 9 10:21:06 DEBUG[25847] manager.c: Manager received command 'Command’
Oct 9 10:21:06 DEBUG[25847] manager.c: Manager received command 'Command’
Oct 9 10:21:06 VERBOSE[25847] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:06 DEBUG[25861] manager.c: Manager received command 'login’
Oct 9 10:21:06 VERBOSE[25861] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:06 VERBOSE[25861] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:06 VERBOSE[25861] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:06 VERBOSE[25861] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:06 VERBOSE[25861] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:06 VERBOSE[25861] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:06 DEBUG[25861] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:06 DEBUG[25861] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:06 DEBUG[25861] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:06 DEBUG[25861] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:06 VERBOSE[25861] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:06 DEBUG[25861] manager.c: Manager received command 'Command’
Oct 9 10:21:06 DEBUG[25861] manager.c: Manager received command 'Command’
Oct 9 10:21:06 DEBUG[25861] manager.c: Manager received command 'Command’
Oct 9 10:21:06 DEBUG[25861] manager.c: Manager received command 'Command’
Oct 9 10:21:06 VERBOSE[25861] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:08 DEBUG[4869] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on ‘5c[email protected]’ Request 102: Found
Oct 9 10:21:08 VERBOSE[25848] logger.c: – SIP/SIPLine1-0823de80 is making progress passing it to SIP/107-08207d10
Oct 9 10:21:08 DEBUG[25848] channel.c: Building translator from ulaw to SLINEAR for spies on channel SIP/107-08207d10
Oct 9 10:21:12 DEBUG[25863] manager.c: Manager received command 'login’
Oct 9 10:21:12 VERBOSE[25863] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:12 VERBOSE[25863] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:12 VERBOSE[25863] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:12 VERBOSE[25863] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:12 VERBOSE[25863] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:12 VERBOSE[25863] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:12 DEBUG[25863] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:12 DEBUG[25863] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:12 DEBUG[25863] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:12 DEBUG[25863] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:12 VERBOSE[25863] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:12 DEBUG[25863] manager.c: Manager received command 'Command’
Oct 9 10:21:12 DEBUG[25863] manager.c: Manager received command 'Command’
Oct 9 10:21:13 DEBUG[25863] manager.c: Manager received command 'Command’
Oct 9 10:21:13 DEBUG[25863] manager.c: Manager received command 'Command’
Oct 9 10:21:13 DEBUG[25863] manager.c: Manager received command 'Command’
Oct 9 10:21:13 DEBUG[25863] manager.c: Manager received command 'Command’
Oct 9 10:21:13 DEBUG[25863] manager.c: Manager received command 'Command’
Oct 9 10:21:13 VERBOSE[25863] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:16 DEBUG[4869] acl.c: ##### Testing 64.47.33.25 with 10.10.5.0
Oct 9 10:21:16 DEBUG[4869] chan_sip.c: Target address 64.47.33.25 is not local, substituting externip
Oct 9 10:21:19 DEBUG[25872] manager.c: Manager received command 'login’
Oct 9 10:21:19 VERBOSE[25872] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:19 VERBOSE[25872] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:19 VERBOSE[25872] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:19 VERBOSE[25872] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:19 VERBOSE[25872] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:19 VERBOSE[25872] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:19 DEBUG[25872] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:19 DEBUG[25872] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:19 DEBUG[25872] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:19 DEBUG[25872] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:19 VERBOSE[25872] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:19 DEBUG[25872] manager.c: Manager received command 'Command’
Oct 9 10:21:19 DEBUG[25872] manager.c: Manager received command 'Command’
Oct 9 10:21:20 DEBUG[25872] manager.c: Manager received command 'Command’
Oct 9 10:21:20 DEBUG[25872] manager.c: Manager received command 'Command’
Oct 9 10:21:20 DEBUG[25872] manager.c: Manager received command 'Command’
Oct 9 10:21:20 DEBUG[25872] manager.c: Manager received command 'Command’
Oct 9 10:21:20 DEBUG[25872] manager.c: Manager received command 'Command’
Oct 9 10:21:20 VERBOSE[25872] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:24 DEBUG[4869] chan_sip.c: Acked pending invite 102
Oct 9 10:21:24 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘5c89634d52e[email protected]’ of Request 102: Match Found
Oct 9 10:21:24 DEBUG[4869] chan_sip.c: build_route: Contact hop: sip:[email protected]:5060
Oct 9 10:21:24 VERBOSE[25848] logger.c: – SIP/SIPLine1-0823de80 answered SIP/107-08207d10
Oct 9 10:21:24 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘[email protected]’ of Response 102: Match Found
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.30 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘0f83ec7d[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.247 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.213 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.179 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.246 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.196 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.245 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.213 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 10.10.5.179 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘1d3cd4b3[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘6f0fafb0[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘3c6a0201[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘6c161142[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘61cfb694[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘79050262[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘45875ac5[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘4f132647[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 64.47.33.25 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Target address 64.47.33.25 is not local, substituting externip
Oct 9 10:21:26 DEBUG[4869] acl.c: ##### Testing 64.47.33.25 with 10.10.5.0
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Target address 64.47.33.25 is not local, substituting externip
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘11e71710170[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘786b0b352b8[email protected]’ of Request 102: Match Found
Oct 9 10:21:26 DEBUG[25883] manager.c: Manager received command 'login’
Oct 9 10:21:26 VERBOSE[25883] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:26 VERBOSE[25883] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:26 VERBOSE[25883] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:26 VERBOSE[25883] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:26 VERBOSE[25883] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:26 VERBOSE[25883] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:26 DEBUG[25883] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:26 DEBUG[25883] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:26 DEBUG[25883] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:26 DEBUG[25883] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:26 VERBOSE[25883] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:27 DEBUG[25883] manager.c: Manager received command 'Command’
Oct 9 10:21:27 DEBUG[25883] manager.c: Manager received command 'Command’
Oct 9 10:21:28 DEBUG[25883] manager.c: Manager received command 'Command’
Oct 9 10:21:28 DEBUG[25883] manager.c: Manager received command 'Command’
Oct 9 10:21:28 DEBUG[25883] manager.c: Manager received command 'Command’
Oct 9 10:21:28 DEBUG[25883] manager.c: Manager received command 'Command’
Oct 9 10:21:28 DEBUG[25883] manager.c: Manager received command 'Command’
Oct 9 10:21:28 VERBOSE[25883] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:29 DEBUG[4869] acl.c: ##### Testing 10.10.5.179 with 10.10.5.0
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: Setting NAT on RTP to 524288
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘[email protected]’ of Response 101: Match Found
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: Setting NAT on RTP to 524288
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: Checking SIP call limits for device 101
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: build_route: Contact hop: “Nick M” sip:[email protected]:5060
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Macro(“SIP/101-0825c160”, “user-callerid|SKIPTTL|”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is 'device’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “user-callerid: device 101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “AMPUSER=101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?report”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Not taking any branch
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing ExecIf(“SIP/101-0825c160”, “1|Set|REALCALLERIDNUM=101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: ExecIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “REALCALLERIDNUM is 101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “AMPUSER=101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is 'Nick M’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “AMPUSERCIDNAME=Nick M”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?report”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Not taking any branch
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '1’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “AMPUSERCID=101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “CALLERID(all)=“Nick M” <101>”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '101’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “REALCALLERIDNUM=101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] db.c: Unable to find key ‘101/language’ in family 'AMPUSER’
Oct 9 10:21:29 DEBUG[25892] func_db.c: DB: AMPUSER/101/language not found in database.
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] db.c: Unable to find key ‘101/language’ in family 'AMPUSER’
Oct 9 10:21:29 DEBUG[25892] func_db.c: DB: AMPUSER/101/language not found in database.
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing ExecIf(“SIP/101-0825c160”, “0|Set|LANGUAGE()=”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: ExecIf
Oct 9 10:21:29 DEBUG[25892] db.c: Unable to find key ‘101/language’ in family 'AMPUSER’
Oct 9 10:21:29 DEBUG[25892] func_db.c: DB: AMPUSER/101/language not found in database.
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] db.c: Unable to find key ‘101/language’ in family 'AMPUSER’
Oct 9 10:21:29 DEBUG[25892] func_db.c: DB: AMPUSER/101/language not found in database.
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “TTL: ARG1: SKIPTTL”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?continue”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-user-callerid,s,23)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '“Nick M” <101>'
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “Using CallerID “Nick M” <101>”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “_NODEST=”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Macro(“SIP/101-0825c160”, “record-enable|101|OUT|”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '0’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?2:4”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-record-enable,s,4)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '20081009-102129’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing AGI(“SIP/101-0825c160”, “recordingcheck|20081009-102129|1223565689.676”) in new stack
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[4856] channel.c: Avoiding initial deadlock for 'SIP/101-0825c160’
Oct 9 10:21:29 WARNING[4856] channel.c: Avoided initial deadlock for ‘0x8173e68’, 9 retries!
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Oct 9 10:21:29 VERBOSE[25892] logger.c: recordingcheck|20081009-102129|1223565689.676: Outbound recording not enabled
Oct 9 10:21:29 VERBOSE[25892] logger.c: – AGI Script recordingcheck completed, returning 0
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: AGI
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “No recording needed”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Macro(“SIP/101-0825c160”, “dialout-trunk|2|3343509||”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “DIAL_TRUNK=2”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] db.c: Unable to find key ‘101/pinless’ in family 'AMPUSER’
Oct 9 10:21:29 DEBUG[25892] func_db.c: DB: AMPUSER/101/pinless not found in database.
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing ExecIf(“SIP/101-0825c160”, “0|Authenticate|”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: ExecIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] db.c: Unable to find key ‘101/pinless’ in family 'AMPUSER’
Oct 9 10:21:29 DEBUG[25892] func_db.c: DB: AMPUSER/101/pinless not found in database.
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?disabletrunk|1”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Not taking any branch
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “DIAL_NUMBER=3343509”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “GROUP()=OUT_2”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?nomax”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-dialout-trunk,s,9)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?skipoutcid”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Not taking any branch
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “DIAL_TRUNK_OPTIONS=”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Macro(“SIP/101-0825c160”, “outbound-callerid|2”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing ExecIf(“SIP/101-0825c160”, “0|SetCallerPres|”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: ExecIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?start”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-outbound-callerid,s,4)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “REALCALLERIDNUM is 101”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?normcid”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-outbound-callerid,s,10)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “USEROUTCID=”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] db.c: Unable to find key ‘101/emergency_cid’ in family 'DEVICE’
Oct 9 10:21:29 DEBUG[25892] func_db.c: DB: DEVICE/101/emergency_cid not found in database.
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is ''
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “EMERGENCYCID=”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “TRUNKOUTCID=“GrandCare Systems” <2623386147>”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?trunkcid”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-outbound-callerid,s,17)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?usercid”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Not taking any branch
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “CALLERID(all)=“GrandCare Systems” <2623386147>”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?report”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-outbound-callerid,s,23)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is '“GrandCare Systems” <2623386147>'
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “CallerID set to “GrandCare Systems” <2623386147>”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Macro
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing AGI(“SIP/101-0825c160”, “fixlocalprefix”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Oct 9 10:21:29 VERBOSE[25892] logger.c: > fixlocalprefix: Using pattern 1262|NXXXXXX
Oct 9 10:21:29 VERBOSE[25892] logger.c: – AGI Script fixlocalprefix completed, returning 0
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: AGI
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “OUTNUM=3343509”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Function result is 'SIP/SIPLine1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Set(“SIP/101-0825c160”, “custom=SIP/SIPLine1”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Set
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?gocall”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-dialout-trunk,s,17)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Macro(“SIP/101-0825c160”, “dialout-trunk-predial-hook|”) in new stack
Oct 9 10:21:29 WARNING[25892] app_macro.c: Context ‘macro-dialout-trunk-predial-hook’ for macro ‘dialout-trunk-predial-hook’ lacks ‘s’ extension, priority 1
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Macro
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?bypass|1”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Not taking any branch
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is ‘0’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “0?customtrunk”) in new stack
Oct 9 10:21:29 DEBUG[25892] pbx.c: Not taking any branch
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Dial(“SIP/101-0825c160”, “SIP/SIPLine1/3343509|300|”) in new stack
Oct 9 10:21:29 DEBUG[25892] chan_sip.c: Setting NAT on RTP to 524288
Oct 9 10:21:29 DEBUG[25892] acl.c: ##### Testing 64.47.33.25 with 10.10.5.0
Oct 9 10:21:29 DEBUG[25892] chan_sip.c: Target address 64.47.33.25 is not local, substituting externip
Oct 9 10:21:29 DEBUG[25892] chan_sip.c: Outgoing Call for 3343509
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Called SIPLine1/3343509
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on ‘13[email protected]’ Request 102: Found
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: Acked pending invite 102
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘13ebc58f62b[email protected]’ of Request 102: Match Found
Oct 9 10:21:29 VERBOSE[4869] logger.c: – Got SIP response 486 “Busy Here” back from 64.47.33.25
Oct 9 10:21:29 VERBOSE[25892] logger.c: – SIP/SIPLine1-082616a0 is busy
Oct 9 10:21:29 DEBUG[25892] chan_sip.c: update_call_counter(3343509) - decrement call limit counter
Oct 9 10:21:29 VERBOSE[25892] logger.c: == Everyone is busy/congested at this time (1:1/0/0)
Oct 9 10:21:29 DEBUG[25892] app_dial.c: Exiting with DIALSTATUS=BUSY.
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Dial
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Goto(“SIP/101-0825c160”, “s-BUSY|1”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-dialout-trunk,s-BUSY,1)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Goto
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoOp(“SIP/101-0825c160”, “Dial failed due to trunk reporting BUSY - giving up”) in new stack
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Noop
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing PlayTones(“SIP/101-0825c160”, “busy”) in new stack
Oct 9 10:21:29 DEBUG[25892] channel.c: Prodding channel ‘SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: Playtones
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Busy(“SIP/101-0825c160”, “20”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on ‘SIP/101-0825c160’ in macro ‘dialout-trunk’
Oct 9 10:21:29 VERBOSE[25892] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on ‘SIP/101-0825c160’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Macro(“SIP/101-0825c160”, “hangupcall|”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing ResetCDR(“SIP/101-0825c160”, “w”) in new stack
Oct 9 10:21:29 DEBUG[25892] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Oct 9 10:21:29 DEBUG[25892] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES (‘2008-10-09 10:21:29’,’“GrandCare Systems” <2623386147>’,‘2623386147’,‘3343509’,‘from-internal’, ‘SIP/101-0825c160’,‘SIP/SIPLine1-082616a0’,‘Busy’,‘20’,0,0,‘BUSY’,3,’’,‘1223565689.676’)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: ResetCDR
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing NoCDR(“SIP/101-0825c160”, “”) in new stack
Oct 9 10:21:29 NOTICE[25892] cdr.c: CDR on channel ‘SIP/101-0825c160’ not posted
Oct 9 10:21:29 NOTICE[25892] cdr.c: CDR on channel ‘SIP/101-0825c160’ lacks end
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: NoCDR
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?skiprg”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-hangupcall,s,6)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?skipblkvm”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-hangupcall,s,9)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 DEBUG[25892] pbx.c: Expression result is '1’
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing GotoIf(“SIP/101-0825c160”, “1?theend”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Goto (macro-hangupcall,s,11)
Oct 9 10:21:29 DEBUG[25892] app_macro.c: Executed application: GotoIf
Oct 9 10:21:29 VERBOSE[25892] logger.c: – Executing Hangup(“SIP/101-0825c160”, “”) in new stack
Oct 9 10:21:29 VERBOSE[25892] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘SIP/101-0825c160’ in macro 'hangupcall’
Oct 9 10:21:29 VERBOSE[25892] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-0825c160’
Oct 9 10:21:29 DEBUG[25892] chan_sip.c: update_call_counter(101) - decrement call limit counter
Oct 9 10:21:29 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘[email protected]’ of Response 102: Match Not Found
Oct 9 10:21:34 DEBUG[25899] manager.c: Manager received command 'login’
Oct 9 10:21:34 VERBOSE[25899] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:34 VERBOSE[25899] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:34 VERBOSE[25899] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:34 VERBOSE[25899] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:34 VERBOSE[25899] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:34 VERBOSE[25899] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:34 DEBUG[25899] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:34 DEBUG[25899] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:34 DEBUG[25899] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:34 DEBUG[25899] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:34 VERBOSE[25899] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:34 DEBUG[25899] manager.c: Manager received command 'Command’
Oct 9 10:21:34 DEBUG[25899] manager.c: Manager received command 'Command’
Oct 9 10:21:35 DEBUG[25899] manager.c: Manager received command 'Command’
Oct 9 10:21:35 DEBUG[25899] manager.c: Manager received command 'Command’
Oct 9 10:21:35 DEBUG[25899] manager.c: Manager received command 'Command’
Oct 9 10:21:35 DEBUG[25899] manager.c: Manager received command 'Command’
Oct 9 10:21:35 DEBUG[25899] manager.c: Manager received command 'Command’
Oct 9 10:21:35 VERBOSE[25899] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:37 DEBUG[25908] manager.c: Manager received command 'login’
Oct 9 10:21:37 VERBOSE[25908] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:37 VERBOSE[25908] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:37 VERBOSE[25908] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:37 VERBOSE[25908] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:37 VERBOSE[25908] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:37 VERBOSE[25908] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:37 DEBUG[25908] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:37 DEBUG[25908] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:37 DEBUG[25908] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:37 DEBUG[25908] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:37 VERBOSE[25908] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:37 DEBUG[25908] manager.c: Manager received command 'Command’
Oct 9 10:21:37 DEBUG[25908] manager.c: Manager received command 'Command’
Oct 9 10:21:37 DEBUG[25908] manager.c: Manager received command ‘Command’
Oct 9 10:21:37 DEBUG[25908] manager.c: Manager received command ‘Command’
Oct 9 10:21:37 VERBOSE[25908] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:38 DEBUG[25848] channel.c: Didn’t get a frame from channel: SIP/107-08207d10
Oct 9 10:21:38 DEBUG[25848] channel.c: Bridge stops bridging channels SIP/107-08207d10 and SIP/SIPLine1-0823de80
Oct 9 10:21:38 DEBUG[25848] chan_sip.c: update_call_counter(3343509) - decrement call limit counter
Oct 9 10:21:38 DEBUG[25848] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Oct 9 10:21:38 VERBOSE[25848] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on ‘SIP/107-08207d10’ in macro ‘dialout-trunk’
Oct 9 10:21:38 VERBOSE[25848] logger.c: == Spawn extension (macro-dialout-trunk, s, 20) exited non-zero on ‘SIP/107-08207d10’
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Executing Macro(“SIP/107-08207d10”, “hangupcall|”) in new stack
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Executing ResetCDR(“SIP/107-08207d10”, “w”) in new stack
Oct 9 10:21:38 DEBUG[25848] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Oct 9 10:21:38 DEBUG[25848] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES (‘2008-10-09 10:21:05’,’“GrandCare Systems” <2623386147>’,‘2623386147’,‘3343509’,‘from-internal’, ‘SIP/107-08207d10’,‘SIP/SIPLine1-0823de80’,‘Dial’,‘SIP/SIPLine1/3343509|300|’,33,14,‘ANSWERED’,3,’’,‘1223565665.674’)
Oct 9 10:21:38 DEBUG[25848] app_macro.c: Executed application: ResetCDR
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Executing NoCDR(“SIP/107-08207d10”, “”) in new stack
Oct 9 10:21:38 NOTICE[25848] cdr.c: CDR on channel ‘SIP/107-08207d10’ not posted
Oct 9 10:21:38 NOTICE[25848] cdr.c: CDR on channel ‘SIP/107-08207d10’ lacks end
Oct 9 10:21:38 DEBUG[25848] app_macro.c: Executed application: NoCDR
Oct 9 10:21:38 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?skiprg”) in new stack
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Goto (macro-hangupcall,s,6)
Oct 9 10:21:38 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:38 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?skipblkvm”) in new stack
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Goto (macro-hangupcall,s,9)
Oct 9 10:21:38 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:38 DEBUG[25848] pbx.c: Expression result is '1’
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Executing GotoIf(“SIP/107-08207d10”, “1?theend”) in new stack
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Goto (macro-hangupcall,s,11)
Oct 9 10:21:38 DEBUG[25848] app_macro.c: Executed application: GotoIf
Oct 9 10:21:38 VERBOSE[25848] logger.c: – Executing Hangup(“SIP/107-08207d10”, “”) in new stack
Oct 9 10:21:38 VERBOSE[25848] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘SIP/107-08207d10’ in macro 'hangupcall’
Oct 9 10:21:38 VERBOSE[25848] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/107-08207d10’
Oct 9 10:21:38 DEBUG[25848] channel.c: Spy MixMonitor removed from channel SIP/107-08207d10
Oct 9 10:21:38 DEBUG[25848] chan_sip.c: update_call_counter(107) - decrement call limit counter
Oct 9 10:21:38 VERBOSE[25851] logger.c: == End MixMonitor Recording SIP/107-08207d10
Oct 9 10:21:39 DEBUG[4869] chan_sip.c: Stopping retransmission on ‘5c89634d52e[email protected]’ of Request 103: Match Found
Oct 9 10:21:41 DEBUG[25912] manager.c: Manager received command 'login’
Oct 9 10:21:41 VERBOSE[25912] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:41 VERBOSE[25912] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:41 VERBOSE[25912] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:41 VERBOSE[25912] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:41 VERBOSE[25912] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:41 VERBOSE[25912] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:41 DEBUG[25912] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:41 DEBUG[25912] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:41 DEBUG[25912] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:41 DEBUG[25912] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:41 VERBOSE[25912] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:41 DEBUG[25912] manager.c: Manager received command 'Command’
Oct 9 10:21:41 DEBUG[25912] manager.c: Manager received command 'Command’
Oct 9 10:21:42 DEBUG[25912] manager.c: Manager received command 'Command’
Oct 9 10:21:42 DEBUG[25912] manager.c: Manager received command 'Command’
Oct 9 10:21:42 DEBUG[25912] manager.c: Manager received command 'Command’
Oct 9 10:21:42 DEBUG[25912] manager.c: Manager received command 'Command’
Oct 9 10:21:42 DEBUG[25912] manager.c: Manager received command 'Command’
Oct 9 10:21:42 VERBOSE[25912] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:46 DEBUG[4869] acl.c: ##### Testing 64.47.33.25 with 10.10.5.0
Oct 9 10:21:46 DEBUG[4869] chan_sip.c: Target address 64.47.33.25 is not local, substituting externip
Oct 9 10:21:48 DEBUG[25921] manager.c: Manager received command 'login’
Oct 9 10:21:48 VERBOSE[25921] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:48 VERBOSE[25921] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:48 VERBOSE[25921] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:48 VERBOSE[25921] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:48 VERBOSE[25921] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:48 VERBOSE[25921] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:48 DEBUG[25921] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:48 DEBUG[25921] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:48 DEBUG[25921] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:48 DEBUG[25921] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:48 VERBOSE[25921] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:49 DEBUG[25921] manager.c: Manager received command 'Command’
Oct 9 10:21:49 DEBUG[25921] manager.c: Manager received command 'Command’
Oct 9 10:21:50 DEBUG[25921] manager.c: Manager received command 'Command’
Oct 9 10:21:50 DEBUG[25921] manager.c: Manager received command 'Command’
Oct 9 10:21:50 DEBUG[25921] manager.c: Manager received command 'Command’
Oct 9 10:21:50 DEBUG[25921] manager.c: Manager received command 'Command’
Oct 9 10:21:50 DEBUG[25921] manager.c: Manager received command 'Command’
Oct 9 10:21:50 VERBOSE[25921] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:21:56 DEBUG[25930] manager.c: Manager received command 'login’
Oct 9 10:21:56 VERBOSE[25930] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:21:56 VERBOSE[25930] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:21:56 VERBOSE[25930] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:21:56 VERBOSE[25930] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:21:56 VERBOSE[25930] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:21:56 VERBOSE[25930] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:21:56 DEBUG[25930] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:21:56 DEBUG[25930] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:21:56 DEBUG[25930] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:21:56 DEBUG[25930] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:21:56 VERBOSE[25930] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:21:56 DEBUG[25930] manager.c: Manager received command 'Command’
Oct 9 10:21:56 DEBUG[25930] manager.c: Manager received command 'Command’
Oct 9 10:21:57 DEBUG[25930] manager.c: Manager received command 'Command’
Oct 9 10:21:57 DEBUG[25930] manager.c: Manager received command 'Command’
Oct 9 10:21:57 DEBUG[25930] manager.c: Manager received command 'Command’
Oct 9 10:21:57 DEBUG[25930] manager.c: Manager received command 'Command’
Oct 9 10:21:57 DEBUG[25930] manager.c: Manager received command 'Command’
Oct 9 10:21:57 VERBOSE[25930] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:22:02 DEBUG[4869] acl.c: ##### Testing 10.10.5.30 with 10.10.5.0
Oct 9 10:22:03 DEBUG[25939] manager.c: Manager received command 'login’
Oct 9 10:22:03 VERBOSE[25939] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:22:03 VERBOSE[25939] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:22:03 VERBOSE[25939] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:22:03 VERBOSE[25939] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:22:03 VERBOSE[25939] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:22:03 VERBOSE[25939] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:22:03 DEBUG[25939] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:22:03 DEBUG[25939] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:22:03 DEBUG[25939] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:22:03 DEBUG[25939] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:22:03 VERBOSE[25939] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:22:03 DEBUG[25939] manager.c: Manager received command 'Command’
Oct 9 10:22:03 DEBUG[25939] manager.c: Manager received command 'Command’
Oct 9 10:22:04 DEBUG[25939] manager.c: Manager received command 'Command’
Oct 9 10:22:04 DEBUG[25939] manager.c: Manager received command 'Command’
Oct 9 10:22:04 DEBUG[25939] manager.c: Manager received command 'Command’
Oct 9 10:22:04 DEBUG[25939] manager.c: Manager received command 'Command’
Oct 9 10:22:04 DEBUG[25939] manager.c: Manager received command 'Command’
Oct 9 10:22:04 VERBOSE[25939] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:22:07 DEBUG[25948] manager.c: Manager received command 'login’
Oct 9 10:22:07 VERBOSE[25948] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:22:07 VERBOSE[25948] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:22:07 VERBOSE[25948] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:22:07 VERBOSE[25948] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:22:07 VERBOSE[25948] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:22:07 VERBOSE[25948] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:22:07 DEBUG[25948] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:22:07 DEBUG[25948] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:22:07 DEBUG[25948] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:22:07 DEBUG[25948] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:22:07 VERBOSE[25948] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:22:07 DEBUG[25948] manager.c: Manager received command 'Command’
Oct 9 10:22:07 DEBUG[25948] manager.c: Manager received command 'Command’
Oct 9 10:22:07 DEBUG[25948] manager.c: Manager received command 'Command’
Oct 9 10:22:07 DEBUG[25948] manager.c: Manager received command 'Command’
Oct 9 10:22:07 VERBOSE[25948] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:22:10 DEBUG[25950] manager.c: Manager received command 'login’
Oct 9 10:22:10 VERBOSE[25950] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:22:10 VERBOSE[25950] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:22:10 VERBOSE[25950] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:22:10 VERBOSE[25950] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:22:10 VERBOSE[25950] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:22:10 VERBOSE[25950] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:22:10 DEBUG[25950] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:22:10 DEBUG[25950] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:22:10 DEBUG[25950] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:22:10 DEBUG[25950] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:22:10 VERBOSE[25950] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:22:11 DEBUG[25950] manager.c: Manager received command 'Command’
Oct 9 10:22:11 DEBUG[25950] manager.c: Manager received command 'Command’
Oct 9 10:22:12 DEBUG[25950] manager.c: Manager received command 'Command’
Oct 9 10:22:12 DEBUG[25950] manager.c: Manager received command 'Command’
Oct 9 10:22:12 DEBUG[25950] manager.c: Manager received command 'Command’
Oct 9 10:22:12 DEBUG[25950] manager.c: Manager received command 'Command’
Oct 9 10:22:12 DEBUG[25950] manager.c: Manager received command 'Command’
Oct 9 10:22:12 VERBOSE[25950] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:22:14 DEBUG[25959] manager.c: Manager received command 'login’
Oct 9 10:22:14 VERBOSE[25959] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:22:14 VERBOSE[25959] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:22:14 VERBOSE[25959] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:22:14 VERBOSE[25959] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:22:14 VERBOSE[25959] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:22:14 VERBOSE[25959] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:22:14 DEBUG[25959] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:22:14 DEBUG[25959] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:22:14 DEBUG[25959] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:22:14 DEBUG[25959] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:22:14 VERBOSE[25959] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
Oct 9 10:22:14 VERBOSE[25959] logger.c: == Manager ‘asteriskmgr’ logged off from 127.0.0.1
Oct 9 10:22:16 DEBUG[25960] manager.c: Manager received command 'login’
Oct 9 10:22:16 VERBOSE[25960] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Oct 9 10:22:16 VERBOSE[25960] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: Found
Oct 9 10:22:16 VERBOSE[25960] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Oct 9 10:22:16 VERBOSE[25960] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: Found
Oct 9 10:22:16 VERBOSE[25960] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Oct 9 10:22:16 VERBOSE[25960] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: Found
Oct 9 10:22:16 DEBUG[25960] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Oct 9 10:22:16 DEBUG[25960] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Oct 9 10:22:16 DEBUG[25960] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Oct 9 10:22:16 DEBUG[25960] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Oct 9 10:22:16 VERBOSE[25960] logger.c: == Manager ‘asteriskmgr’ logged on from 127.0.0.1
so the log is of no help???
no?
adame,
The support you get here is free and 99% of it is done by people like me. I’m not a developer, just a user who has some spare time to try and answer others questions that I think I have answers to. I don’t see anything wrong but I don’t use sip trunks either…
If you want a real answer and quickly you’ll need to pony up some $. Click on support on the top left hand column of the site. If not then wait for somebody else to take a look and respond.
Last week many of the people who answer questions were out at training so they are playing catch up. But having a attitude for not getting free support will not get you anywhere either.
wow cool down there big guy, I wasnt getting a attitude but I also didnt want ppl to think i just left and didnt care so i kept the thread active i am willing to wait for someone to take a look at my issue and i am grateful to anyone that does. anyway thank you for taking the time and replying to my post.