[Sep 13 13:32:03] DEBUG[12215] pbx.c: Launching 'Set'
[Sep 13 13:32:03] VERBOSE[12215] logger.c:     -- Executing [2017@incoming-intertel:1] Set("Zap/21-1", "RECORDED_FILE=rec-2017-1189686723.342") in new stack
[Sep 13 13:32:03] DEBUG[12215] pbx.c: Launching 'Monitor'
[Sep 13 13:32:03] VERBOSE[12215] logger.c:     -- Executing [2017@incoming-intertel:2] Monitor("Zap/21-1", "wav|rec-2017-1189686723.342|") in new stack
[Sep 13 13:32:03] DEBUG[12215] pbx.c: Launching 'Set'
[Sep 13 13:32:03] VERBOSE[12215] logger.c:     -- Executing [2017@incoming-intertel:3] Set("Zap/21-1", "CALLERID(num)=ISHERINT") in new stack
[Sep 13 13:32:03] DEBUG[12215] pbx.c: Launching 'Set'
[Sep 13 13:32:03] VERBOSE[12215] logger.c:     -- Executing [2017@incoming-intertel:4] Set("Zap/21-1", "CALLERID(name)=ISHERINT") in new stack
[Sep 13 13:32:03] DEBUG[12215] pbx.c: Launching 'Dial'
[Sep 13 13:32:03] VERBOSE[12215] logger.c:     -- Executing [2017@incoming-intertel:5] Dial("Zap/21-1", "IAX2/buffalo_cc/2017") in new stack
[Sep 13 13:32:03] DEBUG[12215] chan_iax2.c: Made call 5 into trunk call 16399
[Sep 13 13:32:03] DEBUG[12215] rtp.c: Channel 'IAX2/buffalo_cc-16399' has no RTP, not doing anything
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable STACK-incoming-intertel-2017-5.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable STACK-incoming-intertel-2017-4.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable STACK-incoming-intertel-2017-3.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Copying hard-transferable variable MONITORED.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable STACK-incoming-intertel-2017-2.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable RECORDED_FILE.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable STACK-incoming-intertel-2017-1.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable CALLEDTON.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable ANI2.
[Sep 13 13:32:03] DEBUG[12215] channel.c: Not copying variable TRANSFERCAPABILITY.
[Sep 13 13:32:03] DEBUG[12215] devicestate.c: Notification of state change to be queued on device/channel IAX2/buffalo_cc-16399
[Sep 13 13:32:03] VERBOSE[12215] logger.c:     -- Called buffalo_cc/2017
[Sep 13 13:32:03] VERBOSE[12215] logger.c:     -- IAX2/buffalo_cc-16399 is ringing
[Sep 13 13:32:03] DEBUG[12215] rtp.c: Channel 'Zap/21-1' has no RTP, not doing anything
[Sep 13 13:32:03] DEBUG[12215] chan_zap.c: Requested indication 3 on channel Zap/21-1
[Sep 13 13:32:03] DEBUG[12215] devicestate.c: Notification of state change to be queued on device/channel Zap/21-1
[Sep 13 13:32:10] VERBOSE[12215] logger.c:     -- IAX2/buffalo_cc-16399 stopped sounds
[Sep 13 13:32:10] DEBUG[12215] chan_zap.c: Requested indication -1 on channel Zap/21-1
[Sep 13 13:32:10] DEBUG[12215] devicestate.c: Notification of state change to be queued on device/channel IAX2/buffalo_cc-16399
[Sep 13 13:32:10] VERBOSE[12215] logger.c:     -- IAX2/buffalo_cc-16399 answered Zap/21-1
[Sep 13 13:32:10] DEBUG[12215] rtp.c: Channel 'Zap/21-1' has no RTP, not doing anything
[Sep 13 13:32:10] DEBUG[12215] devicestate.c: Notification of state change to be queued on device/channel Zap/21-1
[Sep 13 13:32:22] DEBUG[12215] channel.c: Didn't get a frame from channel: Zap/21-1
[Sep 13 13:32:22] DEBUG[12215] channel.c: Bridge stops bridging channels Zap/21-1 and IAX2/buffalo_cc-16399
[Sep 13 13:32:22] DEBUG[12215] channel.c: Hanging up channel 'IAX2/buffalo_cc-16399'
[Sep 13 13:32:22] DEBUG[12215] chan_iax2.c: We're hanging up IAX2/buffalo_cc-16399 now...
[Sep 13 13:32:22] VERBOSE[12215] logger.c:     -- Hungup 'IAX2/buffalo_cc-16399'
[Sep 13 13:32:22] DEBUG[12215] devicestate.c: Notification of state change to be queued on device/channel IAX2/buffalo_cc-16399
[Sep 13 13:32:22] DEBUG[12215] rtp.c: Channel 'Zap/21-1' has no RTP, not doing anything
[Sep 13 13:32:22] DEBUG[12215] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[Sep 13 13:32:22] DEBUG[12215] pbx.c: Spawn extension (incoming-intertel,2017,5) exited non-zero on 'Zap/21-1'
[Sep 13 13:32:22] VERBOSE[12215] logger.c:   == Spawn extension (incoming-intertel, 2017, 5) exited non-zero on 'Zap/21-1'
[Sep 13 13:32:22] DEBUG[12215] channel.c: Soft-Hanging up channel 'Zap/21-1'
[Sep 13 13:32:22] DEBUG[12215] channel.c: Hanging up channel 'Zap/21-1'
[Sep 13 13:32:22] DEBUG[12215] chan_zap.c: zt_hangup(Zap/21-1)
[Sep 13 13:32:22] DEBUG[12215] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/21-1
[Sep 13 13:32:22] DEBUG[12215] chan_zap.c: Hangup: channel: 21 index = 0, normal = 32, callwait = -1, thirdcall = -1
[Sep 13 13:32:22] DEBUG[12215] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Sep 13 13:32:22] DEBUG[12215] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/21-1
[Sep 13 13:32:22] DEBUG[12215] chan_zap.c: Updated conferencing on 21, with 0 conference users
[Sep 13 13:32:22] DEBUG[12215] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/21-1
[Sep 13 13:32:22] VERBOSE[12215] logger.c:     -- Hungup 'Zap/21-1'
[Sep 13 13:32:22] DEBUG[12215] devicestate.c: Notification of state change to be queued on device/channel Zap/21-1
[Sep 13 13:32:22] DEBUG[12215] cdr_pgsql.c: cdr_pgsql: inserting a CDR record.
[Sep 13 13:32:22] DEBUG[12215] cdr_pgsql.c: cdr_pgsql: SQL command executed:  INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2007-09-13 13:32:03','"ISHERINT" <ISHERINT>','ISHERINT','2017','incoming-intertel', 'Zap/21-1','IAX2/buffalo_cc-16399','Dial','IAX2/buffalo_cc/2017',19,12,'ANSWERED',3,'','1189686723.342','')