[Feb 27 15:17:57] WARNING[32075] app_dial.c: Unable to create channel of type 'ZAP' (cause 34 - Circuit/channel congestion)
[Feb 27 15:17:57] VERBOSE[32075] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Dial
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:20] Goto("SIP/1201-b6668df8", "s-CONGESTION|1") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Goto
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:1] GotoIf("SIP/1201-b6668df8", "1?noreport") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,3)
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:3] NoOp("SIP/1201-b6668df8", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Noop
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [XXX@from-internal:6] Macro("SIP/1201-b6668df8", "dialout-trunk|6|XXX||") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/1201-b6668df8", "DIAL_TRUNK=6") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] DEBUG[32075] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/1201-b6668df8", "0?sub-pincheck|s|1") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GosubIf
[Feb 27 15:17:57] DEBUG[32075] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/1201-b6668df8", "0?disabletrunk|1") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/1201-b6668df8", "DIAL_NUMBER=XXX") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=r") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/1201-b6668df8", "OUTBOUND_GROUP=OUT_6") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/1201-b6668df8", "1?nomax") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Goto (macro-dialout-trunk,s,9)
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/1201-b6668df8", "0?skipoutcid") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/1201-b6668df8", "outbound-callerid|6") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: ExecIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/1201-b6668df8", "0|Set|REALCALLERIDNUM=XXX") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: ExecIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/1201-b6668df8", "1?normcid") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Goto (macro-outbound-callerid,s,6)
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/1201-b6668df8", "USEROUTCID="XXX" <XXX>") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] DEBUG[32075] func_db.c: DB: DEVICE/1201/emergency_cid not found in database.
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/1201-b6668df8", "EMERGENCYCID=") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/1201-b6668df8", "TRUNKOUTCID=") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/1201-b6668df8", "1?trunkcid") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Goto (macro-outbound-callerid,s,12)
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/1201-b6668df8", "0|Set|CALLERID(all)=") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: ExecIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:13] GotoIf("SIP/1201-b6668df8", "0?exit") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:14] Set("SIP/1201-b6668df8", "CALLERID(all)=XXX <XXX>") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|prohib_passed_screen") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: ExecIf
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Macro
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/1201-b6668df8", "0|AGI|fixlocalprefix") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: ExecIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/1201-b6668df8", "OUTNUM=XXX") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/1201-b6668df8", "custom=IAX2/Fallback DUS.NET") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Set
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/1201-b6668df8", "1|Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: ExecIf
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Last app: Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)M(setmusic^none)
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/1201-b6668df8", "dialout-trunk-predial-hook|") in new stack
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: Macro
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/1201-b6668df8", "0?bypass|1") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/1201-b6668df8", "0?customtrunk") in new stack
[Feb 27 15:17:57] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/1201-b6668df8", "IAX2/Fallback DUS.NET/XXX|300|M(setmusic^none)") in new stack
[Feb 27 15:17:57] DEBUG[32075] chan_iax2.c: prepending 4 to prefs
[Feb 27 15:17:57] VERBOSE[32075] logger.c: -- Called Fallback DUS.NET/XXX
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Hungup 'IAX2/Fallback DUS.NET-7335'
[Feb 27 15:18:20] VERBOSE[32075] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1201-b6668df8' in macro 'dialout-trunk'
[Feb 27 15:18:20] VERBOSE[32075] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1201-b6668df8'
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/1201-b6668df8", "hangupcall|") in new stack
[Feb 27 15:18:20] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state Idle for Notify User 1102
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/1201-b6668df8", "w") in new stack
[Feb 27 15:18:20] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state Idle for Notify User 1301
[Feb 27 15:18:20] DEBUG[32075] app_macro.c: Executed application: ResetCDR
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:20] DEBUG[32075] app_macro.c: Executed application: NoCDR
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/1201-b6668df8", "1?skiprg") in new stack
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Goto (macro-hangupcall,s,6)
[Feb 27 15:18:20] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/1201-b6668df8", "1?skipblkvm") in new stack
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Goto (macro-hangupcall,s,9)
[Feb 27 15:18:20] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:20] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state Idle for Notify User 1501
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/1201-b6668df8", "1?theend") in new stack
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Goto (macro-hangupcall,s,11)
[Feb 27 15:18:20] DEBUG[32075] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:20] VERBOSE[32075] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:20] VERBOSE[32075] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1201-b6668df8' in macro 'hangupcall'
[Feb 27 15:18:20] VERBOSE[32075] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1201-b6668df8'
[Feb 27 15:18:30] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state InUse for Notify User 1102
[Feb 27 15:18:30] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state InUse for Notify User 1301
[Feb 27 15:18:30] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state InUse for Notify User 1501
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [XXX@from-internal:1] Set("SIP/1201-b6668df8", "MOHCLASS=none") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [XXX@from-internal:2] Macro("SIP/1201-b6668df8", "user-callerid|SKIPTTL|") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/1201-b6668df8", "AMPUSER=1201") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/1201-b6668df8", "0?report") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/1201-b6668df8", "1|Set|REALCALLERIDNUM=1201") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/1201-b6668df8", "AMPUSER=1201") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/1201-b6668df8", "AMPUSERCIDNAME=XXX") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/1201-b6668df8", "0?report") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/1201-b6668df8", "AMPUSERCID=1201") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/1201-b6668df8", "CALLERID(all)="XXX" <1201>") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:9] Set("SIP/1201-b6668df8", "REALCALLERIDNUM=1201") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/1201-b6668df8", "1|Set|CHANNEL(language)=de") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Last app: Set|CHANNEL(language)=de
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/1201-b6668df8", "1?continue") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-user-callerid,s,20)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("SIP/1201-b6668df8", "Using CallerID "XXX" <1201>") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Noop
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [XXX@from-internal:3] Set("SIP/1201-b6668df8", "_NODEST=") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [XXX@from-internal:4] Macro("SIP/1201-b6668df8", "record-enable|1201|OUT|") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/1201-b6668df8", "1?check") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-record-enable,s,4)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/1201-b6668df8", "recordingcheck|20090227-151830|asterisk-1235744310.11036") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Launched AGI Script /usr/share/asterisk/agi-bin/recordingcheck
[Feb 27 15:18:30] VERBOSE[32077] logger.c: recordingcheck|20090227-151830|asterisk-1235744310.11036: Outbound recording not enabled
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- AGI Script recordingcheck completed, returning 0
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: AGI
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [XXX@from-internal:5] Macro("SIP/1201-b6668df8", "dialout-trunk|3|071194911308||") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/1201-b6668df8", "DIAL_TRUNK=3") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] DEBUG[32077] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/1201-b6668df8", "0?sub-pincheck|s|1") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GosubIf
[Feb 27 15:18:30] DEBUG[32077] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/1201-b6668df8", "0?disabletrunk|1") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/1201-b6668df8", "DIAL_NUMBER=XXX") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=r") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/1201-b6668df8", "OUTBOUND_GROUP=OUT_3") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/1201-b6668df8", "0?nomax") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/1201-b6668df8", "0?chanfull") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/1201-b6668df8", "0?skipoutcid") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/1201-b6668df8", "outbound-callerid|3") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/1201-b6668df8", "0|Set|REALCALLERIDNUM=1201") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/1201-b6668df8", "1?normcid") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-outbound-callerid,s,6)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/1201-b6668df8", "USEROUTCID="XXX" <XXX>") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] DEBUG[32077] func_db.c: DB: DEVICE/1201/emergency_cid not found in database.
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/1201-b6668df8", "EMERGENCYCID=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/1201-b6668df8", "TRUNKOUTCID=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/1201-b6668df8", "1?trunkcid") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-outbound-callerid,s,12)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/1201-b6668df8", "0|Set|CALLERID(all)=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:13] GotoIf("SIP/1201-b6668df8", "0?exit") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:14] Set("SIP/1201-b6668df8", "CALLERID(all)=XXX <XXX>") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|prohib_passed_screen") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Macro
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/1201-b6668df8", "0|AGI|fixlocalprefix") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/1201-b6668df8", "OUTNUM=XXX") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/1201-b6668df8", "custom=ZAP/g1") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/1201-b6668df8", "1|Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Last app: Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)M(setmusic^none)
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/1201-b6668df8", "dialout-trunk-predial-hook|") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Macro
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/1201-b6668df8", "0?bypass|1") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/1201-b6668df8", "0?customtrunk") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/1201-b6668df8", "ZAP/g1/XXX|300|M(setmusic^none)") in new stack
[Feb 27 15:18:30] WARNING[32077] app_dial.c: Unable to create channel of type 'ZAP' (cause 34 - Circuit/channel congestion)
[Feb 27 15:18:30] VERBOSE[32077] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Dial
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:20] Goto("SIP/1201-b6668df8", "s-CONGESTION|1") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Goto
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:1] GotoIf("SIP/1201-b6668df8", "1?noreport") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,3)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:3] NoOp("SIP/1201-b6668df8", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Noop
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [XXX@from-internal:6] Macro("SIP/1201-b6668df8", "dialout-trunk|6|XXX||") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/1201-b6668df8", "DIAL_TRUNK=6") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] DEBUG[32077] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/1201-b6668df8", "0?sub-pincheck|s|1") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GosubIf
[Feb 27 15:18:30] DEBUG[32077] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/1201-b6668df8", "0?disabletrunk|1") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/1201-b6668df8", "DIAL_NUMBER=XXX") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=r") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/1201-b6668df8", "OUTBOUND_GROUP=OUT_6") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/1201-b6668df8", "1?nomax") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-dialout-trunk,s,9)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/1201-b6668df8", "0?skipoutcid") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/1201-b6668df8", "outbound-callerid|6") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/1201-b6668df8", "0|Set|REALCALLERIDNUM=XXX") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/1201-b6668df8", "1?normcid") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-outbound-callerid,s,6)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/1201-b6668df8", "USEROUTCID="XXX" <XXX>") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] DEBUG[32077] func_db.c: DB: DEVICE/1201/emergency_cid not found in database.
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/1201-b6668df8", "EMERGENCYCID=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/1201-b6668df8", "TRUNKOUTCID=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/1201-b6668df8", "1?trunkcid") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Goto (macro-outbound-callerid,s,12)
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/1201-b6668df8", "0|Set|CALLERID(all)=") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:13] GotoIf("SIP/1201-b6668df8", "0?exit") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:14] Set("SIP/1201-b6668df8", "CALLERID(all)=XXX <XXX>") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|prohib_passed_screen") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Macro
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/1201-b6668df8", "0|AGI|fixlocalprefix") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/1201-b6668df8", "OUTNUM=XXX") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/1201-b6668df8", "custom=IAX2/Fallback DUS.NET") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Set
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/1201-b6668df8", "1|Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Last app: Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)M(setmusic^none)
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/1201-b6668df8", "dialout-trunk-predial-hook|") in new stack
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: Macro
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/1201-b6668df8", "0?bypass|1") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/1201-b6668df8", "0?customtrunk") in new stack
[Feb 27 15:18:30] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/1201-b6668df8", "IAX2/Fallback DUS.NET/XXX|300|M(setmusic^none)") in new stack
[Feb 27 15:18:30] DEBUG[32077] chan_iax2.c: prepending 4 to prefs
[Feb 27 15:18:30] VERBOSE[32077] logger.c: -- Called Fallback DUS.NET/XXX
[Feb 27 15:18:36] DEBUG[4891] chan_zap.c: Echo cancellation already on
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Hungup 'IAX2/Fallback DUS.NET-1267'
[Feb 27 15:18:40] VERBOSE[32077] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1201-b6668df8' in macro 'dialout-trunk'
[Feb 27 15:18:40] VERBOSE[32077] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/1201-b6668df8'
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/1201-b6668df8", "hangupcall|") in new stack
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/1201-b6668df8", "w") in new stack
[Feb 27 15:18:40] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state Idle for Notify User 1102
[Feb 27 15:18:40] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state Idle for Notify User 1301
[Feb 27 15:18:40] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state Idle for Notify User 1501
[Feb 27 15:18:40] DEBUG[32077] app_macro.c: Executed application: ResetCDR
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:40] DEBUG[32077] app_macro.c: Executed application: NoCDR
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/1201-b6668df8", "1?skiprg") in new stack
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Goto (macro-hangupcall,s,6)
[Feb 27 15:18:40] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/1201-b6668df8", "1?skipblkvm") in new stack
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Goto (macro-hangupcall,s,9)
[Feb 27 15:18:40] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/1201-b6668df8", "1?theend") in new stack
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Goto (macro-hangupcall,s,11)
[Feb 27 15:18:40] DEBUG[32077] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:40] VERBOSE[32077] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:40] VERBOSE[32077] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1201-b6668df8' in macro 'hangupcall'
[Feb 27 15:18:40] VERBOSE[32077] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/1201-b6668df8'
[Feb 27 15:18:40] DTMF[32041] channel.c: DTMF begin '3' received on SIP/1405-b663bcc8
[Feb 27 15:18:40] DTMF[32041] channel.c: DTMF begin passthrough '3' on SIP/1405-b663bcc8
[Feb 27 15:18:40] DEBUG[32041] chan_zap.c: Started VLDTMF digit '3'
[Feb 27 15:18:40] DTMF[32041] channel.c: DTMF end '3' received on SIP/1405-b663bcc8, duration 180 ms
[Feb 27 15:18:40] DTMF[32041] channel.c: DTMF end accepted with begin '3' on SIP/1405-b663bcc8
[Feb 27 15:18:40] DTMF[32041] channel.c: DTMF end passthrough '3' on SIP/1405-b663bcc8
[Feb 27 15:18:40] DEBUG[32041] chan_zap.c: Ending VLDTMF digit '3'
[Feb 27 15:18:47] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state InUse for Notify User 1102
[Feb 27 15:18:47] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state InUse for Notify User 1301
[Feb 27 15:18:47] VERBOSE[4840] logger.c: Extension Changed 1201[ext-local] new state InUse for Notify User 1501
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [XXX@from-internal:1] Set("SIP/1201-b6668df8", "MOHCLASS=none") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [XXX@from-internal:2] Macro("SIP/1201-b6668df8", "user-callerid|SKIPTTL|") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/1201-b6668df8", "AMPUSER=1201") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/1201-b6668df8", "0?report") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/1201-b6668df8", "1|Set|REALCALLERIDNUM=1201") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/1201-b6668df8", "AMPUSER=1201") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/1201-b6668df8", "AMPUSERCIDNAME=XXX") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/1201-b6668df8", "0?report") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/1201-b6668df8", "AMPUSERCID=1201") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/1201-b6668df8", "CALLERID(all)="XXX" <1201>") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:9] Set("SIP/1201-b6668df8", "REALCALLERIDNUM=1201") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/1201-b6668df8", "1|Set|CHANNEL(language)=de") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Last app: Set|CHANNEL(language)=de
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/1201-b6668df8", "1?continue") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-user-callerid,s,20)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("SIP/1201-b6668df8", "Using CallerID "XXX" <1201>") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Noop
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [XXX@from-internal:3] Set("SIP/1201-b6668df8", "_NODEST=") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [XXX@from-internal:4] Macro("SIP/1201-b6668df8", "record-enable|1201|OUT|") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/1201-b6668df8", "1?check") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-record-enable,s,4)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/1201-b6668df8", "recordingcheck|20090227-151847|asterisk-1235744327.11038") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Launched AGI Script /usr/share/asterisk/agi-bin/recordingcheck
[Feb 27 15:18:47] VERBOSE[32079] logger.c: recordingcheck|20090227-151847|asterisk-1235744327.11038: Outbound recording not enabled
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- AGI Script recordingcheck completed, returning 0
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: AGI
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [XXX@from-internal:5] Macro("SIP/1201-b6668df8", "dialout-trunk|3|XXX||") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/1201-b6668df8", "DIAL_TRUNK=3") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] DEBUG[32079] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/1201-b6668df8", "0?sub-pincheck|s|1") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GosubIf
[Feb 27 15:18:47] DEBUG[32079] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/1201-b6668df8", "0?disabletrunk|1") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/1201-b6668df8", "DIAL_NUMBER=XXX") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=r") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/1201-b6668df8", "OUTBOUND_GROUP=OUT_3") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/1201-b6668df8", "0?nomax") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/1201-b6668df8", "0?chanfull") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/1201-b6668df8", "0?skipoutcid") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/1201-b6668df8", "outbound-callerid|3") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/1201-b6668df8", "0|Set|REALCALLERIDNUM=1201") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/1201-b6668df8", "1?normcid") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-outbound-callerid,s,6)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/1201-b6668df8", "USEROUTCID="XXX" <XXX>") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] DEBUG[32079] func_db.c: DB: DEVICE/1201/emergency_cid not found in database.
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/1201-b6668df8", "EMERGENCYCID=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/1201-b6668df8", "TRUNKOUTCID=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/1201-b6668df8", "1?trunkcid") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-outbound-callerid,s,12)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/1201-b6668df8", "0|Set|CALLERID(all)=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:13] GotoIf("SIP/1201-b6668df8", "0?exit") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:14] Set("SIP/1201-b6668df8", "CALLERID(all)=XXX <XXX>") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|prohib_passed_screen") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Macro
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/1201-b6668df8", "0|AGI|fixlocalprefix") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/1201-b6668df8", "OUTNUM=XXX") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/1201-b6668df8", "custom=ZAP/g1") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/1201-b6668df8", "1|Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Last app: Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)M(setmusic^none)
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/1201-b6668df8", "dialout-trunk-predial-hook|") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Macro
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/1201-b6668df8", "0?bypass|1") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/1201-b6668df8", "0?customtrunk") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/1201-b6668df8", "ZAP/g1/XXX|300|M(setmusic^none)") in new stack
[Feb 27 15:18:47] WARNING[32079] app_dial.c: Unable to create channel of type 'ZAP' (cause 34 - Circuit/channel congestion)
[Feb 27 15:18:47] VERBOSE[32079] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Dial
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:20] Goto("SIP/1201-b6668df8", "s-CONGESTION|1") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,1)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Goto
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:1] GotoIf("SIP/1201-b6668df8", "1?noreport") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-dialout-trunk,s-CONGESTION,3)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s-CONGESTION@macro-dialout-trunk:3] NoOp("SIP/1201-b6668df8", "TRUNK Dial failed due to CONGESTION - failing through to other trunks") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Noop
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [XXX@from-internal:6] Macro("SIP/1201-b6668df8", "dialout-trunk|6|XXX||") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/1201-b6668df8", "DIAL_TRUNK=6") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] DEBUG[32079] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/1201-b6668df8", "0?sub-pincheck|s|1") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GosubIf
[Feb 27 15:18:47] DEBUG[32079] func_db.c: DB: AMPUSER/1201/pinless not found in database.
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/1201-b6668df8", "0?disabletrunk|1") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/1201-b6668df8", "DIAL_NUMBER=XXX") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=r") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/1201-b6668df8", "OUTBOUND_GROUP=OUT_6") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/1201-b6668df8", "1?nomax") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-dialout-trunk,s,9)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/1201-b6668df8", "0?skipoutcid") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/1201-b6668df8", "DIAL_TRUNK_OPTIONS=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/1201-b6668df8", "outbound-callerid|6") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/1201-b6668df8", "0|Set|REALCALLERIDNUM=XXX") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/1201-b6668df8", "1?normcid") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-outbound-callerid,s,6)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/1201-b6668df8", "USEROUTCID="XXX" <XXX>") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] DEBUG[32079] func_db.c: DB: DEVICE/1201/emergency_cid not found in database.
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/1201-b6668df8", "EMERGENCYCID=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/1201-b6668df8", "TRUNKOUTCID=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/1201-b6668df8", "1?trunkcid") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Goto (macro-outbound-callerid,s,12)
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/1201-b6668df8", "0|Set|CALLERID(all)=") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:13] GotoIf("SIP/1201-b6668df8", "0?exit") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:14] Set("SIP/1201-b6668df8", "CALLERID(all)=XXX <XXX>") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/1201-b6668df8", "0|SetCallerPres|prohib_passed_screen") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Macro
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/1201-b6668df8", "0|AGI|fixlocalprefix") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/1201-b6668df8", "OUTNUM=XXX") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/1201-b6668df8", "custom=IAX2/Fallback DUS.NET") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Set
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/1201-b6668df8", "1|Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: ExecIf
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Last app: Set|DIAL_TRUNK_OPTIONS=M(setmusic^none)M(setmusic^none)
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:16] Macro("SIP/1201-b6668df8", "dialout-trunk-predial-hook|") in new stack
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/1201-b6668df8", "") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: Macro
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/1201-b6668df8", "0?bypass|1") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/1201-b6668df8", "0?customtrunk") in new stack
[Feb 27 15:18:47] DEBUG[32079] app_macro.c: Executed application: GotoIf
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Executing [s@macro-dialout-trunk:19] Dial("SIP/1201-b6668df8", "IAX2/Fallback DUS.NET/XXX|300|M(setmusic^none)") in new stack
[Feb 27 15:18:47] DEBUG[32079] chan_iax2.c: prepending 4 to prefs
[Feb 27 15:18:47] VERBOSE[32079] logger.c: -- Called Fallback DUS.NET/XXX
[Feb 27 15:18:49] VERBOSE[32081] logger.c: -- Executing [XXX@from-internal:1] Set("SIP/1203-b666e330", "MOHCLASS=none") in new stack
[Feb 27 15:18:49] VERBOSE[32081] logger.c: -- Executing [XXX@from-internal:2] Macro("SIP/1203-b666e330", "user-callerid|SKIPTTL|") in new stack
[Feb 27 15:18:49] VERBOSE[32081] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/1203-b666e330", "AMPUSER=1203") in new stack
[Feb 27 15:18:49] DEBUG[32081] app_macro.c: Executed application: Set