Ticket #271: messages

File messages, 67.8 KB (added by san, 7 years ago)
Line 
1Jan 1 00:00:11 syslogd started: BusyBox v1.18.5
2Jan 1 00:00:11 kernel: klogd started: BusyBox v1.18.5 (2017-06-14 11:09:48 +05)
3Jan 1 00:00:11 kernel: Linux version 2.6.22.19-4.07.0-candidate1-c300evm (alx@ubuntu) (gcc version 5.3.0 (GCC) ) #4 Wed Mar 29 09:28:44 +05 2017
4Jan 1 00:00:11 kernel: CPU: ARMv6-compatible processor [4117b361] revision 1 (ARMv6TEJ), cr=00c5387f
5Jan 1 00:00:11 kernel: Machine: Comcerto 300 (x570)
6Jan 1 00:00:11 kernel: Memory policy: ECC disabled, Data cache writeback
7Jan 1 00:00:11 kernel: On node 0 totalpages: 28416
8Jan 1 00:00:11 kernel: DMA zone: 222 pages used for memmap
9Jan 1 00:00:11 kernel: DMA zone: 0 pages reserved
10Jan 1 00:00:11 kernel: DMA zone: 28194 pages, LIFO batch:7
11Jan 1 00:00:11 kernel: Normal zone: 0 pages used for memmap
12Jan 1 00:00:11 kernel: CPU0: D VIPT write-back cache
13Jan 1 00:00:11 kernel: JFFS2 version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
14Jan 1 00:00:11 kernel: io scheduler noop registered
15Jan 1 00:00:11 kernel: io scheduler deadline registered (default)
16Jan 1 00:00:11 kernel: comcerto_wdt: support registered
17Jan 1 00:00:11 kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
18Jan 1 00:00:11 kernel: serial8250.0: ttyS0 at MMIO 0x10090000 (irq = 41) is a 16550A
19Jan 1 00:00:11 kernel: serial8250.0: ttyS1 at MMIO 0x10094000 (irq = 57) is a 16550A
20Jan 1 00:00:11 kernel: loop: module loaded
21Jan 1 00:00:11 kernel: nbd: registered device at major 43
22Jan 1 00:00:11 kernel: comcerto-eth comcerto-eth.0: eth0 support disabled
23Jan 1 00:00:11 kernel: comcerto-eth: probe of comcerto-eth.0 failed with error -16
24Jan 1 00:00:11 sysinit: brctl: SIOCGIFBR: Package not installed
25Jan 1 00:00:12 sysinit: sh: bad number
26Jan 1 00:00:12 sysinit: sh: bad number
27Jan 1 00:00:12 sysinit: sh: bad number
28Jan 1 00:00:12 sysinit: sh: bad number
29Jan 1 00:00:12 sysinit: sh: bad number
30Jan 1 00:00:12 sysinit: sh: bad number
31Jan 1 00:00:12 sysinit: sh: bad number
32Jan 1 00:00:12 sysinit: sh: bad number
33Jan 1 00:00:13 dnsmasq[341]: started, version 2.75 cachesize 150
34Jan 1 00:00:13 dnsmasq[341]: compile time options: no-IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect no-inotify
35Jan 1 00:00:13 dnsmasq[341]: using local addresses only for domain lan
36Jan 1 00:00:13 dnsmasq[341]: no servers found in /tmp/resolv.conf.auto, will retry
37Jan 1 00:00:13 dnsmasq[341]: read /etc/hosts - 1 addresses
38Jan 1 00:00:13 kernel: NET: Registered protocol family 27
39Jan 1 00:00:13 dropbear[346]: Running in background
40Jan 1 00:00:14 sysinit: sh: bad number
41Jan 1 00:00:14 sysinit: sh: bad number
42Jan 1 00:00:14 sysinit: sh: bad number
43Jan 1 00:00:14 sysinit: sh: bad number
44Jan 1 00:00:14 sysinit: sh: bad number
45Jan 1 00:00:14 sysinit: sh: bad number
46Jan 1 00:00:14 sysinit: sh: bad number
47Jan 1 00:00:14 sysinit: sh: bad number
48Jan 1 00:00:14 sysinit: dnsmasq: failed to create listening socket for port 53: Address already in use
49Jan 1 00:00:14 dnsmasq[355]: failed to create listening socket for port 53: Address already in use
50Jan 1 00:00:14 dnsmasq[355]: FAILED to start up
51Jan 1 00:00:15 sysinit: starting SIP UA daemon: sip_ua... done.
52Jan 1 00:00:15 sysinit: sysctl: error: 'net.netfilter.nf_conntrack_checksum' is an unknown key
53Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_checksum' is an unknown key
54Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_max' is an unknown key
55Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_tcp_timeout_established' is an unknown key
56Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout' is an unknown key
57Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout_stream' is an unknown key
58Jan 1 00:00:21 sip_ua[363]: sip_ua.cpp:328: starting sip_ua
59Jan 1 00:00:21 sip_ua[363]: sip_ua.cpp:329: main thread PID is 363
60Jan 1 00:00:21 sip_ua[363]: sip_ua.cpp:338: Waiting for jffs2 scan finish and CPU idle time > 90%...
61Jan 1 00:00:22 kernel: eth1: no IPv6 routers present
62Jan 1 00:00:22 sip_ua[363]: sip_ua.cpp:347: CPU idle 0%
63Jan 1 00:00:23 sip_ua[363]: sip_ua.cpp:347: CPU idle 28%
64Jan 1 00:00:24 sip_ua[363]: sip_ua.cpp:347: CPU idle 100%
65Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4583: ==> SFKEYS: 0460 0305 bfef f6f7
66Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4959: Comcerto C300 device: M82359 Rev.A
67Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4960: Comcerto C300 firmware version: v11_26_02_04_PSN_01
68Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4961: Comcerto C300 SPU version: C64V_7_10_3
69Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4962: Device initialized successfully
70Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4969: TDM initialized successfully
71Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4839: Device mac address: 02:ad:c3:00:00:ea
72Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4981: Channels available: 160 (G.711 20ms)
73Jan 1 00:00:24 sip_ua[363]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
74Jan 1 00:00:24 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
75Jan 1 00:00:24 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
76Jan 1 00:00:25 sip_ua[363]: poller.cpp:2324: firmware revision: 41
77Jan 1 00:00:25 sip_ua[386]: poller.cpp:1841: Configuring FPGA...
78Jan 1 00:00:26 sip_ua[386]: poller.cpp:1850: FPGA configured successfully
79Jan 1 00:00:31 sip_ua[386]: poller.cpp:1588: switching to CRC32 mode
80Jan 1 00:00:31 sip_ua[386]: poller.cpp:771: ===> command globalconf received
81Jan 1 00:00:31 sip_ua[386]: poller.cpp:1157: downloading sound files from http://192.168.1.154/files/ve-01/sounds...
82Jan 1 00:00:31 dnsmasq[341]: reading /tmp/resolv.conf.auto
83Jan 1 00:00:31 dnsmasq[341]: using local addresses only for domain lan
84Jan 1 00:00:31 dnsmasq[341]: using nameserver 8.8.8.8#53
85Jan 1 00:00:31 sip_ua[363]: sip_ua.cpp:511: IP change 127.0.0.1 --> 192.168.1.155 detected
86Jun 8 11:42:22 sip_ua[363]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
87Jun 8 11:42:22 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
88Jun 8 11:42:22 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
89Jun 8 11:42:22 sip_ua[386]: poller.cpp:771: ===> command globalconf received
90Jun 8 11:42:23 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Channel settings, ts=9, flags=0000, data=0
91Jun 8 11:42:23 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Channel settings, ts=10, flags=0000, data=0
92Jun 8 11:42:23 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Channel settings, ts=11, flags=0000, data=0
93Jun 8 11:42:23 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Channel settings, ts=12, flags=0000, data=0
94Jun 8 11:42:23 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Channel settings, ts=13, flags=0000, data=0
95Jun 8 11:42:23 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Channel settings, ts=14, flags=0000, data=0
96Jun 8 11:42:23 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Channel settings, ts=15, flags=0000, data=0
97Jun 8 11:42:23 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: Channel settings, ts=177, flags=0000, data=0
98Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: Channel settings, ts=178, flags=0000, data=0
99Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: CAS event, ts=177, flags=0000, data=9
100Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: CAS event, ts=178, flags=0000, data=9
101Jun 8 11:42:24 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Channel settings, ts=14, flags=0000, data=0
102Jun 8 11:42:24 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Channel settings, ts=15, flags=0000, data=0
103Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: Channel settings, ts=177, flags=0000, data=0
104Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: Channel settings, ts=178, flags=0000, data=0
105Jun 8 11:42:24 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Channel settings, ts=17, flags=0000, data=0
106Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: Channel settings, ts=179, flags=0000, data=0
107Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: Channel settings, ts=180, flags=0000, data=0
108Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: Channel settings, ts=181, flags=0000, data=0
109Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: Channel settings, ts=182, flags=0000, data=0
110Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: CAS event, ts=179, flags=0000, data=9
111Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: CAS event, ts=180, flags=0000, data=9
112Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: CAS event, ts=181, flags=0000, data=9
113Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: CAS event, ts=182, flags=0000, data=9
114Jun 8 11:42:24 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Channel settings, ts=17, flags=0000, data=0
115Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: Channel settings, ts=179, flags=0000, data=0
116Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: Channel settings, ts=180, flags=0000, data=0
117Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: Channel settings, ts=181, flags=0000, data=0
118Jun 8 11:42:24 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: Channel settings, ts=182, flags=0000, data=0
119Jun 8 11:42:25 sip_ua[386]: poller.cpp:771: ===> command sslapply received
120Jun 8 11:42:26 sip_ua[386]: poller.cpp:2209: download complete
121Jun 8 11:42:27 sip_ua[386]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
122Jun 8 11:42:27 sip_ua[386]: repro.cpp:1141: --> skipping secure transport: no cert file
123Jun 8 11:42:27 sip_ua[386]: repro.cpp:1141: --> skipping secure transport: no cert file
124Jun 8 11:42:28 sip_ua[386]: poller.cpp:771: ===> command userlist received
125Jun 8 11:42:28 sip_ua[386]: user_agent.cpp:196: User directory updated
126Jun 8 11:42:29 sip_ua[386]: poller.cpp:771: ===> command ctime received
127Jun 8 11:42:29 sip_ua[386]: poller.cpp:771: ===> command routes received
128Jun 8 11:42:30 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: Channel settings, ts=183, flags=0000, data=0
129Jun 8 11:42:30 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Channel settings, ts=1, flags=0000, data=0
130Jun 8 11:42:30 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: CAS event, ts=183, flags=0000, data=9
131Jun 8 11:42:31 dropbear[478]: Child connection from ::ffff:192.168.1.46:1046
132Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
133Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Channel settings, ts=3, flags=0000, data=0
134Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Channel settings, ts=4, flags=0000, data=0
135Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Channel settings, ts=5, flags=0000, data=0
136Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Channel settings, ts=6, flags=0000, data=0
137Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Channel settings, ts=7, flags=0000, data=0
138Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Channel settings, ts=8, flags=0000, data=0
139Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
140Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
141Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
142Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
143Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
144Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
145Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
146Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
147Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
148Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
149Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
150Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
151Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
152Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0001, data=0
153Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0002, data=0
154Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0002, data=0
155Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0002, data=0
156Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
157Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
158Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
159Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
160Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0002, data=0
161Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
162Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
163Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0
164Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
165Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
166Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
167Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
168Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
169Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
170Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
171Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0001, data=0
172Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0001, data=0
173Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0001, data=0
174Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0001, data=0
175Jun 8 11:42:31 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0001, data=0
176Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0002, data=0
177Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0002, data=0
178Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0002, data=0
179Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
180Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
181Jun 8 11:42:31 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
182Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0002, data=0
183Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
184Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
185Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
186Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
187Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
188Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
189Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
190Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0002, data=0
191Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0002, data=0
192Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0002, data=0
193Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
194Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
195Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
196Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
197Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
198Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0002, data=0
199Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0002, data=0
200Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0002, data=0
201Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0002, data=0
202Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0002, data=0
203Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0002, data=0
204Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0002, data=0
205Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0002, data=0
206Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
207Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
208Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0
209Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
210Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
211Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
212Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
213Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
214Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
215Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
216Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
217Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
218Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
219Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
220Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
221Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
222Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
223Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
224Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
225Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0001, data=0
226Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0001, data=0
227Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0001, data=0
228Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0002, data=0
229Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
230Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
231Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
232Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
233Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
234Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
235Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
236Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0002, data=0
237Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0002, data=0
238Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0002, data=0
239Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
240Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
241Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
242Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
243Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
244Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
245Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
246Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
247Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
248Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
249Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
250Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
251Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0001, data=0
252Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0001, data=0
253Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0002, data=0
254Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0002, data=0
255Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0002, data=0
256Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
257Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
258Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
259Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
260Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
261Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
262Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
263Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0002, data=0
264Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
265Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
266Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0
267Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
268Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
269Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
270Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
271Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
272Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
273Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
274Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
275Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
276Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
277Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
278Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
279Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
280Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
281Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
282Jun 8 11:42:32 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
283Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0001, data=0
284Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0001, data=0
285Jun 8 11:42:32 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0001, data=0
286Jun 8 11:42:32 dropbear[478]: 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.46:1046
287Jun 8 11:47:37 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: CAS event, ts=177, flags=0000, data=1
288Jun 8 11:47:37 sip_ua[363]: induct.cpp:565: ---> ts=177, state=RBlocked: CAS event, ts=177, flags=0000, data=9
289Jun 8 11:47:37 sip_ua[363]: induct.cpp:153: channel 177 (RBlocked): long signal received (100 ms)
290Jun 8 11:47:37 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
291Jun 8 11:47:37 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
292Jun 8 11:47:37 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
293Jun 8 11:47:37 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
294Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
295Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
296Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
297Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
298Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
299Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
300Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
301Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
302Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
303Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
304Jun 8 11:47:38 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: DTMF detected, ts=177, flags=0000, data=7
305Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
306Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
307Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
308Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
309Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
310Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
311Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
312Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
313Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
314Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
315Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
316Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
317Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
318Jun 8 11:47:39 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
319Jun 8 11:47:40 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
320Jun 8 11:47:40 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
321Jun 8 11:47:40 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
322Jun 8 11:47:40 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
323Jun 8 11:47:40 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
324Jun 8 11:47:40 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
325Jun 8 11:47:40 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: DTMF detected, ts=177, flags=0000, data=0
326Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
327Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
328Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
329Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
330Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
331Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
332Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
333Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
334Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
335Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
336Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
337Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
338Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
339Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
340Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
341Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
342Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
343Jun 8 11:47:41 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
344Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=1
345Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: CAS event, ts=177, flags=0000, data=9
346Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=NumRecv: DTMF detected, ts=177, flags=0000, data=0
347Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: DTMF detected, ts=177, flags=0000, data=13
348Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: Tone detected, ts=177, flags=0000, data=255
349Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: DTMF detected, ts=177, flags=0000, data=1
350Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: Tone detected, ts=177, flags=0000, data=255
351Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: DTMF detected, ts=177, flags=0000, data=7
352Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: Tone detected, ts=177, flags=0000, data=255
353Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: DTMF detected, ts=177, flags=0000, data=4
354Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: Tone detected, ts=177, flags=0000, data=255
355Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: DTMF detected, ts=177, flags=0000, data=14
356Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: Tone detected, ts=177, flags=0000, data=255
357Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: DTMF detected, ts=177, flags=0000, data=0
358Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequest: Tone completed, ts=177, flags=0000, data=0
359Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
360Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: DTMF detected, ts=177, flags=0000, data=7
361Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
362Jun 8 11:47:42 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: DTMF detected, ts=177, flags=0000, data=4
363Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
364Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: DTMF detected, ts=177, flags=0000, data=5
365Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
366Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: DTMF detected, ts=177, flags=0000, data=13
367Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
368Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: DTMF detected, ts=177, flags=0000, data=1
369Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
370Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: DTMF detected, ts=177, flags=0000, data=7
371Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
372Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: DTMF detected, ts=177, flags=0000, data=4
373Jun 8 11:47:43 sip_ua[363]: induct.cpp:565: ---> ts=177, state=AONrequested: Tone detected, ts=177, flags=0000, data=255
374Jun 8 11:47:43 kernel: comcerto_wdt: closed unexpectedly. WDT will not stop!
375Jan 1 00:00:11 syslogd started: BusyBox v1.18.5
376Jan 1 00:00:11 kernel: klogd started: BusyBox v1.18.5 (2017-06-14 11:09:48 +05)
377Jan 1 00:00:11 kernel: Linux version 2.6.22.19-4.07.0-candidate1-c300evm (alx@ubuntu) (gcc version 5.3.0 (GCC) ) #4 Wed Mar 29 09:28:44 +05 2017
378Jan 1 00:00:11 kernel: CPU: ARMv6-compatible processor [4117b361] revision 1 (ARMv6TEJ), cr=00c5387f
379Jan 1 00:00:11 kernel: Machine: Comcerto 300 (x570)
380Jan 1 00:00:11 kernel: Memory policy: ECC disabled, Data cache writeback
381Jan 1 00:00:11 kernel: On node 0 totalpages: 28416
382Jan 1 00:00:11 kernel: DMA zone: 222 pages used for memmap
383Jan 1 00:00:11 kernel: DMA zone: 0 pages reserved
384Jan 1 00:00:11 kernel: DMA zone: 28194 pages, LIFO batch:7
385Jan 1 00:00:11 kernel: Normal zone: 0 pages used for memmap
386Jan 1 00:00:11 kernel: CPU0: D VIPT write-back cache
387Jan 1 00:00:11 kernel: 0(irq=33, type=1, trigger=0)
388Jan 1 00:00:11 kernel: 3(irq=36, type=1, trigger=0)
389Jan 1 00:00:11 kernel: Generic PHY: Registered new driver
390Jan 1 00:00:11 kernel: NET: Registered protocol family 2
391Jan 1 00:00:11 kernel: Time: timer3 clocksource has been installed.
392Jan 1 00:00:11 kernel: Switched to high resolution mode on CPU 0
393Jan 1 00:00:11 kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
394Jan 1 00:00:11 kernel: TCP established hash table entries: 4096 (order: 3, 32768 bytes)
395Jan 1 00:00:11 kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
396Jan 1 00:00:11 kernel: TCP: Hash tables configured (established 4096 bind 4096)
397Jan 1 00:00:11 kernel: TCP reno registered
398Jan 1 00:00:12 sysinit: brctl: SIOCGIFBR: Package not installed
399Jan 1 00:00:12 sysinit: sh: bad number
400Jan 1 00:00:12 sysinit: sh: bad number
401Jan 1 00:00:12 sysinit: sh: bad number
402Jan 1 00:00:12 sysinit: sh: bad number
403Jan 1 00:00:12 sysinit: sh: bad number
404Jan 1 00:00:12 sysinit: sh: bad number
405Jan 1 00:00:12 sysinit: sh: bad number
406Jan 1 00:00:12 sysinit: sh: bad number
407Jan 1 00:00:13 dnsmasq[329]: started, version 2.75 cachesize 150
408Jan 1 00:00:13 dnsmasq[329]: compile time options: no-IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP no-DHCPv6 no-Lua TFTP no-conntrack no-ipset no-auth no-DNSSEC loop-detect no-inotify
409Jan 1 00:00:13 dnsmasq[329]: using local addresses only for domain lan
410Jan 1 00:00:13 dnsmasq[329]: no servers found in /tmp/resolv.conf.auto, will retry
411Jan 1 00:00:13 dnsmasq[329]: read /etc/hosts - 1 addresses
412Jan 1 00:00:13 kernel: NET: Registered protocol family 27
413Jan 1 00:00:14 dropbear[346]: Running in background
414Jan 1 00:00:14 sysinit: sh: bad number
415Jan 1 00:00:14 sysinit: sh: bad number
416Jan 1 00:00:14 sysinit: sh: bad number
417Jan 1 00:00:14 sysinit: sh: bad number
418Jan 1 00:00:14 sysinit: sh: bad number
419Jan 1 00:00:14 sysinit: sh: bad number
420Jan 1 00:00:14 sysinit: sh: bad number
421Jan 1 00:00:14 sysinit: sh: bad number
422Jan 1 00:00:14 sysinit: dnsmasq: failed to create listening socket for port 53: Address already in use
423Jan 1 00:00:14 dnsmasq[355]: failed to create listening socket for port 53: Address already in use
424Jan 1 00:00:14 dnsmasq[355]: FAILED to start up
425Jan 1 00:00:15 sysinit: starting SIP UA daemon: sip_ua... done.
426Jan 1 00:00:15 sysinit: sysctl: error: 'net.netfilter.nf_conntrack_checksum' is an unknown key
427Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_checksum' is an unknown key
428Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_max' is an unknown key
429Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_tcp_timeout_established' is an unknown key
430Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout' is an unknown key
431Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout_stream' is an unknown key
432Jan 1 00:00:21 sip_ua[363]: sip_ua.cpp:328: starting sip_ua
433Jan 1 00:00:21 sip_ua[363]: sip_ua.cpp:329: main thread PID is 363
434Jan 1 00:00:21 sip_ua[363]: sip_ua.cpp:338: Waiting for jffs2 scan finish and CPU idle time > 90%...
435Jan 1 00:00:22 sip_ua[363]: sip_ua.cpp:347: CPU idle 0%
436Jan 1 00:00:22 kernel: eth1: no IPv6 routers present
437Jan 1 00:00:23 sip_ua[363]: sip_ua.cpp:347: CPU idle 28%
438Jan 1 00:00:24 sip_ua[363]: sip_ua.cpp:347: CPU idle 100%
439Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4583: ==> SFKEYS: 0460 0305 bfef f6f7
440Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4959: Comcerto C300 device: M82359 Rev.A
441Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4960: Comcerto C300 firmware version: v11_26_02_04_PSN_01
442Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4961: Comcerto C300 SPU version: C64V_7_10_3
443Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4962: Device initialized successfully
444Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4969: TDM initialized successfully
445Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4839: Device mac address: 02:ad:c3:00:00:ea
446Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4981: Channels available: 160 (G.711 20ms)
447Jan 1 00:00:25 sip_ua[363]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
448Jan 1 00:00:25 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
449Jan 1 00:00:25 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
450Jan 1 00:00:26 sip_ua[363]: poller.cpp:2324: firmware revision: 41
451Jan 1 00:00:26 sip_ua[386]: poller.cpp:1841: Configuring FPGA...
452Jan 1 00:00:26 sip_ua[386]: poller.cpp:1850: FPGA configured successfully
453Jan 1 00:00:28 sip_ua[386]: poller.cpp:1588: switching to CRC32 mode
454Jan 1 00:00:28 sip_ua[386]: poller.cpp:771: ===> command globalconf received
455Jan 1 00:00:28 sip_ua[386]: poller.cpp:1157: downloading sound files from http://192.168.1.154/files/ve-01/sounds...
456Jan 1 00:00:28 sip_ua[363]: sip_ua.cpp:511: IP change 127.0.0.1 --> 192.168.1.155 detected
457Jan 1 00:00:28 dnsmasq[329]: reading /tmp/resolv.conf.auto
458Jan 1 00:00:28 dnsmasq[329]: using local addresses only for domain lan
459Jan 1 00:00:28 dnsmasq[329]: using nameserver 8.8.8.8#53
460Jun 8 11:48:59 sip_ua[363]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
461Jun 8 11:48:59 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
462Jun 8 11:48:59 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
463Jun 8 11:48:59 sip_ua[386]: poller.cpp:771: ===> command globalconf received
464Jun 8 11:48:59 sip_ua[363]: comcerto.cpp:2456: comcerto_process_socket(): channel 0xffff: transaction 0x043f15 not found (150a 0602 043f 0000 0000 aaaa)
465Jun 8 11:49:00 sip_ua[386]: poller.cpp:771: ===> command sslapply received
466Jun 8 11:49:02 sip_ua[386]: poller.cpp:2209: download complete
467Jun 8 11:49:02 sip_ua[386]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
468Jun 8 11:49:02 sip_ua[386]: repro.cpp:1141: --> skipping secure transport: no cert file
469Jun 8 11:49:02 sip_ua[386]: repro.cpp:1141: --> skipping secure transport: no cert file
470Jun 8 11:49:02 sip_ua[386]: poller.cpp:771: ===> command sslapply received
471Jun 8 11:49:03 sip_ua[386]: poller.cpp:771: ===> command userlist received
472Jun 8 11:49:03 sip_ua[386]: user_agent.cpp:196: User directory updated
473Jun 8 11:49:03 sip_ua[386]: poller.cpp:771: ===> command ctime received
474Jun 8 11:49:03 sip_ua[386]: poller.cpp:771: ===> command routes received
475Jun 8 11:49:04 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Channel settings, ts=9, flags=0000, data=0
476Jun 8 11:49:04 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Channel settings, ts=10, flags=0000, data=0
477Jun 8 11:49:04 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Channel settings, ts=11, flags=0000, data=0
478Jun 8 11:49:04 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Channel settings, ts=12, flags=0000, data=0
479Jun 8 11:49:04 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Channel settings, ts=13, flags=0000, data=0
480Jun 8 11:49:05 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Channel settings, ts=14, flags=0000, data=0
481Jun 8 11:49:05 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Channel settings, ts=15, flags=0000, data=0
482Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: Channel settings, ts=177, flags=0000, data=0
483Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: Channel settings, ts=178, flags=0000, data=0
484Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: CAS event, ts=177, flags=0000, data=9
485Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: CAS event, ts=178, flags=0000, data=9
486Jun 8 11:49:05 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Channel settings, ts=17, flags=0000, data=0
487Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: Channel settings, ts=179, flags=0000, data=0
488Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: Channel settings, ts=180, flags=0000, data=0
489Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: Channel settings, ts=181, flags=0000, data=0
490Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: Channel settings, ts=182, flags=0000, data=0
491Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: CAS event, ts=179, flags=0000, data=9
492Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: CAS event, ts=180, flags=0000, data=9
493Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: CAS event, ts=181, flags=0000, data=9
494Jun 8 11:49:05 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: CAS event, ts=182, flags=0000, data=9
495Jun 8 11:49:06 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: Channel settings, ts=183, flags=0000, data=0
496Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Channel settings, ts=1, flags=0000, data=0
497Jun 8 11:49:06 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: CAS event, ts=183, flags=0000, data=9
498Jun 8 11:49:06 dropbear[454]: Child connection from ::ffff:192.168.1.46:4100
499Jun 8 11:49:06 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: Channel settings, ts=183, flags=0000, data=0
500Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Channel settings, ts=1, flags=0000, data=0
501Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
502Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Channel settings, ts=3, flags=0000, data=0
503Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Channel settings, ts=4, flags=0000, data=0
504Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Channel settings, ts=5, flags=0000, data=0
505Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
506Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Channel settings, ts=3, flags=0000, data=0
507Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Channel settings, ts=4, flags=0000, data=0
508Jun 8 11:49:06 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Channel settings, ts=5, flags=0000, data=0
509Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Channel settings, ts=6, flags=0000, data=0
510Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Channel settings, ts=7, flags=0000, data=0
511Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Channel settings, ts=8, flags=0000, data=0
512Jun 8 11:49:07 dropbear[454]: 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.46:4100
513Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Channel settings, ts=6, flags=0000, data=0
514Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Channel settings, ts=7, flags=0000, data=0
515Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Channel settings, ts=8, flags=0000, data=0
516Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
517Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
518Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
519Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
520Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
521Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
522Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
523Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
524Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
525Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
526Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
527Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0002, data=0
528Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0002, data=0
529Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0002, data=0
530Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
531Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
532Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
533Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
534Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
535Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0002, data=0
536Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
537Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
538Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0
539Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
540Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
541Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
542Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
543Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
544Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
545Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
546Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
547Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
548Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
549Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
550Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
551Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
552Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
553Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
554Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
555Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
556Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
557Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
558Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
559Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
560Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
561Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0002, data=0
562Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0002, data=0
563Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0002, data=0
564Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0002, data=0
565Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
566Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
567Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
568Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
569Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0001, data=0
570Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0001, data=0
571Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0001, data=0
572Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0001, data=0
573Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0001, data=0
574Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0001, data=0
575Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0001, data=0
576Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0002, data=0
577Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0002, data=0
578Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
579Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
580Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0
581Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
582Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
583Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
584Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
585Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
586Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
587Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
588Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
589Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
590Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
591Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
592Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
593Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
594Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
595Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
596Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
597Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0001, data=0
598Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0001, data=0
599Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0001, data=0
600Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
601Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0
602Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
603Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
604Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
605Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
606Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
607Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
608Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
609Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
610Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
611Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
612Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
613Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
614Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
615Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0002, data=0
616Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0002, data=0
617Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0002, data=0
618Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0002, data=0
619Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0002, data=0
620Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
621Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
622Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0
623Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0001, data=0
624Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0001, data=0
625Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0001, data=0
626Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0001, data=0
627Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0001, data=0
628Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
629Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
630Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
631Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
632Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
633Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
634Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
635Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
636Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
637Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
638Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
639Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0001, data=0
640Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0001, data=0
641Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0001, data=0
642Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0001, data=0
643Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0001, data=0
644Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0001, data=0
645Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0001, data=0
646Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
647Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
648Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
649Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0002, data=0
650Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0002, data=0
651Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0002, data=0
652Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0002, data=0
653Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0002, data=0
654Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0002, data=0
655Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0002, data=0
656Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0002, data=0
657Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
658Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
659Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
660Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
661Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0001, data=0
662Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0001, data=0
663Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0001, data=0
664Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0001, data=0
665Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0001, data=0
666Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0001, data=0
667Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0001, data=0
668Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
669Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
670Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
671Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
672Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
673Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0002, data=0
674Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0002, data=0
675Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0002, data=0
676Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0002, data=0
677Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0002, data=0
678Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0002, data=0
679Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=177, state=Idle: DVO event, ts=-1, flags=0002, data=0
680Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=178, state=Idle: DVO event, ts=-1, flags=0002, data=0
681Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=179, state=Idle: DVO event, ts=-1, flags=0002, data=0
682Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0002, data=0
683Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
684Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
685Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0001, data=0
686Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0001, data=0
687Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0001, data=0
688Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0001, data=0
689Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0001, data=0
690Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0001, data=0
691Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0001, data=0
692Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0001, data=0
693Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0001, data=0
694Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: DVO event, ts=-1, flags=0001, data=0
695Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: DVO event, ts=-1, flags=0001, data=0
696Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=180, state=Idle: DVO event, ts=-1, flags=0001, data=0
697Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0001, data=0
698Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0001, data=0
699Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0001, data=0
700Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: DVO event, ts=-1, flags=0002, data=0
701Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: DVO event, ts=-1, flags=0002, data=0
702Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: DVO event, ts=-1, flags=0002, data=0
703Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: DVO event, ts=-1, flags=0002, data=0
704Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: DVO event, ts=-1, flags=0002, data=0
705Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: DVO event, ts=-1, flags=0002, data=0
706Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: DVO event, ts=-1, flags=0002, data=0
707Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: DVO event, ts=-1, flags=0002, data=0
708Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: DVO event, ts=-1, flags=0002, data=0
709Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: DVO event, ts=-1, flags=0002, data=0
710Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: DVO event, ts=-1, flags=0002, data=0
711Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: DVO event, ts=-1, flags=0002, data=0
712Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: DVO event, ts=-1, flags=0002, data=0
713Jun 8 11:49:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: DVO event, ts=-1, flags=0002, data=0
714Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=181, state=Idle: DVO event, ts=-1, flags=0002, data=0
715Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=182, state=Idle: DVO event, ts=-1, flags=0002, data=0
716Jun 8 11:49:07 sip_ua[363]: induct.cpp:565: ---> ts=183, state=Idle: DVO event, ts=-1, flags=0002, data=0