Ticket #267: messages_1

File messages_1, 101.5 KB (added by san, 7 years ago)
Line 
1Jan 1 00:00:10 syslogd started: BusyBox v1.18.5
2Jan 1 00:00:10 kernel: klogd started: BusyBox v1.18.5 (2017-06-14 11:09:48 +05)
3Jan 1 00:00:10 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:10 kernel: CPU: ARMv6-compatible processor [4117b361] revision 1 (ARMv6TEJ), cr=00c5387f
5Jan 1 00:00:10 kernel: Machine: Comcerto 300 (x570)
6Jan 1 00:00:10 kernel: Memory policy: ECC disabled, Data cache writeback
7Jan 1 00:00:10 kernel: On node 0 totalpages: 28416
8Jan 1 00:00:10 kernel: DMA zone: 222 pages used for memmap
9Jan 1 00:00:10 kernel: DMA zone: 0 pages reserved
10Jan 1 00:00:10 kernel: DMA zone: 28194 pages, LIFO batch:7
11Jan 1 00:00:10 kernel: Normal zone: 0 pages used for memmap
12Jan 1 00:00:10 kernel: CPU0: D VIPT write-back cache
13Jan 1 00:00:10 kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
14Jan 1 00:00:10 kernel: TCP established hash table entries: 4096 (order: 3, 32768 bytes)
15Jan 1 00:00:10 kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
16Jan 1 00:00:10 kernel: TCP: Hash tables configured (established 4096 bind 4096)
17Jan 1 00:00:10 kernel: TCP reno registered
18Jan 1 00:00:10 kernel: msp-driver: version 0.1
19Jan 1 00:00:10 kernel: JFFS2 version 2.2. (NAND) © 2001-2006 Red Hat, Inc.
20Jan 1 00:00:10 kernel: io scheduler noop registered
21Jan 1 00:00:10 kernel: io scheduler deadline registered (default)
22Jan 1 00:00:10 kernel: comcerto_wdt: support registered
23Jan 1 00:00:10 kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
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:12 dnsmasq[329]: started, version 2.75 cachesize 150
34Jan 1 00:00:12 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
35Jan 1 00:00:12 dnsmasq[329]: using local addresses only for domain lan
36Jan 1 00:00:12 dnsmasq[329]: no servers found in /tmp/resolv.conf.auto, will retry
37Jan 1 00:00:12 dnsmasq[329]: 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:13 sysinit: sh: bad number
41Jan 1 00:00:13 sysinit: sh: bad number
42Jan 1 00:00:13 sysinit: sh: bad number
43Jan 1 00:00:13 sysinit: sh: bad number
44Jan 1 00:00:13 sysinit: sh: bad number
45Jan 1 00:00:13 sysinit: sh: bad number
46Jan 1 00:00:13 sysinit: sh: bad number
47Jan 1 00:00:13 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 sysinit: starting SIP UA daemon: sip_ua... done.
51Jan 1 00:00:15 sysinit: sysctl: error: 'net.netfilter.nf_conntrack_checksum' is an unknown key
52Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_checksum' is an unknown key
53Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_max' is an unknown key
54Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_tcp_timeout_established' is an unknown key
55Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout' is an unknown key
56Jan 1 00:00:15 sysinit: sysctl: error: 'net.ipv4.netfilter.ip_conntrack_udp_timeout_stream' is an unknown key
57Jan 1 00:00:20 sip_ua[363]: sip_ua.cpp:328: starting sip_ua
58Jan 1 00:00:20 sip_ua[363]: sip_ua.cpp:329: main thread PID is 363
59Jan 1 00:00:20 sip_ua[363]: sip_ua.cpp:338: Waiting for jffs2 scan finish and CPU idle time > 90%...
60Jan 1 00:00:21 sip_ua[363]: sip_ua.cpp:347: CPU idle 0%
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 28%
63Jan 1 00:00:23 sip_ua[363]: sip_ua.cpp:347: CPU idle 100%
64Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4583: ==> SFKEYS: 0460 0305 bfef f6f7
65Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4959: Comcerto C300 device: M82359 Rev.A
66Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4960: Comcerto C300 firmware version: v11_26_02_04_PSN_01
67Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4961: Comcerto C300 SPU version: C64V_7_10_3
68Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4962: Device initialized successfully
69Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4969: TDM initialized successfully
70Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4839: Device mac address: 02:ad:c3:00:00:e0
71Jan 1 00:00:24 sip_ua[363]: comcerto.cpp:4981: Channels available: 160 (G.711 20ms)
72Jan 1 00:00:24 sip_ua[363]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
73Jan 1 00:00:24 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
74Jan 1 00:00:24 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
75Jan 1 00:00:25 sip_ua[363]: poller.cpp:2324: firmware revision: 42
76Jan 1 00:00:25 sip_ua[386]: poller.cpp:1841: Configuring FPGA...
77Jan 1 00:00:25 sip_ua[386]: poller.cpp:1850: FPGA configured successfully
78Jan 1 00:00:29 sip_ua[386]: poller.cpp:1588: switching to CRC32 mode
79Jan 1 00:00:30 sip_ua[386]: poller.cpp:771: ===> command globalconf received
80Jan 1 00:00:30 sip_ua[386]: poller.cpp:1157: downloading sound files from http://192.168.20.121/files/ve-01/sounds...
81Jan 1 00:00:30 sip_ua[363]: sip_ua.cpp:511: IP change 127.0.0.1 --> 192.168.0.95 detected
82Jan 1 00:00:30 dnsmasq[329]: reading /tmp/resolv.conf.auto
83Jan 1 00:00:30 dnsmasq[329]: using local addresses only for domain lan
84Jan 1 00:00:30 dnsmasq[329]: using nameserver 8.8.8.8#53
85May 23 07:43:11 sip_ua[363]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
86May 23 07:43:11 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
87May 23 07:43:11 sip_ua[363]: repro.cpp:1141: --> skipping secure transport: no cert file
88May 23 07:43:11 sip_ua[386]: poller.cpp:771: ===> command globalconf received
89May 23 07:43:11 sip_ua[386]: poller.cpp:771: ===> command sslapply received
90May 23 07:43:14 sip_ua[386]: repro.cpp:1027: ----> MyReproRunner::addTransports() called
91May 23 07:43:14 sip_ua[386]: repro.cpp:1141: --> skipping secure transport: no cert file
92May 23 07:43:14 sip_ua[386]: repro.cpp:1141: --> skipping secure transport: no cert file
93May 23 07:43:14 sip_ua[386]: poller.cpp:2209: download complete
94May 23 07:43:14 sip_ua[386]: poller.cpp:771: ===> command userlist received
95May 23 07:43:14 sip_ua[386]: user_agent.cpp:196: User directory updated
96May 23 07:43:14 sip_ua[386]: poller.cpp:771: ===> command ctime received
97May 23 07:43:14 sip_ua[386]: poller.cpp:771: ===> command routes received
98May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Channel settings, ts=9, flags=0000, data=0
99May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=99, state=Idle: Channel settings, ts=99, flags=0000, data=0
100May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=100, state=Idle: Channel settings, ts=100, flags=0000, data=0
101May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=101, state=Idle: Channel settings, ts=101, flags=0000, data=0
102May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=102, state=Idle: Channel settings, ts=102, flags=0000, data=0
103May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=103, state=Idle: Channel settings, ts=103, flags=0000, data=0
104May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=104, state=Idle: Channel settings, ts=104, flags=0000, data=0
105May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=105, state=Idle: Channel settings, ts=105, flags=0000, data=0
106May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=106, state=Idle: Channel settings, ts=106, flags=0000, data=0
107May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=107, state=Idle: Channel settings, ts=107, flags=0000, data=0
108May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=108, state=Idle: Channel settings, ts=108, flags=0000, data=0
109May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Channel settings, ts=10, flags=0000, data=0
110May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=109, state=Idle: Channel settings, ts=109, flags=0000, data=0
111May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=110, state=Idle: Channel settings, ts=110, flags=0000, data=0
112May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=111, state=Idle: Channel settings, ts=111, flags=0000, data=0
113May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=112, state=Idle: Channel settings, ts=112, flags=0000, data=0
114May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=113, state=Idle: Channel settings, ts=113, flags=0000, data=0
115May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=114, state=Idle: Channel settings, ts=114, flags=0000, data=0
116May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=115, state=Idle: Channel settings, ts=115, flags=0000, data=0
117May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=116, state=Idle: Channel settings, ts=116, flags=0000, data=0
118May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=117, state=Idle: Channel settings, ts=117, flags=0000, data=0
119May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=118, state=Idle: Channel settings, ts=118, flags=0000, data=0
120May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Channel settings, ts=11, flags=0000, data=0
121May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=119, state=Idle: Channel settings, ts=119, flags=0000, data=0
122May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=120, state=Idle: Channel settings, ts=120, flags=0000, data=0
123May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=121, state=Idle: Channel settings, ts=121, flags=0000, data=0
124May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=122, state=Idle: Channel settings, ts=122, flags=0000, data=0
125May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=123, state=Idle: Channel settings, ts=123, flags=0000, data=0
126May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=124, state=Idle: Channel settings, ts=124, flags=0000, data=0
127May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=125, state=Idle: Channel settings, ts=125, flags=0000, data=0
128May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=126, state=Idle: Channel settings, ts=126, flags=0000, data=0
129May 23 07:43:17 sip_ua[363]: fxs.cpp:419: ---> ts=127, state=Idle: Channel settings, ts=127, flags=0000, data=0
130May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=128, state=Idle: Channel settings, ts=128, flags=0000, data=0
131May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Channel settings, ts=12, flags=0000, data=0
132May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=129, state=Idle: Channel settings, ts=129, flags=0000, data=0
133May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=130, state=Idle: Channel settings, ts=130, flags=0000, data=0
134May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Channel settings, ts=13, flags=0000, data=0
135May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Channel settings, ts=14, flags=0000, data=0
136May 23 07:43:19 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_DCHAN_UP
137May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Channel settings, ts=15, flags=0000, data=0
138May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=16, state=Idle: Channel settings, ts=16, flags=0000, data=0
139May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Channel settings, ts=17, flags=0000, data=0
140May 23 07:43:19 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Channel settings, ts=18, flags=0000, data=0
141May 23 07:43:22 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Channel settings, ts=1, flags=0000, data=0
142May 23 07:43:22 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Channel settings, ts=19, flags=0000, data=0
143May 23 07:43:22 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Channel settings, ts=20, flags=0000, data=0
144May 23 07:43:22 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Channel settings, ts=21, flags=0000, data=0
145May 23 07:43:22 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Channel settings, ts=22, flags=0000, data=0
146May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Channel settings, ts=23, flags=0000, data=0
147May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: Channel settings, ts=24, flags=0000, data=0
148May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=25, state=Idle: Channel settings, ts=25, flags=0000, data=0
149May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=26, state=Idle: Channel settings, ts=26, flags=0000, data=0
150May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=27, state=Idle: Channel settings, ts=27, flags=0000, data=0
151May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: Channel settings, ts=28, flags=0000, data=0
152May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Channel settings, ts=2, flags=0000, data=0
153May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: Channel settings, ts=29, flags=0000, data=0
154May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=30, state=Idle: Channel settings, ts=30, flags=0000, data=0
155May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=31, state=Idle: Channel settings, ts=31, flags=0000, data=0
156May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=33, state=Idle: Channel settings, ts=33, flags=0000, data=0
157May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=34, state=Idle: Channel settings, ts=34, flags=0000, data=0
158May 23 07:43:23 sip_ua[363]: fxs.cpp:419: ---> ts=35, state=Idle: Channel settings, ts=35, flags=0000, data=0
159May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=36, state=Idle: Channel settings, ts=36, flags=0000, data=0
160May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Idle: Channel settings, ts=37, flags=0000, data=0
161May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=38, state=Idle: Channel settings, ts=38, flags=0000, data=0
162May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Channel settings, ts=3, flags=0000, data=0
163May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Channel settings, ts=39, flags=0000, data=0
164May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Channel settings, ts=40, flags=0000, data=0
165May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Channel settings, ts=41, flags=0000, data=0
166May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Channel settings, ts=42, flags=0000, data=0
167May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Channel settings, ts=43, flags=0000, data=0
168May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Channel settings, ts=44, flags=0000, data=0
169May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Channel settings, ts=45, flags=0000, data=0
170May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Channel settings, ts=46, flags=0000, data=0
171May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=47, state=Idle: Channel settings, ts=47, flags=0000, data=0
172May 23 07:43:24 sip_ua[363]: fxs.cpp:419: ---> ts=48, state=Idle: Channel settings, ts=48, flags=0000, data=0
173May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Channel settings, ts=4, flags=0000, data=0
174May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=49, state=Idle: Channel settings, ts=49, flags=0000, data=0
175May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=50, state=Idle: Channel settings, ts=50, flags=0000, data=0
176May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=51, state=Idle: Channel settings, ts=51, flags=0000, data=0
177May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=52, state=Idle: Channel settings, ts=52, flags=0000, data=0
178May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=53, state=Idle: Channel settings, ts=53, flags=0000, data=0
179May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=54, state=Idle: Channel settings, ts=54, flags=0000, data=0
180May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Channel settings, ts=55, flags=0000, data=0
181May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=56, state=Idle: Channel settings, ts=56, flags=0000, data=0
182May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=57, state=Idle: Channel settings, ts=57, flags=0000, data=0
183May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Channel settings, ts=58, flags=0000, data=0
184May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Channel settings, ts=5, flags=0000, data=0
185May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Channel settings, ts=59, flags=0000, data=0
186May 23 07:43:25 sip_ua[363]: fxs.cpp:419: ---> ts=60, state=Idle: Channel settings, ts=60, flags=0000, data=0
187May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=61, state=Idle: Channel settings, ts=61, flags=0000, data=0
188May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=62, state=Idle: Channel settings, ts=62, flags=0000, data=0
189May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=63, state=Idle: Channel settings, ts=63, flags=0000, data=0
190May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=65, state=Idle: Channel settings, ts=65, flags=0000, data=0
191May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=66, state=Idle: Channel settings, ts=66, flags=0000, data=0
192May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=67, state=Idle: Channel settings, ts=67, flags=0000, data=0
193May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=68, state=Idle: Channel settings, ts=68, flags=0000, data=0
194May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Channel settings, ts=6, flags=0000, data=0
195May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Channel settings, ts=69, flags=0000, data=0
196May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Channel settings, ts=70, flags=0000, data=0
197May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Channel settings, ts=71, flags=0000, data=0
198May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Channel settings, ts=72, flags=0000, data=0
199May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Channel settings, ts=73, flags=0000, data=0
200May 23 07:43:26 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Channel settings, ts=74, flags=0000, data=0
201May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Channel settings, ts=75, flags=0000, data=0
202May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Channel settings, ts=76, flags=0000, data=0
203May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=77, state=Idle: Channel settings, ts=77, flags=0000, data=0
204May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=78, state=Idle: Channel settings, ts=78, flags=0000, data=0
205May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Channel settings, ts=7, flags=0000, data=0
206May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=79, state=Idle: Channel settings, ts=79, flags=0000, data=0
207May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=80, state=Idle: Channel settings, ts=80, flags=0000, data=0
208May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=81, state=Idle: Channel settings, ts=81, flags=0000, data=0
209May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=82, state=Idle: Channel settings, ts=82, flags=0000, data=0
210May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=83, state=Idle: Channel settings, ts=83, flags=0000, data=0
211May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=84, state=Idle: Channel settings, ts=84, flags=0000, data=0
212May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=85, state=Idle: Channel settings, ts=85, flags=0000, data=0
213May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Channel settings, ts=86, flags=0000, data=0
214May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Channel settings, ts=87, flags=0000, data=0
215May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Channel settings, ts=88, flags=0000, data=0
216May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Channel settings, ts=8, flags=0000, data=0
217May 23 07:43:27 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Channel settings, ts=89, flags=0000, data=0
218May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Channel settings, ts=90, flags=0000, data=0
219May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Channel settings, ts=91, flags=0000, data=0
220May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Channel settings, ts=92, flags=0000, data=0
221May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Channel settings, ts=93, flags=0000, data=0
222May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Channel settings, ts=94, flags=0000, data=0
223May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=95, state=Idle: Channel settings, ts=95, flags=0000, data=0
224May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=97, state=Idle: Channel settings, ts=97, flags=0000, data=0
225May 23 07:43:28 sip_ua[363]: fxs.cpp:419: ---> ts=98, state=Idle: Channel settings, ts=98, flags=0000, data=0
226May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: CAS A activity detected, ts=29, flags=0000, data=0
227May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: CAS event, ts=29, flags=0000, data=7
228May 23 07:44:03 sip_ua[363]: fxs.cpp:702: ==> ts 29: manual CAS event processing (abcd=13)
229May 23 07:44:03 sip_ua[363]: fxs.cpp:241: ts 29: dialing 400
230May 23 07:44:03 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:129@192.168.0.95> -> sip:400@192.168.0.95 ()...
231May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: CAS event, ts=29, flags=0000, data=7
232May 23 07:44:03 sip_ua[462]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK1996597460'
233May 23 07:44:03 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:400@127.0.0.1:6060;transport=udp (Call-ID: 1887185452@(null))
234May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=2
235May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=2
236May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=2
237May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=2
238May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=2
239May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=2
240May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=2
241May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=2
242May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=2
243May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=2
244May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=2
245May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Incoming call, ts=-1, flags=0001, data=2
246May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0001, data=2
247May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0001, data=2
248May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: Incoming call, ts=-1, flags=0001, data=2
249May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=25, state=Idle: Incoming call, ts=-1, flags=0001, data=2
250May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=26, state=Idle: Incoming call, ts=-1, flags=0001, data=2
251May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=27, state=Idle: Incoming call, ts=-1, flags=0001, data=2
252May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: Incoming call, ts=-1, flags=0001, data=2
253May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: Incoming call, ts=-1, flags=0001, data=2
254May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=30, state=Idle: Incoming call, ts=-1, flags=0001, data=2
255May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=31, state=Idle: Incoming call, ts=-1, flags=0001, data=2
256May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0001, data=2
257May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0001, data=2
258May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Incoming call, ts=-1, flags=0001, data=2
259May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=47, state=Idle: Incoming call, ts=-1, flags=0001, data=2
260May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=48, state=Idle: Incoming call, ts=-1, flags=0001, data=2
261May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=49, state=Idle: Incoming call, ts=-1, flags=0001, data=2
262May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=50, state=Idle: Incoming call, ts=-1, flags=0001, data=2
263May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=51, state=Idle: Incoming call, ts=-1, flags=0001, data=2
264May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=52, state=Idle: Incoming call, ts=-1, flags=0001, data=2
265May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=53, state=Idle: Incoming call, ts=-1, flags=0001, data=2
266May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=54, state=Idle: Incoming call, ts=-1, flags=0001, data=2
267May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Incoming call, ts=-1, flags=0001, data=2
268May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=56, state=Idle: Incoming call, ts=-1, flags=0001, data=2
269May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=57, state=Idle: Incoming call, ts=-1, flags=0001, data=2
270May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Incoming call, ts=-1, flags=0001, data=2
271May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0001, data=2
272May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=60, state=Idle: Incoming call, ts=-1, flags=0001, data=2
273May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=61, state=Idle: Incoming call, ts=-1, flags=0001, data=2
274May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=62, state=Idle: Incoming call, ts=-1, flags=0001, data=2
275May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0001, data=2
276May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Incoming call, ts=-1, flags=0001, data=2
277May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Incoming call, ts=-1, flags=0001, data=2
278May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0001, data=2
279May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0001, data=2
280May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0001, data=2
281May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Incoming call, ts=-1, flags=0001, data=2
282May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Incoming call, ts=-1, flags=0001, data=2
283May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=77, state=Idle: Incoming call, ts=-1, flags=0001, data=2
284May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=78, state=Idle: Incoming call, ts=-1, flags=0001, data=2
285May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=79, state=Idle: Incoming call, ts=-1, flags=0001, data=2
286May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=80, state=Idle: Incoming call, ts=-1, flags=0001, data=2
287May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=81, state=Idle: Incoming call, ts=-1, flags=0001, data=2
288May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=82, state=Idle: Incoming call, ts=-1, flags=0001, data=2
289May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=83, state=Idle: Incoming call, ts=-1, flags=0001, data=2
290May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=84, state=Idle: Incoming call, ts=-1, flags=0001, data=2
291May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=85, state=Idle: Incoming call, ts=-1, flags=0001, data=2
292May 23 07:44:03 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0001, data=2
293May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0001, data=2
294May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0001, data=2
295May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0001, data=2
296May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0001, data=2
297May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0001, data=2
298May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0001, data=2
299May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0001, data=2
300May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0001, data=2
301May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=95, state=Idle: Incoming call, ts=-1, flags=0001, data=2
302May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=97, state=Idle: Incoming call, ts=-1, flags=0001, data=2
303May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=98, state=Idle: Incoming call, ts=-1, flags=0001, data=2
304May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=124, state=Idle: Incoming call, ts=-1, flags=0001, data=2
305May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=125, state=Idle: Incoming call, ts=-1, flags=0001, data=2
306May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=126, state=Idle: Incoming call, ts=-1, flags=0001, data=2
307May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=127, state=Idle: Incoming call, ts=-1, flags=0001, data=2
308May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=128, state=Idle: Incoming call, ts=-1, flags=0001, data=2
309May 23 07:44:04 sip_ua[363]: pri.cpp:582: ---> PRI[144]: Incoming call, ts=-1, flags=0001, data=2
310May 23 07:44:04 sip_ua[363]: virtualChannel.cpp:135: ---> VirtualChannelManager: Incoming call, ts=-1, flags=0001, data=2
311May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0002, data=2
312May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0002, data=2
313May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0002, data=2
314May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0002, data=2
315May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0002, data=2
316May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0002, data=2
317May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0002, data=2
318May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0002, data=2
319May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0002, data=2
320May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0002, data=2
321May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Incoming call, ts=-1, flags=0002, data=2
322May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Incoming call, ts=-1, flags=0002, data=2
323May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Incoming call, ts=-1, flags=0002, data=2
324May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Incoming call, ts=-1, flags=0002, data=2
325May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=16, state=Idle: Incoming call, ts=-1, flags=0002, data=2
326May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0002, data=2
327May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0002, data=2
328May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0002, data=2
329May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0002, data=2
330May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Incoming call, ts=-1, flags=0002, data=2
331May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0002, data=2
332May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0002, data=2
333May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: Incoming call, ts=-1, flags=0002, data=2
334May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=38, state=Idle: Incoming call, ts=-1, flags=0002, data=2
335May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Incoming call, ts=-1, flags=0002, data=2
336May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Incoming call, ts=-1, flags=0002, data=2
337May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0002, data=2
338May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0002, data=2
339May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0002, data=2
340May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0002, data=2
341May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0002, data=2
342May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Incoming call, ts=-1, flags=0002, data=2
343May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=47, state=Idle: Incoming call, ts=-1, flags=0002, data=2
344May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=48, state=Idle: Incoming call, ts=-1, flags=0002, data=2
345May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Incoming call, ts=-1, flags=0002, data=2
346May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=56, state=Idle: Incoming call, ts=-1, flags=0002, data=2
347May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=57, state=Idle: Incoming call, ts=-1, flags=0002, data=2
348May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Incoming call, ts=-1, flags=0002, data=2
349May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0002, data=2
350May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=60, state=Idle: Incoming call, ts=-1, flags=0002, data=2
351May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=61, state=Idle: Incoming call, ts=-1, flags=0002, data=2
352May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=62, state=Idle: Incoming call, ts=-1, flags=0002, data=2
353May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=63, state=Idle: Incoming call, ts=-1, flags=0002, data=2
354May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=65, state=Idle: Incoming call, ts=-1, flags=0002, data=2
355May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=66, state=Idle: Incoming call, ts=-1, flags=0002, data=2
356May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=67, state=Idle: Incoming call, ts=-1, flags=0002, data=2
357May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=68, state=Idle: Incoming call, ts=-1, flags=0002, data=2
358May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0002, data=2
359May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Incoming call, ts=-1, flags=0002, data=2
360May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Incoming call, ts=-1, flags=0002, data=2
361May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0002, data=2
362May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0002, data=2
363May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0002, data=2
364May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Incoming call, ts=-1, flags=0002, data=2
365May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Incoming call, ts=-1, flags=0002, data=2
366May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=77, state=Idle: Incoming call, ts=-1, flags=0002, data=2
367May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=78, state=Idle: Incoming call, ts=-1, flags=0002, data=2
368May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=79, state=Idle: Incoming call, ts=-1, flags=0002, data=2
369May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=80, state=Idle: Incoming call, ts=-1, flags=0002, data=2
370May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=81, state=Idle: Incoming call, ts=-1, flags=0002, data=2
371May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=82, state=Idle: Incoming call, ts=-1, flags=0002, data=2
372May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=83, state=Idle: Incoming call, ts=-1, flags=0002, data=2
373May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=84, state=Idle: Incoming call, ts=-1, flags=0002, data=2
374May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=85, state=Idle: Incoming call, ts=-1, flags=0002, data=2
375May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0002, data=2
376May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0002, data=2
377May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0002, data=2
378May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0002, data=2
379May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0002, data=2
380May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0002, data=2
381May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0002, data=2
382May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0002, data=2
383May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0002, data=2
384May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=95, state=Idle: Incoming call, ts=-1, flags=0002, data=2
385May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=97, state=Idle: Incoming call, ts=-1, flags=0002, data=2
386May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=104, state=Idle: Incoming call, ts=-1, flags=0002, data=2
387May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=105, state=Idle: Incoming call, ts=-1, flags=0002, data=2
388May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=106, state=Idle: Incoming call, ts=-1, flags=0002, data=2
389May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=107, state=Idle: Incoming call, ts=-1, flags=0002, data=2
390May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=108, state=Idle: Incoming call, ts=-1, flags=0002, data=2
391May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=109, state=Idle: Incoming call, ts=-1, flags=0002, data=2
392May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=110, state=Idle: Incoming call, ts=-1, flags=0002, data=2
393May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=111, state=Idle: Incoming call, ts=-1, flags=0002, data=2
394May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=112, state=Idle: Incoming call, ts=-1, flags=0002, data=2
395May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=113, state=Idle: Incoming call, ts=-1, flags=0002, data=2
396May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=114, state=Idle: Incoming call, ts=-1, flags=0002, data=2
397May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=115, state=Idle: Incoming call, ts=-1, flags=0002, data=2
398May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=116, state=Idle: Incoming call, ts=-1, flags=0002, data=2
399May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=117, state=Idle: Incoming call, ts=-1, flags=0002, data=2
400May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=118, state=Idle: Incoming call, ts=-1, flags=0002, data=2
401May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=119, state=Idle: Incoming call, ts=-1, flags=0002, data=2
402May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=120, state=Idle: Incoming call, ts=-1, flags=0002, data=2
403May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=121, state=Idle: Incoming call, ts=-1, flags=0002, data=2
404May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=122, state=Idle: Incoming call, ts=-1, flags=0002, data=2
405May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=123, state=Idle: Incoming call, ts=-1, flags=0002, data=2
406May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=124, state=Idle: Incoming call, ts=-1, flags=0002, data=2
407May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=125, state=Idle: Incoming call, ts=-1, flags=0002, data=2
408May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=126, state=Idle: Incoming call, ts=-1, flags=0002, data=2
409May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
410May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > DL-DATA request
411May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=29
412May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent from originator)
413May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
414May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
415May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=29
416May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent from originator)
417May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
418May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [04 03 80 90 a3]
419May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
420May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
421May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > User information layer 1: A-Law (35)
422May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [18 03 a9 83 81]
423May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
424May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Calling Party Number (len= 7) [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0)
425May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Presentation: Presentation allowed, User-provided, verified and passed (1) '129' ]
426May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [70 04 80 34 30 30]
427May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Called Party Number (len= 6) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '400' ]
428May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [a1]
429May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Sending Complete (len= 1)
430May 23 07:44:04 sip_ua[363]: user_agent.cpp:3484: Call 2 routed to TS 129
431May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: CAS A activity detected, ts=28, flags=0000, data=0
432May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: CAS event, ts=29, flags=0000, data=15
433May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: CAS event, ts=28, flags=0000, data=7
434May 23 07:44:04 sip_ua[363]: fxs.cpp:702: ==> ts 28: manual CAS event processing (abcd=13)
435May 23 07:44:04 sip_ua[363]: fxs.cpp:241: ts 28: dialing 400
436May 23 07:44:04 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:128@192.168.0.95> -> sip:400@192.168.0.95 ()...
437May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
438May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=10
439May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent to originator)
440May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: CALL PROCEEDING (2)
441May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < [18 03 a9 83 81]
442May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
443May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < ChanSel: As indicated in following octets
444May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Coding: 0 Number Specified Channel Type: 3
445May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Channel: 1 Type: CPE]
446May 23 07:44:04 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_PROCEEDING
447May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Calling: CAS event, ts=28, flags=0000, data=7
448May 23 07:44:04 sip_ua[462]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK176168362'
449May 23 07:44:04 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:400@127.0.0.1:6060;transport=udp (Call-ID: 569418787@(null))
450May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=5
451May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=5
452May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=5
453May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=5
454May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=5
455May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=5
456May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=5
457May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=5
458May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=5
459May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=5
460May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=5
461May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=16, state=Idle: Incoming call, ts=-1, flags=0001, data=5
462May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0001, data=5
463May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0001, data=5
464May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0001, data=5
465May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Idle: Incoming call, ts=-1, flags=0001, data=5
466May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=38, state=Idle: Incoming call, ts=-1, flags=0001, data=5
467May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Incoming call, ts=-1, flags=0001, data=5
468May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Incoming call, ts=-1, flags=0001, data=5
469May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0001, data=5
470May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0001, data=5
471May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0001, data=5
472May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Incoming call, ts=-1, flags=0001, data=5
473May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0001, data=5
474May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=60, state=Idle: Incoming call, ts=-1, flags=0001, data=5
475May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=61, state=Idle: Incoming call, ts=-1, flags=0001, data=5
476May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=62, state=Idle: Incoming call, ts=-1, flags=0001, data=5
477May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=63, state=Idle: Incoming call, ts=-1, flags=0001, data=5
478May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=65, state=Idle: Incoming call, ts=-1, flags=0001, data=5
479May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=66, state=Idle: Incoming call, ts=-1, flags=0001, data=5
480May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=67, state=Idle: Incoming call, ts=-1, flags=0001, data=5
481May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=68, state=Idle: Incoming call, ts=-1, flags=0001, data=5
482May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0001, data=5
483May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=81, state=Idle: Incoming call, ts=-1, flags=0001, data=5
484May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=82, state=Idle: Incoming call, ts=-1, flags=0001, data=5
485May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=83, state=Idle: Incoming call, ts=-1, flags=0001, data=5
486May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=84, state=Idle: Incoming call, ts=-1, flags=0001, data=5
487May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=85, state=Idle: Incoming call, ts=-1, flags=0001, data=5
488May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0001, data=5
489May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0001, data=5
490May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0001, data=5
491May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0001, data=5
492May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0001, data=5
493May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=104, state=Idle: Incoming call, ts=-1, flags=0001, data=5
494May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=105, state=Idle: Incoming call, ts=-1, flags=0001, data=5
495May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=106, state=Idle: Incoming call, ts=-1, flags=0001, data=5
496May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=107, state=Idle: Incoming call, ts=-1, flags=0001, data=5
497May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=108, state=Idle: Incoming call, ts=-1, flags=0001, data=5
498May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=109, state=Idle: Incoming call, ts=-1, flags=0001, data=5
499May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=110, state=Idle: Incoming call, ts=-1, flags=0001, data=5
500May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=111, state=Idle: Incoming call, ts=-1, flags=0001, data=5
501May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=112, state=Idle: Incoming call, ts=-1, flags=0001, data=5
502May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=113, state=Idle: Incoming call, ts=-1, flags=0001, data=5
503May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=124, state=Idle: Incoming call, ts=-1, flags=0001, data=5
504May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=125, state=Idle: Incoming call, ts=-1, flags=0001, data=5
505May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=126, state=Idle: Incoming call, ts=-1, flags=0001, data=5
506May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=127, state=Idle: Incoming call, ts=-1, flags=0001, data=5
507May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=128, state=Idle: Incoming call, ts=-1, flags=0001, data=5
508May 23 07:44:04 sip_ua[363]: pri.cpp:582: ---> PRI[144]: Incoming call, ts=-1, flags=0001, data=5
509May 23 07:44:04 sip_ua[363]: virtualChannel.cpp:135: ---> VirtualChannelManager: Incoming call, ts=-1, flags=0001, data=5
510May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0002, data=5
511May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0002, data=5
512May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0002, data=5
513May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0002, data=5
514May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=16, state=Idle: Incoming call, ts=-1, flags=0002, data=5
515May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0002, data=5
516May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0002, data=5
517May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0002, data=5
518May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0002, data=5
519May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Incoming call, ts=-1, flags=0002, data=5
520May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0002, data=5
521May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0002, data=5
522May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: Incoming call, ts=-1, flags=0002, data=5
523May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Incoming call, ts=-1, flags=0002, data=5
524May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Incoming call, ts=-1, flags=0002, data=5
525May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0002, data=5
526May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0002, data=5
527May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0002, data=5
528May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0002, data=5
529May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0002, data=5
530May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Incoming call, ts=-1, flags=0002, data=5
531May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=47, state=Idle: Incoming call, ts=-1, flags=0002, data=5
532May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=48, state=Idle: Incoming call, ts=-1, flags=0002, data=5
533May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=62, state=Idle: Incoming call, ts=-1, flags=0002, data=5
534May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=63, state=Idle: Incoming call, ts=-1, flags=0002, data=5
535May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=65, state=Idle: Incoming call, ts=-1, flags=0002, data=5
536May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=66, state=Idle: Incoming call, ts=-1, flags=0002, data=5
537May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=67, state=Idle: Incoming call, ts=-1, flags=0002, data=5
538May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=68, state=Idle: Incoming call, ts=-1, flags=0002, data=5
539May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0002, data=5
540May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=84, state=Idle: Incoming call, ts=-1, flags=0002, data=5
541May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=85, state=Idle: Incoming call, ts=-1, flags=0002, data=5
542May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0002, data=5
543May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0002, data=5
544May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0002, data=5
545May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0002, data=5
546May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0002, data=5
547May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0002, data=5
548May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0002, data=5
549May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0002, data=5
550May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0002, data=5
551May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=108, state=Idle: Incoming call, ts=-1, flags=0002, data=5
552May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=109, state=Idle: Incoming call, ts=-1, flags=0002, data=5
553May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=110, state=Idle: Incoming call, ts=-1, flags=0002, data=5
554May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=111, state=Idle: Incoming call, ts=-1, flags=0002, data=5
555May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=112, state=Idle: Incoming call, ts=-1, flags=0002, data=5
556May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=113, state=Idle: Incoming call, ts=-1, flags=0002, data=5
557May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=114, state=Idle: Incoming call, ts=-1, flags=0002, data=5
558May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=115, state=Idle: Incoming call, ts=-1, flags=0002, data=5
559May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=116, state=Idle: Incoming call, ts=-1, flags=0002, data=5
560May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=117, state=Idle: Incoming call, ts=-1, flags=0002, data=5
561May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=118, state=Idle: Incoming call, ts=-1, flags=0002, data=5
562May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=126, state=Idle: Incoming call, ts=-1, flags=0002, data=5
563May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=127, state=Idle: Incoming call, ts=-1, flags=0002, data=5
564May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=128, state=Idle: Incoming call, ts=-1, flags=0002, data=5
565May 23 07:44:04 sip_ua[363]: pri.cpp:582: ---> PRI[144]: Incoming call, ts=-1, flags=0002, data=5
566May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
567May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > DL-DATA request
568May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=29
569May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 2/0x2) (Sent from originator)
570May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
571May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 2/0x2) (Sent from originator)
572May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
573May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [04 03 80 90 a3]
574May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
575May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
576May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > User information layer 1: A-Law (35)
577May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [18 03 a9 83 82]
578May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
579May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > ChanSel: As indicated in following octets
580May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [70 04 80 34 30 30]
581May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Called Party Number (len= 6) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '400' ]
582May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > [a1]
583May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Sending Complete (len= 1)
584May 23 07:44:04 sip_ua[363]: user_agent.cpp:3484: Call 5 routed to TS 130
585May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
586May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=9
587May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 1/0x1) (Sent to originator)
588May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: ALERTING (1)
589May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < [1e 02 81 88]
590May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Progress Indicator (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) 0: 0 Location: Private network serving the local user (1)
591May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Calling: CAS event, ts=28, flags=0000, data=15
592May 23 07:44:04 sip_ua[363]: comcerto.cpp:6606: --> ts 129: 192.168.0.95[10258] --> 192.168.0.95[10058]
593May 23 07:44:04 sip_ua[363]: comcerto.cpp:6607: --> ts 129: codec PCMA, VAD is on
594May 23 07:44:04 sip_ua[363]: comcerto.cpp:6608: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
595May 23 07:44:04 sip_ua[363]: comcerto.cpp:6661: ts 129: calling createConnection()
596May 23 07:44:04 sip_ua[363]: comcerto.cpp:2165: !!!!! function SUPVSR_CREATE_CHANNEL (0x0010): error ERR_TDMDRV_INVTS (0xffbd):
597May 23 07:44:04 sip_ua[363]: comcerto.cpp:2166: TDM timeslot is invalid
598May 23 07:44:04 sip_ua[363]: comcerto.cpp:3053: createConnection() failed (result=-65469)
599May 23 07:44:04 sip_ua[363]: comcerto.cpp:6664: ts 129: createConnection() failed (result=-1)
600May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
601May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=10
602May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 2/0x2) (Sent to originator)
603May 23 07:44:04 sip_ua[387]: user_agent.cpp:2132: ---> ringing with status code 180
604May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: RTP parameters, ts=29, flags=0000, data=1
605May 23 07:44:04 sip_ua[363]: comcerto.cpp:6606: --> ts 29: 192.168.0.95[10058] --> 192.168.0.95[10258]
606May 23 07:44:04 sip_ua[363]: comcerto.cpp:6607: --> ts 29: codec PCMA, VAD is on
607May 23 07:44:04 sip_ua[363]: comcerto.cpp:6608: --> audio pt: 8/8, event pt: 101/101, VBD pt: 98/98
608May 23 07:44:04 sip_ua[363]: comcerto.cpp:6723: ts 29: starting RTP stream to 02:ad:c3:00:00:e0
609May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: Remote is ringing, ts=29, flags=0000, data=1
610May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
611May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=9
612May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 2/0x2) (Sent to originator)
613May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: DISCONNECT (69)
614May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < [08 02 81 9f]
615May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
616May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
617May 23 07:44:04 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_HANGUP_REQ
618May 23 07:44:04 sip_ua[363]: pri.cpp:503: --> channel=2, cause=Normal, unspecified
619May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
620May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > DL-DATA request
621May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=9
622May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 2/0x2) (Sent from originator)
623May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: RELEASE (77)
624May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
625May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=9
626May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 2/0x2) (Sent from originator)
627May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: RELEASE (77)
628May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
629May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
630May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: Call disconnected, ts=29, flags=0000, data=1
631May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Busy: Statistics received, ts=29, flags=0000, data=0
632May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Busy: Statistics received, ts=29, flags=0000, data=0
633May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: Tone completed, ts=29, flags=0000, data=3
634May 23 07:44:04 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Calling: Call disconnected, ts=28, flags=0000, data=4
635May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]:
636May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=9
637May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 2/0x2) (Sent to originator)
638May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: RELEASE COMPLETE (90)
639May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < [08 02 81 9f]
640May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
641May 23 07:44:04 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
642May 23 07:44:04 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_HANGUP_ACK
643May 23 07:44:04 sip_ua[363]: pri.cpp:503: --> channel=2, cause=Normal, unspecified
644May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: CAS A activity detected, ts=29, flags=0000, data=0
645May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: CAS event, ts=29, flags=0000, data=7
646May 23 07:44:07 sip_ua[363]: fxs.cpp:702: ==> ts 29: manual CAS event processing (abcd=15)
647May 23 07:44:07 sip_ua[363]: fxs.cpp:241: ts 29: dialing 400
648May 23 07:44:07 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:129@192.168.0.95> -> sip:400@192.168.0.95 ()...
649May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: CAS event, ts=29, flags=0000, data=7
650May 23 07:44:07 sip_ua[462]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK1433390447'
651May 23 07:44:07 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:400@127.0.0.1:6060;transport=udp (Call-ID: 1114692889@(null))
652May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=9
653May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=9
654May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=9
655May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=9
656May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=9
657May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=9
658May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=9
659May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=9
660May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=9
661May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=9
662May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=9
663May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=12, state=Idle: Incoming call, ts=-1, flags=0001, data=9
664May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=13, state=Idle: Incoming call, ts=-1, flags=0001, data=9
665May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Incoming call, ts=-1, flags=0001, data=9
666May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Incoming call, ts=-1, flags=0001, data=9
667May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=16, state=Idle: Incoming call, ts=-1, flags=0001, data=9
668May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0001, data=9
669May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0001, data=9
670May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0001, data=9
671May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0001, data=9
672May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Incoming call, ts=-1, flags=0001, data=9
673May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0001, data=9
674May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=35, state=Idle: Incoming call, ts=-1, flags=0001, data=9
675May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=36, state=Idle: Incoming call, ts=-1, flags=0001, data=9
676May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=37, state=Idle: Incoming call, ts=-1, flags=0001, data=9
677May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=38, state=Idle: Incoming call, ts=-1, flags=0001, data=9
678May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Incoming call, ts=-1, flags=0001, data=9
679May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Incoming call, ts=-1, flags=0001, data=9
680May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0001, data=9
681May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0001, data=9
682May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0001, data=9
683May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0001, data=9
684May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0001, data=9
685May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Incoming call, ts=-1, flags=0001, data=9
686May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=47, state=Idle: Incoming call, ts=-1, flags=0001, data=9
687May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=48, state=Idle: Incoming call, ts=-1, flags=0001, data=9
688May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=49, state=Idle: Incoming call, ts=-1, flags=0001, data=9
689May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=50, state=Idle: Incoming call, ts=-1, flags=0001, data=9
690May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=51, state=Idle: Incoming call, ts=-1, flags=0001, data=9
691May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0001, data=9
692May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0001, data=9
693May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0001, data=9
694May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Incoming call, ts=-1, flags=0001, data=9
695May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Incoming call, ts=-1, flags=0001, data=9
696May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=77, state=Idle: Incoming call, ts=-1, flags=0001, data=9
697May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=78, state=Idle: Incoming call, ts=-1, flags=0001, data=9
698May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=79, state=Idle: Incoming call, ts=-1, flags=0001, data=9
699May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=80, state=Idle: Incoming call, ts=-1, flags=0001, data=9
700May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=81, state=Idle: Incoming call, ts=-1, flags=0001, data=9
701May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=82, state=Idle: Incoming call, ts=-1, flags=0001, data=9
702May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0001, data=9
703May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0001, data=9
704May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0001, data=9
705May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0001, data=9
706May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0001, data=9
707May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0001, data=9
708May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0001, data=9
709May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0001, data=9
710May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0001, data=9
711May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=95, state=Idle: Incoming call, ts=-1, flags=0001, data=9
712May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=97, state=Idle: Incoming call, ts=-1, flags=0001, data=9
713May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=109, state=Idle: Incoming call, ts=-1, flags=0001, data=9
714May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=110, state=Idle: Incoming call, ts=-1, flags=0001, data=9
715May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=111, state=Idle: Incoming call, ts=-1, flags=0001, data=9
716May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=112, state=Idle: Incoming call, ts=-1, flags=0001, data=9
717May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=113, state=Idle: Incoming call, ts=-1, flags=0001, data=9
718May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=114, state=Idle: Incoming call, ts=-1, flags=0001, data=9
719May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=115, state=Idle: Incoming call, ts=-1, flags=0001, data=9
720May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=116, state=Idle: Incoming call, ts=-1, flags=0001, data=9
721May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=117, state=Idle: Incoming call, ts=-1, flags=0001, data=9
722May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=118, state=Idle: Incoming call, ts=-1, flags=0001, data=9
723May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=119, state=Idle: Incoming call, ts=-1, flags=0001, data=9
724May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=14, state=Idle: Incoming call, ts=-1, flags=0002, data=9
725May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=15, state=Idle: Incoming call, ts=-1, flags=0002, data=9
726May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=16, state=Idle: Incoming call, ts=-1, flags=0002, data=9
727May 23 07:44:07 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0002, data=9
728May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0002, data=9
729May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0002, data=9
730May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0002, data=9
731May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Incoming call, ts=-1, flags=0002, data=9
732May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0002, data=9
733May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0002, data=9
734May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=24, state=Idle: Incoming call, ts=-1, flags=0002, data=9
735May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=25, state=Idle: Incoming call, ts=-1, flags=0002, data=9
736May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=26, state=Idle: Incoming call, ts=-1, flags=0002, data=9
737May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=27, state=Idle: Incoming call, ts=-1, flags=0002, data=9
738May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: Incoming call, ts=-1, flags=0002, data=9
739May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: Incoming call, ts=-1, flags=0002, data=9
740May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0002, data=9
741May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0002, data=9
742May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0002, data=9
743May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0002, data=9
744May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=46, state=Idle: Incoming call, ts=-1, flags=0002, data=9
745May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=47, state=Idle: Incoming call, ts=-1, flags=0002, data=9
746May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=48, state=Idle: Incoming call, ts=-1, flags=0002, data=9
747May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=49, state=Idle: Incoming call, ts=-1, flags=0002, data=9
748May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=50, state=Idle: Incoming call, ts=-1, flags=0002, data=9
749May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=51, state=Idle: Incoming call, ts=-1, flags=0002, data=9
750May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=52, state=Idle: Incoming call, ts=-1, flags=0002, data=9
751May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=53, state=Idle: Incoming call, ts=-1, flags=0002, data=9
752May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=54, state=Idle: Incoming call, ts=-1, flags=0002, data=9
753May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Incoming call, ts=-1, flags=0002, data=9
754May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=56, state=Idle: Incoming call, ts=-1, flags=0002, data=9
755May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=57, state=Idle: Incoming call, ts=-1, flags=0002, data=9
756May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Incoming call, ts=-1, flags=0002, data=9
757May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0002, data=9
758May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=60, state=Idle: Incoming call, ts=-1, flags=0002, data=9
759May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=61, state=Idle: Incoming call, ts=-1, flags=0002, data=9
760May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=62, state=Idle: Incoming call, ts=-1, flags=0002, data=9
761May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=63, state=Idle: Incoming call, ts=-1, flags=0002, data=9
762May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=65, state=Idle: Incoming call, ts=-1, flags=0002, data=9
763May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=66, state=Idle: Incoming call, ts=-1, flags=0002, data=9
764May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0002, data=9
765May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0002, data=9
766May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=95, state=Idle: Incoming call, ts=-1, flags=0002, data=9
767May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=97, state=Idle: Incoming call, ts=-1, flags=0002, data=9
768May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=98, state=Idle: Incoming call, ts=-1, flags=0002, data=9
769May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=99, state=Idle: Incoming call, ts=-1, flags=0002, data=9
770May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=100, state=Idle: Incoming call, ts=-1, flags=0002, data=9
771May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=101, state=Idle: Incoming call, ts=-1, flags=0002, data=9
772May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=102, state=Idle: Incoming call, ts=-1, flags=0002, data=9
773May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=103, state=Idle: Incoming call, ts=-1, flags=0002, data=9
774May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=104, state=Idle: Incoming call, ts=-1, flags=0002, data=9
775May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=110, state=Idle: Incoming call, ts=-1, flags=0002, data=9
776May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=111, state=Idle: Incoming call, ts=-1, flags=0002, data=9
777May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=112, state=Idle: Incoming call, ts=-1, flags=0002, data=9
778May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=113, state=Idle: Incoming call, ts=-1, flags=0002, data=9
779May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=114, state=Idle: Incoming call, ts=-1, flags=0002, data=9
780May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=115, state=Idle: Incoming call, ts=-1, flags=0002, data=9
781May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=116, state=Idle: Incoming call, ts=-1, flags=0002, data=9
782May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=117, state=Idle: Incoming call, ts=-1, flags=0002, data=9
783May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=118, state=Idle: Incoming call, ts=-1, flags=0002, data=9
784May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=119, state=Idle: Incoming call, ts=-1, flags=0002, data=9
785May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=120, state=Idle: Incoming call, ts=-1, flags=0002, data=9
786May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=121, state=Idle: Incoming call, ts=-1, flags=0002, data=9
787May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=122, state=Idle: Incoming call, ts=-1, flags=0002, data=9
788May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=123, state=Idle: Incoming call, ts=-1, flags=0002, data=9
789May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=124, state=Idle: Incoming call, ts=-1, flags=0002, data=9
790May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=125, state=Idle: Incoming call, ts=-1, flags=0002, data=9
791May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=126, state=Idle: Incoming call, ts=-1, flags=0002, data=9
792May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=127, state=Idle: Incoming call, ts=-1, flags=0002, data=9
793May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=128, state=Idle: Incoming call, ts=-1, flags=0002, data=9
794May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=29
795May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 3/0x3) (Sent from originator)
796May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
797May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
798May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=29
799May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 3/0x3) (Sent from originator)
800May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
801May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [04 03 80 90 a3]
802May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
803May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
804May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > User information layer 1: A-Law (35)
805May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Channel: 3 Type: CPE]
806May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [6c 05 00 81 31 32 39]
807May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Calling Party Number (len= 7) [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0)
808May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Presentation: Presentation allowed, User-provided, verified and passed (1) '129' ]
809May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [70 04 80 34 30 30]
810May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Called Party Number (len= 6) [ Ext: 1 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0) '400' ]
811May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [a1]
812May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Sending Complete (len= 1)
813May 23 07:44:08 sip_ua[363]: user_agent.cpp:3484: Call 9 routed to TS 131
814May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: CAS event, ts=29, flags=0000, data=15
815May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: CAS A activity detected, ts=28, flags=0000, data=0
816May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Calling: CAS event, ts=29, flags=0000, data=7
817May 23 07:44:08 sip_ua[363]: fxs.cpp:702: ==> ts 29: manual CAS event processing (abcd=15)
818May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Idle: CAS event, ts=28, flags=0000, data=7
819May 23 07:44:08 sip_ua[363]: fxs.cpp:702: ==> ts 28: manual CAS event processing (abcd=15)
820May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
821May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=10
822May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 3/0x3) (Sent to originator)
823May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: CALL PROCEEDING (2)
824May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < [18 03 a9 83 83]
825May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
826May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < ChanSel: As indicated in following octets
827May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Coding: 0 Number Specified Channel Type: 3
828May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Channel: 3 Type: CPE]
829May 23 07:44:08 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_PROCEEDING
830May 23 07:44:08 sip_ua[363]: fxs.cpp:241: ts 28: dialing 400
831May 23 07:44:08 sip_ua[363]: user_agent.cpp:3099: --> ua_dial_out() <sip:128@192.168.0.95> -> sip:400@192.168.0.95 ()...
832May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Calling: CAS event, ts=28, flags=0000, data=7
833May 23 07:44:08 sip_ua[462]: repro.cpp:435: doSessionAccounting(): Session Created 'branch=z9hG4bK1996173393'
834May 23 07:44:08 sip_ua[387]: user_agent.cpp:1987: INVITE received: sip:400@127.0.0.1:6060;transport=udp (Call-ID: 1054480467@(null))
835May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=1, state=Idle: Incoming call, ts=-1, flags=0001, data=12
836May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=2, state=Idle: Incoming call, ts=-1, flags=0001, data=12
837May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=3, state=Idle: Incoming call, ts=-1, flags=0001, data=12
838May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=4, state=Idle: Incoming call, ts=-1, flags=0001, data=12
839May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=5, state=Idle: Incoming call, ts=-1, flags=0001, data=12
840May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=6, state=Idle: Incoming call, ts=-1, flags=0001, data=12
841May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=7, state=Idle: Incoming call, ts=-1, flags=0001, data=12
842May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=8, state=Idle: Incoming call, ts=-1, flags=0001, data=12
843May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=9, state=Idle: Incoming call, ts=-1, flags=0001, data=12
844May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=10, state=Idle: Incoming call, ts=-1, flags=0001, data=12
845May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=11, state=Idle: Incoming call, ts=-1, flags=0001, data=12
846May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=17, state=Idle: Incoming call, ts=-1, flags=0001, data=12
847May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0001, data=12
848May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0001, data=12
849May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0001, data=12
850May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Incoming call, ts=-1, flags=0001, data=12
851May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0001, data=12
852May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0001, data=12
853May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=38, state=Idle: Incoming call, ts=-1, flags=0001, data=12
854May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=39, state=Idle: Incoming call, ts=-1, flags=0001, data=12
855May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=40, state=Idle: Incoming call, ts=-1, flags=0001, data=12
856May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=41, state=Idle: Incoming call, ts=-1, flags=0001, data=12
857May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=42, state=Idle: Incoming call, ts=-1, flags=0001, data=12
858May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=43, state=Idle: Incoming call, ts=-1, flags=0001, data=12
859May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=44, state=Idle: Incoming call, ts=-1, flags=0001, data=12
860May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=45, state=Idle: Incoming call, ts=-1, flags=0001, data=12
861May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=18, state=Idle: Incoming call, ts=-1, flags=0002, data=12
862May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=19, state=Idle: Incoming call, ts=-1, flags=0002, data=12
863May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=20, state=Idle: Incoming call, ts=-1, flags=0002, data=12
864May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=21, state=Idle: Incoming call, ts=-1, flags=0002, data=12
865May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=22, state=Idle: Incoming call, ts=-1, flags=0002, data=12
866May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=23, state=Idle: Incoming call, ts=-1, flags=0002, data=12
867May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=54, state=Idle: Incoming call, ts=-1, flags=0002, data=12
868May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=55, state=Idle: Incoming call, ts=-1, flags=0002, data=12
869May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=56, state=Idle: Incoming call, ts=-1, flags=0002, data=12
870May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=57, state=Idle: Incoming call, ts=-1, flags=0002, data=12
871May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=58, state=Idle: Incoming call, ts=-1, flags=0002, data=12
872May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=59, state=Idle: Incoming call, ts=-1, flags=0002, data=12
873May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=60, state=Idle: Incoming call, ts=-1, flags=0002, data=12
874May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=61, state=Idle: Incoming call, ts=-1, flags=0002, data=12
875May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=62, state=Idle: Incoming call, ts=-1, flags=0002, data=12
876May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=63, state=Idle: Incoming call, ts=-1, flags=0002, data=12
877May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=65, state=Idle: Incoming call, ts=-1, flags=0002, data=12
878May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=66, state=Idle: Incoming call, ts=-1, flags=0002, data=12
879May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=67, state=Idle: Incoming call, ts=-1, flags=0002, data=12
880May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=68, state=Idle: Incoming call, ts=-1, flags=0002, data=12
881May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=69, state=Idle: Incoming call, ts=-1, flags=0002, data=12
882May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=70, state=Idle: Incoming call, ts=-1, flags=0002, data=12
883May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=71, state=Idle: Incoming call, ts=-1, flags=0002, data=12
884May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=72, state=Idle: Incoming call, ts=-1, flags=0002, data=12
885May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=73, state=Idle: Incoming call, ts=-1, flags=0002, data=12
886May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=74, state=Idle: Incoming call, ts=-1, flags=0002, data=12
887May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=75, state=Idle: Incoming call, ts=-1, flags=0002, data=12
888May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=76, state=Idle: Incoming call, ts=-1, flags=0002, data=12
889May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=77, state=Idle: Incoming call, ts=-1, flags=0002, data=12
890May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=78, state=Idle: Incoming call, ts=-1, flags=0002, data=12
891May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=79, state=Idle: Incoming call, ts=-1, flags=0002, data=12
892May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=84, state=Idle: Incoming call, ts=-1, flags=0002, data=12
893May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=85, state=Idle: Incoming call, ts=-1, flags=0002, data=12
894May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=86, state=Idle: Incoming call, ts=-1, flags=0002, data=12
895May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=87, state=Idle: Incoming call, ts=-1, flags=0002, data=12
896May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=88, state=Idle: Incoming call, ts=-1, flags=0002, data=12
897May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=89, state=Idle: Incoming call, ts=-1, flags=0002, data=12
898May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=90, state=Idle: Incoming call, ts=-1, flags=0002, data=12
899May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=91, state=Idle: Incoming call, ts=-1, flags=0002, data=12
900May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=92, state=Idle: Incoming call, ts=-1, flags=0002, data=12
901May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=93, state=Idle: Incoming call, ts=-1, flags=0002, data=12
902May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=94, state=Idle: Incoming call, ts=-1, flags=0002, data=12
903May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=95, state=Idle: Incoming call, ts=-1, flags=0002, data=12
904May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=97, state=Idle: Incoming call, ts=-1, flags=0002, data=12
905May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=98, state=Idle: Incoming call, ts=-1, flags=0002, data=12
906May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=99, state=Idle: Incoming call, ts=-1, flags=0002, data=12
907May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=100, state=Idle: Incoming call, ts=-1, flags=0002, data=12
908May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=101, state=Idle: Incoming call, ts=-1, flags=0002, data=12
909May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=102, state=Idle: Incoming call, ts=-1, flags=0002, data=12
910May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=109, state=Idle: Incoming call, ts=-1, flags=0002, data=12
911May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=110, state=Idle: Incoming call, ts=-1, flags=0002, data=12
912May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=111, state=Idle: Incoming call, ts=-1, flags=0002, data=12
913May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=112, state=Idle: Incoming call, ts=-1, flags=0002, data=12
914May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=113, state=Idle: Incoming call, ts=-1, flags=0002, data=12
915May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=114, state=Idle: Incoming call, ts=-1, flags=0002, data=12
916May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=115, state=Idle: Incoming call, ts=-1, flags=0002, data=12
917May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=116, state=Idle: Incoming call, ts=-1, flags=0002, data=12
918May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=117, state=Idle: Incoming call, ts=-1, flags=0002, data=12
919May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=118, state=Idle: Incoming call, ts=-1, flags=0002, data=12
920May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=119, state=Idle: Incoming call, ts=-1, flags=0002, data=12
921May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=127, state=Idle: Incoming call, ts=-1, flags=0002, data=12
922May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=128, state=Idle: Incoming call, ts=-1, flags=0002, data=12
923May 23 07:44:08 sip_ua[363]: pri.cpp:582: ---> PRI[144]: Incoming call, ts=-1, flags=0002, data=12
924May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
925May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > DL-DATA request
926May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=29
927May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 4/0x4) (Sent from originator)
928May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
929May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
930May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=29
931May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 4/0x4) (Sent from originator)
932May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: SETUP (5)
933May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [04 03 80 90 a3]
934May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Bearer Capability (len= 5) [ Ext: 1 Coding-Std: 0 Info transfer capability: Speech (0)
935May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Trans mode/rate: 64kbps, circuit-mode (16)
936May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > User information layer 1: A-Law (35)
937May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [18 03 a9 83 84]
938May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
939May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > ChanSel: As indicated in following octets
940May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Coding: 0 Number Specified Channel Type: 3
941May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Channel: 4 Type: CPE]
942May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [6c 05 00 81 31 32 38]
943May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Calling Party Number (len= 7) [ Ext: 0 TON: Unknown Number Type (0) NPI: Unknown Number Plan (0)
944May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Presentation: Presentation allowed, User-provided, verified and passed (1) '128' ]
945May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [70 04 80 34 30 30]
946May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
947May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=9
948May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 3/0x3) (Sent to originator)
949May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: DISCONNECT (69)
950May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < [08 02 81 9f]
951May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
952May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
953May 23 07:44:08 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_HANGUP_REQ
954May 23 07:44:08 sip_ua[363]: pri.cpp:503: --> channel=3, cause=Normal, unspecified
955May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
956May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > DL-DATA request
957May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: RELEASE (77)
958May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [08 02 81 9f]
959May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
960May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
961May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Calling: CAS event, ts=28, flags=0000, data=15
962May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
963May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=10
964May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 4/0x4) (Sent to originator)
965May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: CALL PROCEEDING (2)
966May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < [18 03 a9 83 84]
967May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Channel ID (len= 5) [ Ext: 1 IntID: Implicit Other(PRI) Spare: 0 Exclusive Dchan: 0
968May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < ChanSel: As indicated in following octets
969May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Busy: CAS event, ts=29, flags=0000, data=15
970May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
971May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=9
972May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 3/0x3) (Sent to originator)
973May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: RELEASE COMPLETE (90)
974May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < [08 02 81 9f]
975May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
976May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
977May 23 07:44:08 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_HANGUP_ACK
978May 23 07:44:08 sip_ua[363]: pri.cpp:503: --> channel=3, cause=Normal, unspecified
979May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
980May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=9
981May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 4/0x4) (Sent to originator)
982May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: DISCONNECT (69)
983May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < [08 02 81 9f]
984May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
985May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
986May 23 07:44:08 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_HANGUP_REQ
987May 23 07:44:08 sip_ua[363]: pri.cpp:503: --> channel=4, cause=Normal, unspecified
988May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
989May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > DL-DATA request
990May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=9
991May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 4/0x4) (Sent from originator)
992May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: RELEASE (77)
993May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
994May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Protocol Discriminator: Q.931 (8) len=9
995May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > TEI=0 Call Ref: len= 2 (reference 4/0x4) (Sent from originator)
996May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Message Type: RELEASE (77)
997May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > [08 02 81 9f]
998May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
999May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: > Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
1000May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=28, state=Calling: Call disconnected, ts=28, flags=0000, data=11
1001May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]:
1002May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Protocol Discriminator: Q.931 (8) len=9
1003May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < TEI=0 Call Ref: len= 2 (reference 4/0x4) (Sent to originator)
1004May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Message Type: RELEASE COMPLETE (90)
1005May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < [08 02 81 9f]
1006May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Cause (len= 4) [ Ext: 1 Coding: CCITT (ITU) standard (0) Spare: 0 Location: Private network serving the local user (1)
1007May 23 07:44:08 sip_ua[363]: pri.cpp:544: PRI[144]: < Ext: 1 Cause: Normal, unspecified (31), class = Normal Event (1) ]
1008May 23 07:44:08 sip_ua[363]: pri.cpp:200: --> PRI[144]: PRI event: PRI_EVENT_HANGUP_ACK
1009May 23 07:44:08 sip_ua[363]: pri.cpp:503: --> channel=4, cause=Normal, unspecified
1010May 23 07:44:08 sip_ua[363]: fxs.cpp:419: ---> ts=29, state=Idle: CAS A activity detected, ts=29, flags=0000, data=536870912
1011May 23 07:45:57 dropbear[466]: Child connection from ::ffff:192.168.0.111:62239
1012May 23 07:45:59 dropbear[466]: pubkey auth succeeded for 'root' with key md5 cd:45:20:9a:f9:f0:11:55:1e:2b:2d:23:b7:0a:47:65 from ::ffff:192.168.0.111:62239