Link to home
Start Free TrialLog in
Avatar of StevenHook
StevenHook

asked on

logging a singe connection on asterisk

Hi,
I have an asterisk box as our internal PBX hooked up to a PRI. I'm having trouble with calls dropping about 5 min into the call (inbound and outbound) - it only happens on calls over the PRI, I have telco techies coming tomorrow to investigate as it's been going on for about a year and they haven't been able to find any reason.
Is it possible to generate a call on our server to one of their toll free numbers and log that call's activity until such time as is disconnects?
If I can make a script that does this, I could create 4 or 5 calls, and then use the one that gets dropped for trouble shooting?
ASKER CERTIFIED SOLUTION
Avatar of bwilks99
bwilks99
Flag of Australia image

Link to home
membership
This solution is only available to members.
To access this solution, you must be a member of Experts Exchange.
Start Free Trial
Avatar of StevenHook
StevenHook

ASKER

Thanks
what format must my channel be in? Can I choose a specific ISDN channel number and then the phone number?
Steven
I'm sorry - I'm a bit rusty :) I'm even having to google how to view what's happening in asterisk.
It doesn't seem to pick up my callfile
What version of asterisk/Zaptel or DAHDI  are you using and what linux distro?
was permissions...

Channel: Zap/g0/090824915321
MaxRetries: 1
RetryTime: 30
WaitTime: 45
Context: internal
Extension: 308
Priority: 1

That's my cell - but it's not working yet - still trying...
SOLUTION
Link to home
membership
This solution is only available to members.
To access this solution, you must be a member of Experts Exchange.
Start Free Trial
:) - now it dials me - when I answer it says "goodbye" and hangs up :)

308 is an internal queue that just plays MOH ...

asterisk 1.4.2.2, zaptel 1.4.12.1

the memories are starting to come back :)
Wow. It takes quite a lot of energy to hang up a call.
[Apr 15 14:00:47] DEBUG[873]: chan_zap.c:1872 zt_enable_ec: Echo cancel                    lation already on
[Apr 15 14:00:47]        > Channel Zap/1-1 was answered.
[Apr 15 14:00:47]   == Starting Zap/1-1 at internal,308,1 failed so fal                    ling back to exten 's'
[Apr 15 14:00:47]   == Starting Zap/1-1 at internal,s,1 still failed so                     falling back to context 'default'
[Apr 15 14:00:47]     -- Executing [s@default:1] Playback("Zap/1-1", "v                    m-goodbye") in new stack
[Apr 15 14:00:47]     -- <Zap/1-1> Playing 'vm-goodbye' (language 'en')
[Apr 15 14:00:48]     -- Executing [s@default:2] Macro("Zap/1-1", "hang                    upcall") in new stack
[Apr 15 14:00:48]     -- Executing [s@macro-hangupcall:1] GotoIf("Zap/1                    -1", "1?skiprg") in new stack
[Apr 15 14:00:48]     -- Goto (macro-hangupcall,s,4)
[Apr 15 14:00:48] DEBUG[19499]: app_macro.c:337 _macro_exec: Executed a                    pplication: GotoIf
[Apr 15 14:00:48]     -- Executing [s@macro-hangupcall:4] GotoIf("Zap/1                    -1", "1?skipblkvm") in new stack
[Apr 15 14:00:48]     -- Goto (macro-hangupcall,s,7)
[Apr 15 14:00:48] DEBUG[19499]: app_macro.c:337 _macro_exec: Executed a                    pplication: GotoIf
[Apr 15 14:00:48]     -- Executing [s@macro-hangupcall:7] GotoIf("Zap/1                    -1", "1?theend") in new stack
[Apr 15 14:00:48]     -- Goto (macro-hangupcall,s,9)
[Apr 15 14:00:48] DEBUG[19499]: app_macro.c:337 _macro_exec: Executed a                    pplication: GotoIf
[Apr 15 14:00:48]     -- Executing [s@macro-hangupcall:9] Hangup("Zap/1                    -1", "") in new stack
[Apr 15 14:00:48]   == Spawn extension (macro-hangupcall, s, 9) exited                     non-zero on 'Zap/1-1' in macro 'hangupcall'
[Apr 15 14:00:48]   == Spawn extension (macro-hangupcall, s, 9) exited                     non-zero on 'Zap/1-1'
[Apr 15 14:00:48] DEBUG[19499]: chan_zap.c:3474 zt_setoption: Set optio                    n AUDIO MODE, value: ON(1) on Zap/1-1
[Apr 15 14:00:48] DEBUG[19499]: chan_zap.c:3107 zt_hangup: Not yet hung                    up...  Calling hangup once with icause, and clearing call
[Apr 15 14:00:48] DEBUG[19499]: chan_zap.c:3470 zt_setoption: Set optio                    n AUDIO MODE, value: OFF(0) on Zap/1-1
[Apr 15 14:00:48]     -- Hungup 'Zap/1-1'
[Apr 15 14:00:48] NOTICE[19499]: pbx_spool.c:351 attempt_thread: Call c                    ompleted to Zap/g0/0824915321

Open in new window

Thanks - context was supposed to be from-internal
Working great now
Thanks
Steven
This is side-tracking from the topic. but I was holding for technical (10217) when my call was disconnected.
This is the CLI
Can you see anything suspicious?
ph-b7bfafd8", "1|Set|REALCALLERIDNUM=355") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:4] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "AMPUSER=355") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:5] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "AMPUSERCIDNAME=Steven") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0?report") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:7] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "AMPUSERCID=355") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:8] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "CALLERID(all)="Steven" <355>") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:9] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0|Set|CHANNEL(language)=") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:10] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?continue") in new stack
[Apr 15 14:05:10]     -- Goto (macro-user-callerid,s,19)
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-user-callerid:19] NoOp("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "Using CallerID "Steven" <355>") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Noop
[Apr 15 14:05:10]     -- Executing [10217@from-internal:4] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "_NODEST=") in new stack
[Apr 15 14:05:10]     -- Executing [10217@from-internal:5] Macro("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "record-enable|355|OUT|") in new stack
[Apr 15 14:05:10]     -- Executing [s@macro-record-enable:1] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?check") in new stack
[Apr 15 14:05:10]     -- Goto (macro-record-enable,s,4)
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-record-enable:4] AGI("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "recordingcheck|20100415-140510|1271333106.9847") in new stack
[Apr 15 14:05:10]     -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Apr 15 14:05:10]   recordingcheck|20100415-140510|1271333106.9847: Outbound recording enabled.
[Apr 15 14:05:10]   recordingcheck|20100415-140510|1271333106.9847: CALLFILENAME=OUT355-20100415-140510-1271333106.9847
[Apr 15 14:05:10]     -- AGI Script recordingcheck completed, returning 0
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: AGI
[Apr 15 14:05:10]     -- Executing [s@macro-record-enable:999] MixMonitor("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "OUT355-20100415-140510-1271333106.9847.wav||") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: MixMonitor
[Apr 15 14:05:10]     -- Executing [10217@from-internal:6] Macro("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "dialout-trunk|1|10217||") in new stack
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:1] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "DIAL_TRUNK=1") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10] DEBUG[20055]: func_db.c:70 function_db_read: DB: AMPUSER/355/pinless not found in database.
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0?sub-pincheck|s|1") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GosubIf
[Apr 15 14:05:10] DEBUG[20055]: func_db.c:70 function_db_read: DB: AMPUSER/355/pinless not found in database.
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0?disabletrunk|1") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:4] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "DIAL_NUMBER=10217") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:5] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "DIAL_TRUNK_OPTIONS=trTwW") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:6] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "OUTBOUND_GROUP=OUT_1") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?nomax") in new stack
[Apr 15 14:05:10]     -- Goto (macro-dialout-trunk,s,9)
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0?skipoutcid") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:10] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "DIAL_TRUNK_OPTIONS=TW") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "outbound-callerid|1") in new stack
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0|SetCallerPres|") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0|Set|REALCALLERIDNUM=355") in new stack
[Apr 15 14:05:10]   == Begin MixMonitor Recording SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?normcid") in new stack
[Apr 15 14:05:10]     -- Goto (macro-outbound-callerid,s,6)
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:6] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "USEROUTCID=private") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10] DEBUG[20055]: func_db.c:70 function_db_read: DB: DEVICE/355/emergency_cid not found in database.
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:7] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "EMERGENCYCID=") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:8] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "TRUNKOUTCID=") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?trunkcid") in new stack
[Apr 15 14:05:10]     -- Goto (macro-outbound-callerid,s,12)
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0|Set|CALLERID(all)=") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1|Set|CALLERID(all)=private") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:378 _macro_exec: Last app: Set|CALLERID(all)=private
[Apr 15 14:05:10]     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0|SetCallerPres|prohib_passed_screen") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Macro
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1|AGI|fixlocalprefix") in new stack
[Apr 15 14:05:10]     -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Apr 15 14:05:10]        >  fixlocalprefix: Using pattern 7|0+.
[Apr 15 14:05:10]        >  fixlocalprefix: Using pattern 09|XXXXXXX.
[Apr 15 14:05:10]        >  fixlocalprefix: Using pattern 1XXX
[Apr 15 14:05:10]        >  fixlocalprefix: Using pattern 1XXXX
[Apr 15 14:05:10]   ==  fixlocalprefix: Dialpattern 1XXXX matched. 10217 -> 10217
[Apr 15 14:05:10]     -- AGI Script fixlocalprefix completed, returning 0
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:378 _macro_exec: Last app: AGI|fixlocalprefix
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:13] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "OUTNUM=10217") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:14] Set("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "custom=ZAP/g0") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Set
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1|Set|DIAL_TRUNK_OPTIONS=M(setmusic^bowens)TW") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: ExecIf
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:378 _macro_exec: Last app: Set|DIAL_TRUNK_OPTIONS=M(setmusic^bowens)M(setmusic^bowens)TW
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:16] Macro("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "dialout-trunk-predial-hook|") in new stack
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: Macro
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0?bypass|1") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "0?customtrunk") in new stack
[Apr 15 14:05:10] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:05:10]     -- Executing [s@macro-dialout-trunk:19] Dial("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "ZAP/g0/10217|300|M(setmusic^bowens)TW") in new stack
[Apr 15 14:05:10] DEBUG[20055]: dsp.c:1699 ast_dsp_set_busy_pattern: dsp busy pattern set to 0,0
[Apr 15 14:05:10]     -- Requested transfer capability: 0x00 - SPEECH
[Apr 15 14:05:10]     -- Called g0/10217
[Apr 15 14:05:11] DEBUG[873]: chan_zap.c:9802 pri_dchannel: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
[Apr 15 14:05:11]     -- Zap/1-1 is proceeding passing it to SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8
[Apr 15 14:05:11]     -- Zap/1-1 is making progress passing it to SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8
[Apr 15 14:05:12]     -- Zap/1-1 is ringing
[Apr 15 14:05:12] DEBUG[873]: chan_zap.c:1872 zt_enable_ec: Echo cancellation already on
[Apr 15 14:05:12]     -- Zap/1-1 answered SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8
[Apr 15 14:05:12]     -- Executing [s@macro-setmusic:1] NoOp("Zap/1-1", "Setting Outbound Route MoH To: bowens") in new stack
[Apr 15 14:05:12] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: NoOp
[Apr 15 14:05:12]     -- Executing [s@macro-setmusic:2] SetMusicOnHold("Zap/1-1", "bowens") in new stack
[Apr 15 14:05:12] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: SetMusicOnHold
[Apr 15 14:05:12] DEBUG[20055]: app_dial.c:1573 dial_exec_full: Macro exited with status 0
[Apr 15 14:05:33]     -- Registered SIP '320' at 192.168.10.71 port 63010 expires 3600
[Apr 15 14:05:33]     -- Saved useragent "X-Lite release 1011s stamp 41150" for peer 320
[Apr 15 14:05:33] NOTICE[866]: chan_sip.c:12669 handle_response_peerpoke: Peer '320' is now Reachable. (7ms / 2000ms)
[Apr 15 14:05:33]     -- Unregistered SIP '320'
[Apr 15 14:05:33]     -- Registered SIP '320' at 192.168.10.71 port 63010 expires 3600
[Apr 15 14:05:33]     -- Saved useragent "X-Lite release 1011s stamp 41150" for peer 320
[Apr 15 14:05:33] NOTICE[866]: chan_sip.c:12669 handle_response_peerpoke: Peer '320' is now Reachable. (40ms / 2000ms)
[Apr 15 14:05:39]     -- Registered SIP '326' at 192.168.10.96 port 5060 expires 3600
[Apr 15 14:05:39] NOTICE[866]: chan_sip.c:12669 handle_response_peerpoke: Peer '326' is now Reachable. (69ms / 2000ms)
[Apr 15 14:05:39]     -- Got SIP response 400 "Bad Request" back from 192.168.10.96
[Apr 15 14:05:39]     -- Registered SIP '201' at 192.168.10.40 port 5060 expires 3600
[Apr 15 14:05:40] NOTICE[866]: chan_sip.c:12669 handle_response_peerpoke: Peer '201' is now Reachable. (77ms / 2000ms)
[Apr 15 14:05:40]     -- Got SIP response 400 "Bad Request" back from 192.168.10.40
[Apr 15 14:05:40] WARNING[872]: pbx_spool.c:419 scan_service: Unable to open /var/spool/asterisk/outgoing/.callfile.txt.swp: Permission denied, deleting
[Apr 15 14:05:40] WARNING[872]: pbx_spool.c:461 scan_thread: Failed to scan service '/var/spool/asterisk/outgoing/.callfile.txt.swp'
[Apr 15 14:05:41]     -- Got SIP response 405 "Method Not Allowed" back from 192.168.10.68
[Apr 15 14:05:45] DEBUG[20055]: chan_zap.c:1505 zt_digit_begin: Started VLDTMF digit '2'
[Apr 15 14:05:45] DEBUG[20055]: chan_zap.c:1540 zt_digit_end: Ending VLDTMF digit '2'
[Apr 15 14:05:47] WARNING[872]: pbx_spool.c:419 scan_service: Unable to open /var/spool/asterisk/outgoing/callfile.txt: Permission denied, deleting
[Apr 15 14:05:47] WARNING[872]: pbx_spool.c:461 scan_thread: Failed to scan service '/var/spool/asterisk/outgoing/callfile.txt'
[Apr 15 14:05:56] DEBUG[20055]: chan_zap.c:1505 zt_digit_begin: Started VLDTMF digit '2'
[Apr 15 14:05:56] DEBUG[20055]: chan_zap.c:1540 zt_digit_end: Ending VLDTMF digit '2'
[Apr 15 14:06:11] DEBUG[20055]: chan_zap.c:1505 zt_digit_begin: Started VLDTMF digit '1'
[Apr 15 14:06:11] DEBUG[20055]: chan_zap.c:1540 zt_digit_end: Ending VLDTMF digit '1'
[Apr 15 14:06:20]     -- Channel 0/1, span 1 got hangup request, cause 127
[Apr 15 14:06:20] DEBUG[20055]: chan_zap.c:3474 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/1-1
[Apr 15 14:06:20] DEBUG[20055]: chan_zap.c:3107 zt_hangup: Not yet hungup...  Calling hangup once with icause, and clearing call
[Apr 15 14:06:20] DEBUG[20055]: chan_zap.c:3470 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
[Apr 15 14:06:20]     -- Hungup 'Zap/1-1'
[Apr 15 14:06:20]   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8' in macro 'dialout-trunk'
[Apr 15 14:06:20]   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8'
[Apr 15 14:06:20]     -- Executing [h@macro-dialout-trunk:1] Macro("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "hangupcall|") in new stack
[Apr 15 14:06:20]     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?skiprg") in new stack
[Apr 15 14:06:20]     -- Goto (macro-hangupcall,s,4)
[Apr 15 14:06:20] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:06:20]     -- Executing [s@macro-hangupcall:4] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?skipblkvm") in new stack
[Apr 15 14:06:20]     -- Goto (macro-hangupcall,s,7)
[Apr 15 14:06:20] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:06:20]     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "1?theend") in new stack
[Apr 15 14:06:20]     -- Goto (macro-hangupcall,s,9)
[Apr 15 14:06:20] DEBUG[20055]: app_macro.c:337 _macro_exec: Executed application: GotoIf
[Apr 15 14:06:20]     -- Executing [s@macro-hangupcall:9] Hangup("SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8", "") in new stack
[Apr 15 14:06:20]   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8' in macro 'hangupcall'
[Apr 15 14:06:20]   == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8'
[Apr 15 14:06:20]   == End MixMonitor Recording SIP/BzNkpJWSSJR_Tm3X0qph-b7bfafd8
mail*CLI> exit
Executing last minute cleanups
Asterisk cleanly ending (0).
[mail.bowens.co.za ~]#

Open in new window

Funny - I'm actually calling them to find out what number I should use - and it dropps the call :) again
b730e0a8", "AMPUSERCIDNAME=Steven") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-user-callerid:6] GotoIf(ph-b730e0a8", "0?report") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-user-callerid:7] Set("SIb730e0a8", "AMPUSERCID=355") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-user-callerid:8] Set("SIb730e0a8", "CALLERID(all)="Steven" <355>") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-user-callerid:9] ExecIf(ph-b730e0a8", "0|Set|CHANNEL(language)=") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-user-callerid:10] GotoIfqph-b730e0a8", "1?continue") in new stack
[Apr 15 14:29:03]     -- Goto (macro-user-callerid,s,19)
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-user-callerid:19] NoOp("h-b730e0a8", "Using CallerID "Steven" <355>") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [10217@from-internal:4] Set("SIP/30e0a8", "_NODEST=") in new stack
[Apr 15 14:29:03]     -- Executing [10217@from-internal:5] Macro("SIb730e0a8", "record-enable|355|OUT|") in new stack
[Apr 15 14:29:03]     -- Executing [s@macro-record-enable:1] GotoIf(ph-b730e0a8", "1?check") in new stack
[Apr 15 14:29:03]     -- Goto (macro-record-enable,s,4)
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-record-enable:4] AGI("SIb730e0a8", "recordingcheck|20100415-142903|1271334537.9948") in new
[Apr 15 14:29:03]     -- Launched AGI Script /var/lib/asterisk/agi-b
[Apr 15 14:29:03]   recordingcheck|20100415-142903|1271334537.9948: led.
[Apr 15 14:29:03]   recordingcheck|20100415-142903|1271334537.9948: 00415-142903-1271334537.9948
[Apr 15 14:29:03]     -- AGI Script recordingcheck completed, return
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-record-enable:999] MixMoTm3X0qph-b730e0a8", "OUT355-20100415-142903-1271334537.9948.wav||")
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Executeor
[Apr 15 14:29:03]     -- Executing [10217@from-internal:6] Macro("SIb730e0a8", "dialout-trunk|1|10217||") in new stack
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:1] Set("SIb730e0a8", "DIAL_TRUNK=1") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03] DEBUG[22295]: func_db.c:70 function_db_read: DB: Afound in database.
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:2] GosubIfqph-b730e0a8", "0?sub-pincheck|s|1") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03] DEBUG[22295]: func_db.c:70 function_db_read: DB: Afound in database.
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:3] GotoIf(ph-b730e0a8", "0?disabletrunk|1") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:4] Set("SIb730e0a8", "DIAL_NUMBER=10217") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:5] Set("SIb730e0a8", "DIAL_TRUNK_OPTIONS=trTwW") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:6] Set("SIb730e0a8", "OUTBOUND_GROUP=OUT_1") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:7] GotoIf(ph-b730e0a8", "1?nomax") in new stack
[Apr 15 14:29:03]     -- Goto (macro-dialout-trunk,s,9)
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:9] GotoIf(ph-b730e0a8", "0?skipoutcid") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:10] Set("S-b730e0a8", "DIAL_TRUNK_OPTIONS=TW") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:11] Macro(ph-b730e0a8", "outbound-callerid|1") in new stack
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:1] Exe3X0qph-b730e0a8", "0|SetCallerPres|") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:2] Exe3X0qph-b730e0a8", "0|Set|REALCALLERIDNUM=355") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:3] Got3X0qph-b730e0a8", "1?normcid") in new stack
[Apr 15 14:29:03]     -- Goto (macro-outbound-callerid,s,6)
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:6] Setqph-b730e0a8", "USEROUTCID=private") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03] DEBUG[22295]: func_db.c:70 function_db_read: DB: D not found in database.
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:7] Setqph-b730e0a8", "EMERGENCYCID=") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:8] Setqph-b730e0a8", "TRUNKOUTCID=") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:9] Got3X0qph-b730e0a8", "1?trunkcid") in new stack
[Apr 15 14:29:03]     -- Goto (macro-outbound-callerid,s,12)
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:12] Exm3X0qph-b730e0a8", "0|Set|CALLERID(all)=") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:13] Exm3X0qph-b730e0a8", "1|Set|CALLERID(all)=private") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:378 _macro_exec: Last apivate
[Apr 15 14:29:03]     -- Executing [s@macro-outbound-callerid:14] Exm3X0qph-b730e0a8", "0|SetCallerPres|prohib_passed_screen") in new st
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:12] ExecIfqph-b730e0a8", "1|AGI|fixlocalprefix") in new stack
[Apr 15 14:29:03]     -- Launched AGI Script /var/lib/asterisk/agi-b
[Apr 15 14:29:03]   == Begin MixMonitor Recording SIP/BzNkpJWSSJR_Tm
[Apr 15 14:29:03]        >  fixlocalprefix: Using pattern 7|0+.
[Apr 15 14:29:03]        >  fixlocalprefix: Using pattern 09|XXXXXXX
[Apr 15 14:29:03]        >  fixlocalprefix: Using pattern 1XXX
[Apr 15 14:29:03]        >  fixlocalprefix: Using pattern 1XXXX
[Apr 15 14:29:03]   ==  fixlocalprefix: Dialpattern 1XXXX matched. 1
[Apr 15 14:29:03]     -- AGI Script fixlocalprefix completed, return
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:378 _macro_exec: Last ap
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:13] Set("S-b730e0a8", "OUTNUM=10217") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:14] Set("S-b730e0a8", "custom=ZAP/g0") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:15] ExecIfqph-b730e0a8", "1|Set|DIAL_TRUNK_OPTIONS=M(setmusic^bowens)TW") in n
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:378 _macro_exec: Last apNS=M(setmusic^bowens)M(setmusic^bowens)TW
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:16] Macro(ph-b730e0a8", "dialout-trunk-predial-hook|") in new stack
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk-predial-hoNkpJWSSJR_Tm3X0qph-b730e0a8", "") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:17] GotoIfqph-b730e0a8", "0?bypass|1") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:18] GotoIfqph-b730e0a8", "0?customtrunk") in new stack
[Apr 15 14:29:03] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:03]     -- Executing [s@macro-dialout-trunk:19] Dial("h-b730e0a8", "ZAP/g0/10217|300|M(setmusic^bowens)TW") in new stack
[Apr 15 14:29:03] DEBUG[22295]: dsp.c:1699 ast_dsp_set_busy_pattern:o 0,0
[Apr 15 14:29:03]     -- Requested transfer capability: 0x00 - SPEEC
[Apr 15 14:29:03]     -- Called g0/10217
[Apr 15 14:29:04] DEBUG[873]: chan_zap.c:9802 pri_dchannel: Queuing OCEEDING on channel 0/2 span 1
[Apr 15 14:29:04]     -- Zap/2-1 is proceeding passing it to SIP/BzN0a8
[Apr 15 14:29:04]     -- Zap/2-1 is making progress passing it to SIb730e0a8
[Apr 15 14:29:05]     -- Zap/2-1 is ringing
[Apr 15 14:29:05] DEBUG[873]: chan_zap.c:1872 zt_enable_ec: Echo can
[Apr 15 14:29:05]     -- Zap/2-1 answered SIP/BzNkpJWSSJR_Tm3X0qph-b
[Apr 15 14:29:05]     -- Executing [s@macro-setmusic:1] NoOp("Zap/2-oute MoH To: bowens") in new stack
[Apr 15 14:29:05] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:05]     -- Executing [s@macro-setmusic:2] SetMusicOnHo in new stack
[Apr 15 14:29:05] DEBUG[22295]: app_macro.c:337 _macro_exec: ExecuteOnHold
[Apr 15 14:29:05] DEBUG[22295]: app_dial.c:1573 dial_exec_full: Macr
[Apr 15 14:29:15] DEBUG[22288]: chan_zap.c:3474 zt_setoption: Set op ON(1) on Zap/1-1
[Apr 15 14:29:15] DEBUG[22288]: chan_zap.c:3107 zt_hangup: Not yet hp once with icause, and clearing call
[Apr 15 14:29:15] DEBUG[22288]: chan_zap.c:3470 zt_setoption: Set op OFF(0) on Zap/1-1
[Apr 15 14:29:15]     -- Hungup 'Zap/1-1'
[Apr 15 14:29:15]   == Spawn extension (macro-dialout-trunk, s, 19) /353-b7322d18' in macro 'dialout-trunk'
[Apr 15 14:29:15]   == Spawn extension (macro-dialout-trunk, s, 19) /353-b7322d18'
[Apr 15 14:29:15]     -- Executing [h@macro-dialout-trunk:1] Macro("gupcall|") in new stack
[Apr 15 14:29:15]     -- Executing [s@macro-hangupcall:1] GotoIf("SIprg") in new stack
[Apr 15 14:29:15]     -- Goto (macro-hangupcall,s,4)
[Apr 15 14:29:15] DEBUG[22288]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:15]     -- Executing [s@macro-hangupcall:4] GotoIf("SIpblkvm") in new stack
[Apr 15 14:29:15]     -- Goto (macro-hangupcall,s,7)
[Apr 15 14:29:15] DEBUG[22288]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:15]     -- Executing [s@macro-hangupcall:7] GotoIf("SIend") in new stack
[Apr 15 14:29:15]     -- Goto (macro-hangupcall,s,9)
[Apr 15 14:29:15] DEBUG[22288]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:15]     -- Executing [s@macro-hangupcall:9] Hangup("SI new stack
[Apr 15 14:29:15]   == Spawn extension (macro-hangupcall, s, 9) exit-b7322d18' in macro 'hangupcall'
[Apr 15 14:29:15]   == Spawn extension (macro-hangupcall, s, 9) exit-b7322d18'
[Apr 15 14:29:15]   == End MixMonitor Recording SIP/353-b7322d18
[Apr 15 14:29:34] DEBUG[22295]: chan_zap.c:1505 zt_digit_begin: Star
[Apr 15 14:29:34] DEBUG[22295]: chan_zap.c:1540 zt_digit_end: Ending
[Apr 15 14:29:43] DEBUG[22295]: chan_zap.c:1505 zt_digit_begin: Star
[Apr 15 14:29:43] DEBUG[22295]: chan_zap.c:1540 zt_digit_end: Ending
[Apr 15 14:29:52]     -- Got SIP response 405 "Method Not Allowed" b
[Apr 15 14:29:53]     -- Got SIP response 405 "Method Not Allowed" b
[Apr 15 14:29:53]     -- Channel 0/2, span 1 got hangup request, cau
[Apr 15 14:29:53] DEBUG[22295]: chan_zap.c:3474 zt_setoption: Set op ON(1) on Zap/2-1
[Apr 15 14:29:53] DEBUG[22295]: chan_zap.c:3107 zt_hangup: Not yet hp once with icause, and clearing call
[Apr 15 14:29:53] DEBUG[22295]: chan_zap.c:3470 zt_setoption: Set op OFF(0) on Zap/2-1
[Apr 15 14:29:53]     -- Hungup 'Zap/2-1'
[Apr 15 14:29:53]   == Spawn extension (macro-dialout-trunk, s, 19) /BzNkpJWSSJR_Tm3X0qph-b730e0a8' in macro 'dialout-trunk'
[Apr 15 14:29:53]   == Spawn extension (macro-dialout-trunk, s, 19) /BzNkpJWSSJR_Tm3X0qph-b730e0a8'
[Apr 15 14:29:53]     -- Executing [h@macro-dialout-trunk:1] Macro("h-b730e0a8", "hangupcall|") in new stack
[Apr 15 14:29:53]     -- Executing [s@macro-hangupcall:1] GotoIf("SIb730e0a8", "1?skiprg") in new stack
[Apr 15 14:29:53]     -- Goto (macro-hangupcall,s,4)
[Apr 15 14:29:53] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:53]     -- Executing [s@macro-hangupcall:4] GotoIf("SIb730e0a8", "1?skipblkvm") in new stack
[Apr 15 14:29:53]     -- Goto (macro-hangupcall,s,7)
[Apr 15 14:29:53] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:53]     -- Executing [s@macro-hangupcall:7] GotoIf("SIb730e0a8", "1?theend") in new stack
[Apr 15 14:29:53]     -- Goto (macro-hangupcall,s,9)
[Apr 15 14:29:53] DEBUG[22295]: app_macro.c:337 _macro_exec: Execute
[Apr 15 14:29:53]     -- Executing [s@macro-hangupcall:9] Hangup("SIb730e0a8", "") in new stack
[Apr 15 14:29:53]   == Spawn extension (macro-hangupcall, s, 9) exitkpJWSSJR_Tm3X0qph-b730e0a8' in macro 'hangupcall'
[Apr 15 14:29:53]   == Spawn extension (macro-hangupcall, s, 9) exitkpJWSSJR_Tm3X0qph-b730e0a8'
[Apr 15 14:29:53]   == End MixMonitor Recording SIP/BzNkpJWSSJR_Tm3X
mail*CLI> exit
Executing last minute cleanups
Asterisk cleanly ending (0).
[mail.bowens.co.za ~]#

Open in new window