[root@asterisk1 libunicall-0.0.3]# cat testcall.conf destination-no 4132185900 protocol-class mfcr2 protocol-variant br,20,4 protocol-end co on-offered accept circuits 1-10 [root@asterisk1 libunicall-0.0.3]# ./testcall Chan 1, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185900' Chan 2, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185901' Chan 3, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185902' Chan 4, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185903' Chan 5, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185904' Chan 6, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185905' Chan 7, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185906' Chan 8, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185907' Chan 9, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185908' Chan 10, class 'mfcr2', variant 'br,20,4', end 2, caller 0, from '' to '4132185909' Loading protocol mfcr2 Thread for channel 0 Thread for channel 1 Thread for channel 2 Thread for channel 3 Thread for channel 4 MFC/R2 Chan 1: Call control(9) MFC/R2 Chan 1: Unblock MFC/R2 Chan 1: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 2: Call control(9) MFC/R2 Chan 2: Unblock MFC/R2 Chan 2: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 3: Call control(9) MFC/R2 Chan 3: Unblock MFC/R2 Chan 3: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 4: Call control(9) MFC/R2 Chan 4: Unblock MFC/R2 Chan 4: 1001 -> [1/BLOCKED /Idle /Idle ] Thread for channel 5 Thread for channel 6 Thread for channel 7 Thread for channel 8 Thread for channel 9 MFC/R2 Chan 5: Call control(9) MFC/R2 Chan 5: Unblock MFC/R2 Chan 5: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 6: Call control(9) MFC/R2 Chan 6: Unblock MFC/R2 Chan 6: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 7: Call control(9) MFC/R2 Chan 7: Unblock MFC/R2 Chan 7: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 8: Call control(9) MFC/R2 Chan 8: Unblock MFC/R2 Chan 8: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 9: Call control(9) MFC/R2 Chan 9: Unblock MFC/R2 Chan 9: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 10: Call control(9) MFC/R2 Chan 10: Unblock MFC/R2 Chan 10: 1001 -> [1/BLOCKED /Idle /Idle ] MFC/R2 Chan 1: far_unblocking_expired MFC/R2 Chan 1: local_unblocking_expired Chan 1: -- Far end unblocked! :-) Chan 1: -- Far end unblocked! :-) Chan 1: -- Local end unblocked! :-) Chan 1: -- Local end unblocked! :-) MFC/R2 Chan 2: far_unblocking_expired MFC/R2 Chan 2: local_unblocking_expired Chan 2: -- Far end unblocked! :-) Chan 2: -- Far end unblocked! :-) Chan 2: -- Local end unblocked! :-) Chan 2: -- Local end unblocked! :-) MFC/R2 Chan 3: far_unblocking_expired MFC/R2 Chan 3: local_unblocking_expired Chan 3: -- Far end unblocked! :-) Chan 3: -- Far end unblocked! :-) Chan 3: -- Local end unblocked! :-) Chan 3: -- Local end unblocked! :-) MFC/R2 Chan 4: far_unblocking_expired MFC/R2 Chan 4: local_unblocking_expired Chan 4: -- Far end unblocked! :-) Chan 4: -- Far end unblocked! :-) Chan 4: -- Local end unblocked! :-) Chan 4: -- Local end unblocked! :-) MFC/R2 Chan 5: far_unblocking_expired MFC/R2 Chan 5: local_unblocking_expired Chan 5: -- Far end unblocked! :-) Chan 5: -- Far end unblocked! :-) Chan 5: -- Local end unblocked! :-) Chan 5: -- Local end unblocked! :-) MFC/R2 Chan 6: far_unblocking_expired MFC/R2 Chan 6: local_unblocking_expired Chan 6: -- Far end unblocked! :-) Chan 6: -- Far end unblocked! :-) Chan 6: -- Local end unblocked! :-) Chan 6: -- Local end unblocked! :-) MFC/R2 Chan 7: far_unblocking_expired MFC/R2 Chan 7: local_unblocking_expired Chan 7: -- Far end unblocked! :-) Chan 7: -- Far end unblocked! :-) Chan 7: -- Local end unblocked! :-) Chan 7: -- Local end unblocked! :-) MFC/R2 Chan 8: far_unblocking_expired MFC/R2 Chan 8: local_unblocking_expired Chan 8: -- Far end unblocked! :-) Chan 8: -- Far end unblocked! :-) Chan 8: -- Local end unblocked! :-) Chan 8: -- Local end unblocked! :-) MFC/R2 Chan 9: far_unblocking_expired MFC/R2 Chan 9: local_unblocking_expired Chan 9: -- Far end unblocked! :-) Chan 9: -- Far end unblocked! :-) Chan 9: -- Local end unblocked! :-) Chan 9: -- Local end unblocked! :-) MFC/R2 Chan 10: far_unblocking_expired MFC/R2 Chan 10: local_unblocking_expired Chan 10: -- Far end unblocked! :-) Chan 10: -- Far end unblocked! :-) Chan 10: -- Local end unblocked! :-) Chan 10: -- Local end unblocked! :-) Main thread Main thread Main thread zaptel.conf # MFC/R2 does not normally use CRC4 loadzone = br defaultzone = br span=1,1,0,cas,hdb3 span=2,2,0,cas,hdb3 span=3,3,0,cas,hdb3 span=4,4,0,cas,hdb3 # cas=1-15:1101 cas=17-31:1101 # cas=32-46:1101 cas=48-62:1101 # cas=63-77:1101 cas=79-93:1101 # unused=94-124 # Global data unicall.conf [channels] language=br context=from-pstn usecallerid=yes hidecallerid=no immediate=no callwaitingcallerid=yes threewaycalling=yes transfer=yes cancallforward=yes callreturn=yes echocancel=yes echocancelwhenbridged=yes rxgain=0.0 txgain=0.0 faxdetect=both loglevel=0 protocolclass=mfcr2 protocolvariant=br,20,4 protocolend=co group=1 callerid=asreceived channel=>1-15 channel=>17-31 channel=>32-46 channel=>48-62 channel=>63-77 channel=>79-93 [Dec 11 10:03:29] VERBOSE[12896] logger.c: Asterisk Ready. [Dec 11 10:03:29] NOTICE[12925] chan_sip.c: Peer '2290' is now Reachable. (2ms / 2000ms) [Dec 11 10:03:29] NOTICE[12903] chan_unicall.c: Unicall/1 event Far end unblocked [Dec 11 10:03:29] NOTICE[12903] chan_unicall.c: Unicall/1 event Local end unblocked *SNIP* [Dec 11 10:03:29] NOTICE[12903] chan_unicall.c: Unicall/93 event Far end unblocked [Dec 11 10:03:29] NOTICE[12903] chan_unicall.c: Unicall/93 event Local end unblocked [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [04188287068@from-internal:1] Macro("SIP/2290-09b18a68", "dialout-trunk|3|04188287068||") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/2290-09b18a68", "DIAL_TRUNK=3") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/2290-09b18a68", "DIAL_NUMBER=04188287068") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/2290-09b18a68", "ROUTE_PASSWD=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/2290-09b18a68", "1?noauth") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-dialout-trunk,s,6) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/2290-09b18a68", "0?disabletrunk|1") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/2290-09b18a68", "_NODEST=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/2290-09b18a68", "DIAL_TRUNK_OPTIONS=tr") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/2290-09b18a68", "GROUP()=OUT_3") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/2290-09b18a68", "user-callerid|SKIPTTL") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/2290-09b18a68", "user-callerid: device 2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Noop [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/2290-09b18a68", "AMPUSER=2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/2290-09b18a68", "0?report") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/2290-09b18a68", "0?start") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/2290-09b18a68", "REALCALLERIDNUM=2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/2290-09b18a68", "REALCALLERIDNUM is 2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: NoOp [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/2290-09b18a68", "AMPUSER=2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/2290-09b18a68", "AMPUSERCIDNAME=Testes") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/2290-09b18a68", "0?report") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/2290-09b18a68", "AMPUSERCID=2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/2290-09b18a68", "CALLERID(all)="Testes" <2290>") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/2290-09b18a68", "REALCALLERIDNUM=2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/2290-09b18a68", "TTL: ARG1: SKIPTTL") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Noop [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/2290-09b18a68", "1?continue") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-user-callerid,s,23) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/2290-09b18a68", "Using CallerID "Testes" <2290>") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: NoOp [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Macro [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/2290-09b18a68", "record-enable|2290|OUT") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/2290-09b18a68", "0?2:4") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-record-enable,s,4) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/2290-09b18a68", "recordingcheck|20071211-100351|1197374631.0") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck [Dec 11 10:03:51] VERBOSE[12935] logger.c: recordingcheck|20071211-100351|1197374631.0: Outbound recording not enabled [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- AGI Script recordingcheck completed, returning 0 [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: AGI [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-record-enable:5] NoOp("SIP/2290-09b18a68", "No recording needed") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Noop [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Macro [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/2290-09b18a68", "0?skipoutcid") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/2290-09b18a68", "DIAL_TRUNK_OPTIONS=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/2290-09b18a68", "outbound-callerid|3") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/2290-09b18a68", "1?start") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-outbound-callerid,s,3) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/2290-09b18a68", "REALCALLERIDNUM is 2290") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: NoOp [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/2290-09b18a68", "1?normcid") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-outbound-callerid,s,9) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/2290-09b18a68", "USEROUTCID=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] DEBUG[12935] db.c: Unable to find key '2290/emergency_cid' in family 'DEVICE' [Dec 11 10:03:51] DEBUG[12935] func_db.c: DB: DEVICE/2290/emergency_cid not found in database. [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/2290-09b18a68", "EMERGENCYCID=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/2290-09b18a68", "TRUNKOUTCID=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/2290-09b18a68", "1?trunkcid") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-outbound-callerid,s,16) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/2290-09b18a68", "1?usercid") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-outbound-callerid,s,18) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/2290-09b18a68", "1?report") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-outbound-callerid,s,22) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/2290-09b18a68", "CallerID set to "Testes" <2290>") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: NoOp [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Macro [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/2290-09b18a68", "0?nomax") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:16] GotoIf("SIP/2290-09b18a68", "0?chanfull") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/2290-09b18a68", "fixlocalprefix") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix [Dec 11 10:03:51] VERBOSE[12935] logger.c: > fixlocalprefix: Using pattern XXXXXXXX [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- AGI Script fixlocalprefix completed, returning 0 [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: AGI [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/2290-09b18a68", "OUTNUM=04188287068") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/2290-09b18a68", "custom=AMP") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/2290-09b18a68", "1?gocall") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-dialout-trunk,s,24) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/2290-09b18a68", "1?customtrunk") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-dialout-trunk,s,27) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:27] Set("SIP/2290-09b18a68", "pre_num=AMP:UniCall/g1") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:28] Set("SIP/2290-09b18a68", "the_num=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:29] Set("SIP/2290-09b18a68", "post_num=") in new stack [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: Set [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:30] GotoIf("SIP/2290-09b18a68", "0?outnum:skipoutnum") in new stack [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Goto (macro-dialout-trunk,s,32) [Dec 11 10:03:51] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:32] Dial("SIP/2290-09b18a68", "UniCall/g1|300|") in new stack [Dec 11 10:03:51] DEBUG[12935] chan_unicall.c: unicall_call called - 'g1' [Dec 11 10:03:51] DEBUG[12935] chan_unicall.c: unicall_call caller id - '2290' [Dec 11 10:03:51] VERBOSE[12935] logger.c: -- Called g1 [Dec 11 10:03:51] NOTICE[12935] chan_unicall.c: Unicall/1 event Dialing [Dec 11 10:03:54] NOTICE[12935] chan_unicall.c: Unicall/1 event Protocol failure [Dec 11 10:03:54] ERROR[12935] chan_unicall.c: Unicall/1 protocol error. Cause 32776 [Dec 11 10:03:54] DEBUG[12935] chan_unicall.c: disabled echo cancellation on channel 1 [Dec 11 10:03:54] WARNING[12935] app_dial.c: Unable to forward voice or dtmf [Dec 11 10:03:54] DEBUG[12935] chan_unicall.c: Hangup: channel: 1 index = 0, normal = 10, callwait = -1, thirdcall = -1 [Dec 11 10:03:54] DEBUG[12935] chan_unicall.c: Updated conferencing on 1, with 0 conference users [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Hungup 'UniCall/1-1' [Dec 11 10:03:54] VERBOSE[12935] logger.c: == Everyone is busy/congested at this time (1:0/0/1) [Dec 11 10:03:54] DEBUG[12935] app_macro.c: Executed application: Dial [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Executing [s@macro-dialout-trunk:33] Goto("SIP/2290-09b18a68", "s-CHANUNAVAIL|1") in new stack [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,1) [Dec 11 10:03:54] DEBUG[12935] app_macro.c: Executed application: Goto [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] GotoIf("SIP/2290-09b18a68", "1?noreport") in new stack [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Goto (macro-dialout-trunk,s-CHANUNAVAIL,3) [Dec 11 10:03:54] DEBUG[12935] app_macro.c: Executed application: GotoIf [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Executing [s-CHANUNAVAIL@macro-dialout-trunk:3] NoOp("SIP/2290-09b18a68", "TRUNK Dial failed due to CHANUNAVAIL - failing through to other trunks") in new stack [Dec 11 10:03:54] DEBUG[12935] app_macro.c: Executed application: Noop [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Executing [04188287068@from-internal:2] Macro("SIP/2290-09b18a68", "outisbusy|") in new stack [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Executing [s@macro-outisbusy:1] Playback("SIP/2290-09b18a68", "all-circuits-busy-now|noanswer") in new stack [Dec 11 10:03:54] VERBOSE[12935] logger.c: -- Playing 'all-circuits-busy-now' (language 'en') [Dec 11 10:03:55] VERBOSE[12935] logger.c: == Spawn extension (macro-outisbusy, s, 1) exited non-zero on 'SIP/2290-09b18a68' in macro 'outisbusy' [Dec 11 10:03:55] VERBOSE[12935] logger.c: == Spawn extension (macro-outisbusy, s, 1) exited non-zero on 'SIP/2290-09b18a68'