Apr 4 16:11:19 DEBUG[24143] chan_zap.c: DTMF digit: 4 on Zap/50-2 Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Got event Wink/Flash(3) on channel 50 (index 0) Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Winkflash, index: 0, normal: 34, callwait: -1, thirdcall: 136 Apr 4 16:11:21 VERBOSE[24143] logger.c: -- Dumping incomplete call on on Zap/50-1 Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Swapping 2 and 0 Apr 4 16:11:21 VERBOSE[24143] logger.c: -- Stopped music on hold on SIP/213.166.5.134-00791990 Apr 4 16:11:21 DEBUG[24143] channel.c: Scheduling timer at 0 sample intervals Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Enabled echo cancellation on channel 50 Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Updated conferencing on 50, with 0 conference users Apr 4 16:11:21 DEBUG[24143] chan_zap.c: waitfordigit returned < 0... Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Hangup: channel: 50 index = 2, normal = 34, callwait = -1, thirdcall = 136 Apr 4 16:11:21 DEBUG[24143] chan_zap.c: Released sub 2 of channel 50 Apr 4 16:11:21 VERBOSE[24143] logger.c: -- Hungup 'Zap/50-2' Apr 4 16:11:22 DEBUG[23963] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:22 DEBUG[23963] chan_zap.c: Got event Wink/Flash(3) on channel 50 (index 0) Apr 4 16:11:22 DEBUG[23963] chan_zap.c: Winkflash, index: 0, normal: 34, callwait: -1, thirdcall: -1 Apr 4 16:11:22 DEBUG[23963] chan_zap.c: Already have a dsp on Zap/50-2? Apr 4 16:11:22 DEBUG[23963] chan_zap.c: Swapping 2 and 0 Apr 4 16:11:22 DEBUG[23963] chan_zap.c: disabled echo cancellation on channel 50 Apr 4 16:11:22 VERBOSE[23963] logger.c: -- Started three way call on channel 50 Apr 4 16:11:22 VERBOSE[23963] logger.c: -- Started music on hold, class 'default', on SIP/213.166.5.134-00791990 Apr 4 16:11:22 DEBUG[23963] channel.c: Scheduling timer at 160 sample intervals Apr 4 16:11:22 DEBUG[23963] chan_zap.c: Updated conferencing on 50, with 0 conference users Apr 4 16:11:22 VERBOSE[24146] logger.c: -- Starting simple switch on 'Zap/50-2' Apr 4 16:11:22 DEBUG[23963] channel.c: Generator got voice, switching to phase locked mode Apr 4 16:11:22 DEBUG[23963] channel.c: Scheduling timer at 0 sample intervals Apr 4 16:11:22 DEBUG[24132] chan_zap.c: Enabled echo cancellation on channel 37 Apr 4 16:11:22 VERBOSE[24132] logger.c: -- Executing SetCallerID("Zap/37-1", "") in new stack Apr 4 16:11:22 WARNING[24132] app_setcallerid.c: SetCallerID requires an argument! Apr 4 16:11:22 VERBOSE[24132] logger.c: -- Executing Dial("Zap/37-1", "local/01433668031111@fromoffice") in new stack Apr 4 16:11:22 VERBOSE[24132] logger.c: -- Called 01433668031111@fromoffice Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing NoOp("Local/01433668031111@fromoffice-ad49,2", "MAGRATHEA") in new stack Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing Macro("Local/01433668031111@fromoffice-ad49,2", "recexten|SIP/33668 035598@magratheasip") in new stack Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing NoOp("Local/01433668031111@fromoffice-ad49,2", " Recorder Channel [ Local/01433668031111@fromoffice-ad49,2] CallerID [7777]") in new stack Apr 4 16:11:22 DEBUG[24149] pbx.c: Expression result is '0' Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing GotoIf("Local/01433668031111@fromoffice-ad49,2", "0 ? 5") in new stack Apr 4 16:11:22 DEBUG[24149] pbx.c: Not taking any branch Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing SetVar("Local/01433668031111@fromoffice-ad49,2", "ID=7777") in new stack Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing Goto("Local/01433668031111@fromoffice-ad49,2", "6") in new stack Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Goto (macro-recexten,s,6) Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing SetVar("Local/01433668031111@fromoffice-ad49,2", "CALLFILENAME=/dat a/rec/2006/04/04/from-7777-to-01433668031111-161122") in new stack Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing Monitor("Local/01433668031111@fromoffice-ad49,2", "gsm|/data/rec/20 06/04/04/from-7777-to-01433668031111-161122|m") in new stack Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Executing Dial("Local/01433668031111@fromoffice-ad49,2", "SIP/33668031111@mag ratheasip|120|L(3600000)") in new stack Apr 4 16:11:22 DEBUG[24149] chan_sip.c: Setting NAT on RTP to 524288 Apr 4 16:11:22 DEBUG[24149] chan_sip.c: Outgoing Call for 33668031111 Apr 4 16:11:22 VERBOSE[24149] logger.c: -- Called 33668031111@magratheasip Apr 4 16:11:22 DEBUG[22414] chan_sip.c: Acked pending invite 102 Apr 4 16:11:22 DEBUG[22414] chan_sip.c: Stopping retransmission on '3f14330902f810872f83c9c154304a97@62.0.28.200' of Request 102: Match Found Apr 4 16:11:22 DEBUG[22414] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '3f14330902f810872f8 3c9c154304a97@62.0.28.200' Request 103: Found Apr 4 16:11:23 DEBUG[24146] chan_zap.c: DTMF digit: 4 on Zap/50-2 Apr 4 16:11:24 DEBUG[24146] chan_zap.c: DTMF digit: 9 on Zap/50-2 Apr 4 16:11:24 DEBUG[24146] chan_zap.c: DTMF digit: 9 on Zap/50-2 Apr 4 16:11:24 DEBUG[24146] chan_zap.c: DTMF digit: 9 on Zap/50-2 Apr 4 16:11:24 DEBUG[24146] chan_zap.c: Enabled echo cancellation on channel 50 Apr 4 16:11:24 VERBOSE[24146] logger.c: -- Executing NoOp("Zap/50-2", "_____Agent_Login_____") in new stack Apr 4 16:11:24 VERBOSE[24146] logger.c: -- Executing AgentLogin("Zap/50-2", "") in new stack Apr 4 16:11:24 DEBUG[24146] chan_zap.c: Took Zap/50-2 off hook Apr 4 16:11:24 DEBUG[24146] channel.c: Scheduling timer at 160 sample intervals Apr 4 16:11:24 VERBOSE[24146] logger.c: -- Playing 'agent-user' (language 'en') Apr 4 16:11:25 DEBUG[24146] chan_zap.c: DTMF digit: 1 on Zap/50-2 Apr 4 16:11:25 DEBUG[24146] channel.c: Scheduling timer at 0 sample intervals Apr 4 16:11:25 DEBUG[24146] chan_zap.c: DTMF digit: 2 on Zap/50-2 Apr 4 16:11:25 DEBUG[24146] chan_zap.c: DTMF digit: 2 on Zap/50-2 Apr 4 16:11:26 DEBUG[24146] chan_zap.c: DTMF digit: # on Zap/50-2 Apr 4 16:11:26 DEBUG[24146] channel.c: Scheduling timer at 160 sample intervals Apr 4 16:11:26 VERBOSE[24146] logger.c: -- Playing 'agent-alreadyon' (language 'en') Apr 4 16:11:28 DEBUG[24146] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:28 DEBUG[24146] chan_zap.c: Got event On hook(1) on channel 50 (index 0) Apr 4 16:11:28 DEBUG[24146] chan_zap.c: Last flash was 6248 ms ago Apr 4 16:11:28 VERBOSE[24146] logger.c: -- Stopped music on hold on SIP/213.166.5.134-00791990 Apr 4 16:11:28 DEBUG[24146] channel.c: Scheduling timer at 0 sample intervals Apr 4 16:11:28 DEBUG[24146] channel.c: Planning to masquerade channel SIP/213.166.5.134-00791990 into the structure of Zap/5 0-2 Apr 4 16:11:28 DEBUG[24146] channel.c: Done planning to masquerade channel SIP/213.166.5.134-00791990 into the structure of Zap/50-2 Apr 4 16:11:28 DEBUG[24146] chan_zap.c: Swapping 2 and 0 Apr 4 16:11:28 DEBUG[24146] chan_zap.c: Released sub 2 of channel 50 Apr 4 16:11:28 DEBUG[24146] channel.c: Got clone lock for masquerade on 'SIP/213.166.5.134-00791990' at 0x7a40f0 Apr 4 16:11:28 DEBUG[24146] chan_zap.c: New owner for channel 50 is SIP/213.166.5.134-00791990 Apr 4 16:11:28 DEBUG[24146] chan_zap.c: Updated conferencing on 50, with 0 conference users Apr 4 16:11:28 DEBUG[24146] chan_zap.c: Hangup: channel: 50 index = -1, normal = 34, callwait = -1, thirdcall = -1 Apr 4 16:11:28 VERBOSE[24146] logger.c: -- Hungup 'SIP/213.166.5.134-00791990' Apr 4 16:11:28 DEBUG[24146] channel.c: Putting channel SIP/213.166.5.134-00791990 in 2/4 formats Apr 4 16:11:28 DEBUG[24146] channel.c: Released clone lock on 'Zap/50-2' Apr 4 16:11:28 DEBUG[24146] channel.c: Done Masquerading SIP/213.166.5.134-00791990 (6) Apr 4 16:11:28 DEBUG[23963] channel.c: Didn't get a frame from channel: Zap/50-2 Apr 4 16:11:28 DEBUG[23963] channel.c: Bridge stops bridging channels Zap/50-2 and Agent/122 Apr 4 16:11:28 DEBUG[23963] chan_agent.c: Hangup called for state Up Apr 4 16:11:28 VERBOSE[23963] logger.c: -- Started music on hold, class 'default', on Zap/50-1 Apr 4 16:11:28 DEBUG[23963] channel.c: Scheduling timer at 160 sample intervals Apr 4 16:11:28 VERBOSE[23963] logger.c: == Spawn extension (applications, 4998, 4) exited non-zero on 'Zap/50-2' Apr 4 16:11:28 DEBUG[22949] channel.c: Generator got voice, switching to phase locked mode Apr 4 16:11:28 DEBUG[22949] channel.c: Scheduling timer at 0 sample intervals Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2 Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2 Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2 Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2 Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2 Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2 Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2 Apr 4 16:11:30 DEBUG[22949] chan_zap.c: Exception on 34, channel 50 Apr 4 16:11:30 WARNING[22949] chan_zap.c: We're Zap/50-1, not Zap/50-2