[Apr 3 20:52:29] VERBOSE[22851] logger.c: dialparties.agi: Caller ID name is 'unknown' number is 'unknown' [Apr 3 20:52:29] VERBOSE[22851] logger.c: dialparties.agi: Methodology of ring is 'none' [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- dialparties.agi: Added extension 2214 to extension map [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- dialparties.agi: Extension 2214 cf is disabled [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- dialparties.agi: Extension 2214 do not disturb is disabled [Apr 3 20:52:29] VERBOSE[22851] logger.c: dialparties.agi: Extension 2214 has ExtensionState: 0 [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 2214 [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- dialparties.agi: DbDel CALLTRACE/2214 - Caller ID is not defined [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- AGI Script dialparties.agi completed, returning 0 [Apr 3 20:52:29] DEBUG[22851] app_macro.c: Executed application: AGI [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- Executing [s@macro-dial:10] Dial("Zap/2-1", "SIP/2214||tr") in new stack [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- Called 2214 [Apr 3 20:52:29] VERBOSE[22854] logger.c: == Manager 'admin' logged off from 127.0.0.1 [Apr 3 20:52:29] VERBOSE[22851] logger.c: -- SIP/2214-09133098 is ringing [Apr 3 20:52:31] NOTICE[21276] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:52:31] NOTICE[21270] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:52:41] VERBOSE[22851] logger.c: -- SIP/2214-09133098 answered Zap/2-1 [Apr 3 20:53:26] NOTICE[21276] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:53:26] NOTICE[21273] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:54:04] VERBOSE[21272] logger.c: -- Registered IAX2 '4481' (AUTHENTICATED) at 192.168.91.1:5631 [Apr 3 20:54:04] NOTICE[21272] chan_iax2.c: Still have a callno... [Apr 3 20:54:17] NOTICE[21271] chan_iax2.c: Peer '3361' is now UNREACHABLE! Time: 600 [Apr 3 20:54:21] NOTICE[21267] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:54:21] NOTICE[21276] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:54:38] VERBOSE[21250] logger.c: -- Remote UNIX connection [Apr 3 20:54:47] VERBOSE[22851] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/2-1' in macro 'dial' [Apr 3 20:54:47] VERBOSE[22851] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/2-1' in macro 'exten-vm' [Apr 3 20:54:47] VERBOSE[22851] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'Zap/2-1' [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Executing [h@macro-dial:1] Macro("Zap/2-1", "hangupcall") in new stack [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("Zap/2-1", "w") in new stack [Apr 3 20:54:47] DEBUG[22851] app_macro.c: Executed application: ResetCDR [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("Zap/2-1", "") in new stack [Apr 3 20:54:47] DEBUG[22851] app_macro.c: Executed application: NoCDR [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("Zap/2-1", "1?skiprg") in new stack [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Goto (macro-hangupcall,s,6) [Apr 3 20:54:47] DEBUG[22851] app_macro.c: Executed application: GotoIf [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("Zap/2-1", "1?skipblkvm") in new stack [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Goto (macro-hangupcall,s,9) [Apr 3 20:54:47] DEBUG[22851] app_macro.c: Executed application: GotoIf [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("Zap/2-1", "1?theend") in new stack [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Goto (macro-hangupcall,s,11) [Apr 3 20:54:47] DEBUG[22851] app_macro.c: Executed application: GotoIf [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("Zap/2-1", "") in new stack [Apr 3 20:54:47] VERBOSE[22851] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/2-1' in macro 'hangupcall' [Apr 3 20:54:47] VERBOSE[22851] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'Zap/2-1' [Apr 3 20:54:47] VERBOSE[22851] logger.c: -- Hungup 'Zap/2-1' [Apr 3 20:55:01] VERBOSE[21250] logger.c: -- Remote UNIX connection [Apr 3 20:55:01] VERBOSE[22890] logger.c: -- Remote UNIX connection disconnected [Apr 3 20:55:04] VERBOSE[21274] logger.c: -- Registered IAX2 '4481' (AUTHENTICATED) at 192.168.91.1:7706 [Apr 3 20:55:16] NOTICE[21276] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:55:16] NOTICE[21275] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:56:04] VERBOSE[21267] logger.c: -- Registered IAX2 '4481' (AUTHENTICATED) at 192.168.91.1:8183 [Apr 3 20:56:11] NOTICE[21271] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:56:11] NOTICE[21276] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:56:27] NOTICE[21268] chan_iax2.c: Peer '3361' is now REACHABLE! Time: 417 [Apr 3 20:57:06] NOTICE[21276] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:57:06] NOTICE[21270] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:57:31] VERBOSE[21271] logger.c: -- Accepting AUTHENTICATED call from 192.168.91.1: > requested format = ulaw, > requested prefs = (), > actual format = ulaw, > host prefs = (ulaw|alaw|gsm), > priority = mine [Apr 3 20:57:31] VERBOSE[22894] logger.c: -- Executing [72550394@from-internal:1] Macro("IAX2/4461-1", "pinsets|2|1") in new stack [Apr 3 20:57:31] VERBOSE[22894] logger.c: -- Executing [s@macro-pinsets:1] GotoIf("IAX2/4461-1", "1 = 1?cdr|1") in new stack [Apr 3 20:57:31] VERBOSE[22894] logger.c: -- Goto (macro-pinsets,cdr,1) [Apr 3 20:57:31] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:31] VERBOSE[22894] logger.c: -- Executing [cdr@macro-pinsets:1] Authenticate("IAX2/4461-1", "/etc/asterisk/pinset_2|a") in new stack [Apr 3 20:57:31] VERBOSE[22894] logger.c: -- Playing 'agent-pass' (language 'en') [Apr 3 20:57:38] WARNING[22894] app_authenticate.c: EEEEEEEEEEEEEEEEEEEELASTIX: The passwords '4384' and '4384' are equal [Apr 3 20:57:38] VERBOSE[22894] logger.c: -- Playing 'auth-thankyou' (language 'en') [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Authenticate [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [72550394@from-internal:2] Macro("IAX2/4461-1", "dialout-trunk|4|72550394||") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("IAX2/4461-1", "DIAL_TRUNK=4") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("IAX2/4461-1", "DIAL_NUMBER=72550394") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("IAX2/4461-1", "ROUTE_PASSWD=") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("IAX2/4461-1", "1?noauth") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-dialout-trunk,s,6) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("IAX2/4461-1", "0?disabletrunk|1") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("IAX2/4461-1", "_NODEST=") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("IAX2/4461-1", "DIAL_TRUNK_OPTIONS=tr") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("IAX2/4461-1", "GROUP()=OUT_4") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("IAX2/4461-1", "user-callerid|SKIPTTL") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("IAX2/4461-1", "user-callerid: device 4461") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Noop [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:2] Set("IAX2/4461-1", "AMPUSER=4461") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("IAX2/4461-1", "0?report") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("IAX2/4461-1", "1?start") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-user-callerid,s,6) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("IAX2/4461-1", "REALCALLERIDNUM is 4461") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: NoOp [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:7] Set("IAX2/4461-1", "AMPUSER=4461") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:8] Set("IAX2/4461-1", "AMPUSERCIDNAME=Term. CBBA") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("IAX2/4461-1", "0?report") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:10] Set("IAX2/4461-1", "AMPUSERCID=4461") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:11] Set("IAX2/4461-1", "CALLERID(all)="Term. CBBA" <4461>") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:12] Set("IAX2/4461-1", "REALCALLERIDNUM=4461") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("IAX2/4461-1", "TTL: ARG1: SKIPTTL") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Noop [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("IAX2/4461-1", "1?continue") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-user-callerid,s,23) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("IAX2/4461-1", "Using CallerID "Term. CBBA" <4461>") in new sta ck [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: NoOp [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Macro [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("IAX2/4461-1", "record-enable|4461|OUT") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("IAX2/4461-1", "0?2:4") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-record-enable,s,4) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-record-enable:4] AGI("IAX2/4461-1", "recordingcheck|20080403-205739|1207270651.457") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck [Apr 3 20:57:39] VERBOSE[22894] logger.c: recordingcheck|20080403-205739|1207270651.457: Outbound recording not enabled [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- AGI Script recordingcheck completed, returning 0 [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: AGI [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-record-enable:5] NoOp("IAX2/4461-1", "No recording needed") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Noop [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Macro [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("IAX2/4461-1", "0?skipoutcid") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("IAX2/4461-1", "DIAL_TRUNK_OPTIONS=") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("IAX2/4461-1", "outbound-callerid|4") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("IAX2/4461-1", "1?start") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-outbound-callerid,s,3) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("IAX2/4461-1", "REALCALLERIDNUM is 4461") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: NoOp [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("IAX2/4461-1", "1?normcid") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-outbound-callerid,s,9) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("IAX2/4461-1", "USEROUTCID=") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] DEBUG[22894] func_db.c: DB: DEVICE/4461/emergency_cid not found in database. [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("IAX2/4461-1", "EMERGENCYCID=") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("IAX2/4461-1", "TRUNKOUTCID=") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("IAX2/4461-1", "1?trunkcid") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-outbound-callerid,s,16) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("IAX2/4461-1", "1?usercid") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-outbound-callerid,s,18) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("IAX2/4461-1", "1?report") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-outbound-callerid,s,22) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("IAX2/4461-1", "CallerID set to "Term. CBBA" <4461>") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: NoOp [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Macro [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("IAX2/4461-1", "1?nomax") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-dialout-trunk,s,17) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("IAX2/4461-1", "fixlocalprefix") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 010|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 011|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 012|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 013|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 014|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 016|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 017|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 021|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: > fixlocalprefix: Using pattern 0|. [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- AGI Script fixlocalprefix completed, returning 0 [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: AGI [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("IAX2/4461-1", "OUTNUM=72550394") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("IAX2/4461-1", "custom=ZAP/g3") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: Set [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("IAX2/4461-1", "1?gocall") in new stack [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Goto (macro-dialout-trunk,s,24) [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("IAX2/4461-1", "0?customtrunk") in new stack [Apr 3 20:57:39] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Executing [s@macro-dialout-trunk:25] Dial("IAX2/4461-1", "ZAP/g3/72550394|300|") in new stack [Apr 3 20:57:39] DEBUG[22894] dsp.c: dsp busy pattern set to 0,0 [Apr 3 20:57:39] DEBUG[22894] chan_zap.c: Dialing '72550394' [Apr 3 20:57:39] DEBUG[22894] chan_zap.c: Deferring dialing... [Apr 3 20:57:39] VERBOSE[22894] logger.c: -- Called g3/72550394 [Apr 3 20:57:42] VERBOSE[22894] logger.c: -- Zap/7-1 answered IAX2/4461-1 [Apr 3 20:57:45] VERBOSE[22894] logger.c: -- Started music on hold, class 'default', on Zap/7-1 [Apr 3 20:57:58] VERBOSE[21271] logger.c: -- Accepting AUTHENTICATED call from 192.168.91.1: > requested format = ulaw, > requested prefs = (), > actual format = ulaw, > host prefs = (ulaw|alaw|gsm), > priority = mine [Apr 3 20:57:58] VERBOSE[22897] logger.c: -- Executing [72550394@from-internal:1] Macro("IAX2/4461-4", "pinsets|2|1") in new stack [Apr 3 20:57:58] VERBOSE[22897] logger.c: -- Executing [s@macro-pinsets:1] GotoIf("IAX2/4461-4", "1 = 1?cdr|1") in new stack [Apr 3 20:57:58] VERBOSE[22897] logger.c: -- Goto (macro-pinsets,cdr,1) [Apr 3 20:57:58] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:57:58] VERBOSE[22897] logger.c: -- Executing [cdr@macro-pinsets:1] Authenticate("IAX2/4461-4", "/etc/asterisk/pinset_2|a") in new stack [Apr 3 20:57:58] VERBOSE[22897] logger.c: -- Playing 'agent-pass' (language 'en') [Apr 3 20:57:58] DEBUG[22894] chan_zap.c: Ignoring Polarity switch to IDLE on channel 7, state 6 [Apr 3 20:57:58] DEBUG[22894] chan_zap.c: Polarity Reversal event occured - DEBUG 2: channel 7, state 6, pol= 0, aonp= 0, honp= 0, pdelay= 600, tv= 384868441 [Apr 3 20:58:01] NOTICE[21275] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:58:01] NOTICE[21269] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:58:04] WARNING[22897] app_authenticate.c: EEEEEEEEEEEEEEEEEEEELASTIX: The passwords '4384' and '4384' are equals [Apr 3 20:58:04] VERBOSE[22897] logger.c: -- Playing 'auth-thankyou' (language 'en') [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Authenticate [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [72550394@from-internal:2] Macro("IAX2/4461-4", "dialout-trunk|4|72550394||") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("IAX2/4461-4", "DIAL_TRUNK=4") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("IAX2/4461-4", "DIAL_NUMBER=72550394") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("IAX2/4461-4", "ROUTE_PASSWD=") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("IAX2/4461-4", "1?noauth") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-dialout-trunk,s,6) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("IAX2/4461-4", "0?disabletrunk|1") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("IAX2/4461-4", "_NODEST=") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("IAX2/4461-4", "DIAL_TRUNK_OPTIONS=tr") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("IAX2/4461-4", "GROUP()=OUT_4") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("IAX2/4461-4", "user-callerid|SKIPTTL") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("IAX2/4461-4", "user-callerid: device 4461") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Noop [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:2] Set("IAX2/4461-4", "AMPUSER=4461") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("IAX2/4461-4", "0?report") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("IAX2/4461-4", "1?start") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-user-callerid,s,6) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("IAX2/4461-4", "REALCALLERIDNUM is 4461") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: NoOp [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:7] Set("IAX2/4461-4", "AMPUSER=4461") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:8] Set("IAX2/4461-4", "AMPUSERCIDNAME=Term. CBBA") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("IAX2/4461-4", "0?report") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:10] Set("IAX2/4461-4", "AMPUSERCID=4461") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:11] Set("IAX2/4461-4", "CALLERID(all)="Term. CBBA" <4461>") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:12] Set("IAX2/4461-4", "REALCALLERIDNUM=4461") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("IAX2/4461-4", "TTL: ARG1: SKIPTTL") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Noop [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("IAX2/4461-4", "1?continue") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-user-callerid,s,23) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("IAX2/4461-4", "Using CallerID "Term. CBBA" <4461>") in new sta ck [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: NoOp [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Macro [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("IAX2/4461-4", "record-enable|4461|OUT") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("IAX2/4461-4", "0?2:4") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-record-enable,s,4) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-record-enable:4] AGI("IAX2/4461-4", "recordingcheck|20080403-205805|1207270678.459") i n new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck [Apr 3 20:58:05] VERBOSE[22897] logger.c: recordingcheck|20080403-205805|1207270678.459: Outbound recording not enabled [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- AGI Script recordingcheck completed, returning 0 [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: AGI [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-record-enable:5] NoOp("IAX2/4461-4", "No recording needed") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Noop [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Macro [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("IAX2/4461-4", "0?skipoutcid") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("IAX2/4461-4", "DIAL_TRUNK_OPTIONS=") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("IAX2/4461-4", "outbound-callerid|4") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("IAX2/4461-4", "1?start") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-outbound-callerid,s,3) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("IAX2/4461-4", "REALCALLERIDNUM is 4461") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: NoOp [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("IAX2/4461-4", "1?normcid") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-outbound-callerid,s,9) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("IAX2/4461-4", "USEROUTCID=") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] DEBUG[22897] func_db.c: DB: DEVICE/4461/emergency_cid not found in database. [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("IAX2/4461-4", "EMERGENCYCID=") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("IAX2/4461-4", "TRUNKOUTCID=") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("IAX2/4461-4", "1?trunkcid") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-outbound-callerid,s,16) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("IAX2/4461-4", "1?usercid") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-outbound-callerid,s,18) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("IAX2/4461-4", "1?report") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-outbound-callerid,s,22) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("IAX2/4461-4", "CallerID set to "Term. CBBA" <4461>") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: NoOp [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Macro [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("IAX2/4461-4", "1?nomax") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-dialout-trunk,s,17) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("IAX2/4461-4", "fixlocalprefix") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 010|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 011|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 012|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 013|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 014|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 016|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 017|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 021|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: > fixlocalprefix: Using pattern 0|. [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- AGI Script fixlocalprefix completed, returning 0 [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: AGI [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("IAX2/4461-4", "OUTNUM=72550394") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("IAX2/4461-4", "custom=ZAP/g3") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: Set [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("IAX2/4461-4", "1?gocall") in new stack [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Goto (macro-dialout-trunk,s,24) [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("IAX2/4461-4", "0?customtrunk") in new stack [Apr 3 20:58:05] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Executing [s@macro-dialout-trunk:25] Dial("IAX2/4461-4", "ZAP/g3/72550394|300|") in new stack [Apr 3 20:58:05] DEBUG[22897] dsp.c: dsp busy pattern set to 0,0 [Apr 3 20:58:05] DEBUG[22897] chan_zap.c: Dialing '72550394' [Apr 3 20:58:05] DEBUG[22897] chan_zap.c: Deferring dialing... [Apr 3 20:58:05] VERBOSE[22897] logger.c: -- Called g3/72550394 [Apr 3 20:58:08] VERBOSE[22897] logger.c: -- Zap/8-1 answered IAX2/4461-4 [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Stopped music on hold on Zap/7-1 [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Hungup 'Zap/7-1' [Apr 3 20:58:10] VERBOSE[22894] logger.c: == Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'IAX2/4461-1' in macro 'dialout-trunk' [Apr 3 20:58:10] VERBOSE[22894] logger.c: == Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'IAX2/4461-1' [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("IAX2/4461-1", "hangupcall") in new stack [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("IAX2/4461-1", "w") in new stack [Apr 3 20:58:10] DEBUG[22894] app_macro.c: Executed application: ResetCDR [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("IAX2/4461-1", "") in new stack [Apr 3 20:58:10] DEBUG[22894] app_macro.c: Executed application: NoCDR [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("IAX2/4461-1", "1?skiprg") in new stack [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Goto (macro-hangupcall,s,6) [Apr 3 20:58:10] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("IAX2/4461-1", "1?skipblkvm") in new stack [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Goto (macro-hangupcall,s,9) [Apr 3 20:58:10] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("IAX2/4461-1", "1?theend") in new stack [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Goto (macro-hangupcall,s,11) [Apr 3 20:58:10] DEBUG[22894] app_macro.c: Executed application: GotoIf [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("IAX2/4461-1", "") in new stack [Apr 3 20:58:10] VERBOSE[22894] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'IAX2/4461-1' in macro 'hangupcall' [Apr 3 20:58:10] VERBOSE[22894] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'IAX2/4461-1' [Apr 3 20:58:10] VERBOSE[22894] logger.c: -- Hungup 'IAX2/4461-1' [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Hungup 'Zap/8-1' [Apr 3 20:58:10] VERBOSE[22897] logger.c: == Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'IAX2/4461-4' in macro 'dialout-trunk' [Apr 3 20:58:10] VERBOSE[22897] logger.c: == Spawn extension (macro-dialout-trunk, s, 25) exited non-zero on 'IAX2/4461-4' [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("IAX2/4461-4", "hangupcall") in new stack [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("IAX2/4461-4", "w") in new stack [Apr 3 20:58:10] DEBUG[22897] app_macro.c: Executed application: ResetCDR [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("IAX2/4461-4", "") in new stack [Apr 3 20:58:10] DEBUG[22897] app_macro.c: Executed application: NoCDR [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("IAX2/4461-4", "1?skiprg") in new stack [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Goto (macro-hangupcall,s,6) [Apr 3 20:58:10] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("IAX2/4461-4", "1?skipblkvm") in new stack [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Goto (macro-hangupcall,s,9) [Apr 3 20:58:10] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("IAX2/4461-4", "1?theend") in new stack [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Goto (macro-hangupcall,s,11) [Apr 3 20:58:10] DEBUG[22897] app_macro.c: Executed application: GotoIf [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("IAX2/4461-4", "") in new stack [Apr 3 20:58:10] VERBOSE[22897] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'IAX2/4461-4' in macro 'hangupcall' [Apr 3 20:58:10] VERBOSE[22897] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'IAX2/4461-4' [Apr 3 20:58:10] VERBOSE[22897] logger.c: -- Hungup 'IAX2/4461-4' [Apr 3 20:58:56] NOTICE[21273] chan_iax2.c: Restricting registration for peer '2249' to 60 seconds (requested 300) [Apr 3 20:58:56] NOTICE[21270] chan_iax2.c: Restricting registration for peer '2159' to 60 seconds (requested 300) [Apr 3 20:59:13] VERBOSE[21271] logger.c: -- Registered IAX2 '4461' (AUTHENTICATED) at 192.168.91.1:7991 [Apr 3 20:59:13] VERBOSE[22886] logger.c: -- Remote UNIX connection disconnected