- Mitglied seit
- 25 Mai 2004
- Beiträge
- 483
- Punkte für Reaktionen
- 0
- Punkte
- 16
[Gelöst] ein SIP Account bekommt kein Amt bei Elastix
Hallo,
habe Elastix 1.6 Installiert, soweit läuft alles gut. Nun habe ich eine neue Nebenstelle mit der Rufnummer 290 und 291 eingerichtet. Intern geht alles man kann Anrufen und Anrufe entgegennehmem. Leider bekommt die 290 und 291 aber kein Amt, es kommt eine Ansage welche am Anfang etwas nuschelt. "All circuits are busy now, please try your call again later". Folgendes habe ich schon gemacht.
Telefon an einen anderen Account angemeldet = geht alles
den 290 Account an einem anderen Telefon angemeldet = Amt geht nicht
Account auf Elastix gelöscht und genau so erstellt wie die anderen Accounts = Amt geht nicht
Kann es was mit der 290 oder 291 Nummer zu tun haben, wird die irgendwie benutzt ? Wie kann man die Ansage Interpretieren? Habe einen normalen ISDN PMP Anschluss.
Ich habe jetzt ein Logfile folgendes habe ich gemacht :
ALs erstes habe ich mit der SIP Nummer 619 nach extern 11776 angerudfen, also am Telefon Hörer ab und 011776 gewählt. Bei der 619 bekomme ich dann ein freizeichen, alles fein.
Danach habe das selbe mit der Sip Nummer 290 gemacht, eine der zwei Problemnummern. Da bekomme ich die Meldung das alles besetzt sei, siehe oben.
Gleich danach habe ich es wieder mit dem SIP Nummer 619 gemacht und es klingelt wieder.
Wie gesagt den Fehler habe ich nur bei der Nummer 290 und 291, alle anderen Nummern funktionieren.
Anbei das Log, ich habe es Makiert wo die 290 versucht extern zu telefonieren, ich sehe das es ein Cause Fehler gibt aber wieso mit den anderen Phones geht doch oder habe ich ein denkfehler ?
Würde mich sehr freuen wenn mir jemand helfen kann ich zweifel schon langsam...
Grüße
Chris
Jetzt das Log
[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'
Hallo,
habe Elastix 1.6 Installiert, soweit läuft alles gut. Nun habe ich eine neue Nebenstelle mit der Rufnummer 290 und 291 eingerichtet. Intern geht alles man kann Anrufen und Anrufe entgegennehmem. Leider bekommt die 290 und 291 aber kein Amt, es kommt eine Ansage welche am Anfang etwas nuschelt. "All circuits are busy now, please try your call again later". Folgendes habe ich schon gemacht.
Telefon an einen anderen Account angemeldet = geht alles
den 290 Account an einem anderen Telefon angemeldet = Amt geht nicht
Account auf Elastix gelöscht und genau so erstellt wie die anderen Accounts = Amt geht nicht
Kann es was mit der 290 oder 291 Nummer zu tun haben, wird die irgendwie benutzt ? Wie kann man die Ansage Interpretieren? Habe einen normalen ISDN PMP Anschluss.
Ich habe jetzt ein Logfile folgendes habe ich gemacht :
ALs erstes habe ich mit der SIP Nummer 619 nach extern 11776 angerudfen, also am Telefon Hörer ab und 011776 gewählt. Bei der 619 bekomme ich dann ein freizeichen, alles fein.
Danach habe das selbe mit der Sip Nummer 290 gemacht, eine der zwei Problemnummern. Da bekomme ich die Meldung das alles besetzt sei, siehe oben.
Gleich danach habe ich es wieder mit dem SIP Nummer 619 gemacht und es klingelt wieder.
Wie gesagt den Fehler habe ich nur bei der Nummer 290 und 291, alle anderen Nummern funktionieren.
Anbei das Log, ich habe es Makiert wo die 290 versucht extern zu telefonieren, ich sehe das es ein Cause Fehler gibt aber wieso mit den anderen Phones geht doch oder habe ich ein denkfehler ?
Würde mich sehr freuen wenn mir jemand helfen kann ich zweifel schon langsam...
Grüße
Chris
Jetzt das Log
[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'
Zuletzt bearbeitet: