Ticket #276: messages

File messages, 99.0 KB (added by san, 7 years ago)
Line 
1Jun 29 10:37:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
2Jun 29 10:37:51 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
3Jun 29 10:37:51 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
4Jun 29 10:37:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: ACK received, ts=177, flags=0000, data=25
5Jun 29 10:37:53 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
6Jun 29 10:37:53 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
7Jun 29 10:37:53 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
8Jun 29 10:37:53 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
9Jun 29 10:37:55 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Connected: Statistics received, ts=71, flags=0000, data=0
10Jun 29 10:37:55 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Connected: Statistics received, ts=71, flags=0000, data=0
11Jun 29 10:37:55 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Connected: Statistics received, ts=24, flags=0000, data=0
12Jun 29 10:37:55 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Connected: Statistics received, ts=24, flags=0000, data=0
13Jun 29 10:37:55 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=5
14Jun 29 10:37:55 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=1540
15Jun 29 10:37:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
16Jun 29 10:37:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=2
17Jun 29 10:37:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=772
18Jun 29 10:37:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
19Jun 29 10:37:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=7
20Jun 29 10:37:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=2052
21Jun 29 10:37:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
22Jun 29 10:37:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
23Jun 29 10:37:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
24Jun 29 10:37:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
25Jun 29 10:37:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
26Jun 29 10:37:58 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
27Jun 29 10:37:58 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
28Jun 29 10:37:58 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Connected: CAS event, ts=71, flags=0000, data=15
29Jun 29 10:37:58 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
30Jun 29 10:37:58 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
31Jun 29 10:37:58 sip_ua[454]: repro.cpp:516: doSessionAccounting(): Session Ended 'branch=z9hG4bK1713835313'
32Jun 29 10:37:58 sip_ua[454]: repro.cpp:534: --> duration=14, [FXS]tel:491729@127.0.0.1: ---> [FXS]sip:491772@127.0.0.1:
33Jun 29 10:37:58 sip_ua[454]: repro.cpp:701: --> CDR: {"answer":1530268664,"duration":14,"hangup":1530268678,"org-host":"127.0.0.1","org-scheme":"tel","org-type":"FXS","org-user":"491729","to-host":"127.0.0.1","to-scheme":"sip","to-type":"FXS","to-user
34Jun 29 10:37:58 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Call disconnected, ts=71, flags=0000, data=28
35Jun 29 10:37:58 sip_ua[387]: user_agent.cpp:2763: ---> BYE in call received
36Jun 29 10:37:58 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Connected: Call disconnected, ts=24, flags=0000, data=27
37Jun 29 10:37:58 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Message answered 2xx, ts=71, flags=0000, data=28
38Jun 29 10:37:58 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Busy: CAS event, ts=24, flags=0000, data=15
39Jun 29 10:37:59 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: CAS A activity detected, ts=24, flags=0000, data=0
40Jun 29 10:37:59 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: CAS event, ts=24, flags=0000, data=7
41Jun 29 10:37:59 sip_ua[363]: fxs.cpp:702: ==> ts 24: manual CAS event processing (abcd=15)
42Jun 29 10:37:59 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Dialtone: CAS event, ts=24, flags=0000, data=7
43Jun 29 10:38:01 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
44Jun 29 10:38:01 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
45Jun 29 10:38:01 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
46Jun 29 10:38:01 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
47Jun 29 10:38:01 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Dialtone: CAS event, ts=24, flags=0000, data=15
48Jun 29 10:38:03 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
49Jun 29 10:38:03 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
50Jun 29 10:38:03 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
51Jun 29 10:38:03 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
52Jun 29 10:38:04 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=5
53Jun 29 10:38:04 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=1540
54Jun 29 10:38:04 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
55Jun 29 10:38:04 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=2
56Jun 29 10:38:04 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=772
57Jun 29 10:38:05 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
58Jun 29 10:38:05 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=7
59Jun 29 10:38:05 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=2052
60Jun 29 10:38:05 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
61Jun 29 10:38:06 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
62Jun 29 10:38:06 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
63Jun 29 10:38:06 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
64Jun 29 10:38:06 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
65Jun 29 10:38:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
66Jun 29 10:38:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
67Jun 29 10:38:08 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
68Jun 29 10:38:08 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
69Jun 29 10:38:11 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
70Jun 29 10:38:11 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
71Jun 29 10:38:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
72Jun 29 10:38:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
73Jun 29 10:38:13 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=5
74Jun 29 10:38:13 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=1540
75Jun 29 10:38:13 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
76Jun 29 10:38:13 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
77Jun 29 10:38:13 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
78Jun 29 10:38:13 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
79Jun 29 10:38:13 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
80Jun 29 10:38:13 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=2
81Jun 29 10:38:13 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=772
82Jun 29 10:38:14 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
83Jun 29 10:38:14 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: DTMF detected, ts=15, flags=0000, data=7
84Jun 29 10:38:14 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Remote tone detected, ts=177, flags=0000, data=2052
85Jun 29 10:38:14 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Tone detected, ts=15, flags=0000, data=255
86Jun 29 10:38:16 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
87Jun 29 10:38:16 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
88Jun 29 10:38:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
89Jun 29 10:38:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
90Jun 29 10:38:18 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
91Jun 29 10:38:18 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
92Jun 29 10:38:18 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
93Jun 29 10:38:18 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
94Jun 29 10:38:21 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
95Jun 29 10:38:21 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
96Jun 29 10:38:21 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
97Jun 29 10:38:21 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
98Jun 29 10:38:23 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
99Jun 29 10:38:23 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
100Jun 29 10:38:23 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
101Jun 29 10:38:23 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
102Jun 29 10:38:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
103Jun 29 10:38:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
104Jun 29 10:38:26 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
105Jun 29 10:38:26 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
106Jun 29 10:38:28 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
107Jun 29 10:38:28 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
108Jun 29 10:38:28 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
109Jun 29 10:38:28 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
110Jun 29 10:38:31 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
111Jun 29 10:38:31 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
112Jun 29 10:38:31 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
113Jun 29 10:38:31 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
114Jun 29 10:38:33 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
115Jun 29 10:38:33 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
116Jun 29 10:38:33 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
117Jun 29 10:38:33 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
118Jun 29 10:38:36 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
119Jun 29 10:38:36 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
120Jun 29 10:38:36 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
121Jun 29 10:38:36 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
122Jun 29 10:38:38 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
123Jun 29 10:38:38 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
124Jun 29 10:38:38 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
125Jun 29 10:38:38 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
126Jun 29 10:38:41 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
127Jun 29 10:38:41 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
128Jun 29 10:38:41 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
129Jun 29 10:38:41 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
130Jun 29 10:38:43 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
131Jun 29 10:38:43 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
132Jun 29 10:38:43 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
133Jun 29 10:38:43 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
134Jun 29 10:38:46 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
135Jun 29 10:38:46 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
136Jun 29 10:38:46 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
137Jun 29 10:38:46 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
138Jun 29 10:38:48 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
139Jun 29 10:38:48 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
140Jun 29 10:38:48 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
141Jun 29 10:38:48 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
142Jun 29 10:38:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
143Jun 29 10:38:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
144Jun 29 10:38:51 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
145Jun 29 10:38:51 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
146Jun 29 10:38:53 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
147Jun 29 10:38:53 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
148Jun 29 10:38:53 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
149Jun 29 10:38:53 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
150Jun 29 10:38:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
151Jun 29 10:38:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
152Jun 29 10:38:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
153Jun 29 10:38:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
154Jun 29 10:38:58 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
155Jun 29 10:38:58 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
156Jun 29 10:38:58 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
157Jun 29 10:38:58 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
158Jun 29 10:39:01 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
159Jun 29 10:39:01 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
160Jun 29 10:39:01 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
161Jun 29 10:39:01 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
162Jun 29 10:39:03 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
163Jun 29 10:39:03 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
164Jun 29 10:39:03 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
165Jun 29 10:39:03 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
166Jun 29 10:39:06 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
167Jun 29 10:39:06 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
168Jun 29 10:39:06 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
169Jun 29 10:39:06 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
170Jun 29 10:39:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
171Jun 29 10:39:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
172Jun 29 10:39:08 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
173Jun 29 10:39:08 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
174Jun 29 10:39:11 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
175Jun 29 10:39:11 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
176Jun 29 10:39:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
177Jun 29 10:39:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
178Jun 29 10:39:13 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
179Jun 29 10:39:13 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
180Jun 29 10:39:13 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
181Jun 29 10:39:13 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
182Jun 29 10:39:16 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
183Jun 29 10:39:16 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
184Jun 29 10:39:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
185Jun 29 10:39:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
186Jun 29 10:39:18 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
187Jun 29 10:39:18 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
188Jun 29 10:39:18 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
189Jun 29 10:39:18 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
190Jun 29 10:39:21 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
191Jun 29 10:39:21 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
192Jun 29 10:39:21 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
193Jun 29 10:39:21 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
194Jun 29 10:39:23 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
195Jun 29 10:39:23 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: Statistics received, ts=28, flags=0000, data=0
196Jun 29 10:39:23 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
197Jun 29 10:39:23 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Statistics received, ts=178, flags=0000, data=0
198Jun 29 10:39:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
199Jun 29 10:39:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
200Jun 29 10:39:26 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
201Jun 29 10:39:26 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
202Jun 29 10:39:28 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Connected: CAS event, ts=28, flags=0000, data=15
203Jun 29 10:39:28 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: Call disconnected, ts=28, flags=0000, data=13
204Jun 29 10:39:28 sip_ua[454]: repro.cpp:516: doSessionAccounting(): Session Ended 'branch=z9hG4bK756761583'
205Jun 29 10:39:28 sip_ua[454]: repro.cpp:534: --> duration=150, [1IND]tel:gw2@127.0.0.1: ---> [FXS]sip:491733@127.0.0.1:
206Jun 29 10:39:28 sip_ua[454]: repro.cpp:701: --> CDR: {"answer":1530268618,"duration":150,"hangup":1530268768,"org-host":"127.0.0.1","org-scheme":"tel","org-type":"1IND","org-user":"gw2","to-host":"127.0.0.1","to-scheme":"sip","to-type":"FXS","to-user"
207Jun 29 10:39:28 sip_ua[387]: user_agent.cpp:2763: ---> BYE in call received
208Jun 29 10:39:28 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Connected: Call disconnected, ts=178, flags=0000, data=12
209Jun 29 10:39:28 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: Message answered 2xx, ts=28, flags=0000, data=13
210Jun 29 10:39:29 sip_ua[363]: induct.cpp:581: ---> ts=178, state=DiscWait: CAS event, ts=178, flags=0000, data=1
211Jun 29 10:39:29 sip_ua[363]: induct.cpp:581: ---> ts=178, state=DiscWait: CAS event, ts=178, flags=0000, data=9
212Jun 29 10:39:29 sip_ua[363]: induct.cpp:159: channel 178 (DiscWait): disconnect received (522 ms)
213Jun 29 10:39:31 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
214Jun 29 10:39:31 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
215Jun 29 10:39:31 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
216Jun 29 10:39:31 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
217Jun 29 10:39:36 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
218Jun 29 10:39:36 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
219Jun 29 10:39:36 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
220Jun 29 10:39:36 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
221Jun 29 10:39:41 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
222Jun 29 10:39:41 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
223Jun 29 10:39:41 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
224Jun 29 10:39:41 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
225Jun 29 10:39:46 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
226Jun 29 10:39:46 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
227Jun 29 10:39:46 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
228Jun 29 10:39:46 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
229Jun 29 10:39:51 sip_ua[363]: comcerto.cpp:7146: ts 177: starting RTP stream to 02:ad:c3:00:00:ea
230Jun 29 10:39:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
231Jun 29 10:39:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
232Jun 29 10:39:51 sip_ua[363]: comcerto.cpp:7146: ts 15: starting RTP stream to 02:ad:c3:00:00:ea
233Jun 29 10:39:51 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
234Jun 29 10:39:51 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
235Jun 29 10:39:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
236Jun 29 10:39:56 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
237Jun 29 10:39:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
238Jun 29 10:39:56 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
239Jun 29 10:40:01 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
240Jun 29 10:40:01 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
241Jun 29 10:40:01 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
242Jun 29 10:40:01 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
243Jun 29 10:40:06 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
244Jun 29 10:40:06 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
245Jun 29 10:40:06 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
246Jun 29 10:40:06 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
247Jun 29 10:40:11 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
248Jun 29 10:40:11 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
249Jun 29 10:40:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
250Jun 29 10:40:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
251Jun 29 10:40:16 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
252Jun 29 10:40:16 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
253Jun 29 10:40:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
254Jun 29 10:40:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
255Jun 29 10:40:21 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
256Jun 29 10:40:21 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
257Jun 29 10:40:21 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
258Jun 29 10:40:21 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
259Jun 29 10:40:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
260Jun 29 10:40:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
261Jun 29 10:40:26 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
262Jun 29 10:40:26 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
263Jun 29 10:40:31 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
264Jun 29 10:40:31 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
265Jun 29 10:40:31 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
266Jun 29 10:40:31 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
267Jun 29 10:40:36 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
268Jun 29 10:40:36 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
269Jun 29 10:40:36 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
270Jun 29 10:40:36 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
271Jun 29 10:40:41 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
272Jun 29 10:40:41 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
273Jun 29 10:40:41 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
274Jun 29 10:40:41 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
275Jun 29 10:40:46 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
276Jun 29 10:40:46 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
277Jun 29 10:40:46 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
278Jun 29 10:40:46 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
279Jun 29 10:40:51 sip_ua[363]: comcerto.cpp:5887: ts 177: RTP timeout
280Jun 29 10:40:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Call disconnected, ts=177, flags=0000, data=25
281Jun 29 10:40:51 sip_ua[454]: repro.cpp:516: doSessionAccounting(): Session Ended 'branch=z9hG4bK1091914438'
282Jun 29 10:40:51 sip_ua[454]: repro.cpp:534: --> duration=180, [FXS]tel:491717@127.0.0.1: ---> [1IND]tel:83422801186@127.0.0.1:
283Jun 29 10:40:51 sip_ua[454]: repro.cpp:701: --> CDR: {"answer":1530268671,"duration":180,"hangup":1530268851,"org-host":"127.0.0.1","org-scheme":"tel","org-type":"FXS","org-user":"491717","to-host":"127.0.0.1","to-scheme":"tel","to-type":"1IND","to-us
284Jun 29 10:40:51 sip_ua[387]: user_agent.cpp:2763: ---> BYE in call received
285Jun 29 10:40:51 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Call disconnected, ts=15, flags=0000, data=24
286Jun 29 10:40:51 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Blocked: Message answered 2xx, ts=177, flags=0000, data=25
287Jun 29 10:40:57 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Busy: CAS event, ts=15, flags=0000, data=15
288Jun 29 10:41:31 dropbear[458]: Child connection from ::ffff:192.168.1.16:1779
289Jun 29 10:41:33 dropbear[458]: pubkey auth succeeded for 'root' with key md5 16:f2:97:78:f6:b2:f8:91:aa:f8:48:d5:ae:68:89:4f from ::ffff:192.168.1.16:1779
290Jun 29 10:42:26 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: CAS A activity detected, ts=55, flags=0000, data=0
291Jun 29 10:42:26 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: CAS event, ts=55, flags=0000, data=7
292Jun 29 10:42:26 sip_ua[363]: fxs.cpp:702: ==> ts 55: manual CAS event processing (abcd=13)
293Jun 29 10:42:26 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
294Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
295Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
296Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: Tone completed, ts=55, flags=0000, data=3
297Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
298Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
299Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
300Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
301Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
302Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
303Jun 29 10:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: DTMF detected, ts=55, flags=0000, data=4
304Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: CAS A activity detected, ts=91, flags=0000, data=0
305Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: CAS event, ts=91, flags=0000, data=7
306Jun 29 10:42:31 sip_ua[363]: fxs.cpp:702: ==> ts 91: manual CAS event processing (abcd=15)
307Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Dialtone: CAS event, ts=91, flags=0000, data=7
308Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
309Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
310Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
311Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
312Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
313Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
314Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
315Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
316Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
317Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
318Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
319Jun 29 10:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
320Jun 29 10:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
321Jun 29 10:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
322Jun 29 10:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
323Jun 29 10:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
324Jun 29 10:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
325Jun 29 10:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
326Jun 29 10:42:32 sip_ua[363]: fxs.cpp:309: ==> flash pressed
327Jun 29 10:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Dialtone: CAS event, ts=91, flags=0000, data=15
328Jun 29 10:42:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
329Jun 29 10:42:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
330Jun 29 10:42:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=9
331Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
332Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
333Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
334Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
335Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
336Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
337Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
338Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
339Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
340Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
341Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
342Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
343Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
344Jun 29 10:42:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
345Jun 29 10:42:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=7
346Jun 29 10:42:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
347Jun 29 10:42:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
348Jun 29 10:42:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
349Jun 29 10:42:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
350Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
351Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
352Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
353Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
354Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
355Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
356Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
357Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
358Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
359Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
360Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
361Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
362Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
363Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
364Jun 29 10:42:36 sip_ua[363]: fxs.cpp:309: ==> flash pressed
365Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
366Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
367Jun 29 10:42:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=9
368Jun 29 10:42:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
369Jun 29 10:42:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
370Jun 29 10:42:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
371Jun 29 10:42:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
372Jun 29 10:42:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
373Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
374Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
375Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
376Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
377Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
378Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
379Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
380Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
381Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
382Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
383Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
384Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
385Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
386Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
387Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
388Jun 29 10:42:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=0
389Jun 29 10:42:43 sip_ua[363]: fxs.cpp:241: ts 55: dialing 49790
390Jun 29 10:42:43 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:491763@127.0.0.1> -> sip:49790@127.0.0.1 ()...
391Jun 29 10:42:43 sip_ua[454]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK773051206'
392Jun 29 10:42:43 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:49790@127.0.0.1:6060;transport=udp (Call-ID: 1712782933@(null))
393Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=33
394Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=33
395Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=33
396Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=33
397Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=33
398Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=33
399Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=33
400Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=33
401Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=33
402Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=33
403Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=33
404Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Incoming call, ts=-1, flags=0001, data=33
405Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Incoming call, ts=-1, flags=0001, data=33
406Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Incoming call, ts=-1, flags=0001, data=33
407Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0001, data=33
408Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0001, data=33
409Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0001, data=33
410Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0001, data=33
411Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Busy: Incoming call, ts=-1, flags=0001, data=33
412Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0001, data=33
413Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0001, data=33
414Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: Incoming call, ts=-1, flags=0001, data=33
415Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=36, state=Idle: Incoming call, ts=-1, flags=0001, data=33
416Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Idle: Incoming call, ts=-1, flags=0001, data=33
417Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=38, state=Idle: Incoming call, ts=-1, flags=0001, data=33
418Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Incoming call, ts=-1, flags=0001, data=33
419Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Incoming call, ts=-1, flags=0001, data=33
420Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0001, data=33
421Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0001, data=33
422Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0001, data=33
423Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0001, data=33
424Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0001, data=33
425Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Incoming call, ts=-1, flags=0001, data=33
426Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0001, data=33
427Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0001, data=33
428Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Incoming call, ts=-1, flags=0001, data=33
429Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Incoming call, ts=-1, flags=0001, data=33
430Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0001, data=33
431Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0001, data=33
432Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0001, data=33
433Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Incoming call, ts=-1, flags=0001, data=33
434Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Incoming call, ts=-1, flags=0001, data=33
435Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=77, state=Idle: Incoming call, ts=-1, flags=0001, data=33
436Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0001, data=33
437Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0001, data=33
438Jun 29 10:42:43 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0001, data=33
439Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0001, data=33
440Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0001, data=33
441Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0001, data=33
442Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0001, data=33
443Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=25, state=Idle: Incoming call, ts=-1, flags=0002, data=33
444Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=26, state=Idle: Incoming call, ts=-1, flags=0002, data=33
445Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=27, state=Idle: Incoming call, ts=-1, flags=0002, data=33
446Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: Incoming call, ts=-1, flags=0002, data=33
447Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: Incoming call, ts=-1, flags=0002, data=33
448Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=30, state=Idle: Incoming call, ts=-1, flags=0002, data=33
449Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=31, state=Idle: Incoming call, ts=-1, flags=0002, data=33
450Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=33, state=Idle: Incoming call, ts=-1, flags=0002, data=33
451Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0002, data=33
452Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0002, data=33
453Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0002, data=33
454Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0002, data=33
455Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0002, data=33
456Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Incoming call, ts=-1, flags=0002, data=33
457Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=47, state=Idle: Incoming call, ts=-1, flags=0002, data=33
458Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=49, state=Idle: Incoming call, ts=-1, flags=0002, data=33
459Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=50, state=Idle: Incoming call, ts=-1, flags=0002, data=33
460Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=51, state=Idle: Incoming call, ts=-1, flags=0002, data=33
461Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0002, data=33
462Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Incoming call, ts=-1, flags=0002, data=33
463Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Incoming call, ts=-1, flags=0002, data=33
464Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0002, data=33
465Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0002, data=33
466Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0002, data=33
467Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Incoming call, ts=-1, flags=0002, data=33
468Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Incoming call, ts=-1, flags=0002, data=33
469Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=77, state=Idle: Incoming call, ts=-1, flags=0002, data=33
470Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0002, data=33
471Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0002, data=33
472Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0002, data=33
473Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0002, data=33
474Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0002, data=33
475Jun 29 10:42:44 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0002, data=33
476Jun 29 10:42:47 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialing: CAS event, ts=177, flags=0000, data=1
477Jun 29 10:42:48 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialing: CAS event, ts=177, flags=0000, data=9
478Jun 29 10:42:48 sip_ua[363]: induct.cpp:159: channel 177 (Dialing): disconnect received (518 ms)
479Jun 29 10:42:48 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Blocked: Call disconnected, ts=177, flags=0000, data=33
480Jun 29 10:42:48 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Calling: Call disconnected, ts=55, flags=0000, data=32
481Jun 29 10:43:02 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Busy: CAS event, ts=55, flags=0000, data=15
482Jun 29 10:43:08 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: CAS A activity detected, ts=55, flags=0000, data=0
483Jun 29 10:43:08 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: CAS event, ts=55, flags=0000, data=7
484Jun 29 10:43:08 sip_ua[363]: fxs.cpp:702: ==> ts 55: manual CAS event processing (abcd=15)
485Jun 29 10:43:08 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
486Jun 29 10:43:14 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
487Jun 29 10:43:15 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: CAS A activity detected, ts=55, flags=0000, data=0
488Jun 29 10:43:15 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: CAS event, ts=55, flags=0000, data=7
489Jun 29 10:43:15 sip_ua[363]: fxs.cpp:702: ==> ts 55: manual CAS event processing (abcd=15)
490Jun 29 10:43:15 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
491Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
492Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
493Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: Tone completed, ts=55, flags=0000, data=3
494Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
495Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
496Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
497Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
498Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=15
499Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: CAS event, ts=55, flags=0000, data=7
500Jun 29 10:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialtone: DTMF detected, ts=55, flags=0000, data=4
501Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
502Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
503Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
504Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
505Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
506Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
507Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
508Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
509Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
510Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
511Jun 29 10:43:30 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
512Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
513Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
514Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
515Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
516Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
517Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
518Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
519Jun 29 10:43:31 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=9
520Jun 29 10:43:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
521Jun 29 10:43:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
522Jun 29 10:43:32 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=1
523Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
524Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
525Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
526Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
527Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
528Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
529Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
530Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
531Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
532Jun 29 10:43:33 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
533Jun 29 10:43:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
534Jun 29 10:43:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
535Jun 29 10:43:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
536Jun 29 10:43:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
537Jun 29 10:43:34 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=7
538Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
539Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
540Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
541Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
542Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
543Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
544Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
545Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
546Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
547Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
548Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
549Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
550Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
551Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
552Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
553Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
554Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
555Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
556Jun 29 10:43:35 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
557Jun 29 10:43:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
558Jun 29 10:43:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=0
559Jun 29 10:43:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
560Jun 29 10:43:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
561Jun 29 10:43:36 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
562Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
563Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
564Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
565Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
566Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
567Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
568Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
569Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
570Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
571Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
572Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
573Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
574Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
575Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
576Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
577Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=15
578Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: CAS event, ts=55, flags=0000, data=7
579Jun 29 10:43:37 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Dialing: DTMF detected, ts=55, flags=0000, data=0
580Jun 29 10:43:37 sip_ua[363]: fxs.cpp:241: ts 55: dialing 491700
581Jun 29 10:43:37 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:491763@127.0.0.1> -> sip:491700@127.0.0.1 ()...
582Jun 29 10:43:37 sip_ua[454]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK2035641448'
583Jun 29 10:43:38 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:491700@127.0.0.1:6060;transport=udp (Call-ID: 858676156@(null))
584Jun 29 10:43:38 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=36
585Jun 29 10:43:38 sip_ua[363]: fxs.cpp:962: channel 1: ringing
586Jun 29 10:43:38 sip_ua[363]: user_agent.cpp:3484: Call 36 routed to TS 1
587Jun 29 10:43:38 sip_ua[387]: user_agent.cpp:2132: ---> ringing with status code 180
588Jun 29 10:43:38 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Calling: Remote is ringing, ts=55, flags=0000, data=35
589Jun 29 10:43:40 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Ringing: Caller ID sending complete, ts=1, flags=0000, data=0
590Jun 29 10:43:45 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Ringing: Caller ID sending complete, ts=1, flags=0000, data=0
591Jun 29 10:44:24 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Calling: CAS event, ts=55, flags=0000, data=15
592Jun 29 10:44:24 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Call disconnected, ts=55, flags=0000, data=35
593Jun 29 10:44:24 sip_ua[454]: repro.cpp:552: doSessionAccounting(): Session Cancelled 'branch=z9hG4bK2035641448'
594Jun 29 10:44:24 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Ringing: Call disconnected, ts=1, flags=0000, data=36
595Jun 29 10:44:24 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Call disconnected, ts=1, flags=0000, data=36
596Jun 29 10:44:24 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Call disconnected, ts=55, flags=0000, data=35
597Jun 29 10:44:55 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Idle: CAS event, ts=179, flags=0000, data=1
598Jun 29 10:44:55 sip_ua[363]: induct.cpp:581: ---> ts=179, state=RBlocked: CAS event, ts=179, flags=0000, data=9
599Jun 29 10:44:55 sip_ua[363]: induct.cpp:140: channel 179 (RBlocked): short signal received (32 ms)
600Jun 29 10:44:55 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
601Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
602Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
603Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
604Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
605Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
606Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
607Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
608Jun 29 10:44:56 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: DTMF detected, ts=179, flags=0000, data=4
609Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
610Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
611Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
612Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
613Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
614Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
615Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
616Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
617Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
618Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
619Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
620Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
621Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
622Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
623Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
624Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
625Jun 29 10:44:57 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
626Jun 29 10:44:58 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
627Jun 29 10:44:58 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: DTMF detected, ts=179, flags=0000, data=9
628Jun 29 10:44:58 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
629Jun 29 10:44:58 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
630Jun 29 10:44:58 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: DTMF detected, ts=179, flags=0000, data=1
631Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
632Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
633Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
634Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
635Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
636Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
637Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
638Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
639Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
640Jun 29 10:44:59 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
641Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
642Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
643Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
644Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
645Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: DTMF detected, ts=179, flags=0000, data=7
646Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
647Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
648Jun 29 10:45:00 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
649Jun 29 10:45:01 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
650Jun 29 10:45:01 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
651Jun 29 10:45:01 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
652Jun 29 10:45:01 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
653Jun 29 10:45:01 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
654Jun 29 10:45:01 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: DTMF detected, ts=179, flags=0000, data=4
655Jun 29 10:45:01 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
656Jun 29 10:45:02 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
657Jun 29 10:45:02 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
658Jun 29 10:45:02 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
659Jun 29 10:45:02 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=1
660Jun 29 10:45:02 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: CAS event, ts=179, flags=0000, data=9
661Jun 29 10:45:02 sip_ua[363]: induct.cpp:581: ---> ts=179, state=NumRecv: DTMF detected, ts=179, flags=0000, data=3
662Jun 29 10:45:02 sip_ua[363]: induct.cpp:499: channel 179: dialing 491743
663Jun 29 10:45:02 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:gw3@127.0.0.1> -> sip:491743@127.0.0.1 ()...
664Jun 29 10:45:02 sip_ua[454]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK1768292773'
665Jun 29 10:45:02 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:491743@127.0.0.1:6060;transport=udp (Call-ID: 2065142821@(null))
666Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=40
667Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=40
668Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=40
669Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=40
670Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=40
671Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=40
672Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=40
673Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=40
674Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=40
675Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=40
676Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=40
677Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Incoming call, ts=-1, flags=0001, data=40
678Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Incoming call, ts=-1, flags=0001, data=40
679Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Incoming call, ts=-1, flags=0001, data=40
680Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Incoming call, ts=-1, flags=0001, data=40
681Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0001, data=40
682Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0001, data=40
683Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0001, data=40
684Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0001, data=40
685Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Busy: Incoming call, ts=-1, flags=0001, data=40
686Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0001, data=40
687Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0001, data=40
688Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=36, state=Idle: Incoming call, ts=-1, flags=0001, data=40
689Jun 29 10:45:02 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Idle: Incoming call, ts=-1, flags=0001, data=40
690Jun 29 10:45:02 sip_ua[363]: fxs.cpp:962: channel 37: ringing
691Jun 29 10:45:02 sip_ua[363]: user_agent.cpp:3484: Call 40 routed to TS 37
692Jun 29 10:45:02 sip_ua[387]: user_agent.cpp:2132: ---> ringing with status code 180
693Jun 29 10:45:02 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Outgoing: Remote is ringing, ts=179, flags=0000, data=39
694Jun 29 10:45:03 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Outgoing: CAS event, ts=179, flags=0000, data=1
695Jun 29 10:45:03 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Outgoing: CAS event, ts=179, flags=0000, data=9
696Jun 29 10:45:03 sip_ua[363]: induct.cpp:153: channel 179 (Outgoing): long signal received (102 ms)
697Jun 29 10:45:10 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: CAS A activity detected, ts=15, flags=0000, data=0
698Jun 29 10:45:10 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: CAS event, ts=15, flags=0000, data=7
699Jun 29 10:45:10 sip_ua[363]: fxs.cpp:702: ==> ts 15: manual CAS event processing (abcd=15)
700Jun 29 10:45:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialtone: CAS event, ts=15, flags=0000, data=7
701Jun 29 10:45:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialtone: DTMF detected, ts=15, flags=0000, data=8
702Jun 29 10:45:11 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone completed, ts=15, flags=0000, data=3
703Jun 29 10:45:12 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
704Jun 29 10:45:13 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=3
705Jun 29 10:45:13 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
706Jun 29 10:45:13 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=4
707Jun 29 10:45:13 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
708Jun 29 10:45:14 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=2
709Jun 29 10:45:14 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
710Jun 29 10:45:14 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=2
711Jun 29 10:45:14 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
712Jun 29 10:45:15 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=8
713Jun 29 10:45:15 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
714Jun 29 10:45:15 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=0
715Jun 29 10:45:15 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
716Jun 29 10:45:15 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=1
717Jun 29 10:45:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
718Jun 29 10:45:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=1
719Jun 29 10:45:16 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
720Jun 29 10:45:19 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=8
721Jun 29 10:45:19 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
722Jun 29 10:45:19 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: DTMF detected, ts=15, flags=0000, data=6
723Jun 29 10:45:19 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Dialing: Tone detected, ts=15, flags=0000, data=255
724Jun 29 10:45:24 sip_ua[363]: fxs.cpp:241: ts 15: dialing 83422801186
725Jun 29 10:45:24 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:491717@127.0.0.1> -> sip:83422801186@127.0.0.1 ()...
726Jun 29 10:45:24 sip_ua[454]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK1518198748'
727Jun 29 10:45:24 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:83422801186@127.0.0.1:6060;transport=udp (Call-ID: 1597825930@(null))
728Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=44
729Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=44
730Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=44
731Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=44
732Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=44
733Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=44
734Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=44
735Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=44
736Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=44
737Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=44
738Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=44
739Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Incoming call, ts=-1, flags=0001, data=44
740Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Incoming call, ts=-1, flags=0001, data=44
741Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Incoming call, ts=-1, flags=0001, data=44
742Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Incoming call, ts=-1, flags=0001, data=44
743Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0001, data=44
744Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0001, data=44
745Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0001, data=44
746Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0001, data=44
747Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=36, state=Idle: Incoming call, ts=-1, flags=0001, data=44
748Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Ringing: Incoming call, ts=-1, flags=0001, data=44
749Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=38, state=Idle: Incoming call, ts=-1, flags=0001, data=44
750Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Incoming call, ts=-1, flags=0001, data=44
751Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Incoming call, ts=-1, flags=0001, data=44
752Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0001, data=44
753Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0001, data=44
754Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0001, data=44
755Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0001, data=44
756Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0001, data=44
757Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Incoming call, ts=-1, flags=0001, data=44
758Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0001, data=44
759Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0001, data=44
760Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Incoming call, ts=-1, flags=0001, data=44
761Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Incoming call, ts=-1, flags=0001, data=44
762Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0001, data=44
763Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0001, data=44
764Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0001, data=44
765Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Incoming call, ts=-1, flags=0001, data=44
766Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Incoming call, ts=-1, flags=0001, data=44
767Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0001, data=44
768Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0001, data=44
769Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0001, data=44
770Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0001, data=44
771Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0001, data=44
772Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0001, data=44
773Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0001, data=44
774Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0001, data=44
775Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0001, data=44
776Jun 29 10:45:24 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Idle: Incoming call, ts=-1, flags=0001, data=44
777Jun 29 10:45:24 sip_ua[363]: induct.cpp:581: ---> ts=178, state=Idle: Incoming call, ts=-1, flags=0001, data=44
778Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0002, data=44
779Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0002, data=44
780Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0002, data=44
781Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0002, data=44
782Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0002, data=44
783Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0002, data=44
784Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Incoming call, ts=-1, flags=0002, data=44
785Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Incoming call, ts=-1, flags=0002, data=44
786Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Incoming call, ts=-1, flags=0002, data=44
787Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Incoming call, ts=-1, flags=0002, data=44
788Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0002, data=44
789Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0002, data=44
790Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0002, data=44
791Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0002, data=44
792Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Busy: Incoming call, ts=-1, flags=0002, data=44
793Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0002, data=44
794Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0002, data=44
795Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: Incoming call, ts=-1, flags=0002, data=44
796Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=25, state=Idle: Incoming call, ts=-1, flags=0002, data=44
797Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=26, state=Idle: Incoming call, ts=-1, flags=0002, data=44
798Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=27, state=Idle: Incoming call, ts=-1, flags=0002, data=44
799Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: Incoming call, ts=-1, flags=0002, data=44
800Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=54, state=Idle: Incoming call, ts=-1, flags=0002, data=44
801Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Incoming call, ts=-1, flags=0002, data=44
802Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=56, state=Idle: Incoming call, ts=-1, flags=0002, data=44
803Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=57, state=Idle: Incoming call, ts=-1, flags=0002, data=44
804Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Incoming call, ts=-1, flags=0002, data=44
805Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0002, data=44
806Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0002, data=44
807Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Incoming call, ts=-1, flags=0002, data=44
808Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Incoming call, ts=-1, flags=0002, data=44
809Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0002, data=44
810Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0002, data=44
811Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0002, data=44
812Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=79, state=Idle: Incoming call, ts=-1, flags=0002, data=44
813Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=81, state=Idle: Incoming call, ts=-1, flags=0002, data=44
814Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=82, state=Idle: Incoming call, ts=-1, flags=0002, data=44
815Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=83, state=Idle: Incoming call, ts=-1, flags=0002, data=44
816Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=84, state=Idle: Incoming call, ts=-1, flags=0002, data=44
817Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=85, state=Idle: Incoming call, ts=-1, flags=0002, data=44
818Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0002, data=44
819Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0002, data=44
820Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0002, data=44
821Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0002, data=44
822Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0002, data=44
823Jun 29 10:45:24 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0002, data=44
824Jun 29 10:45:25 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0002, data=44
825Jun 29 10:45:25 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Idle: Incoming call, ts=-1, flags=0002, data=44
826Jun 29 10:45:25 sip_ua[363]: user_agent.cpp:3484: Call 44 routed to TS 177
827Jun 29 10:45:25 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialing: CAS event, ts=177, flags=0000, data=1
828Jun 29 10:45:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialing: CAS event, ts=177, flags=0000, data=9
829Jun 29 10:45:26 sip_ua[363]: induct.cpp:153: channel 177 (Dialing): long signal received (102 ms)
830Jun 29 10:45:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Answered: Tone detected, ts=177, flags=0000, data=47
831Jun 29 10:45:26 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Answered: CAS A activity detected, ts=177, flags=0000, data=131072
832Jun 29 10:45:27 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Answered: Tone completed, ts=177, flags=0000, data=0
833Jun 29 10:45:27 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialing: CAS event, ts=177, flags=0000, data=9
834Jun 29 10:45:27 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialing: CAS event, ts=177, flags=0000, data=9
835Jun 29 10:45:37 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: RTP parameters, ts=177, flags=0000, data=44
836Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6606: --> ts 177: 192.168.1.155[10354] --> 192.168.1.155[10030]
837Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6607: --> ts 177: codec PCMA, VAD is off
838Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6608: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
839Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6723: ts 177: starting RTP stream to 02:ad:c3:00:00:ea
840Jun 29 10:45:37 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: Statistics received, ts=177, flags=0000, data=0
841Jun 29 10:45:37 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: Statistics received, ts=177, flags=0000, data=0
842Jun 29 10:45:37 sip_ua[387]: user_agent.cpp:2132: ---> ringing with status code 183
843Jun 29 10:45:37 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: RTP parameters, ts=15, flags=0000, data=43
844Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6606: --> ts 15: 192.168.1.155[10030] --> 192.168.1.155[10354]
845Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6607: --> ts 15: codec PCMA, VAD is off
846Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6608: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
847Jun 29 10:45:37 sip_ua[363]: comcerto.cpp:6723: ts 15: starting RTP stream to 02:ad:c3:00:00:ea
848Jun 29 10:45:37 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Have media, ts=15, flags=0000, data=43
849Jun 29 10:45:37 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Statistics received, ts=15, flags=0000, data=0
850Jun 29 10:45:37 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Statistics received, ts=15, flags=0000, data=0
851Jun 29 10:45:37 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: RTP monitor, ts=177, flags=0000, data=1
852Jun 29 10:45:42 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: Statistics received, ts=177, flags=0000, data=0
853Jun 29 10:45:42 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: Statistics received, ts=177, flags=0000, data=0
854Jun 29 10:45:42 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Statistics received, ts=15, flags=0000, data=0
855Jun 29 10:45:42 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Statistics received, ts=15, flags=0000, data=0
856Jun 29 10:45:47 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: Statistics received, ts=177, flags=0000, data=0
857Jun 29 10:45:47 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: Statistics received, ts=177, flags=0000, data=0
858Jun 29 10:45:47 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Statistics received, ts=15, flags=0000, data=0
859Jun 29 10:45:47 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Statistics received, ts=15, flags=0000, data=0
860Jun 29 10:45:49 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: CAS event, ts=177, flags=0000, data=1
861Jun 29 10:45:49 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Dialed: CAS event, ts=177, flags=0000, data=9
862Jun 29 10:45:49 sip_ua[363]: induct.cpp:153: channel 177 (Dialed): long signal received (100 ms)
863Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:5838: channel 177: answer
864Jun 29 10:45:50 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: RTP parameters, ts=177, flags=0000, data=44
865Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6606: --> ts 177: 192.168.1.155[10354] --> 192.168.1.155[10030]
866Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6607: --> ts 177: codec PCMA, VAD is off
867Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6608: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
868Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6723: ts 177: starting RTP stream to 02:ad:c3:00:00:ea
869Jun 29 10:45:50 sip_ua[454]: repro.cpp:578: doSessionAccounting(): Session Established 'branch=z9hG4bK1518198748'
870Jun 29 10:45:50 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
871Jun 29 10:45:50 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
872Jun 29 10:45:50 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: RTP monitor, ts=177, flags=0000, data=1
873Jun 29 10:45:50 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: RTP parameters, ts=15, flags=0000, data=43
874Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6606: --> ts 15: 192.168.1.155[10030] --> 192.168.1.155[10354]
875Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6607: --> ts 15: codec PCMA, VAD is off
876Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6608: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
877Jun 29 10:45:50 sip_ua[363]: comcerto.cpp:6723: ts 15: starting RTP stream to 02:ad:c3:00:00:ea
878Jun 29 10:45:50 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Calling: Call answered, ts=15, flags=0000, data=43
879Jun 29 10:45:50 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
880Jun 29 10:45:50 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
881Jun 29 10:45:50 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: ACK received, ts=177, flags=0000, data=44
882Jun 29 10:45:55 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
883Jun 29 10:45:55 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
884Jun 29 10:45:55 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
885Jun 29 10:45:55 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
886Jun 29 10:46:00 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
887Jun 29 10:46:00 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Statistics received, ts=177, flags=0000, data=0
888Jun 29 10:46:00 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
889Jun 29 10:46:00 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: Statistics received, ts=15, flags=0000, data=0
890Jun 29 10:46:04 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Connected: CAS event, ts=15, flags=0000, data=15
891Jun 29 10:46:04 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Call disconnected, ts=15, flags=0000, data=43
892Jun 29 10:46:04 sip_ua[454]: repro.cpp:516: doSessionAccounting(): Session Ended 'branch=z9hG4bK851060302'
893Jun 29 10:46:04 sip_ua[454]: repro.cpp:534: --> duration=14, [FXS]tel:491717@127.0.0.1: ---> [1IND]tel:83422801186@127.0.0.1:
894Jun 29 10:46:04 sip_ua[454]: repro.cpp:701: --> CDR: {"answer":1530269150,"duration":14,"hangup":1530269164,"org-host":"127.0.0.1","org-scheme":"tel","org-type":"FXS","org-user":"491717","to-host":"127.0.0.1","to-scheme":"tel","to-type":"1IND","to-use
895Jun 29 10:46:04 sip_ua[387]: user_agent.cpp:2763: ---> BYE in call received
896Jun 29 10:46:04 sip_ua[363]: induct.cpp:581: ---> ts=177, state=Connected: Call disconnected, ts=177, flags=0000, data=44
897Jun 29 10:46:04 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Message answered 2xx, ts=15, flags=0000, data=43
898Jun 29 10:46:32 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Outgoing: CAS event, ts=179, flags=0000, data=1
899Jun 29 10:46:33 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Outgoing: CAS event, ts=179, flags=0000, data=9
900Jun 29 10:46:33 sip_ua[363]: induct.cpp:159: channel 179 (Outgoing): disconnect received (522 ms)
901Jun 29 10:46:33 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Blocked: Call disconnected, ts=179, flags=0000, data=39
902Jun 29 10:46:33 sip_ua[454]: repro.cpp:552: doSessionAccounting(): Session Cancelled 'branch=z9hG4bK1768292773'
903Jun 29 10:46:33 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Ringing: Call disconnected, ts=37, flags=0000, data=40
904Jun 29 10:46:33 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Idle: Call disconnected, ts=37, flags=0000, data=40
905Jun 29 10:46:33 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Blocked: Call disconnected, ts=179, flags=0000, data=39
906Jun 29 10:46:34 sip_ua[363]: induct.cpp:581: ---> ts=179, state=Idle: Tone completed, ts=179, flags=0000, data=3