Ticket #2710: Extract.txt

File Extract.txt, 56.9 kB (added by kenn10, 4 years ago)
Line 
1 [Feb 25 17:16:14] WARNING[9711] rtp.c: Unable to set TOS to 184
2 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:1] Set("SIP/kenn10-084e3af0",
3 "__FROM_DID=678881NNNN") in new stack
4 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:2] Gosub("SIP/kenn10-084e3af0", "app-
5 blacklist-check|s|1") in new stack
6 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/kenn10-
7 084e3af0", "") in new stack
8 [Feb 25 17:16:14] WARNING[9772] app_lookupblacklist.c: LookupBlacklist is deprecated.  Please use ${BLACKLIST()}
9 instead.
10 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@app-blacklist-check:2] GotoIf("SIP/kenn10-084e3af0",
11 "0?blacklisted") in new stack
12 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@app-blacklist-check:3] Return("SIP/kenn10-084e3af0", "")
13 in new stack
14 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:3] GotoIf("SIP/kenn10-084e3af0", "1
15 ?cidok") in new stack
16 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (from-pstn,678881NNNN,5)
17 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:5] NoOp("SIP/kenn10-084e3af0",
18 "CallerID is "SOMEONE      " <6784219999>") in new stack
19 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:6] Ringing("SIP/kenn10-084e3af0", "")
20 in new stack
21 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:7] Set("SIP/kenn10-084e3af0",
22 "FAX_RX_EMAIL=kenn10@comcast.net") in new stack
23 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:8] Macro("SIP/kenn10-084e3af0",
24 "privacy-mgr|") in new stack
25 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:1] Set("SIP/kenn10-084e3af0",
26 "KEEPCID=6784219999") in new stack
27 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Set
28 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:2] GotoIf("SIP/kenn10-084e3af0",
29 "0?CIDTEST2:CIDTEST1") in new stack
30 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (macro-privacy-mgr,s,3)
31 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
32 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:3] Set("SIP/kenn10-084e3af0",
33 "TESTCID=6784215453.000000") in new stack
34 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Set
35 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:4] Goto("SIP/kenn10-084e3af0",
36 "TESTRESULT") in new stack
37 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (macro-privacy-mgr,s,6)
38 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Goto
39 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:6] GotoIf("SIP/kenn10-084e3af0",
40 "0?CLEARCID:PRIVMGR") in new stack
41 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (macro-privacy-mgr,s,8)
42 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
43 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:8] PrivacyManager("SIP/kenn10-
44 084e3af0", "") in new stack
45 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- CallerID Present: Skipping
46 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: PrivacyManager
47 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:9] GotoIf("SIP/kenn10-084e3af0",
48 "0?fail") in new stack
49 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
50 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-privacy-mgr:10] SetCallerPres("SIP/kenn10-
51 084e3af0", "allowed_passed_screen") in new stack
52 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: SetCallerPres
53 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:9] Set("SIP/kenn10-084e3af0",
54 "__ALERT_INFO=Bellcore-r2") in new stack
55 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [678881NNNN@from-pstn:10] Goto("SIP/kenn10-084e3af0", "ext-
56 group|2083|1") in new stack
57 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (ext-group,2083,1)
58 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:1] Macro("SIP/kenn10-084e3af0", "user-
59 callerid|") in new stack
60 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:1] NoOp("SIP/kenn10-084e3af0", "user-
61 callerid: SOMEONE       6784219999") in new stack
62 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Noop
63 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/kenn10-084e3af0",
64 "AMPUSER=6784219999") in new stack
65 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Set
66 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/kenn10-084e3af0",
67 "0?report") in new stack
68 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
69 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/kenn10-084e3af0",
70 "1|Set|REALCALLERIDNUM=6784219999") in new stack
71 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: ExecIf
72 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:5] NoOp("SIP/kenn10-084e3af0",
73 "REALCALLERIDNUM is 6784219999") in new stack
74 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Noop
75 [Feb 25 17:16:14] DEBUG[9772] func_db.c: DB: DEVICE/6784219999/user not found in database.
76 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:6] Set("SIP/kenn10-084e3af0",
77 "AMPUSER=") in new stack
78 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Set
79 [Feb 25 17:16:14] DEBUG[9772] func_db.c: DB: AMPUSER//cidname not found in database.
80 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/kenn10-084e3af0",
81 "AMPUSERCIDNAME=") in new stack
82 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Set
83 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/kenn10-084e3af0",
84 "1?report") in new stack
85 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (macro-user-callerid,s,13)
86 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
87 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:13] NoOp("SIP/kenn10-084e3af0", "TTL: 
88 ARG1: ") in new stack
89 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Noop
90 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:14] GotoIf("SIP/kenn10-084e3af0",
91 "0?continue") in new stack
92 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
93 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:15] Set("SIP/kenn10-084e3af0",
94 "__TTL=64") in new stack
95 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Set
96 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:16] GotoIf("SIP/kenn10-084e3af0",
97 "1?continue") in new stack
98 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (macro-user-callerid,s,23)
99 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
100 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:23] NoOp("SIP/kenn10-084e3af0",
101 "Using CallerID "SOMEONE      " <6784219999>") in new stack
102 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Noop
103 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:2] GotoIf("SIP/kenn10-084e3af0", "1?skipdb")
104 in new stack
105 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (ext-group,2083,4)
106 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:4] Set("SIP/kenn10-084e3af0", "__NODEST=")
107 in new stack
108 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:5] Set("SIP/kenn10-084e3af0",
109 "__BLKVM_OVERRIDE=BLKVM/2083/SIP/kenn10-084e3af0") in new stack
110 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:6] Set("SIP/kenn10-084e3af0",
111 "__BLKVM_BASE=2083") in new stack
112 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:7] Set("SIP/kenn10-084e3af0",
113 "DB(BLKVM/2083/SIP/kenn10-084e3af0)=TRUE") in new stack
114 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:8] Set("SIP/kenn10-084e3af0", "RRNODEST=")
115 in new stack
116 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:9] Set("SIP/kenn10-084e3af0",
117 "__NODEST=2083") in new stack
118 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:10] Set("SIP/kenn10-084e3af0",
119 "__ALERT_INFO=Bellcore-r3") in new stack
120 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:11] Set("SIP/kenn10-084e3af0",
121 "RecordMethod=Group") in new stack
122 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:12] Macro("SIP/kenn10-084e3af0", "record-
123 enable|2008-2010-2012|Group") in new stack
124 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-record-enable:1] GotoIf("SIP/kenn10-084e3af0",
125 "0?2:4") in new stack
126 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (macro-record-enable,s,4)
127 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
128 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-record-enable:4] AGI("SIP/kenn10-084e3af0",
129 "recordingcheck|20080225-171614|1203977774.0") in new stack
130 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
131 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- AGI Script recordingcheck completed, returning 0
132 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: AGI
133 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-record-enable:5] NoOp("SIP/kenn10-084e3af0", "No
134 recording needed") in new stack
135 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: Noop
136 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:13] Set("SIP/kenn10-084e3af0",
137 "RingGroupMethod=ringall") in new stack
138 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:14] Macro("SIP/kenn10-084e3af0",
139 "dial|30|tr|2008-2010-2012") in new stack
140 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-dial:1] GotoIf("SIP/kenn10-084e3af0", "1?dial") in
141 new stack
142 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Goto (macro-dial,s,3)
143 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: GotoIf
144 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-dial:3] AGI("SIP/kenn10-084e3af0",
145 "dialparties.agi") in new stack
146 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
147 [Feb 25 17:16:14] VERBOSE[9772] logger.c:   dialparties.agi: Starting New Dialparties.agi
148 [Feb 25 17:16:14] VERBOSE[9775] logger.c:   == Parsing '/etc/asterisk/manager.conf': [Feb 25 17:16:14] VERBOSE[9775]
149 logger.c: Found
150 [Feb 25 17:16:14] VERBOSE[9775] logger.c:   == Parsing '/etc/asterisk/manager_additional.conf': [Feb 25 17:16:14]
151 VERBOSE[9775] logger.c: Found
152 [Feb 25 17:16:14] VERBOSE[9775] logger.c:   == Parsing '/etc/asterisk/manager_custom.conf': [Feb 25 17:16:14]
153 VERBOSE[9775] logger.c: Found
154 [Feb 25 17:16:14] VERBOSE[9775] logger.c:   == Manager 'admin' logged on from 127.0.0.1
155 [Feb 25 17:16:14] VERBOSE[9772] logger.c:   dialparties.agi: Caller ID name is 'SOMEONE' number is '6784219999'
156 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- AGI Script Executing Application: (SIPAddHeader) Options: (Alert-
157 Info:Bellcore-r3)
158 [Feb 25 17:16:14] VERBOSE[9772] logger.c:   dialparties.agi: Methodology of ring is  'ringall'
159 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Added extension 2008 to extension map
160 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Added extension 2010 to extension map
161 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Added extension 2012 to extension map
162 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Extension 2008 cf is disabled
163 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Extension 2010 cf is disabled
164 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Extension 2012 cf is disabled
165 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Extension 2008 do not disturb is disabled
166 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Extension 2010 do not disturb is disabled
167 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Extension 2012 do not disturb is disabled
168 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: dbset CALLTRACE/2008 to 6784219999
169 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: dbset CALLTRACE/2010 to 6784219999
170 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: dbset CALLTRACE/2012 to 6784219999
171 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     --  dialparties.agi: Filtered ARG3: 2008-2010-2012
172 [Feb 25 17:16:14] VERBOSE[9775] logger.c:   == Manager 'admin' logged off from 127.0.0.1
173 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- AGI Script dialparties.agi completed, returning 0
174 [Feb 25 17:16:14] DEBUG[9772] app_macro.c: Executed application: AGI
175 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Executing [s@macro-dial:7] Dial("SIP/kenn10-084e3af0",
176 "SIP/2008&SIP/2010&SIP/2012|30|trM(auto-blkvm)") in new stack
177 [Feb 25 17:16:14] WARNING[9772] rtp.c: Unable to set TOS to 184
178 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- Called 2008
179 [Feb 25 17:16:14] WARNING[9772] rtp.c: Unable to set TOS to 184
180 [Feb 25 17:16:14] WARNING[9772] app_dial.c: Unable to create channel of type 'SIP' (cause 3 - No route to destination)
181 [Feb 25 17:16:14] WARNING[9772] rtp.c: Unable to set TOS to 184
182 [Feb 25 17:16:14] WARNING[9772] app_dial.c: Unable to create channel of type 'SIP' (cause 3 - No route to destination)
183 [Feb 25 17:16:14] VERBOSE[9772] logger.c:     -- SIP/2008-084f3790 is ringing
184 [Feb 25 17:16:23] VERBOSE[9685] logger.c:     -- Remote UNIX connection
185 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Nobody picked up in 30000 ms
186 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Dial
187 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-dial:8] Set("SIP/kenn10-084e3af0",
188 "DIALSTATUS=NOANSWER") in new stack
189 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Set
190 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:15] Set("SIP/kenn10-084e3af0",
191 "RingGroupMethod=") in new stack
192 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:16] GotoIf("SIP/kenn10-084e3af0",
193 "0?nodest") in new stack
194 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:17] Set("SIP/kenn10-084e3af0", "__NODEST=")
195 in new stack
196 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:18] DBdel("SIP/kenn10-084e3af0",
197 "BLKVM/2083/SIP/kenn10-084e3af0") in new stack
198 [Feb 25 17:16:44] WARNING[9772] app_db.c: The DBdel application has been deprecated in favor of the DB_DELETE dialplan
199 function!
200 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- DBdel: family=BLKVM, key=2083/SIP/kenn10-084e3af0
201 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [2083@ext-group:19] Goto("SIP/kenn10-084e3af0", "ext-
202 local|vmu2008|1") in new stack
203 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Goto (ext-local,vmu2008,1)
204 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [vmu2008@ext-local:1] Macro("SIP/kenn10-084e3af0",
205 "vm|2008|NOANSWER") in new stack
206 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:1] Macro("SIP/kenn10-084e3af0", "user-callerid")
207 in new stack
208 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:1] NoOp("SIP/kenn10-084e3af0", "user-
209 callerid: SOMEONE       6784219999") in new stack
210 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Noop
211 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/kenn10-084e3af0",
212 "AMPUSER=6784219999") in new stack
213 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Set
214 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/kenn10-084e3af0",
215 "0?report") in new stack
216 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: GotoIf
217 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/kenn10-084e3af0",
218 "0|Set|REALCALLERIDNUM=6784219999") in new stack
219 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: ExecIf
220 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:5] NoOp("SIP/kenn10-084e3af0",
221 "REALCALLERIDNUM is 6784219999") in new stack
222 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Noop
223 [Feb 25 17:16:44] DEBUG[9772] func_db.c: DB: DEVICE/6784219999/user not found in database.
224 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:6] Set("SIP/kenn10-084e3af0",
225 "AMPUSER=") in new stack
226 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Set
227 [Feb 25 17:16:44] DEBUG[9772] func_db.c: DB: AMPUSER//cidname not found in database.
228 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/kenn10-084e3af0",
229 "AMPUSERCIDNAME=") in new stack
230 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Set
231 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/kenn10-084e3af0",
232 "1?report") in new stack
233 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Goto (macro-user-callerid,s,13)
234 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: GotoIf
235 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:13] NoOp("SIP/kenn10-084e3af0", "TTL:
236 64 ARG1: 2008") in new stack
237 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Noop
238 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:14] GotoIf("SIP/kenn10-084e3af0",
239 "0?continue") in new stack
240 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: GotoIf
241 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:15] Set("SIP/kenn10-084e3af0",
242 "__TTL=63") in new stack
243 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Set
244 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:16] GotoIf("SIP/kenn10-084e3af0",
245 "1?continue") in new stack
246 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Goto (macro-user-callerid,s,23)
247 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: GotoIf
248 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-user-callerid:23] NoOp("SIP/kenn10-084e3af0",
249 "Using CallerID "SOMEONE      " <6784219999>") in new stack
250 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Noop
251 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Macro
252 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
253 new stack
254 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Set
255 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
256 in new stack
257 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
258 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Goto
259 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
260 vmcontext|2008") in new stack
261 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
262 "VMCONTEXT=default") in new stack
263 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Set
264 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
265 "0?200:300") in new stack
266 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
267 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: GotoIf
268 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
269 in new stack
270 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: NoOp
271 [Feb 25 17:16:44] DEBUG[9772] app_macro.c: Executed application: Macro
272 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:2] VoiceMail("SIP/kenn10-084e3af0",
273 "2008@default|u") in new stack
274 [Feb 25 17:16:44] VERBOSE[9772] logger.c:     -- <SIP/kenn10-084e3af0> Playing
275 '/var/spool/asterisk/voicemail/default/2008/unavail' (language 'en')
276 [Feb 25 17:16:46] VERBOSE[9772] logger.c:   == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on
277 'SIP/kenn10-084e3af0' in macro 'vm'
278 [Feb 25 17:16:46] VERBOSE[9772] logger.c:   == Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on
279 'SIP/kenn10-084e3af0'
280 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [h@macro-vm:1] Macro("SIP/kenn10-084e3af0", "vm-
281 notify|2008") in new stack
282 [Feb 25 17:16:46] DEBUG[9772] func_db.c: DB: vmnotify/2008 not found in database.
283 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm-notify:1] Set("SIP/kenn10-084e3af0",
284 "phonenumber=") in new stack
285 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
286 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm-notify:2] GotoIf("SIP/kenn10-084e3af0",
287 "1?0:10") in new stack
288 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm-notify,s,0)
289 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Gotoif
290 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
291 new stack
292 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
293 in new stack
294 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
295 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
296 vmcontext|2008") in new stack
297 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
298 "VMCONTEXT=default") in new stack
299 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
300 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
301 "0?200:300") in new stack
302 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
303 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
304 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
305 in new stack
306 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
307 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
308 new stack
309 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
310 in new stack
311 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
312 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
313 vmcontext|2008") in new stack
314 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
315 "VMCONTEXT=default") in new stack
316 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
317 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
318 "0?200:300") in new stack
319 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
320 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
321 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
322 in new stack
323 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
324 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
325 new stack
326 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
327 in new stack
328 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
329 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
330 vmcontext|2008") in new stack
331 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
332 "VMCONTEXT=default") in new stack
333 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
334 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
335 "0?200:300") in new stack
336 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
337 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
338 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
339 in new stack
340 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
341 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
342 new stack
343 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
344 in new stack
345 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
346 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
347 vmcontext|2008") in new stack
348 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
349 "VMCONTEXT=default") in new stack
350 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
351 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
352 "0?200:300") in new stack
353 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
354 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
355 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
356 in new stack
357 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
358 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
359 new stack
360 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
361 in new stack
362 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
363 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
364 vmcontext|2008") in new stack
365 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
366 "VMCONTEXT=default") in new stack
367 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
368 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
369 "0?200:300") in new stack
370 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
371 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
372 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
373 in new stack
374 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
375 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
376 new stack
377 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
378 in new stack
379 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
380 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
381 vmcontext|2008") in new stack
382 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
383 "VMCONTEXT=default") in new stack
384 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
385 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
386 "0?200:300") in new stack
387 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
388 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
389 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
390 in new stack
391 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
392 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
393 new stack
394 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
395 in new stack
396 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
397 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
398 vmcontext|2008") in new stack
399 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
400 "VMCONTEXT=default") in new stack
401 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
402 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
403 "0?200:300") in new stack
404 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
405 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
406 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
407 in new stack
408 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
409 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
410 new stack
411 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
412 in new stack
413 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
414 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
415 vmcontext|2008") in new stack
416 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
417 "VMCONTEXT=default") in new stack
418 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
419 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
420 "0?200:300") in new stack
421 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
422 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
423 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
424 in new stack
425 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
426 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
427 new stack
428 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
429 in new stack
430 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
431 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
432 vmcontext|2008") in new stack
433 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
434 "VMCONTEXT=default") in new stack
435 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
436 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
437 "0?200:300") in new stack
438 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
439 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
440 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
441 in new stack
442 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
443 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
444 new stack
445 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
446 in new stack
447 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
448 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
449 vmcontext|2008") in new stack
450 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
451 "VMCONTEXT=default") in new stack
452 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
453 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
454 "0?200:300") in new stack
455 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
456 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
457 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
458 in new stack
459 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
460 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
461 new stack
462 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
463 in new stack
464 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
465 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
466 vmcontext|2008") in new stack
467 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
468 "VMCONTEXT=default") in new stack
469 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
470 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
471 "0?200:300") in new stack
472 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
473 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
474 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
475 in new stack
476 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
477 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
478 new stack
479 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
480 in new stack
481 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
482 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
483 vmcontext|2008") in new stack
484 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
485 "VMCONTEXT=default") in new stack
486 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
487 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
488 "0?200:300") in new stack
489 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
490 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
491 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
492 in new stack
493 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
494 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
495 new stack
496 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
497 in new stack
498 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
499 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
500 vmcontext|2008") in new stack
501 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
502 "VMCONTEXT=default") in new stack
503 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
504 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
505 "0?200:300") in new stack
506 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
507 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
508 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
509 in new stack
510 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
511 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
512 new stack
513 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
514 in new stack
515 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
516 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
517 vmcontext|2008") in new stack
518 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
519 "VMCONTEXT=default") in new stack
520 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
521 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
522 "0?200:300") in new stack
523 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
524 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
525 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
526 in new stack
527 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
528 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
529 new stack
530 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
531 in new stack
532 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
533 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
534 vmcontext|2008") in new stack
535 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
536 "VMCONTEXT=default") in new stack
537 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
538 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
539 "0?200:300") in new stack
540 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
541 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
542 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
543 in new stack
544 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
545 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
546 new stack
547 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
548 in new stack
549 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
550 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
551 vmcontext|2008") in new stack
552 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
553 "VMCONTEXT=default") in new stack
554 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
555 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
556 "0?200:300") in new stack
557 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
558 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
559 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
560 in new stack
561 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
562 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
563 new stack
564 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
565 in new stack
566 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
567 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
568 vmcontext|2008") in new stack
569 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
570 "VMCONTEXT=default") in new stack
571 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
572 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
573 "0?200:300") in new stack
574 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
575 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
576 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
577 in new stack
578 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
579 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
580 new stack
581 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
582 in new stack
583 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
584 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
585 vmcontext|2008") in new stack
586 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
587 "VMCONTEXT=default") in new stack
588 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
589 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
590 "0?200:300") in new stack
591 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
592 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
593 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
594 in new stack
595 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
596 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
597 new stack
598 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
599 in new stack
600 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
601 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
602 vmcontext|2008") in new stack
603 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
604 "VMCONTEXT=default") in new stack
605 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
606 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
607 "0?200:300") in new stack
608 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
609 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
610 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
611 in new stack
612 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
613 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
614 new stack
615 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
616 in new stack
617 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
618 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
619 vmcontext|2008") in new stack
620 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
621 "VMCONTEXT=default") in new stack
622 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
623 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
624 "0?200:300") in new stack
625 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
626 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
627 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
628 in new stack
629 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
630 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
631 new stack
632 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
633 in new stack
634 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
635 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
636 vmcontext|2008") in new stack
637 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
638 "VMCONTEXT=default") in new stack
639 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
640 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
641 "0?200:300") in new stack
642 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
643 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
644 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
645 in new stack
646 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
647 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
648 new stack
649 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
650 in new stack
651 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
652 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
653 vmcontext|2008") in new stack
654 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
655 "VMCONTEXT=default") in new stack
656 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
657 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
658 "0?200:300") in new stack
659 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
660 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
661 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
662 in new stack
663 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
664 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
665 new stack
666 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
667 in new stack
668 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
669 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
670 vmcontext|2008") in new stack
671 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
672 "VMCONTEXT=default") in new stack
673 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
674 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
675 "0?200:300") in new stack
676 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
677 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
678 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
679 in new stack
680 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
681 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
682 new stack
683 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
684 in new stack
685 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
686 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
687 vmcontext|2008") in new stack
688 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
689 "VMCONTEXT=default") in new stack
690 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
691 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
692 "0?200:300") in new stack
693 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
694 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
695 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
696 in new stack
697 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
698 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
699 new stack
700 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
701 in new stack
702 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
703 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
704 vmcontext|2008") in new stack
705 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
706 "VMCONTEXT=default") in new stack
707 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
708 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
709 "0?200:300") in new stack
710 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
711 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
712 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
713 in new stack
714 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
715 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
716 new stack
717 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
718 in new stack
719 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
720 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
721 vmcontext|2008") in new stack
722 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
723 "VMCONTEXT=default") in new stack
724 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
725 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
726 "0?200:300") in new stack
727 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
728 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
729 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
730 in new stack
731 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
732 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
733 new stack
734 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
735 in new stack
736 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
737 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
738 vmcontext|2008") in new stack
739 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
740 "VMCONTEXT=default") in new stack
741 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
742 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
743 "0?200:300") in new stack
744 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
745 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
746 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
747 in new stack
748 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
749 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
750 new stack
751 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
752 in new stack
753 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
754 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
755 vmcontext|2008") in new stack
756 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
757 "VMCONTEXT=default") in new stack
758 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
759 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
760 "0?200:300") in new stack
761 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
762 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
763 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
764 in new stack
765 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
766 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
767 new stack
768 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
769 in new stack
770 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
771 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
772 vmcontext|2008") in new stack
773 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
774 "VMCONTEXT=default") in new stack
775 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
776 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
777 "0?200:300") in new stack
778 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-get-vmcontext,s,300)
779 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: GotoIf
780 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/kenn10-084e3af0", "")
781 in new stack
782 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: NoOp
783 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:2] Set("SIP/kenn10-084e3af0", "VMGAIN=""") in
784 new stack
785 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-vm:3] Goto("SIP/kenn10-084e3af0", "s-NOANSWER|1")
786 in new stack
787 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Goto (macro-vm,s-NOANSWER,1)
788 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s-NOANSWER@macro-vm:1] Macro("SIP/kenn10-084e3af0", "get-
789 vmcontext|2008") in new stack
790 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/kenn10-084e3af0",
791 "VMCONTEXT=default") in new stack
792 [Feb 25 17:16:46] DEBUG[9772] app_macro.c: Executed application: Set
793 [Feb 25 17:16:46] VERBOSE[9772] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/kenn10-084e3af0",
794 "0?200:300") in new stack
795 NTEXT=default") in new stack