CDR Reports - One call, two entries

ere's picture

Disclaimer: I will be the first to admit that I am really very new with FreePBX, Asterisk, and SIP in general; from zero to now in about half a week. But I've googled and googled, and just can't seem to find anything.

Hi,

I am currently running FreePBX on Solaris with success. I have now installed cdr_addon_mysql.so, and can successfully see call logs in the Reports section. However, I see duplicate entries for outbound calls to the PSTN via an SIP trunk from a third-party provider, which are placed and connected.

For example, from a soft-phone on this computer I dial 918001234567. The outbound line is picked up (9) and 1-800-123-4567 is dialed. The phone rings, my friend answers it, and we talk for a few minutes. One of us hangs up.

In Reports->Call Logs, I now see something like...

7. 2008-10-31 02:14:59 SIP/202-08... 8009876543 8009876543 918001234567 NO ANSWER 00:00
8. 2008-10-31 02:09:24 SIP/202-08... 8009876543 8009876543 918001234567 ANSWERED 05:35

...where 918001234567 is what I dial and 8009876543 is my DID/phone number.

Why is there a second log entry of type NO ANSWER with zero time? This does not happen with internal, extension-to-extension calls, or on an inbound call from the PSTN.

Thanks,

Eric


__________________


Comment viewing options

Select your preferred way to display the comments and click "Save settings" to activate your changes.

Same problem here, since

Yes's picture

Same problem here, since upgrade from a trunk version of february to asterisk 1.6.0.1.
I can see from the logging that there are duplicate calls to cdr logging. I don't think therefore that it has something to do with the cdr module itself. I think it must be something with the dialplans but i can't figure out what.

Hope that somebody else can shed some more light on your question.


__________________

The answer is Yes! The question is the question?


I changed following part of

Yes's picture

I changed following part of extensions.conf from:

; What to do on hangup. 
[macro-hangupcall] 
exten => s,1,ResetCDR(w) 
exten => s,n,NoCDR()

to

; What to do on hangup. 
[macro-hangupcall] 
exten => s,n,NoCDR()

Now only the correct entry is being generated.
Since the code will be replaced by the original version when applying changes from the GUI, i still have to figure out how to make the change persistent.


__________________

The answer is Yes! The question is the question?


I can't make any guarantees,

w5waf's picture

I can't make any guarantees, but I THINK this might work. Docs are fairly slim on this.

Open extensions.conf and copy select that part of the file beginning with

; What to do on hangup.

through the end of that section ending with the line

[macro-faxreceive]

Copy this selection to a file called extensions_override_freepbx.conf in the /etc/asterisk directory.

Edit this new file to reflect the changes you describe.

Reload Asterisk.

The way I understand it, the contents of the override file will supercede any content in the other extensions files.

As I said, I'm probably wrong on this, so if it doesn't work, deleting the override file will return tour system to normal.

Bill/W5WAF


__________________

Bill/W5WAF
IT Specialist
City of Vicksburg, MS


Just tried your suggestion

Yes's picture

Thanks for your suggestion, just tried it but could not get it to work.
I tried placing an adjusted copy of the [macro-hangupcall] section, successively in extensions_override_freepbx.conf and extensions_custom.conf but the original definition of [macro-hangupcall] was not being overruled by any of those.

After re-applying the modification to [macro-hangupcall] of extensions.conf, the second CDR entry is skipped again.
I will leave it this way for the moment, since re-generating the files from the GUI does not seem to affect the modification.

Of course, other suggestions are welcome.


__________________

The answer is Yes! The question is the question?


Well, I guess i

w5waf's picture

Well, I guess i misunderstood how the override file worked..

BF


__________________

Bill/W5WAF
IT Specialist
City of Vicksburg, MS


Putting it into the

fskrotzki's picture

Putting it into the extensions_override_freepbx.conf file is the proper place to put it.

But just putting it into the file is not enough. Once the changes are placed in that file and saves you need to reload asterisk so that it re-reads the configuration files and see's the changes.


I wonder if he realoded

w5waf's picture

I wonder if he realoded asterisk? So...for future reference...my suggestions should work?

Bill/W5WAF


__________________

Bill/W5WAF
IT Specialist
City of Vicksburg, MS


yes, once the dialplan is

fskrotzki's picture

yes, once the dialplan is reloaded it should work. (has in the past for me) when I've modified the hangup routines.


Yes, sure he reloaded

Yes's picture

Yes, sure he reloaded asterisk ;-)

I even forced the freepbx reconfiguration by making an simple modification to one of the extensions, pushed the submit button and confirmed the question to continue the reload.
I did that because i wanted to check if making changes from the GUI would reverse the adjustments i manually made previously to extensions.conf.

But i will try and redo the steps and post the conclusion.

Y.E.S.


__________________

The answer is Yes! The question is the question?


Ditto

get_the_fish's picture

I have seen other posts regarding this as well. Not sure what the answer is- could use some help from someone smarter than me. These are both working test boxes, willing to try or hand over the reigns via ssh to a dev or someone smarter than me for testing if need be, just say the word. Let me know if you need more information.

Basic configuration:

Asterisk 1.4.22 x86_64 with MySQL Version: 5.0.45 asterisk and asteriskcdr databases running on a different server. FreePBX 2.5.1.0, Core 2.5.1.2, all running on CentOS 5.2. Fresh install, yum updates for all as of tonight. Using SIP trunks (no registration on the SIP trunks btw) no DAHDI hardware and G729 on the trunk. ztdummy installed and running according to zttool. SIP user agent is configured device/user rather than the default extension. Testing below was performed with CounterPath EyeBeam Version 1.5.15.1 build 42237, also using G729.

Some more information:

Asterisk -r CLI output:

experimental001*CLI> cdr status
CDR logging: enabled
CDR mode: simple
CDR output unanswered calls: no
CDR registered backend: csv
CDR registered backend: cdr_manager
CDR registered backend: cdr-custom
CDR registered backend: mysql

experimental001*CLI> cdr mysql status
Connected to asteriskcdrdb@10.1.1.103 using table cdr for 49 minutes, 40 seconds.
Wrote 10 records since last restart.

CDR Output:

(this was one call that lasted 16 seconds to my cell phone. Last 4 digits of the destination number X'd out)
2008-11-06 03:12:04 SIP/550199... 8005551212 8005551212 303378xxxx NO ANSWER 00:00
2008-11-06 03:11:48 SIP/550199... 8005551212 8005551212 303378xxxx ANSWERED 00:16

(another call, 13 seconds long, to my cell phone)
2008-11-06 03:11:26 SIP/550199... 8005551212 8005551212 303378xxxx NO ANSWER 00:00
2008-11-06 03:11:13 SIP/550199... 8005551212 8005551212 303378xxxx ANSWERED 00:13

Asterisk box:
[root@experimental001 asterisk]# uname -r
2.6.18-92.1.17.el5

MYSQL Box:
[root@localhost freepbx-2.5.1]# uname -r
2.6.18-92.1.17.el5


ditto ditto

get_the_fish's picture

bounce


Almost there

dpengelley's picture

This drove me crazy and was not allowing me to properly report on call data due to the duplicate entries - even after following the instructions on this thread I was unsuccessful and was still getting duplicate entries. I added the following to my extensions_override_freepbx.conf and did a reload and was still getting duplicate CDR entries.

; What to do on hangup. 
[macro-hangupcall] 
exten => s,n,NoCDR()

After reading around I worked it out it turns out that the code fragment isn't quite right, though extremely close. The solution that finally worked for me was to place in extensions_override_freepbx.conf the following line

; What to do on hangup. 
[macro-hangupcall] 
exten => s,1,NoCDR()

A dialplan item, like an extension of a macro, or a whatever, apparently should begin with priority "1". We lose that when deleting the ResetCDR line that was previously above it and as such need to modify the 'n' in the the remaining NoCDR line to a 1

Hope this helps someone else save the time it took me to figure it out.


Thanks!!

D4n13L's picture

Thanks!!


ResetCDR and NoCDR broken?

wiseoldowl's picture

Just as a comment, I think that to some degree ResetCDR and NoCDR may be broken in current versions of Asterisk. See this thread, especially the last few comments...

http://freepbx.org/forum/freepbx/users/a-different-approach-to-placing-o...

However I can confirm that adding the code in extensions_override_freepbx.conf from dpengelley's December 1 post has eliminated the double CDR entries on my box.

Edit: Unfortunately, it also means there will be no entry at all for an unanswered call...


This problem only started

fskrotzki's picture

This problem only started appearing when * 1.6 came out (1.4 was suppsoed to ahve a stable locked down set). Based on the past history of the asterisk development team changes like this can happen on a whim and sometimes not making them backwards compatible with the previous sub-release. With the whole 1.6 they changed what and how new things are done so it is now possible that how a command operates from one release to the next might not be the same, making it fun to keep up with. The n to throw a big wrench into the mess some of those code changes have been back ported to the latest 1.4 branch and thus messing with a command that for years has worked correctly and suddenly does not.

Otherwise all of those users who have only been upgrading FreePBX and not asterisk would also be complaining.


Any updates on this?

agrisolutions_techsupport's picture

I was searching around for a different reporting questions and this thread caught my eye because I've been perplexed with the dual entries in my CDR database.

Have there been any updates on this? I'm dealing with the dual entries for the moment, but it sure would be nice to be able to clean my database up and know it will stay that way going forward. I haven't done the above override because I do need to see incomplete calls in our company.

So have there been any new findings on this?


Is 2 cdr per call & 2 posts per Asterisk Log occurrence related?

ecase's picture

When using google voice or a similar service provider, it seems that there must be two cdr posts per call, because one call is to the outbound destination, one call is made to the google voice account, and then the two calls are "bridged." Am I right on this???
Can anyone take a quick look at my status and tell me if something looks wrong?

I'm using FreePBX v2.9.0beta2.3
PIAF Purple
Asterisk 1.8.3
I am trying out softphones, still trying to find a good one to use- I currently use 3CX or Bria.

From the Asterisk CLI:
Command: 'cdr show status'
Call Detail Record (CDR) settings
----------------------------------
Logging: Enabled
Mode: Simple
Log unanswered calls: No

* Registered Backends
-------------------
mysql
cdr_manager
cdr-custom
csv
Adaptive ODBC
Command: 'show mysyql status'
Connected to asteriskcdrdb on socket file default using table cdr for 2 days, 1 hours, 18 minutes, 4 seconds.
Wrote 51 records since last restart.

My question is this: When I check the Asterisk log in the FreePBX GUI (Last 500 lines default) All of the log files are posted twice - I realize this isn't exactly the same as two cdr posts, but is it related? The 'shortened' info below is just from one 29 seconds outbound-call to my cell. It seems like a ton of work for a 30 second call... not only that, but is it bad to see ZOMBIE in an Asterisk log file? My guess is that it is...

I apologize for the long log file!!! I tried to keep it as short as possible - again, this is just for one outbound-call that lasted 29 seconds:

[2011-03-17 17:54:13] VERBOSE[24868] netsock2.c: == Using SIP RTP TOS bits 184
[2011-03-17 17:54:13] VERBOSE[24868] netsock2.c: == Using SIP RTP TOS bits 184
[2011-03-17 17:54:13] VERBOSE[24868] netsock2.c: == Using SIP RTP CoS mark 5
[2011-03-17 17:54:13] VERBOSE[24868] netsock2.c: == Using SIP RTP CoS mark 5
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:1] Macro("SIP/701-0000002c", "user-callerid,LIMIT,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:1] Macro("SIP/701-0000002c", "user-callerid,LIMIT,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/701-0000002c", "AMPUSER=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/701-0000002c", "AMPUSER=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/701-0000002c", "0?report") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/701-0000002c", "0?report") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/701-0000002c", "1?Set(REALCALLERIDNUM=701)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/701-0000002c", "1?Set(REALCALLERIDNUM=701)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/701-0000002c", "AMPUSER=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/701-0000002c", "AMPUSER=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/701-0000002c", "AMPUSERCIDNAME=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/701-0000002c", "AMPUSERCIDNAME=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/701-0000002c", "0?report") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/701-0000002c", "0?report") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/701-0000002c", "AMPUSERCID=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/701-0000002c", "AMPUSERCID=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/701-0000002c", "CALLERID(all)="701" <701>") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:8] Set("SIP/701-0000002c", "CALLERID(all)="701" <701>") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/701-0000002c", "0?limit") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/701-0000002c", "0?limit") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/701-0000002c", "1?Set(GROUP(concurrency_limit)=701)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/701-0000002c", "1?Set(GROUP(concurrency_limit)=701)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:11] ExecIf("SIP/701-0000002c", "0?Set(CHANNEL(language)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:11] ExecIf("SIP/701-0000002c", "0?Set(CHANNEL(language)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/701-0000002c", "1?continue") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/701-0000002c", "1?continue") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-user-callerid,s,25)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-user-callerid,s,25)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:25] Set("SIP/701-0000002c", "CALLERID(number)=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:25] Set("SIP/701-0000002c", "CALLERID(number)=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/701-0000002c", "CALLERID(name)=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/701-0000002c", "CALLERID(name)=701") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/701-0000002c", "CHANNEL(language)=en") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/701-0000002c", "CHANNEL(language)=en") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:2] Set("SIP/701-0000002c", "MOHCLASS=default") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:2] Set("SIP/701-0000002c", "MOHCLASS=default") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:3] Set("SIP/701-0000002c", "_NODEST=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:3] Set("SIP/701-0000002c", "_NODEST=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:4] Macro("SIP/701-0000002c", "record-enable,701,OUT,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:4] Macro("SIP/701-0000002c", "record-enable,701,OUT,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/701-0000002c", "1?check") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/701-0000002c", "1?check") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-record-enable,s,4)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-record-enable,s,4)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/701-0000002c", "0?MacroExit()") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:4] ExecIf("SIP/701-0000002c", "0?MacroExit()") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/701-0000002c", "0?Group:OUT") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:5] GotoIf("SIP/701-0000002c", "0?Group:OUT") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-record-enable,s,14)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-record-enable,s,14)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/701-0000002c", "0?IN") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:14] GotoIf("SIP/701-0000002c", "0?IN") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:15] ExecIf("SIP/701-0000002c", "0?MacroExit()") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:15] ExecIf("SIP/701-0000002c", "0?MacroExit()") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:16] Set("SIP/701-0000002c", "CALLFILENAME=OUT701-20110317-175413-1300409653.107") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:16] Set("SIP/701-0000002c", "CALLFILENAME=OUT701-20110317-175413-1300409653.107") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:17] Goto("SIP/701-0000002c", "record") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:17] Goto("SIP/701-0000002c", "record") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-record-enable,s,20)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-record-enable,s,20)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:20] MixMonitor("SIP/701-0000002c", "OUT701-20110317-175413-1300409653.107.wav,,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:20] MixMonitor("SIP/701-0000002c", "OUT701-20110317-175413-1300409653.107.wav,,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:21] MacroExit("SIP/701-0000002c", "") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-record-enable:21] MacroExit("SIP/701-0000002c", "") in new stack
[2011-03-17 17:54:13] VERBOSE[4614] app_mixmonitor.c: == Begin MixMonitor Recording SIP/701-0000002c
[2011-03-17 17:54:13] VERBOSE[4614] app_mixmonitor.c: == Begin MixMonitor Recording SIP/701-0000002c
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:5] Macro("SIP/701-0000002c", "dialout-trunk,2,6557668777,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [6557668777@from-internal:5] Macro("SIP/701-0000002c", "dialout-trunk,2,6557668777,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/701-0000002c", "DIAL_TRUNK=2") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/701-0000002c", "DIAL_TRUNK=2") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/701-0000002c", "0?sub-pincheck,s,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/701-0000002c", "0?sub-pincheck,s,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/701-0000002c", "0?disabletrunk,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/701-0000002c", "0?disabletrunk,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/701-0000002c", "DIAL_NUMBER=6557668777") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/701-0000002c", "DIAL_NUMBER=6557668777") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/701-0000002c", "DIAL_TRUNK_OPTIONS=tTr") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/701-0000002c", "DIAL_TRUNK_OPTIONS=tTr") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/701-0000002c", "OUTBOUND_GROUP=OUT_2") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/701-0000002c", "OUTBOUND_GROUP=OUT_2") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/701-0000002c", "1?nomax") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/701-0000002c", "1?nomax") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/701-0000002c", "0?skipoutcid") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/701-0000002c", "0?skipoutcid") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/701-0000002c", "DIAL_TRUNK_OPTIONS=tT") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/701-0000002c", "DIAL_TRUNK_OPTIONS=tT") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/701-0000002c", "outbound-callerid,2") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/701-0000002c", "outbound-callerid,2") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/701-0000002c", "0?Set(CALLERPRES()=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/701-0000002c", "0?Set(CALLERPRES()=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/701-0000002c", "0?Set(REALCALLERIDNUM=701)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/701-0000002c", "0?Set(REALCALLERIDNUM=701)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/701-0000002c", "1?normcid") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/701-0000002c", "1?normcid") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/701-0000002c", "USEROUTCID=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/701-0000002c", "USEROUTCID=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/701-0000002c", "EMERGENCYCID=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/701-0000002c", "EMERGENCYCID=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/701-0000002c", "TRUNKOUTCID=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/701-0000002c", "TRUNKOUTCID=") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/701-0000002c", "1?trunkcid") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/701-0000002c", "1?trunkcid") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-outbound-callerid,s,12)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-outbound-callerid,s,12)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/701-0000002c", "0?Set(CALLERID(all)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/701-0000002c", "0?Set(CALLERID(all)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/701-0000002c", "0?Set(CALLERID(all)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/701-0000002c", "0?Set(CALLERID(all)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/701-0000002c", "0?Set(CALLERID(all)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/701-0000002c", "0?Set(CALLERID(all)=)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/701-0000002c", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/701-0000002c", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/701-0000002c", "0?sub-flp-2,s,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/701-0000002c", "0?sub-flp-2,s,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/701-0000002c", "OUTNUM=6557668777") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/701-0000002c", "OUTNUM=6557668777") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/701-0000002c", "custom=AMP") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/701-0000002c", "custom=AMP") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/701-0000002c", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)tT)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/701-0000002c", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)tT)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/701-0000002c", "0?Set(DIAL_TRUNK_OPTIONS=M(confirm)tT)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/701-0000002c", "0?Set(DIAL_TRUNK_OPTIONS=M(confirm)tT)") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("SIP/701-0000002c", "dialout-trunk-predial-hook,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("SIP/701-0000002c", "dialout-trunk-predial-hook,") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/701-0000002c", "") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/701-0000002c", "") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/701-0000002c", "0?bypass,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/701-0000002c", "0?bypass,1") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:19] GotoIf("SIP/701-0000002c", "1?customtrunk") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:19] GotoIf("SIP/701-0000002c", "1?customtrunk") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-dialout-trunk,s,23)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-dialout-trunk,s,23)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:23] Set("SIP/701-0000002c", "pre_num=AMP:local/") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:23] Set("SIP/701-0000002c", "pre_num=AMP:local/") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:24] Set("SIP/701-0000002c", "the_num=OUTNUM") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:24] Set("SIP/701-0000002c", "the_num=OUTNUM") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:25] Set("SIP/701-0000002c", "post_num=@gvoice-example001") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:25] Set("SIP/701-0000002c", "post_num=@gvoice-example001") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:26] GotoIf("SIP/701-0000002c", "1?outnum:skipoutnum") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:26] GotoIf("SIP/701-0000002c", "1?outnum:skipoutnum") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-dialout-trunk,s,27)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Goto (macro-dialout-trunk,s,27)
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:27] Set("SIP/701-0000002c", "the_num=6557668777") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:27] Set("SIP/701-0000002c", "the_num=6557668777") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:28] Dial("SIP/701-0000002c", "local/6557668777@gvoice-example001,300,tT") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] pbx.c: -- Executing [s@macro-dialout-trunk:28] Dial("SIP/701-0000002c", "local/6557668777@gvoice-example001,300,tT") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] app_dial.c: -- Called 6557668777@gvoice-example001
[2011-03-17 17:54:13] VERBOSE[4613] app_dial.c: -- Called 6557668777@gvoice-example001
[2011-03-17 17:54:13] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:1] System("Local/6557668777@gvoice-example001-78ce;2", "sudo /usr/bin/gvoice -e example001@gmail.com -p pwordexample call 6557668777 example001@gmail.com 9 &") in new stack
[2011-03-17 17:54:13] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:1] System("Local/6557668777@gvoice-example001-78ce;2", "sudo /usr/bin/gvoice -e example001@gmail.com -p pwordexample call 6557668777 example001@gmail.com 9 &") in new stack
[2011-03-17 17:54:13] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:2] BackGround("Local/6557668777@gvoice-example001-78ce;2", "pls-wait-connect-call") in new stack
[2011-03-17 17:54:13] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:2] BackGround("Local/6557668777@gvoice-example001-78ce;2", "pls-wait-connect-call") in new stack
[2011-03-17 17:54:13] VERBOSE[4613] app_dial.c: -- Local/6557668777@gvoice-example001-78ce;1 answered SIP/701-0000002c
[2011-03-17 17:54:13] VERBOSE[4613] app_dial.c: -- Local/6557668777@gvoice-example001-78ce;1 answered SIP/701-0000002c
[2011-03-17 17:54:13] VERBOSE[4615] file.c: -- Playing 'pls-wait-connect-call.gsm' (language 'en')
[2011-03-17 17:54:13] VERBOSE[4615] file.c: -- Playing 'pls-wait-connect-call.gsm' (language 'en')
[2011-03-17 17:54:15] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:3] Set("Local/6557668777@gvoice-example001-78ce;2", "DB(gv_dialout_example001/channel)=Local/6557668777@gvoice-example001-78ce;2") in new stack
[2011-03-17 17:54:15] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:3] Set("Local/6557668777@gvoice-example001-78ce;2", "DB(gv_dialout_example001/channel)=Local/6557668777@gvoice-example001-78ce;2") in new stack
[2011-03-17 17:54:15] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:4] Wait("Local/6557668777@gvoice-example001-78ce;2", "30") in new stack
[2011-03-17 17:54:15] VERBOSE[4615] pbx.c: -- Executing [6557668777@gvoice-example001:4] Wait("Local/6557668777@gvoice-example001-78ce;2", "30") in new stack
[2011-03-17 17:54:18] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:1] Wait("Gtalk/+12234451212-2360", "1") in new stack
[2011-03-17 17:54:18] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:1] Wait("Gtalk/+12234451212-2360", "1") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:2] Set("Gtalk/+12234451212-2360", "ALERTNAME=example001@gmail.com") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:2] Set("Gtalk/+12234451212-2360", "ALERTNAME=example001@gmail.com") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:3] JabberSend("Gtalk/+12234451212-2360", "asterisk,example001@gmail.com,Incoming Google Voice Call: 2234451212") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:3] JabberSend("Gtalk/+12234451212-2360", "asterisk,example001@gmail.com,Incoming Google Voice Call: 2234451212") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:4] Set("Gtalk/+12234451212-2360", "CALLERID(number)=2234451212") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:4] Set("Gtalk/+12234451212-2360", "CALLERID(number)=2234451212") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:5] Set("Gtalk/+12234451212-2360", "CALLERID(name)=2234451212") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:5] Set("Gtalk/+12234451212-2360", "CALLERID(name)=2234451212") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:6] GotoIf("Gtalk/+12234451212-2360", "1?bridged") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:6] GotoIf("Gtalk/+12234451212-2360", "1?bridged") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Goto (googlein,example001@gmail.com,8)
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Goto (googlein,example001@gmail.com,8)
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:8] Bridge("Gtalk/+12234451212-2360", "Local/6557668777@gvoice-example001-78ce;2, p") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] pbx.c: -- Executing [example001@gmail.com@googlein:8] Bridge("Gtalk/+12234451212-2360", "Local/6557668777@gvoice-example001-78ce;2, p") in new stack
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: == Spawn extension (gvoice-example001, 6557668777, 4) exited non-zero on 'Bridge/Local/6557668777@gvoice-example001-78ce;2'
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: == Spawn extension (gvoice-example001, 6557668777, 4) exited non-zero on 'Bridge/Local/6557668777@gvoice-example001-78ce;2'
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: -- Executing [h@gvoice-example001:1] GotoIf("Bridge/Local/6557668777@gvoice-example001-78ce;2", "0?:bridged") in new stack
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: -- Executing [h@gvoice-example001:1] GotoIf("Bridge/Local/6557668777@gvoice-example001-78ce;2", "0?:bridged") in new stack
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: -- Goto (gvoice-example001,h,5)
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: -- Goto (gvoice-example001,h,5)
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: -- Executing [h@gvoice-example001:5] NoOp("Bridge/Local/6557668777@gvoice-example001-78ce;2", "The channel has been bridged successfully") in new stack
[2011-03-17 17:54:19] VERBOSE[4615] pbx.c: -- Executing [h@gvoice-example001:5] NoOp("Bridge/Local/6557668777@gvoice-example001-78ce;2", "The channel has been bridged successfully") in new stack
[2011-03-17 17:54:19] VERBOSE[4631] file.c: -- Playing 'beep.gsm' (language 'en')
[2011-03-17 17:54:19] VERBOSE[4631] file.c: -- Playing 'beep.gsm' (language 'en')
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/701-0000002c", "hangupcall,") in new stack
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/701-0000002c", "hangupcall,") in new stack
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/701-0000002c", "1?theend") in new stack
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/701-0000002c", "1?theend") in new stack
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Goto (macro-hangupcall,s,3)
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Goto (macro-hangupcall,s,3)
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Executing [s@macro-hangupcall:3] Hangup("SIP/701-0000002c", "") in new stack
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: -- Executing [s@macro-hangupcall:3] Hangup("SIP/701-0000002c", "") in new stack
[2011-03-17 17:54:52] VERBOSE[4613] app_macro.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/701-0000002c' in macro 'hangupcall'
[2011-03-17 17:54:52] VERBOSE[4613] app_macro.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/701-0000002c' in macro 'hangupcall'
[2011-03-17 17:54:52] VERBOSE[4613] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 28) exited non-zero on 'SIP/701-0000002c' in macro 'dialout-trunk'
[2011-03-17 17:54:52] VERBOSE[4613] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 28) exited non-zero on 'SIP/701-0000002c' in macro 'dialout-trunk'
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: == Spawn extension (from-internal, 6557668777, 5) exited non-zero on 'SIP/701-0000002c'
[2011-03-17 17:54:52] VERBOSE[4613] pbx.c: == Spawn extension (from-internal, 6557668777, 5) exited non-zero on 'SIP/701-0000002c'
[2011-03-17 17:54:52] VERBOSE[4614] app_mixmonitor.c: == MixMonitor close filestream
[2011-03-17 17:54:52] VERBOSE[4614] app_mixmonitor.c: == MixMonitor close filestream
[2011-03-17 17:54:52] VERBOSE[4614] app_mixmonitor.c: == End MixMonitor Recording SIP/701-0000002c
[2011-03-17 17:54:52] VERBOSE[4614] app_mixmonitor.c: == End MixMonitor Recording SIP/701-0000002c
[2011-03-17 17:55:01] VERBOSE[4666] manager.c: == Manager 'admin' logged on from 127.0.0.1

Thanks for any feedback.
~Ethan~


I'll pay anyone to help!

ecase's picture

I don't have a great deal of resources, but if anyone can help me get my system up to a production level, I can pay a few hundred dollars - (a few...)


ecase, check your

mickecarlsson's picture

ecase, check your /etc/asterisk/logger_logfiles_additional.conf and /etc/asterisk/logger_logfiles_custom.conf

Make sure that only one has the following line:
full=notice,warning,error,debug,verbose

If this entry are in both files, you will get duplicate log entries. Remove the line for the _custom file.


__________________

Mikael Carlsson
(I am off-line, tinkering with my Chevy and my radios, don't know when I will be back)


2.9 update

ecase's picture

Hi Mikael:

Thanks for the reply to my post. I eventually re-installed the PIAF iso and haven't upgraded FreePBX to 2.9 yet. I am learning more and more everyday :) I've set up some non-google services to use as backups. Do you think 2.9 is safe for production use yet?
Thanks!


There are over 2000 systems

mickecarlsson's picture

There are over 2000 systems running FreePBX 2.9 beta, and we are confident that it is a stable release. But, it is still in beta, and if you are running a production system, well, you might want to wait until at least RC is out.

It also depends on the size of the production system and how complex it is. And as always, do a full backup before upgrading.


__________________

Mikael Carlsson
(I am off-line, tinkering with my Chevy and my radios, don't know when I will be back)