Ticket #4145: asterisk-logs.txt

File asterisk-logs.txt, 30.3 kB (added by dsdee, 2 years ago)

Log file demonstrating two calls with OB-CID disappearing

Line 
1 #
2 # Using Call Manager to initiate call on x200 from 303-111-1111 to 303-222-2222
3 #
4
5 [Mar 17 07:41:09] VERBOSE[30891] manager.c:   == Manager 'dialer' logged on from 192.168.200.4
6 [Mar 17 07:41:09] VERBOSE[30892] netsock.c:   == Using SIP RTP TOS bits 184
7 [Mar 17 07:41:09] VERBOSE[30892] netsock.c:   == Using SIP RTP CoS mark 5
8 [Mar 17 07:41:09] VERBOSE[30891] manager.c:   == Manager 'dialer' logged off from 192.168.200.4
9 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [3032222222@from-internal:1] Macro("SIP/200-0000001a", "user-callerid,SKIPTTL,") in new stack
10 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/200-0000001a", "AMPUSER=3031111111") in new stack
11 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/200-0000001a", "0?report") in new stack
12 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/200-0000001a", "1?Set(REALCALLERIDNUM=3031111111)") in new stack
13 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/200-0000001a", "AMPUSER=") in new stack
14 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/200-0000001a", "AMPUSERCIDNAME=") in new stack
15 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/200-0000001a", "1?report") in new stack
16 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-user-callerid,s,9)
17 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:9] GotoIf("SIP/200-0000001a", "1?continue") in new stack
18 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-user-callerid,s,18)
19 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-user-callerid:18] NoOp("SIP/200-0000001a", "Using CallerID "" <3031111111>") in new stack
20 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [3032222222@from-internal:2] Set("SIP/200-0000001a", "_NODEST=") in new stack
21 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [3032222222@from-internal:3] Macro("SIP/200-0000001a", "record-enable,,OUT,") in new stack
22 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-record-enable:1] GotoIf("SIP/200-0000001a", "1?check") in new stack
23 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-record-enable,s,4)
24 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-record-enable:4] ExecIf("SIP/200-0000001a", "1?MacroExit()") in new stack
25 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [3032222222@from-internal:4] Macro("SIP/200-0000001a", "dialout-enum,1,3032222222,,") in new stack
26 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:1] GosubIf("SIP/200-0000001a", "0?sub-pincheck,s,1") in new stack
27 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:2] Macro("SIP/200-0000001a", "outbound-callerid,1") in new stack
28 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/200-0000001a", "0?Set(CALLERPRES()=)") in new stack
29 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/200-0000001a", "0?Set(REALCALLERIDNUM=3031111111)") in new stack
30 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/200-0000001a", "1?normcid") in new stack
31 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
32 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/200-0000001a", "USEROUTCID=") in new stack
33 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/200-0000001a", "EMERGENCYCID=") in new stack
34 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/200-0000001a", "TRUNKOUTCID=") in new stack
35 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/200-0000001a", "1?trunkcid") in new stack
36 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-outbound-callerid,s,12)
37 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/200-0000001a", "0?Set(CALLERID(all)=)") in new stack
38 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/200-0000001a", "0?Set(CALLERID(all)=)") in new stack
39 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/200-0000001a", "0?Set(CALLERID(all)=)") in new stack
40 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/200-0000001a", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
41 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:3] Set("SIP/200-0000001a", "OUTBOUND_GROUP=OUT_1") in new stack
42 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:4] GotoIf("SIP/200-0000001a", "1?nomax") in new stack
43 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-dialout-enum,s,6)
44 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:6] Set("SIP/200-0000001a", "DIAL_NUMBER=3032222222") in new stack
45 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:7] Set("SIP/200-0000001a", "DIAL_TRUNK=1") in new stack
46 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:8] ExecIf("SIP/200-0000001a", "1?AGI(fixlocalprefix)") in new stack
47 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
48 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:   == fixlocalprefix: Dialpattern 1+NXXNXXXXXX matched. 3032222222 -> 13032222222
49 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- <SIP/200-0000001a>AGI Script fixlocalprefix completed, returning 0
50 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:9] AGI("SIP/200-0000001a", "enumlookup.agi") in new stack
51 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/enumlookup.agi
52 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- enumlookup.agi: Looking up 13032222222 on e164.org via dns_get_record
53 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- enumlookup.agi: Looking up 13032222222 on e164.arpa via dns_get_record
54 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- enumlookup.agi: Looking up 13032222222 on e164.info via dns_get_record
55 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- enumlookup.agi: Setting DIALARR to
56 [Mar 17 07:41:10] VERBOSE[30893] res_agi.c:     -- <SIP/200-0000001a>AGI Script enumlookup.agi completed, returning 0
57 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-enum:10] GotoIf("SIP/200-0000001a", "1?s-CONGESTION,1") in new stack
58 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-dialout-enum,s-CONGESTION,1)
59 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s-CONGESTION@macro-dialout-enum:1] Set("SIP/200-0000001a", "RC=16") in new stack
60 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s-CONGESTION@macro-dialout-enum:2] Goto("SIP/200-0000001a", "16,1") in new stack
61 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-dialout-enum,16,1)
62 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [16@macro-dialout-enum:1] Goto("SIP/200-0000001a", "continue,1") in new stack
63 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-dialout-enum,continue,1)
64 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [continue@macro-dialout-enum:1] GotoIf("SIP/200-0000001a", "1?noreport") in new stack
65 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Goto (macro-dialout-enum,continue,3)
66 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [continue@macro-dialout-enum:3] NoOp("SIP/200-0000001a", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 16 - failing through to other trunks") in new stack
67 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [continue@macro-dialout-enum:4] Set("SIP/200-0000001a", "CALLERID(number)=") in new stack
68 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [3032222222@from-internal:5] Macro("SIP/200-0000001a", "dialout-trunk,6,3032222222,,") in new stack
69 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/200-0000001a", "DIAL_TRUNK=6") in new stack
70 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/200-0000001a", "0?sub-pincheck,s,1") in new stack
71 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/200-0000001a", "0?disabletrunk,1") in new stack
72 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/200-0000001a", "DIAL_NUMBER=3032222222") in new stack
73 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/200-0000001a", "DIAL_TRUNK_OPTIONS=tTrwW") in new stack
74 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/200-0000001a", "OUTBOUND_GROUP=OUT_6") in new stack
75 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/200-0000001a", "0?nomax") in new stack
76 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/200-0000001a", "0?chanfull") in new stack
77 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/200-0000001a", "0?skipoutcid") in new stack
78 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/200-0000001a", "DIAL_TRUNK_OPTIONS=tTwW") in new stack
79 [Mar 17 07:41:10] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/200-0000001a", "outbound-callerid,6") in new stack
80 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/200-0000001a", "0?Set(CALLERPRES()=)") in new stack
81 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/200-0000001a", "0?Set(REALCALLERIDNUM=)") in new stack
82 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/200-0000001a", "1?normcid") in new stack
83 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
84 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/200-0000001a", "USEROUTCID=") in new stack
85 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/200-0000001a", "EMERGENCYCID=") in new stack
86 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/200-0000001a", "TRUNKOUTCID=") in new stack
87 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/200-0000001a", "1?trunkcid") in new stack
88 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Goto (macro-outbound-callerid,s,12)
89 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/200-0000001a", "0?Set(CALLERID(all)=)") in new stack
90 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/200-0000001a", "0?Set(CALLERID(all)=)") in new stack
91 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/200-0000001a", "0?Set(CALLERID(all)=)") in new stack
92 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/200-0000001a", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
93 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/200-0000001a", "0?AGI(fixlocalprefix)") in new stack
94 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/200-0000001a", "OUTNUM=3032222222") in new stack
95 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/200-0000001a", "custom=SIP/Vitel-SIP-Trunk") in new stack
96 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/200-0000001a", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^)tTwW)") in new stack
97 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:16] Macro("SIP/200-0000001a", "dialout-trunk-predial-hook,") in new stack
98 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/200-0000001a", "") in new stack
99 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/200-0000001a", "0?bypass,1") in new stack
100 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/200-0000001a", "0?customtrunk") in new stack
101 [Mar 17 07:41:11] VERBOSE[30893] pbx.c:     -- Executing [s@macro-dialout-trunk:19] Dial("SIP/200-0000001a", "SIP/Vitel-SIP-Trunk/3032222222,300,tTwW") in new stack
102 [Mar 17 07:41:11] VERBOSE[30893] netsock.c:   == Using SIP RTP TOS bits 184
103 [Mar 17 07:41:11] VERBOSE[30893] netsock.c:   == Using SIP RTP CoS mark 5
104 [Mar 17 07:41:11] VERBOSE[30893] app_dial.c:     -- Called Vitel-SIP-Trunk/3032222222
105 [Mar 17 07:41:14] VERBOSE[30893] app_dial.c:     -- SIP/Vitel-SIP-Trunk-0000001b is making progress passing it to SIP/200-0000001a
106 [Mar 17 07:41:18] VERBOSE[30893] app_macro.c:   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/200-0000001a' in macro 'dialout-trunk'
107 [Mar 17 07:41:18] VERBOSE[30893] pbx.c:   == Spawn extension (from-internal, 3032222222, 5) exited non-zero on 'SIP/200-0000001a'
108 [Mar 17 07:41:18] VERBOSE[30893] pbx.c:     -- Executing [h@from-internal:1] Macro("SIP/200-0000001a", "hangupcall") in new stack
109 [Mar 17 07:41:18] VERBOSE[30893] pbx.c:     -- Executing [s@macro-hangupcall:1] NoOp("SIP/200-0000001a", "In Davids Hangupcall Routine.  Do system flag touch here") in new stack
110 [Mar 17 07:41:18] VERBOSE[30893] pbx.c:     -- Executing [s@macro-hangupcall:2] System("SIP/200-0000001a", "/bin/echo Channel SIP/200-0000001a | /usr/bin/logger -t hangupcall") in new stack
111 [Mar 17 07:41:18] VERBOSE[30893] pbx.c:     -- Executing [s@macro-hangupcall:3] System("SIP/200-0000001a", "/bin/touch /var/spool/asterisk/tmp/last-voip-call.1268833278") in new stack
112 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:     -- Executing [s@macro-hangupcall:4] GotoIf("SIP/200-0000001a", "1?skiprg") in new stack
113 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:     -- Goto (macro-hangupcall,s,7)
114 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/200-0000001a", "1?skipblkvm") in new stack
115 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:     -- Goto (macro-hangupcall,s,10)
116 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:     -- Executing [s@macro-hangupcall:10] GotoIf("SIP/200-0000001a", "1?theend") in new stack
117 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:     -- Goto (macro-hangupcall,s,12)
118 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:     -- Executing [s@macro-hangupcall:12] Hangup("SIP/200-0000001a", "") in new stack
119 [Mar 17 07:41:19] VERBOSE[30893] app_macro.c:   == Spawn extension (macro-hangupcall, s, 12) exited non-zero on 'SIP/200-0000001a' in macro 'hangupcall'
120 [Mar 17 07:41:19] VERBOSE[30893] pbx.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/200-0000001a'
121
122
123 ### Using DISA to dial out
124
125
126 [Mar 17 07:46:57] VERBOSE[8197] netsock.c:   == Using SIP RTP TOS bits 184
127 [Mar 17 07:46:57] VERBOSE[8197] netsock.c:   == Using SIP RTP CoS mark 5
128 [Mar 17 07:46:57] VERBOSE[30926] pbx.c:     -- Executing [1111@from-internal:1] Answer("SIP/200-0000001c", "") in new stack
129 [Mar 17 07:46:57] VERBOSE[30926] pbx.c:     -- Executing [1111@from-internal:2] DISA("SIP/200-0000001c", "no-password,from-internal,3031111111") in new stack
130 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [3032222222@from-internal:1] Macro("SIP/200-0000001c", "user-callerid,SKIPTTL,") in new stack
131 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/200-0000001c", "AMPUSER=3031111111") in new stack
132 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/200-0000001c", "0?report") in new stack
133 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/200-0000001c", "1?Set(REALCALLERIDNUM=3031111111)") in new stack
134 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/200-0000001c", "AMPUSER=") in new stack
135 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/200-0000001c", "AMPUSERCIDNAME=") in new stack
136 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/200-0000001c", "1?report") in new stack
137 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-user-callerid,s,9)
138 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:9] GotoIf("SIP/200-0000001c", "1?continue") in new stack
139 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-user-callerid,s,18)
140 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-user-callerid:18] NoOp("SIP/200-0000001c", "Using CallerID "" <3031111111>") in new stack
141 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [3032222222@from-internal:2] Set("SIP/200-0000001c", "_NODEST=") in new stack
142 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [3032222222@from-internal:3] Macro("SIP/200-0000001c", "record-enable,,OUT,") in new stack
143 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-record-enable:1] GotoIf("SIP/200-0000001c", "1?check") in new stack
144 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-record-enable,s,4)
145 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-record-enable:4] ExecIf("SIP/200-0000001c", "1?MacroExit()") in new stack
146 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [3032222222@from-internal:4] Macro("SIP/200-0000001c", "dialout-enum,1,3032222222,,") in new stack
147 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:1] GosubIf("SIP/200-0000001c", "0?sub-pincheck,s,1") in new stack
148 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:2] Macro("SIP/200-0000001c", "outbound-callerid,1") in new stack
149 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/200-0000001c", "0?Set(CALLERPRES()=)") in new stack
150 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/200-0000001c", "0?Set(REALCALLERIDNUM=3031111111)") in new stack
151 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/200-0000001c", "1?normcid") in new stack
152 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
153 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/200-0000001c", "USEROUTCID=") in new stack
154 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/200-0000001c", "EMERGENCYCID=") in new stack
155 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/200-0000001c", "TRUNKOUTCID=") in new stack
156 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/200-0000001c", "1?trunkcid") in new stack
157 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-outbound-callerid,s,12)
158 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/200-0000001c", "0?Set(CALLERID(all)=)") in new stack
159 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/200-0000001c", "0?Set(CALLERID(all)=)") in new stack
160 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/200-0000001c", "0?Set(CALLERID(all)=)") in new stack
161 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/200-0000001c", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
162 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:3] Set("SIP/200-0000001c", "OUTBOUND_GROUP=OUT_1") in new stack
163 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:4] GotoIf("SIP/200-0000001c", "1?nomax") in new stack
164 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-dialout-enum,s,6)
165 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:6] Set("SIP/200-0000001c", "DIAL_NUMBER=3032222222") in new stack
166 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:7] Set("SIP/200-0000001c", "DIAL_TRUNK=1") in new stack
167 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:8] ExecIf("SIP/200-0000001c", "1?AGI(fixlocalprefix)") in new stack
168 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
169 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:   == fixlocalprefix: Dialpattern 1+NXXNXXXXXX matched. 3032222222 -> 13032222222
170 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- <SIP/200-0000001c>AGI Script fixlocalprefix completed, returning 0
171 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:9] AGI("SIP/200-0000001c", "enumlookup.agi") in new stack
172 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/enumlookup.agi
173 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- enumlookup.agi: Looking up 13032222222 on e164.org via dns_get_record
174 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- enumlookup.agi: Looking up 13032222222 on e164.arpa via dns_get_record
175 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- enumlookup.agi: Looking up 13032222222 on e164.info via dns_get_record
176 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- enumlookup.agi: Setting DIALARR to
177 [Mar 17 07:47:06] VERBOSE[30926] res_agi.c:     -- <SIP/200-0000001c>AGI Script enumlookup.agi completed, returning 0
178 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-enum:10] GotoIf("SIP/200-0000001c", "1?s-CONGESTION,1") in new stack
179 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-dialout-enum,s-CONGESTION,1)
180 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s-CONGESTION@macro-dialout-enum:1] Set("SIP/200-0000001c", "RC=0") in new stack
181 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s-CONGESTION@macro-dialout-enum:2] Goto("SIP/200-0000001c", "0,1") in new stack
182 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-dialout-enum,0,1)
183 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [0@macro-dialout-enum:1] Goto("SIP/200-0000001c", "continue,1") in new stack
184 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-dialout-enum,continue,1)
185 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [continue@macro-dialout-enum:1] GotoIf("SIP/200-0000001c", "1?noreport") in new stack
186 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-dialout-enum,continue,3)
187 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [continue@macro-dialout-enum:3] NoOp("SIP/200-0000001c", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 0 - failing through to other trunks") in new stack
188 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [continue@macro-dialout-enum:4] Set("SIP/200-0000001c", "CALLERID(number)=") in new stack
189 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [3032222222@from-internal:5] Macro("SIP/200-0000001c", "dialout-trunk,6,3032222222,,") in new stack
190 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/200-0000001c", "DIAL_TRUNK=6") in new stack
191 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/200-0000001c", "0?sub-pincheck,s,1") in new stack
192 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/200-0000001c", "0?disabletrunk,1") in new stack
193 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/200-0000001c", "DIAL_NUMBER=3032222222") in new stack
194 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/200-0000001c", "DIAL_TRUNK_OPTIONS=tTrwW") in new stack
195 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/200-0000001c", "OUTBOUND_GROUP=OUT_6") in new stack
196 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/200-0000001c", "0?nomax") in new stack
197 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:8] GotoIf("SIP/200-0000001c", "0?chanfull") in new stack
198 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/200-0000001c", "0?skipoutcid") in new stack
199 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/200-0000001c", "DIAL_TRUNK_OPTIONS=tTwW") in new stack
200 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/200-0000001c", "outbound-callerid,6") in new stack
201 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/200-0000001c", "0?Set(CALLERPRES()=)") in new stack
202 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/200-0000001c", "0?Set(REALCALLERIDNUM=)") in new stack
203 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/200-0000001c", "1?normcid") in new stack
204 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
205 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/200-0000001c", "USEROUTCID=") in new stack
206 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/200-0000001c", "EMERGENCYCID=") in new stack
207 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/200-0000001c", "TRUNKOUTCID=") in new stack
208 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/200-0000001c", "1?trunkcid") in new stack
209 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Goto (macro-outbound-callerid,s,12)
210 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/200-0000001c", "0?Set(CALLERID(all)=)") in new stack
211 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/200-0000001c", "0?Set(CALLERID(all)=)") in new stack
212 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/200-0000001c", "0?Set(CALLERID(all)=)") in new stack
213 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/200-0000001c", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
214 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:12] ExecIf("SIP/200-0000001c", "0?AGI(fixlocalprefix)") in new stack
215 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/200-0000001c", "OUTNUM=3032222222") in new stack
216 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/200-0000001c", "custom=SIP/Vitel-SIP-Trunk") in new stack
217 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/200-0000001c", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^)tTwW)") in new stack
218 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:16] Macro("SIP/200-0000001c", "dialout-trunk-predial-hook,") in new stack
219 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/200-0000001c", "") in new stack
220 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:17] GotoIf("SIP/200-0000001c", "0?bypass,1") in new stack
221 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/200-0000001c", "0?customtrunk") in new stack
222 [Mar 17 07:47:06] VERBOSE[30926] pbx.c:     -- Executing [s@macro-dialout-trunk:19] Dial("SIP/200-0000001c", "SIP/Vitel-SIP-Trunk/3032222222,300,tTwW") in new stack
223 [Mar 17 07:47:06] VERBOSE[30926] netsock.c:   == Using SIP RTP TOS bits 184
224 [Mar 17 07:47:06] VERBOSE[30926] netsock.c:   == Using SIP RTP CoS mark 5
225 [Mar 17 07:47:06] VERBOSE[30926] app_dial.c:     -- Called Vitel-SIP-Trunk/3032222222
226 [Mar 17 07:47:09] VERBOSE[30926] app_dial.c:     -- SIP/Vitel-SIP-Trunk-0000001d is making progress passing it to SIP/200-0000001c
227 [Mar 17 07:47:14] VERBOSE[30926] app_macro.c:   == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on 'SIP/200-0000001c' in macro 'dialout-trunk'
228 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:   == Spawn extension (from-internal, 3032222222, 5) exited non-zero on 'SIP/200-0000001c'
229 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [h@from-internal:1] Macro("SIP/200-0000001c", "hangupcall") in new stack
230 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [s@macro-hangupcall:1] NoOp("SIP/200-0000001c", "In Davids Hangupcall Routine.  Do system flag touch here") in new stack
231 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [s@macro-hangupcall:2] System("SIP/200-0000001c", "/bin/echo Channel SIP/200-0000001c | /usr/bin/logger -t hangupcall") in new stack
232 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [s@macro-hangupcall:3] System("SIP/200-0000001c", "/bin/touch /var/spool/asterisk/tmp/last-voip-call.1268833634") in new stack
233 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [s@macro-hangupcall:4] GotoIf("SIP/200-0000001c", "1?skiprg") in new stack
234 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Goto (macro-hangupcall,s,7)
235 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [s@macro-hangupcall:7] GotoIf("SIP/200-0000001c", "1?skipblkvm") in new stack
236 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Goto (macro-hangupcall,s,10)
237 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [s@macro-hangupcall:10] GotoIf("SIP/200-0000001c", "1?theend") in new stack
238 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Goto (macro-hangupcall,s,12)
239 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:     -- Executing [s@macro-hangupcall:12] Hangup("SIP/200-0000001c", "") in new stack
240 [Mar 17 07:47:14] VERBOSE[30926] app_macro.c:   == Spawn extension (macro-hangupcall, s, 12) exited non-zero on 'SIP/200-0000001c' in macro 'hangupcall'
241 [Mar 17 07:47:14] VERBOSE[30926] pbx.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/200-0000001c'