two extension can not call outside

Discussion in 'General' started by Chris_PBX, Jan 28, 2011.

  1. Chris_PBX

    Joined:
    Jan 28, 2011
    Messages:
    12
    Likes Received:
    0
    Hello,
    i have a problem with two extension, that is Sip Account 290 and 291. 290 and 291 use two SIP Hardphones.

    When dial the 0 for outside calling i become a Message "All circuits are busy now, please try your call again later".
    Some times calling with other Sip Phone and other Account, then can i call outside with no Problem. When i delete the extension 290 and make new, the outside call is nit going. The Problem is only with Number 290 and 291.
    I have make a log :

    step one : i call from Sip Account 619 with Hard Sip Phone to 0 11779 (Outside) that is going.

    step two : i call fron Sip Account 290 with Hard Sip Phone to 0 11779 (outside) that is not going and i hear the Message "All circuits are busy now, please try your call again later"

    step three : i call again from Sip Account 619 to 0 11779 that is going.

    What has Asterisk problem with my Number 290 ?

    ---- Log File (verbose 3) -----
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [011776@from-internal:1] Macro("SIP/619-00000002", "user-callerid|SKIPTTL|") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/619-00000002", "AMPUSER=619") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/619-00000002", "0?report") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/619-00000002", "1|Set|REALCALLERIDNUM=619") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/619-00000002", "AMPUSER=619") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/619-00000002", "AMPUSERCIDNAME=Privat") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/619-00000002", "0?report") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/619-00000002", "AMPUSERCID=619") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/619-00000002", "CALLERID(all)="Privat" <619>") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/619-00000002", "0|Set|CHANNEL(language)=") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/619-00000002", "1?continue") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Goto (macro-user-callerid,s,19)
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-user-callerid:19] NoOp("SIP/619-00000002", "Using CallerID "Privat" <619>") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Noop
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [011776@from-internal:2] Set("SIP/619-00000002", "_NODEST=") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [011776@from-internal:3] Macro("SIP/619-00000002", "record-enable|619|OUT|") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/619-00000002", "1?check") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Goto (macro-record-enable,s,4)
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/619-00000002", "recordingcheck|20110127-140150|1296133310.7") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: recordingcheck|20110127-140150|1296133310.7: Outbound recording not enabled
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- AGI Script recordingcheck completed, returning 0
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: AGI
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/619-00000002", "") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [011776@from-internal:4] Macro("SIP/619-00000002", "dialout-trunk|1|11776||") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/619-00000002", "DIAL_TRUNK=1") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] DEBUG[3480] func_db.c: DB: AMPUSER/619/pinless not found in database.
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/619-00000002", "0?sub-pincheck|s|1") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GosubIf
    [Jan 27 14:01:50] DEBUG[3480] func_db.c: DB: AMPUSER/619/pinless not found in database.
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/619-00000002", "0?disabletrunk|1") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/619-00000002", "DIAL_NUMBER=11776") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/619-00000002", "DIAL_TRUNK_OPTIONS=tr") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/619-00000002", "OUTBOUND_GROUP=OUT_1") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/619-00000002", "1?nomax") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Goto (macro-dialout-trunk,s,9)
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/619-00000002", "0?skipoutcid") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/619-00000002", "DIAL_TRUNK_OPTIONS=") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/619-00000002", "outbound-callerid|1") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/619-00000002", "0|SetCallerPres|") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/619-00000002", "0|Set|REALCALLERIDNUM=619") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/619-00000002", "1?normcid") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Goto (macro-outbound-callerid,s,6)
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/619-00000002", "USEROUTCID=") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] DEBUG[3480] func_db.c: DB: DEVICE/619/emergency_cid not found in database.
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/619-00000002", "EMERGENCYCID=") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/619-00000002", "TRUNKOUTCID=") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/619-00000002", "1?trunkcid") in new stack
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Goto (macro-outbound-callerid,s,12)
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/619-00000002", "0|Set|CALLERID(all)=") in new stack
    [Jan 27 14:01:50] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:50] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/619-00000002", "0|Set|CALLERID(all)=") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/619-00000002", "0|SetCallerPres|prohib_passed_screen") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: Macro
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/619-00000002", "0|AGI|fixlocalprefix") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/619-00000002", "OUTNUM=11776") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/619-00000002", "custom=DAHDI/g0") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: Set
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/619-00000002", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: ExecIf
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/619-00000002", "dialout-trunk-predial-hook|") in new stack
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/619-00000002", "") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: Macro
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/619-00000002", "0?bypass|1") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/619-00000002", "0?customtrunk") in new stack
    [Jan 27 14:01:51] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/619-00000002", "DAHDI/g0/11776|300|") in new stack
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Requested transfer capability: 0x00 - SPEECH
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- Called g0/11776
    [Jan 27 14:01:51] DEBUG[3429] chan_dahdi.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- DAHDI/1-1 is proceeding passing it to SIP/619-00000002
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- DAHDI/1-1 is making progress passing it to SIP/619-00000002
    [Jan 27 14:01:51] VERBOSE[3480] logger.c: -- DAHDI/1-1 is ringing
    [Jan 27 14:01:58] DEBUG[3480] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/1-1
    [Jan 27 14:01:58] DEBUG[3480] chan_dahdi.c: Not yet hungup... Calling hangup once with icause, and clearing call
    [Jan 27 14:01:58] DEBUG[3480] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/1-1
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Hungup 'DAHDI/1-1'
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/619-00000002' in macro 'dialout-trunk'
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: == Spawn extension (from-internal, 011776, 4) exited non-zero on 'SIP/619-00000002'
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/619-00000002", "hangupcall|") in new stack
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/619-00000002", "1?skiprg") in new stack
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Goto (macro-hangupcall,s,4)
    [Jan 27 14:01:58] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/619-00000002", "1?skipblkvm") in new stack
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Goto (macro-hangupcall,s,7)
    [Jan 27 14:01:58] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/619-00000002", "1?theend") in new stack
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Goto (macro-hangupcall,s,9)
    [Jan 27 14:01:58] DEBUG[3480] app_macro.c: Executed application: GotoIf
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/619-00000002", "") in new stack
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/619-00000002' in macro 'hangupcall'
    [Jan 27 14:01:58] VERBOSE[3480] logger.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/619-00000002'
    ============
    Problem Kind
    ============
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [011776@from-internal:1] Macro("SIP/290-00000003", "user-callerid|SKIPTTL|") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/290-00000003", "AMPUSER=290") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/290-00000003", "0?report") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/290-00000003", "1|Set|REALCALLERIDNUM=290") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/290-00000003", "AMPUSER=290") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/290-00000003", "AMPUSERCIDNAME=Office") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/290-00000003", "0?report") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/290-00000003", "AMPUSERCID=290") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/290-00000003", "CALLERID(all)="Office" <290>") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/290-00000003", "0|Set|CHANNEL(language)=") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/290-00000003", "1?continue") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Goto (macro-user-callerid,s,19)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-user-callerid:19] NoOp("SIP/290-00000003", "Using CallerID "Office" <290>") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Noop
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [011776@from-internal:2] Set("SIP/290-00000003", "_NODEST=") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [011776@from-internal:3] Macro("SIP/290-00000003", "record-enable|290|OUT|") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/290-00000003", "1?check") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Goto (macro-record-enable,s,4)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/290-00000003", "recordingcheck|20110127-140208|1296133328.9") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: recordingcheck|20110127-140208|1296133328.9: Outbound recording not enabled
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- AGI Script recordingcheck completed, returning 0
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: AGI
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/290-00000003", "") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [011776@from-internal:4] Macro("SIP/290-00000003", "dialout-trunk|1|11776||") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/290-00000003", "DIAL_TRUNK=1") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] DEBUG[3482] func_db.c: DB: AMPUSER/290/pinless not found in database.
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/290-00000003", "0?sub-pincheck|s|1") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GosubIf
    [Jan 27 14:02:08] DEBUG[3482] func_db.c: DB: AMPUSER/290/pinless not found in database.
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/290-00000003", "0?disabletrunk|1") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/290-00000003", "DIAL_NUMBER=11776") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/290-00000003", "DIAL_TRUNK_OPTIONS=tr") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/290-00000003", "OUTBOUND_GROUP=OUT_1") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/290-00000003", "1?nomax") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Goto (macro-dialout-trunk,s,9)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/290-00000003", "0?skipoutcid") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/290-00000003", "DIAL_TRUNK_OPTIONS=") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/290-00000003", "outbound-callerid|1") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/290-00000003", "0|SetCallerPres|") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/290-00000003", "0|Set|REALCALLERIDNUM=290") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/290-00000003", "1?normcid") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Goto (macro-outbound-callerid,s,6)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/290-00000003", "USEROUTCID=") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] DEBUG[3482] func_db.c: DB: DEVICE/290/emergency_cid not found in database.
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/290-00000003", "EMERGENCYCID=") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/290-00000003", "TRUNKOUTCID=") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/290-00000003", "1?trunkcid") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Goto (macro-outbound-callerid,s,12)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/290-00000003", "0|Set|CALLERID(all)=") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/290-00000003", "0|Set|CALLERID(all)=") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/290-00000003", "0|SetCallerPres|prohib_passed_screen") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Macro
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/290-00000003", "0|AGI|fixlocalprefix") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/290-00000003", "OUTNUM=11776") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/290-00000003", "custom=DAHDI/g0") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Set
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/290-00000003", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/290-00000003", "dialout-trunk-predial-hook|") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/290-00000003", "") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Macro
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/290-00000003", "0?bypass|1") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/290-00000003", "0?customtrunk") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/290-00000003", "DAHDI/g0/11776|300|") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Requested transfer capability: 0x00 - SPEECH
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Called g0/11776
    [Jan 27 14:02:08] VERBOSE[3429] logger.c: -- Channel 0/1, span 1 got hangup, cause 63
    [Jan 27 14:02:08] DEBUG[3482] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/1-1
    [Jan 27 14:02:08] DEBUG[3482] chan_dahdi.c: Already hungup... Calling hangup once, and clearing call
    [Jan 27 14:02:08] DEBUG[3482] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/1-1
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Hungup 'DAHDI/1-1'
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Dial
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-dialout-trunk:20] Goto("SIP/290-00000003", "s-CHANUNAVAIL|1") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Goto
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] GotoIf("SIP/290-00000003", "1?noreport") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,3)
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:3] NoOp("SIP/290-00000003", "TRUNK Dial failed due to CHANUNAVAIL (hangupcause: 63) - failing through to other trunks") in new stack
    [Jan 27 14:02:08] DEBUG[3482] app_macro.c: Executed application: Noop
    ---->
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [011776@from-internal:5] Macro("SIP/290-00000003", "outisbusy|") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- Executing [s@macro-outisbusy:1] Playback("SIP/290-00000003", "all-circuits-busy-now|noanswer") in new stack
    [Jan 27 14:02:08] VERBOSE[3482] logger.c: -- <SIP/290-00000003> Playing 'all-circuits-busy-now' (language 'en')
    [Jan 27 14:02:10] DEBUG[3482] app_macro.c: Executed application: Playback
    [Jan 27 14:02:10] VERBOSE[3482] logger.c: -- Executing [s@macro-outisbusy:2] Playback("SIP/290-00000003", "pls-try-call-later|noanswer") in new stack
    [Jan 27 14:02:10] VERBOSE[3482] logger.c: -- <SIP/290-00000003> Playing 'pls-try-call-later' (language 'en')
    ---->
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: == Spawn extension (macro-outisbusy, s, 2) exited non-zero on 'SIP/290-00000003' in macro 'outisbusy'
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: == Spawn extension (from-internal, 011776, 5) exited non-zero on 'SIP/290-00000003'
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Executing [h@from-internal:1] Macro("SIP/290-00000003", "hangupcall") in new stack
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/290-00000003", "1?skiprg") in new stack
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Goto (macro-hangupcall,s,4)
    [Jan 27 14:02:11] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/290-00000003", "1?skipblkvm") in new stack
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Goto (macro-hangupcall,s,7)
    [Jan 27 14:02:11] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/290-00000003", "1?theend") in new stack
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Goto (macro-hangupcall,s,9)
    [Jan 27 14:02:11] DEBUG[3482] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/290-00000003", "") in new stack
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/290-00000003' in macro 'hangupcall'
    [Jan 27 14:02:11] VERBOSE[3482] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/290-00000003'
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [011776@from-internal:1] Macro("SIP/619-00000004", "user-callerid|SKIPTTL|") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/619-00000004", "AMPUSER=619") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/619-00000004", "0?report") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/619-00000004", "1|Set|REALCALLERIDNUM=619") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/619-00000004", "AMPUSER=619") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/619-00000004", "AMPUSERCIDNAME=Privat") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/619-00000004", "0?report") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/619-00000004", "AMPUSERCID=619") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/619-00000004", "CALLERID(all)="Privat" <619>") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:9] ExecIf("SIP/619-00000004", "0|Set|CHANNEL(language)=") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:10] GotoIf("SIP/619-00000004", "1?continue") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Goto (macro-user-callerid,s,19)
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-user-callerid:19] NoOp("SIP/619-00000004", "Using CallerID "Privat" <619>") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Noop
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [011776@from-internal:2] Set("SIP/619-00000004", "_NODEST=") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [011776@from-internal:3] Macro("SIP/619-00000004", "record-enable|619|OUT|") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/619-00000004", "1?check") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Goto (macro-record-enable,s,4)
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/619-00000004", "recordingcheck|20110127-140215|1296133335.11") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: recordingcheck|20110127-140215|1296133335.11: Outbound recording not enabled
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- AGI Script recordingcheck completed, returning 0
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: AGI
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/619-00000004", "") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [011776@from-internal:4] Macro("SIP/619-00000004", "dialout-trunk|1|11776||") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/619-00000004", "DIAL_TRUNK=1") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] DEBUG[3484] func_db.c: DB: AMPUSER/619/pinless not found in database.
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/619-00000004", "0?sub-pincheck|s|1") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GosubIf
    [Jan 27 14:02:15] DEBUG[3484] func_db.c: DB: AMPUSER/619/pinless not found in database.
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/619-00000004", "0?disabletrunk|1") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/619-00000004", "DIAL_NUMBER=11776") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/619-00000004", "DIAL_TRUNK_OPTIONS=tr") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/619-00000004", "OUTBOUND_GROUP=OUT_1") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/619-00000004", "1?nomax") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Goto (macro-dialout-trunk,s,9)
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/619-00000004", "0?skipoutcid") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/619-00000004", "DIAL_TRUNK_OPTIONS=") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/619-00000004", "outbound-callerid|1") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/619-00000004", "0|SetCallerPres|") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/619-00000004", "0|Set|REALCALLERIDNUM=619") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/619-00000004", "1?normcid") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Goto (macro-outbound-callerid,s,6)
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/619-00000004", "USEROUTCID=") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] DEBUG[3484] func_db.c: DB: DEVICE/619/emergency_cid not found in database.
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/619-00000004", "EMERGENCYCID=") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/619-00000004", "TRUNKOUTCID=") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/619-00000004", "1?trunkcid") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Goto (macro-outbound-callerid,s,12)
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/619-00000004", "0|Set|CALLERID(all)=") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/619-00000004", "0|Set|CALLERID(all)=") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/619-00000004", "0|SetCallerPres|prohib_passed_screen") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Macro
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/619-00000004", "0|AGI|fixlocalprefix") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/619-00000004", "OUTNUM=11776") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/619-00000004", "custom=DAHDI/g0") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Set
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/619-00000004", "0|Set|DIAL_TRUNK_OPTIONS=M(setmusic^)") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: ExecIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/619-00000004", "dialout-trunk-predial-hook|") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/619-00000004", "") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: Macro
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/619-00000004", "0?bypass|1") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/619-00000004", "0?customtrunk") in new stack
    [Jan 27 14:02:15] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/619-00000004", "DAHDI/g0/11776|300|") in new stack
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Requested transfer capability: 0x00 - SPEECH
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- Called g0/11776
    [Jan 27 14:02:15] DEBUG[3429] chan_dahdi.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- DAHDI/1-1 is proceeding passing it to SIP/619-00000004
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- DAHDI/1-1 is making progress passing it to SIP/619-00000004
    [Jan 27 14:02:15] VERBOSE[3484] logger.c: -- DAHDI/1-1 is ringing
    [Jan 27 14:02:19] DEBUG[3484] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on DAHDI/1-1
    [Jan 27 14:02:19] DEBUG[3484] chan_dahdi.c: Not yet hungup... Calling hangup once with icause, and clearing call
    [Jan 27 14:02:19] DEBUG[3484] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on DAHDI/1-1
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Hungup 'DAHDI/1-1'
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/619-00000004' in macro 'dialout-trunk'
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: == Spawn extension (from-internal, 011776, 4) exited non-zero on 'SIP/619-00000004'
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/619-00000004", "hangupcall|") in new stack
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/619-00000004", "1?skiprg") in new stack
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Goto (macro-hangupcall,s,4)
    [Jan 27 14:02:19] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Executing [s@macro-hangupcall:4] GotoIf("SIP/619-00000004", "1?skipblkvm") in new stack
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Goto (macro-hangupcall,s,7)
    [Jan 27 14:02:19] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Executing [s@macro-hangupcall:7] GotoIf("SIP/619-00000004", "1?theend") in new stack
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Goto (macro-hangupcall,s,9)
    [Jan 27 14:02:19] DEBUG[3484] app_macro.c: Executed application: GotoIf
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: -- Executing [s@macro-hangupcall:9] Hangup("SIP/619-00000004", "") in new stack
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/619-00000004' in macro 'hangupcall'
    [Jan 27 14:02:19] VERBOSE[3484] logger.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/619-00000004'

    thx

    Chris
     

Share This Page