[2009-09-27 14:24:19] VERBOSE[18623] logger.c: [2009-09-27 14:24:19] -- Starting simple switch on 'Zap/62-1' [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Echo cancellation isn't required on digital connection [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@s2m-intern:1] NoOp("Zap/62-1", "######### Die Nummer 747XXXXX wird von avalon gerufen <<<<<<<<<<<<") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@s2m-intern:2] Set("Zap/62-1", "orig_id=555") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@s2m-intern:3] Set("Zap/62-1", "CHANNEL(transfercapability)=DIGITAL") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Goto' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@s2m-intern:4] Goto("Zap/62-1", "routing-extern|747XXXXX|1") in new stack [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Goto (routing-extern,747XXXXX,1) [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@routing-extern:1] NoOp("Zap/62-1", "Externes Ziel 747XXXXX soll angerufen werden ...") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Gosub' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@routing-extern:2] Gosub("Zap/62-1", "allow-transfer|747XXXXX|1") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@allow-transfer:1] NoOp("Zap/62-1", "allow transfer") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@allow-transfer:2] NoOp("Zap/62-1", "") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Return' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@allow-transfer:3] Return("Zap/62-1", "") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@routing-extern:3] Set("Zap/62-1", "Route=festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Gosub' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@routing-extern:4] Gosub("Zap/62-1", "lcr|747XXXXX|1") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@lcr:1] NoOp("Zap/62-1", "####### Prüfe LCR-Möglichkeit >>>>>>>>>>>>>>") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@lcr:2] NoOp("Zap/62-1", "") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Return' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@lcr:3] Return("Zap/62-1", "") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Goto' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@routing-extern:5] Goto("Zap/62-1", "dial-festnetz|747XXXXX|1") in new stack [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Goto (dial-festnetz,747XXXXX,1) [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-festnetz:1] NoOp("Zap/62-1", "Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-festnetz:2] Set("Zap/62-1", "_Methode=Zap") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-festnetz:3] Set("Zap/62-1", "_Kanal=g1") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-festnetz:4] Set("Zap/62-1", "_Fallback=none") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Goto' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-festnetz:5] Goto("Zap/62-1", "dial-extern|747XXXXX|1") in new stack [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Goto (dial-extern,747XXXXX,1) [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'DIGITAL' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:1] NoOp("Zap/62-1", "+++ 555 -> 747XXXXX mit DIGITAL +++") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'DIGITAL' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'DIGITAL' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:2] Set("Zap/62-1", "CHANNEL(transfercapability)=DIGITAL") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Macro' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:3] Macro("Zap/62-1", "cdr-out-userfield") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:1] NoOp("Zap/62-1", "Macro cdr-OUT-userfield") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: NoOp [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:2] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:3] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:4] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:5] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:6] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:7] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:8] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:9] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'OUT-001 Festnetz -> Festnetz' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:10] Set("Zap/62-1", "tmp=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [s@macro-cdr-out-userfield:11] Set("Zap/62-1", "CDR(userfield)=OUT-001 Festnetz -> Festnetz") in new stack [2009-09-27 14:24:23] DEBUG[18623] app_macro.c: Executed application: Set [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:4] Set("Zap/62-1", "Pfx=") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:5] Set("Zap/62-1", "CALLERID(number)=555") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Expression result is '0' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is 'T' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Set' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:6] Set("Zap/62-1", "tfer1=T") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Function result is '555' [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'NoOp' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:7] NoOp("Zap/62-1", "#### 555 ####") in new stack [2009-09-27 14:24:23] DEBUG[18623] pbx.c: Launching 'Dial' [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Executing [747XXXXX@dial-extern:8] Dial("Zap/62-1", "Zap/g1/747XXXXX|120|T") in new stack [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Using channel 1 [2009-09-27 14:24:23] DEBUG[18623] rtp.c: Channel 'Zap/1-1' has no RTP, not doing anything [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable DIALEDTIME. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable ANSWEREDTIME. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable DIALEDPEERNAME. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable DIALEDPEERNUMBER. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable DIALSTATUS. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable tfer1. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable Pfx. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable MACRO_DEPTH. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable tmp. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Copying soft-transferable variable Fallback. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Copying soft-transferable variable Kanal. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Copying soft-transferable variable Methode. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable Route. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable orig_id. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable CALLEDTON. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable ANI2. [2009-09-27 14:24:23] DEBUG[18623] channel.c: Not copying variable TRANSFERCAPABILITY. [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Requested transfer capability: 0x08 - DIGITAL [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Called g1/747XXXXX [2009-09-27 14:24:23] DEBUG[18623] channel.c: Set channel Zap/1-1 to read format ulaw [2009-09-27 14:24:23] DEBUG[18623] channel.c: Set channel Zap/62-1 to read format alaw [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:23] VERBOSE[18623] logger.c: [2009-09-27 14:24:23] -- Zap/1-1 is ringing [2009-09-27 14:24:23] DEBUG[18623] rtp.c: Channel 'Zap/62-1' has no RTP, not doing anything [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Requested indication 3 on channel Zap/62-1 [2009-09-27 14:24:23] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:24] DEBUG[18623] chan_dahdi.c: Dropping frame since I'm still dialing on Zap/1-1... [2009-09-27 14:24:24] VERBOSE[18623] logger.c: [2009-09-27 14:24:24] -- Zap/1-1 answered Zap/62-1 [2009-09-27 14:24:24] DEBUG[18623] chan_dahdi.c: Requested indication -1 on channel Zap/62-1 [2009-09-27 14:24:24] DEBUG[18623] chan_dahdi.c: Requested indication 3 on channel Zap/1-1 [2009-09-27 14:24:24] DEBUG[18623] chan_dahdi.c: Requested indication -1 on channel Zap/62-1 [2009-09-27 14:24:24] DEBUG[18623] chan_dahdi.c: Requested indication 20 on channel Zap/62-1 [2009-09-27 14:24:24] DEBUG[18623] chan_dahdi.c: Requested indication 20 on channel Zap/1-1 [2009-09-27 14:24:28] DEBUG[18623] channel.c: Didn't get a frame from channel: Zap/62-1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Requested indication 20 on channel Zap/1-1 [2009-09-27 14:24:28] DEBUG[18623] channel.c: Bridge stops bridging channels Zap/62-1 and Zap/1-1 [2009-09-27 14:24:28] DEBUG[18623] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. [2009-09-27 14:24:28] DEBUG[18623] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,userfield) VALUES ('2009-09-27 14:24:19','555','555','747XXXXX','dial-extern', 'Zap/62-1','Zap/1-1','Dial','Zap/g1/747XXXXX|120|T',9,4,'ANSWERED',3,'','OUT-001 Festnetz -> Festnetz') [2009-09-27 14:24:28] DEBUG[18623] channel.c: Hanging up channel 'Zap/1-1' [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: dahdi_hangup(Zap/1-1) [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Hangup: channel: 1 index = 0, normal = 21, callwait = -1, thirdcall = -1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Not yet hungup... Calling hangup once with icause, and clearing call [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: disabled echo cancellation on channel 1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on Zap/1-1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Updated conferencing on 1, with 0 conference users [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: disabled echo cancellation on channel 1 [2009-09-27 14:24:28] VERBOSE[18623] logger.c: [2009-09-27 14:24:28] -- Hungup 'Zap/1-1' [2009-09-27 14:24:28] DEBUG[18623] rtp.c: Channel 'Zap/62-1' has no RTP, not doing anything [2009-09-27 14:24:28] DEBUG[18623] app_dial.c: Exiting with DIALSTATUS=ANSWER. [2009-09-27 14:24:28] DEBUG[18623] pbx.c: Spawn extension (dial-extern,747XXXXX,8) exited non-zero on 'Zap/62-1' [2009-09-27 14:24:28] VERBOSE[18623] logger.c: [2009-09-27 14:24:28] == Spawn extension (dial-extern, 747XXXXX, 8) exited non-zero on 'Zap/62-1' [2009-09-27 14:24:28] DEBUG[18623] channel.c: Soft-Hanging up channel 'Zap/62-1' [2009-09-27 14:24:28] DEBUG[18623] channel.c: Hanging up channel 'Zap/62-1' [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: dahdi_hangup(Zap/62-1) [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Set option AUDIO MODE, value: ON(1) on Zap/62-1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Hangup: channel: 62 index = 0, normal = 80, callwait = -1, thirdcall = -1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Not yet hungup... Calling hangup once with icause, and clearing call [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: disabled echo cancellation on channel 62 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on Zap/62-1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Updated conferencing on 62, with 0 conference users [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: Set option AUDIO MODE, value: OFF(0) on Zap/62-1 [2009-09-27 14:24:28] DEBUG[18623] chan_dahdi.c: disabled echo cancellation on channel 62 [2009-09-27 14:24:28] VERBOSE[18623] logger.c: [2009-09-27 14:24:28] -- Hungup 'Zap/62-1'