Hi… I have pasted the log below. It shows an extn getting registered, placing the call, All circuits are busy… then i hangup. Hope these logs help.
VERBOSE[2478] logger.c: – Unregistered SIP ‘1000’
[Dec 17 15:28:00] VERBOSE[2478] logger.c: – Registered SIP ‘1000’ at 10.43.9.11 port 64904
[Dec 17 15:28:00] NOTICE[2478] chan_sip.c: Peer ‘1000’ is now Reachable. (1ms / 2000ms)
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [18006249896@from-internal:1] Macro(“SIP/1000-08cadae0”, “user-callerid|SKIPTTL|”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:1] Set(“SIP/1000-08cadae0”, “AMPUSER=1000”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/1000-08cadae0”, “0?report”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/1000-08cadae0”, “1|Set|REALCALLERIDNUM=1000”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:4] Set(“SIP/1000-08cadae0”, “AMPUSER=1000”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:5] Set(“SIP/1000-08cadae0”, “AMPUSERCIDNAME=1000”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/1000-08cadae0”, “0?report”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:7] Set(“SIP/1000-08cadae0”, “AMPUSERCID=1000”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:8] Set(“SIP/1000-08cadae0”, “CALLERID(all)=“1000” <1000>”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:9] GotoIf(“SIP/1000-08cadae0”, “1?continue”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Goto (macro-user-callerid,s,18)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-user-callerid:18] NoOp(“SIP/1000-08cadae0”, “Using CallerID “1000” <1000>”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Noop
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [18006249896@from-internal:2] Set(“SIP/1000-08cadae0”, “_NODEST=”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [18006249896@from-internal:3] Macro(“SIP/1000-08cadae0”, “record-enable|1000|OUT|”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/1000-08cadae0”, “1?check”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Goto (macro-record-enable,s,4)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-record-enable:4] AGI(“SIP/1000-08cadae0”, “recordingcheck|20091217-152806|1261043886.25”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Dec 17 15:28:06] VERBOSE[3597] logger.c: recordingcheck|20091217-152806|1261043886.25: Outbound recording not enabled
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – AGI Script recordingcheck completed, returning 0
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: AGI
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-record-enable:5] MacroExit(“SIP/1000-08cadae0”, “”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [18006249896@from-internal:4] Macro(“SIP/1000-08cadae0”, “dialout-trunk|2|18006249896||”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/1000-08cadae0”, “DIAL_TRUNK=2”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] DEBUG[3597] func_db.c: DB: AMPUSER/1000/pinless not found in database.
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/1000-08cadae0”, “0?sub-pincheck|s|1”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GosubIf
[Dec 17 15:28:06] DEBUG[3597] func_db.c: DB: AMPUSER/1000/pinless not found in database.
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/1000-08cadae0”, “0?disabletrunk|1”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/1000-08cadae0”, “DIAL_NUMBER=18006249896”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/1000-08cadae0”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/1000-08cadae0”, “OUTBOUND_GROUP=OUT_2”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/1000-08cadae0”, “1?nomax”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Goto (macro-dialout-trunk,s,9)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/1000-08cadae0”, “0?skipoutcid”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/1000-08cadae0”, “DIAL_TRUNK_OPTIONS=”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/1000-08cadae0”, “outbound-callerid|2”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/1000-08cadae0”, “0|SetCallerPres|”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/1000-08cadae0”, “0|Set|REALCALLERIDNUM=1000”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/1000-08cadae0”, “1?normcid”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Goto (macro-outbound-callerid,s,6)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/1000-08cadae0”, “USEROUTCID=”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] DEBUG[3597] func_db.c: DB: DEVICE/1000/emergency_cid not found in database.
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/1000-08cadae0”, “EMERGENCYCID=”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/1000-08cadae0”, “TRUNKOUTCID=”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/1000-08cadae0”, “1?trunkcid”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Goto (macro-outbound-callerid,s,12)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“SIP/1000-08cadae0”, “0|Set|CALLERID(all)=”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“SIP/1000-08cadae0”, “0|Set|CALLERID(all)=”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/1000-08cadae0”, “0|SetCallerPres|prohib_passed_screen”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Macro
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:12] ExecIf(“SIP/1000-08cadae0”, “0|AGI|fixlocalprefix”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/1000-08cadae0”, “OUTNUM=18006249896”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/1000-08cadae0”, “custom=SIP/US”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Set
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/1000-08cadae0”, “0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: ExecIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:16] Macro(“SIP/1000-08cadae0”, “dialout-trunk-predial-hook|”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/1000-08cadae0”, “”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Macro
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:17] GotoIf(“SIP/1000-08cadae0”, “0?bypass|1”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/1000-08cadae0”, “0?customtrunk”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:19] Dial(“SIP/1000-08cadae0”, “SIP/US/18006249896|300|”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Called US/18006249896
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – SIP/US-08cc6978 is circuit-busy
[Dec 17 15:28:06] VERBOSE[3597] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Dial
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-dialout-trunk:20] Goto(“SIP/1000-08cadae0”, “s-CONGESTION|1”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Goto (macro-dialout-trunk,s-CONGESTION,1)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Goto
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s-CONGESTION@macro-dialout-trunk:1] GotoIf(“SIP/1000-08cadae0”, “1?noreport”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Goto (macro-dialout-trunk,s-CONGESTION,3)
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s-CONGESTION@macro-dialout-trunk:3] NoOp(“SIP/1000-08cadae0”, “TRUNK Dial failed due to CONGESTION - failing through to other trunks”) in new stack
[Dec 17 15:28:06] DEBUG[3597] app_macro.c: Executed application: Noop
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [18006249896@from-internal:5] Macro(“SIP/1000-08cadae0”, “outisbusy|”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – Executing [s@macro-outisbusy:1] Playback(“SIP/1000-08cadae0”, “all-circuits-busy-now|noanswer”) in new stack
[Dec 17 15:28:06] VERBOSE[3597] logger.c: – <SIP/1000-08cadae0> Playing ‘all-circuits-busy-now’ (language ‘en’)
[Dec 17 15:28:08] DEBUG[3597] app_macro.c: Executed application: Playback
[Dec 17 15:28:08] VERBOSE[3597] logger.c: – Executing [s@macro-outisbusy:2] Playback(“SIP/1000-08cadae0”, “pls-try-call-later|noanswer”) in new stack
[Dec 17 15:28:08] VERBOSE[3597] logger.c: – <SIP/1000-08cadae0> Playing ‘pls-try-call-later’ (language ‘en’)
[Dec 17 15:28:10] DEBUG[3597] app_macro.c: Executed application: Playback
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-outisbusy:3] Macro(“SIP/1000-08cadae0”, “hangupcall”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/1000-08cadae0”, “1?skiprg”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Goto (macro-hangupcall,s,4)
[Dec 17 15:28:10] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/1000-08cadae0”, “1?skipblkvm”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Goto (macro-hangupcall,s,7)
[Dec 17 15:28:10] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/1000-08cadae0”, “1?theend”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Goto (macro-hangupcall,s,9)
[Dec 17 15:28:10] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:9] Hangup(“SIP/1000-08cadae0”, “”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/1000-08cadae0’ in macro ‘hangupcall’
[Dec 17 15:28:10] VERBOSE[3597] logger.c: == Spawn extension (macro-outisbusy, s, 3) exited non-zero on ‘SIP/1000-08cadae0’ in macro ‘outisbusy’
[Dec 17 15:28:10] VERBOSE[3597] logger.c: == Spawn extension (from-internal, 18006249896, 5) exited non-zero on ‘SIP/1000-08cadae0’
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [h@from-internal:1] Macro(“SIP/1000-08cadae0”, “hangupcall”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/1000-08cadae0”, “1?skiprg”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Goto (macro-hangupcall,s,4)
[Dec 17 15:28:10] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/1000-08cadae0”, “1?skipblkvm”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Goto (macro-hangupcall,s,7)
[Dec 17 15:28:10] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/1000-08cadae0”, “1?theend”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Goto (macro-hangupcall,s,9)
[Dec 17 15:28:10] DEBUG[3597] app_macro.c: Executed application: GotoIf
[Dec 17 15:28:10] VERBOSE[3597] logger.c: – Executing [s@macro-hangupcall:9] Hangup(“SIP/1000-08cadae0”, “”) in new stack
[Dec 17 15:28:10] VERBOSE[3597] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/1000-08cadae0’ in macro ‘hangupcall’
[Dec 17 15:28:10] VERBOSE[3597] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/1000-08cadae0’