[Jul 23 11:50:53] VERBOSE[23924] logger.c:     -- Remote UNIX connection
[Jul 23 11:50:53] DEBUG[24158] channel.c: Soft-Hanging up channel 'IAX2/mgrs-4e1-9655'
[Jul 23 11:50:53] DEBUG[32546] channel.c: Returning from native bridge, channels: IAX2/officeUsievicha-16276, IAX2/mgrs-4e1-9655
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Launching 'Macro'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Executing [h@from-internal:1] Macro("IAX2/officeUsievicha-16276", "hangupcall") in new stack
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Launching 'ResetCDR'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Executing [s@macro-hangupcall:1] ResetCDR("IAX2/officeUsievicha-16276", "w") in new stack
[Jul 23 11:50:53] DEBUG[32546] cdr_addon_mysql.c: Inserting a CDR record.
[Jul 23 11:50:53] DEBUG[32546] cdr_addon_mysql.c: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid,userfield) VALUES ('2009-07-23 08:11:27','1110','1110','591110','from-internal','IAX2/officeUsievicha-16276','','ResetCDR','w','13166','13200','ANSWERED','3','','1248322287.784','')
[Jul 23 11:50:53] DEBUG[32546] app_macro.c: Executed application: ResetCDR
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Launching 'NoCDR'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Executing [s@macro-hangupcall:2] NoCDR("IAX2/officeUsievicha-16276", "") in new stack
[Jul 23 11:50:53] DEBUG[32546] app_macro.c: Executed application: NoCDR
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Expression result is '1'
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Launching 'GotoIf'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Executing [s@macro-hangupcall:3] GotoIf("IAX2/officeUsievicha-16276", "1?skiprg") in new stack
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Goto (macro-hangupcall,s,6)
[Jul 23 11:50:53] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Expression result is '1'
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Launching 'GotoIf'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Executing [s@macro-hangupcall:6] GotoIf("IAX2/officeUsievicha-16276", "1?skipblkvm") in new stack
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Goto (macro-hangupcall,s,9)
[Jul 23 11:50:53] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Expression result is '1'
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Launching 'GotoIf'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Executing [s@macro-hangupcall:9] GotoIf("IAX2/officeUsievicha-16276", "1?theend") in new stack
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Goto (macro-hangupcall,s,11)
[Jul 23 11:50:53] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Jul 23 11:50:53] DEBUG[32546] pbx.c: Launching 'Hangup'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Executing [s@macro-hangupcall:11] Hangup("IAX2/officeUsievicha-16276", "") in new stack
[Jul 23 11:50:53] VERBOSE[32546] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'IAX2/officeUsievicha-16276' in macro 'hangupcall'
[Jul 23 11:50:53] DEBUG[32546] features.c: Spawn extension (from-internal,h,1) exited non-zero on 'IAX2/officeUsievicha-16276'
[Jul 23 11:50:53] VERBOSE[32546] logger.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'IAX2/officeUsievicha-16276'
[Jul 23 11:50:53] VERBOSE[32546] features.c: putting peer IAX2/officeUsievicha-16276 into PBX again
[Jul 23 11:50:53] DEBUG[32546] channel.c: Hanging up channel 'IAX2/mgrs-4e1-9655'
[Jul 23 11:50:53] DEBUG[32546] chan_iax2.c: We're hanging up IAX2/mgrs-4e1-9655 now...
[Jul 23 11:50:53] DEBUG[32546] chan_iax2.c: Really destroying IAX2/mgrs-4e1-9655 now...
[Jul 23 11:50:53] VERBOSE[32546] logger.c:     -- Hungup 'IAX2/mgrs-4e1-9655'
[Jul 23 11:50:53] DEBUG[24159] pbx.c: Launching 'Playback'
[Jul 23 11:50:53] VERBOSE[24159] logger.c:     -- Executing [591110@from-internal:6] Playback("IAX2/officeUsievicha-16276", "vm-goodbye") in new stack
[Jul 23 11:50:53] DEBUG[24159] channel.c: Set channel IAX2/officeUsievicha-16276 to write format gsm
[Jul 23 11:50:53] DEBUG[24159] channel.c: Scheduling timer at 160 sample intervals
[Jul 23 11:50:53] VERBOSE[24159] logger.c:     -- <IAX2/officeUsievicha-16276> Playing 'vm-goodbye.gsm' (language 'en')
[Jul 23 11:50:53] VERBOSE[24158] logger.c:     -- Remote UNIX connection disconnected
[Jul 23 11:50:54] DEBUG[23943] chan_iax2.c: Packet arrived out of order (expecting 177, got 176) (frametype = 2, subclass = 8)
[Jul 23 11:50:54] DEBUG[23943] chan_iax2.c: Acking anyway
[Jul 23 11:50:54] DEBUG[24159] channel.c: Scheduling timer at 0 sample intervals
[Jul 23 11:50:54] DEBUG[24159] channel.c: Scheduling timer at 0 sample intervals
[Jul 23 11:50:54] DEBUG[24159] channel.c: Set channel IAX2/officeUsievicha-16276 to write format slin
[Jul 23 11:50:54] DEBUG[24159] pbx.c: Launching 'Hangup'
[Jul 23 11:50:54] VERBOSE[24159] logger.c:     -- Executing [591110@from-internal:7] Hangup("IAX2/officeUsievicha-16276", "") in new stack
[Jul 23 11:50:54] DEBUG[24159] pbx.c: Spawn extension (from-internal,591110,7) exited non-zero on 'IAX2/officeUsievicha-16276'
[Jul 23 11:50:54] VERBOSE[24159] logger.c:   == Spawn extension (from-internal, 591110, 7) exited non-zero on 'IAX2/officeUsievicha-16276'
[Jul 23 11:50:54] DEBUG[24159] channel.c: Soft-Hanging up channel 'IAX2/officeUsievicha-16276'
[Jul 23 11:50:54] DEBUG[24159] channel.c: Hanging up channel 'IAX2/officeUsievicha-16276'
[Jul 23 11:50:54] DEBUG[24159] chan_iax2.c: We're hanging up IAX2/officeUsievicha-16276 now...
[Jul 23 11:50:54] DEBUG[24159] chan_iax2.c: Really destroying IAX2/officeUsievicha-16276 now...
[Jul 23 11:50:54] VERBOSE[24159] logger.c:     -- Hungup 'IAX2/officeUsievicha-16276'