SMG-2. SN: VI45000362 Software Version: V.3.1.1.1089. 2/S Build: Jun 30 2015 10:43:35
Log started at:  12:24:57.091742
  12:25:47.445626  [INFO]  Q931. Line 00/00. Call 8011. RX 33
DUMP:	08.02.00.11.05.04.03.90.90.A3.18.03.A1.83.9F.1E.
	02.81.83.6C.0C.01.80.38.34.33.32.30.32.32.37.30.
	30.
		0x0011 (from orig), MSG=SETUP: 
		[bearer capability: 
			cap=3.1 kHz audio
			std=CCITT
			rate=64 kbit/s
			mode=circuit
			layer1=G.711 A-law]
		[channel id: channel=31 (preferred)] Coding=CCITT
		[progress ind: Origin not isdn]
		[calling party number: 4952222222 (type=unknown, plan=ISDN, present.allowed, not screened)]
---------------------------------
  12:25:47.446170  [INFO]  Q931. Line 00/00. Create Call, entry 00. Call 8011 (Incoming)
  12:25:47.446320  [INFO]  Q931. Line 00/00. Call 8011. Incoming SETUP. Check chan id
  12:25:47.446424  [INFO]  Check IE 'Channel ID': channel id='as indicated' chan=31, mode non-excl
  12:25:47.446517  [INFO]  Q931. Line 00/00. Search B-chan: chan 31, mask 00010001 - chan ok: mask 00010001
  12:25:47.446599  [INFO]  Check IE 'Bearer capablity: {3.1 kHz audio}{64 kbit/s}{G.711 A-law}
  12:25:47.446696  [INFO]  Check IE 'Calling party number': {type=0x00, plan=0x01}, {presentation=0, screening=0} <4952222222> (10)
  12:25:47.446785  [INFO]  Q931. Line 00/00. Call 8011. Incoming SETUP. {PI 3} CgPN <4952222222>(10), CdPN <>(0) isdn flag=02
  12:25:47.446907  [INFO]  Port PRI:00:00. VChan set (chan 31) - 'line 0, chan 124'
  12:25:47.447022  [INFO]  Port PRI:00:00. Seize (v-chan:31, TG:00). Cld:'<none>'(0). (Trunk) . Clg-len: 10. clg-set: y. COS: -1. RADIUS=-1
  12:25:47.447121  [INFO]  Port PRI:00:00. ModTable in [-1:-1], out [-1:-1], out redir:orig [-1:-1]
  12:25:47.447220  [INFO]  Port PRI:00:00. CgPN: <4952222222>(10) <set> <unknown> <isdn/telephony> <allowed> <not verified>. cat [7].
  12:25:47.447314  [INFO]  Port PRI:00:00. Calling number: 4952222222, use for sorm: all digits
  12:25:47.447407  [INFO]  Port PRI:00:00. Calling Info (pres allowed): <4952222222>. <Category=7>
  12:25:47.447495  [INFO]  Port PRI:00:00. CgPN: <4952222222>(10) <set> <unknown> <isdn/telephony> <allowed> <not verified>. cat [7].
  12:25:47.447590  [INFO]  Port PRI:00:00. Set state 'In-Dial'
  12:25:47.447676  [INFO]  Q931. Line 00/00. Call 8011. Incoming seize - PLAN 0. COS 0
  12:25:47.447776  [INFO]  Q931. Line 00/00. Call 8011. Incoming SETUP. {PI 3} proc dial
  12:25:47.447866  [INFO]  Q931. Line 00/00. Call 8011. State 'Overlap Receiving'
  12:25:47.447955  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:47.448076  [INFO]  Q931. Line 00/00. Call 8011. TX 11
DUMP:	08.02.80.11.0D.18.03.A9.83.9F.
		0x0011 (to orig), MSG=SETUP ACKNOWLEDGE: 
		[channel id: channel=31 (exclusive)] Coding=CCITT
---------------------------------
  12:25:47.448329  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 00): <>
  12:25:47.448437  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:47.448526  [INFO]  Port PRI:00:00. Search route. Mode: 'calling'. Num: <4952222222>(10).# Complete
  12:25:47.448643  [INFO]  Port PRI:00:00. Search route. result: route not found. res (-1)
  12:25:49.921562  [INFO]  Q931. Line 00/00. Call 8011. RX 9
DUMP:	08.02.00.11.7B.70.02.81.32.
		0x0011 (from orig), MSG=INFORMATION: 
		[called  party number: 2 (type=unknown, plan=ISDN)]
---------------------------------
  12:25:49.922260  [INFO]  Check IE 'Called party number': {type=0x00, plan=0x01} <2> (1)
  12:25:49.922393  [INFO]  Q931. Line 00/00. Call 8011. In Dial: 2
  12:25:49.922487  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:49.922594  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): <2> (flg=next)
  12:25:49.922686  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <2>
  12:25:49.922771  [INFO]  Port PRI:00:00. Dial buf <2>(1)
  12:25:49.922860  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 01): <2>
  12:25:49.922947  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:49.923117  [INFO]  Port PRI:00:00. Search route. Mode: 'called'. Num: <2>(1).
  12:25:49.923277  [INFO]  Port PRI:00:00. Search route. result: incomplete route: L[10] S[0] D[30]. res (0)
  12:25:50.759949  [INFO]  Q931. Line 00/00. Call 8011. RX 9
DUMP:	08.02.00.11.7B.70.02.81.30.
		0x0011 (from orig), MSG=INFORMATION: 
		[called  party number: 0 (type=unknown, plan=ISDN)]
---------------------------------
  12:25:50.760276  [INFO]  Check IE 'Called party number': {type=0x00, plan=0x01} <0> (1)
  12:25:50.760375  [INFO]  Q931. Line 00/00. Call 8011. In Dial: 0
  12:25:50.760467  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:50.760576  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): <0> (flg=next)
  12:25:50.760667  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <20>
  12:25:50.760753  [INFO]  Port PRI:00:00. Dial buf <20>(2)
  12:25:50.760842  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 02): <20>
  12:25:50.760931  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:50.761022  [INFO]  Port PRI:00:00. Search route. Mode: 'called'. Num: <20>(2).
  12:25:50.761172  [INFO]  Port PRI:00:00. Search route. result: incomplete route: L[10] S[0] D[30]. res (0)
  12:25:51.599217  [INFO]  Q931. Line 00/00. Call 8011. RX 9
DUMP:	08.02.00.11.7B.70.02.81.32.
		0x0011 (from orig), MSG=INFORMATION: 
		[called  party number: 2 (type=unknown, plan=ISDN)]
---------------------------------
  12:25:51.599540  [INFO]  Check IE 'Called party number': {type=0x00, plan=0x01} <2> (1)
  12:25:51.599644  [INFO]  Q931. Line 00/00. Call 8011. In Dial: 2
  12:25:51.599737  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:51.599845  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): <2> (flg=next)
  12:25:51.599936  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <202>
  12:25:51.600020  [INFO]  Port PRI:00:00. Dial buf <202>(3)
  12:25:51.600109  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 03): <202>
  12:25:51.600196  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:51.600288  [INFO]  Port PRI:00:00. Search route. Mode: 'called'. Num: <202>(3).
  12:25:51.600447  [INFO]  Port PRI:00:00. Search route. result: incomplete route: L[10] S[0] D[30]. res (0)
  12:25:52.725624  [INFO]  Q931. Line 00/00. Call 8011. RX 9
DUMP:	08.02.00.11.7B.70.02.81.33.
		0x0011 (from orig), MSG=INFORMATION: 
		[called  party number: 3 (type=unknown, plan=ISDN)]
---------------------------------
  12:25:52.725955  [INFO]  Check IE 'Called party number': {type=0x00, plan=0x01} <3> (1)
  12:25:52.726055  [INFO]  Q931. Line 00/00. Call 8011. In Dial: 3
  12:25:52.726148  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:52.726255  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): <3> (flg=next)
  12:25:52.726347  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <2023>
  12:25:52.726433  [INFO]  Port PRI:00:00. Dial buf <2023>(4)
  12:25:52.726522  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 04): <2023>
  12:25:52.726609  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:52.726700  [INFO]  Port PRI:00:00. Search route. Mode: 'called'. Num: <2023>(4).
  12:25:52.726869  [INFO]  Port PRI:00:00. Search route. result: incomplete route: L[10] S[0] D[30]. res (0)
  12:25:53.379266  [INFO]  Q931. Line 00/00. Call 8011. RX 9
DUMP:	08.02.00.11.7B.70.02.81.32.
		0x0011 (from orig), MSG=INFORMATION: 
		[called  party number: 2 (type=unknown, plan=ISDN)]
---------------------------------
  12:25:53.379599  [INFO]  Check IE 'Called party number': {type=0x00, plan=0x01} <2> (1)
  12:25:53.379702  [INFO]  Q931. Line 00/00. Call 8011. In Dial: 2
  12:25:53.379795  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:53.379900  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): <2> (flg=next)
  12:25:53.379991  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <20232>
  12:25:53.380154  [INFO]  Port PRI:00:00. Dial buf <20232>(5)
  12:25:53.380259  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 05): <20232>
  12:25:53.380350  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:53.380444  [INFO]  Port PRI:00:00. Search route. Mode: 'called'. Num: <20232>(5).
  12:25:53.380621  [INFO]  Port PRI:00:00. Search route. result: incomplete route: L[10] S[0] D[30]. res (0)
  12:25:54.680694  [INFO]  Q931. Line 00/00. Call 8011. RX 9
DUMP:	08.02.00.11.7B.70.02.81.33.
		0x0011 (from orig), MSG=INFORMATION: 
		[called  party number: 3 (type=unknown, plan=ISDN)]
---------------------------------
  12:25:54.680988  [INFO]  Check IE 'Called party number': {type=0x00, plan=0x01} <3> (1)
  12:25:54.681088  [INFO]  Q931. Line 00/00. Call 8011. In Dial: 3
  12:25:54.681184  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:54.681291  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): <3> (flg=next)
  12:25:54.681383  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <202323>
  12:25:54.681469  [INFO]  Port PRI:00:00. Dial buf <202323>(6)
  12:25:54.681558  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 06): <202323>
  12:25:54.681646  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:54.681740  [INFO]  Port PRI:00:00. Search route. Mode: 'called'. Num: <202323>(6).
  12:25:54.686858  [INFO]  Port PRI:00:00. Search route. result: incomplete route: L[10] S[0] D[30]. res (0)
  12:25:55.983482  [INFO]  Q931. Line 00/00. Call 8011. RX 9
DUMP:	08.02.00.11.7B.70.02.81.38.
		0x0011 (from orig), MSG=INFORMATION: 
		[called  party number: 8 (type=unknown, plan=ISDN)]
---------------------------------
  12:25:55.983805  [INFO]  Check IE 'Called party number': {type=0x00, plan=0x01} <8> (1)
  12:25:55.983904  [INFO]  Q931. Line 00/00. Call 8011. In Dial: 8
  12:25:55.983998  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' (15 sec) start in state 'Overlap Receiving'
  12:25:55.984103  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): <8> (flg=next)
  12:25:55.984195  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <2023238>
  12:25:55.984281  [INFO]  Port PRI:00:00. Dial buf <2023238>(7)
  12:25:55.984370  [INFO]  Port PRI:00:00. Dial Proc (state In-Dial, called len 07): <2023238>
  12:25:55.984460  [INFO]  Port PRI:00:00. Dial Proc. RADIUS return-code: [0].
  12:25:55.984554  [INFO]  Port PRI:00:00. Search route. Mode: 'called'. Num: <2023238>(7).
  12:25:55.984747  [INFO]  Port PRI:00:00. Search route. result: found route: prefix[1]. res (7)
  12:25:55.984853  [INFO]  Port PRI:00:00. Process route to prefix[1].
  12:25:55.984972  [INFO]  Port PRI:00:00. Dial In (in=01, need rm=0): [.]
  12:25:55.985062  [INFO]  Port PRI:00:00. In Digit 1 sym: called buf <2023238.>
  12:25:55.985148  [INFO]  Port PRI:00:00. Dial buf <2023238>(7)
  12:25:55.985235  [INFO]  Port PRI:00:00. Send 'End of Dial' to incoming call
  12:25:55.985321  [INFO]  Q931. Line 00/00. Call 8011. End Dial in state 'Overlap Receiving' (condition for call proceeding reached)
  12:25:55.985410  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T302' stop in state 'Overlap Receiving'
  12:25:55.985517  [INFO]  Q931. Line 00/00. Call 8011. TX 6
DUMP:	08.02.80.11.02.
		0x0011 (to orig), MSG=CALL PROCEEDING: 
---------------------------------
  12:25:55.985738  [INFO]  Q931. Line 00/00. Call 8011. State 'Incoming Call Proceeding'
  12:25:55.985850  [INFO]  Port PRI:00:00. Prefix 01: type 'TRUNK', idx=1
  12:25:55.985940  [INFO]  Port PRI:00:00. Accs on
  12:25:55.986028  [INFO]  Port PRI:00:00. Link to TG 01. Out modifiers-table: clg[-1], cld[-1], redir[-1], orig[-1], generic[-1]
  12:25:55.986120  [INFO]  Port PRI:00:00. Link to TG 01. Setup mode: overlap <ST>
  12:25:55.986222  [INFO]  Port PRI:00:00. Link to TG 01: mode Local. Dir=0, Cld=0 Select Order=. In buf: ~2023238.
  12:25:55.986314  [INFO]  Port PRI:00:00. TG: Link entry to SIPT[00]
  12:25:55.986488  [INFO]  Port PRI:00:00. Outgoing setup to SIPT:0000.
  12:25:55.986590  [INFO]  SIPT[00]. Seize from port PRI:00:00.
  12:25:55.986718  [INFO]  Port SIPT:0400. Check out-calling-modify: '4952222222'(10). ModTable[-1], ClgMod[-1]
  12:25:55.986824  [INFO]  Port SIPT:0400. Check out-calling-from-RADIUS-modify: '4952222222'(10). ClgMod[-1]
  12:25:55.986917  [INFO]  SIPT[00]. Seize  - interface 0 [ID: 01]: set host:port 1.1.1.1:5060
  12:25:55.987012  [INFO]  Port SIPT:0400. Set parent PRI:00:00
  12:25:55.987096  [INFO]  Port SIPT:0400. VChanIP set - 'line 0, chan 124'
  12:25:55.987207  [INFO]  Port SIPT:0400. Create msp-conn Conn[001f].
  12:25:55.987295  [INFO]  Conn[001f]: Set state 'Idle'->'AllocReq'
  12:25:55.987505  [INFO]  Port SIPT:0400. Set new IP Conn[001f] with chan[124]
  12:25:55.987625  [INFO]  Conn[001f]: set network interface in state 'AllocReq'. IP <2.2.2.2>. net-dev [1]
  12:25:55.987753  [INFO]  SIP. Callref 0400. IP-conn [001f] ready
  12:25:55.987853  [INFO]  Port SIPT:0400. loc 0, trunk-loc 0. peer-loc 0, peer-tunk-loc: 0
  12:25:55.987957  [INFO]  Port PRI:00:00. loc 0, trunk-loc 0. peer-loc 0, peer-tunk-loc: 0
  12:25:55.988052  [INFO]  Conn[001f]. Set owner Port SIPT:0400 (previous was SIPT:0400)
  12:25:55.988141  [INFO]  Conn[001f]: set network interface in state 'AllocReq'. IP <2.2.2.2>. net-dev [1]
  12:25:55.988389  [INFO]  SIPT[00]. Seize  - dst '1.1.1.1:5060' src-port 0
  12:25:55.988510  [INFO]  SIPT[00]. Seize  CgPN: <4952222222>(10) <set> <unknown> <isdn/telephony> <allowed> <not verified>. cat [7](0x37)
  12:25:55.988606  [INFO]  SIPT[00]. Seize  - calling category: '7' convert to ISUP category 227
  12:25:55.988691  [INFO]  SIPT[00]. Seize  - enblock mode
  12:25:55.988806  [INFO]  Port SIPT:0400. Set state 'Wait-Num'
  12:25:55.988910  [INFO]  Port SIPT:0400. Query Next Digit - peer PRI:00:00 (next.digit=n). Called len 08, proceed 00
  12:25:55.989000  [INFO]  Port SIPT:0400. Dial Out=1 (ext)
  12:25:55.989088  [INFO]  Port SIPT:0400. Check out-called-modify: '1'(1). ModTable[-1], CldMod[-1]
  12:25:55.989178  [INFO]  SIP. Callref 0400. Dial 1
  12:25:55.989260  [INFO]  Port SIPT:0400. Dial Out: called len 00, append 01 (max digit 30)
  12:25:55.989359  [INFO]  Port SIPT:0400. Query Next Digit - peer PRI:00:00 (next.digit=n). Called len 08, proceed 01
  12:25:55.989444  [INFO]  Port SIPT:0400. Dial Out=1 (ext)
  12:25:55.989545  [INFO]  SIP. Callref 0400. Dial 1
  12:25:55.989631  [INFO]  Port SIPT:0400. Dial Out: called len 01, append 01 (max digit 30)
  12:25:55.989731  [INFO]  Port SIPT:0400. Query Next Digit - peer PRI:00:00 (next.digit=n). Called len 08, proceed 02
  12:25:55.989942  [INFO]  Port SIPT:0400. Dial Out=1 (ext)
  12:25:55.990047  [INFO]  SIP. Callref 0400. Dial 1
  12:25:55.990133  [INFO]  Port SIPT:0400. Dial Out: called len 02, append 01 (max digit 30)
  12:25:55.990231  [INFO]  Port SIPT:0400. Query Next Digit - peer PRI:00:00 (next.digit=n). Called len 08, proceed 03
  12:25:55.990315  [INFO]  Port SIPT:0400. Dial Out=1 (ext)
  12:25:55.990400  [INFO]  SIP. Callref 0400. Dial 1
  12:25:55.990515  [INFO]  Port SIPT:0400. Dial Out: called len 03, append 01 (max digit 30)
  12:25:55.990621  [INFO]  Port SIPT:0400. Query Next Digit - peer PRI:00:00 (next.digit=n). Called len 08, proceed 04
  12:25:55.990708  [INFO]  Port SIPT:0400. Dial Out=1 (ext)
  12:25:55.990794  [INFO]  SIP. Callref 0400. Dial 1
  12:25:55.990876  [INFO]  Port SIPT:0400. Dial Out: called len 04, append 01 (max digit 30)
  12:25:55.990974  [INFO]  Port SIPT:0400. Query Next Digit - peer PRI:00:00 (next.digit=n). Called len 08, proceed 05
  12:25:55.991074  [INFO]  Port SIPT:0400. Dial Out=1 (ext)
  12:25:55.991163  [INFO]  SIP. Callref 0400. Dial 1
  12:25:55.991246  [INFO]  Port SIPT:0400. Dial Out: called len 05, append 01 (max digit 30)
  12:25:55.991343  [INFO]  Port SIPT:0400. Query Next Digit - peer PRI:00:00 (next.digit=n). Called len 08, proceed 06
  12:25:55.991429  [INFO]  Port SIPT:0400. Dial Out=1. (ext)
  12:25:55.991586  [INFO]  SIP. Callref 0400. Dial 1.
  12:25:55.991682  [INFO]  Port SIPT:0400. Dial Out: called len 06, append 02 (max digit 30)
  12:25:55.991772  [INFO]  Port SIPT:0400. Dial Out: 'End_of_Dial' digit/stop dial
  12:25:55.992082  [INFO]  SIP. Callref 0400. New state 'SIPT_OVL_SENDING'
  12:25:55.992215  [INFO]  Port SIPT:0400. Setup Ack (peer=PRI:00:00)
  12:25:55.992306  [INFO]  Port SIPT:0400. Set state 'Out-Dial'
  12:25:55.992397  [INFO]  Port PRI:00:00. Setup Acked in state In-Dial. (peer=SIPT:0400)
  12:25:55.992487  [INFO]  Port PRI:00:00. LinkUp to SIPT:0400
  12:25:55.992577  [INFO]  Port SIPT:0400. Activate IP-conn [001f]
  12:25:55.992660  [INFO]  Conn[001f]: try activate. state 'AllocReq'.no media
  12:25:55.992749  [INFO]  SIP. Callref 0400. Prepare media requested
  12:25:55.992847  [INFO]  SIP. Callref 0400. Send Media-Info. [core/sipt_proc.c:1836]
  12:25:55.992940  [INFO]  SIP. Callref 0400. Media-Info (offer): [2.2.2.2]
  12:25:55.993031  [INFO]  SIP. Callref 0400. 
			 o=mgapp 1870674206 1696601059 IN IP4 2.2.2.2
			 c=IN IP4 2.2.2.2
  12:25:55.993190  [INFO]  Valid
			 m[0]: audio 10006 RTP/AVP 8 0 18
			 a[0]:8 G711.A [set]
			 a[1]:0 G711.U [set]
			 a[2]:18 G729.A [set]
			 a[3]:ptime:30
			 a[4]:sendrecv
  12:25:55.993425  [INFO]  SIP. Callref 0400. Prepare media finished (should send)
  12:25:55.993520  [INFO]  SIP. Callref 0400. Mode: 'SIP' Destination: [1.1.1.1:5060] localport [0] (with media)
  12:25:55.993631  [INFO]  Port PRI:00:00. Get CallerId: '-none-' (0) no set-flg
  12:25:55.993721  [INFO]  SIP. Callref 0400. Get display name: <>(0)
  12:25:55.993805  [INFO]  SIP. Callref 0400. Seize (media ready)
  12:25:55.993893  [INFO]  SIP. Callref 0400. Media-Info encode. Media-list size: 1 
  12:25:55.994048  [INFO]  PBXIPC-SIP. TX. seize 
  12:25:55.994158  [INFO]  		# callref 0400
  12:25:55.994245  [INFO]  		# trunkID 01
  12:25:55.994325  [INFO]  		# Hostname '1.1.1.1'
  12:25:55.994418  [INFO]  		# port 5060
  12:25:55.994499  [INFO]  		# cdpn <1111111> (7) cat 0x00
  12:25:55.994580  [INFO]  		# cgpn <4952222222> (10) cat 0xe3
  12:25:55.994659  [INFO]  		# SDP len (239)
  12:25:55.994862  [INFO]  Port PRI:00:00. Prefix linked: called len 08, proceed 08
  12:25:55.997437  [INFO]  Conn[001f]: Set state 'AllocReq'->'Allocated'
  12:25:55.997753  [INFO]  Conn[001f]: Set state 'Allocated'->'DisableReq'
  12:25:55.998940  [INFO]  Conn[001f]: Created (disabled). State 'DisableReq'
  12:25:55.999065  [INFO]  Conn[001f]: Set state 'DisableReq'->'Disabled'
  12:25:55.999161  [INFO]  Port SIPT:0400. Created IP-conn [001f]. 
  12:25:55.999247  [INFO]  Conn[001f]: try activate. state 'Disabled'.no media
  12:25:56.324385  [INFO]  PBXIPC-SIP. RX. answer 
  12:25:56.324580  [INFO]  		# callref 0400
  12:25:56.324670  [INFO]  		# cause 180
  12:25:56.324748  [INFO]  		# Call-ID len (32)
  12:25:56.324824  [INFO]  		# Display name ''
  12:25:56.325646  [INFO]  SIP. Callref 0400. Response 180 in state 'SIPT_OVL_SENDING' call-ID: 5Nni8od6FBdDdiChZnfEqyLonSjUdoAi
  12:25:56.325791  [INFO]  SIP. Callref 0400. Set display name: <>(0)
  12:25:56.325884  [INFO]  SIP. Callref 0400. Alert in state 'SIPT_OVL_SENDING'
  12:25:56.325969  [INFO]  SIP. Callref 0400. New state 'SIPT_DELIVERED'
  12:25:56.326065  [INFO]  SIP. Callref 0400. ISDN flg: BCI=0x50: 'ISDN All Way'  'Change' 'Send' 
  12:25:56.326157  [INFO]  Port SIPT:0400. Alert (peer=PRI:00:00)
  12:25:56.326242  [INFO]  Port SIPT:0400. Stop Dial, reason 'alert'
  12:25:56.326339  [INFO]  Port PRI:00:00. LinkUp to SIPT:0400
  12:25:56.326425  [INFO]  Port SIPT:0400. Activate IP-conn [001f]
  12:25:56.326509  [INFO]  Conn[001f]: try activate. state 'Disabled'.no media
  12:25:56.326597  [INFO]  Port SIPT:0400. Set state 'Out-Call'
  12:25:56.326699  [INFO]  Port PRI:00:00. Set state 'In-Call'
  12:25:56.326795  [INFO]  Port PRI:00:00. LinkUp to SIPT:0400
  12:25:56.326881  [INFO]  Port SIPT:0400. Activate IP-conn [001f]
  12:25:56.326961  [INFO]  Conn[001f]: try activate. state 'Disabled'.no media
  12:25:56.327125  [INFO]  Port SIPT:0400. Dir[1], obci-req[1]. Call-ind: change[1], obci[0:0], isdn: allway[1], fwd_acs[0]
  12:25:56.327231  [INFO]  Port SIPT:0400. Dir[1], obci-req[0]. Call-ind: change[1], obci[0:0], isdn: allway[1], fwd_acs[0]
  12:25:56.327325  [INFO]  Q931. Line 00/00. Call 8011. Call Received. PI [2][0]
  12:25:56.327413  [INFO]  Q931. Line 00/00. Call 8011. Send 'Alert'
  12:25:56.327531  [INFO]  Q931. Line 00/00. Call 8011. TX 10
DUMP:	08.02.80.11.01.1E.02.81.82.
		0x0011 (to orig), MSG=ALERTING: 
		[progress ind: Destination not isdn]
---------------------------------
  12:25:56.327799  [INFO]  Q931. Line 00/00. Call 8011. State 'Call Received'
  12:25:56.327909  [INFO]  Port SIPT:0400. TDM-RingBack for IP-conn [001f], mode [0]
  12:25:56.328010  [INFO]  Conn[001f]: RingBack. State 'Disabled', ip <not set>, flag <0>
  12:25:56.434361  [INFO]  Conn[001f]. Start tone 'RINGBACKTONE'(23), direction 'TDM' in state 'Disabled'
  12:25:56.435963  [INFO]  Conn[001f]: Tone started
  12:26:23.987426  [INFO]  PBXIPC-SIP. RX. media-connected 
  12:26:23.987586  [INFO]  		# callref 0400
  12:26:23.987726  [INFO]  SIP. Callref 0400. MediaConnected in state 'SIPT_DELIVERED'
  12:26:23.987827  [INFO]  SIP. Callref 0400. Media connected. Mode: 'inactive'
  12:26:23.987920  [INFO]  Port SIPT:0400. Media-connected for IP-conn [001f]
  12:26:23.988024  [INFO]  Conn[001f]: Media connected indication
  12:26:23.988123  [INFO]  Conn[001f]: try activate. state 'Disabled'.no media
  12:26:23.988212  [INFO]  Port SIPT:0400. Set IP-conn [001f] inactive
  12:26:23.988295  [INFO]  Conn[001f]: SendRecv set 'inactive' in state 'Disabled'
  12:26:23.988433  [INFO]  PBXIPC-SIP. RX. answer 
  12:26:23.988527  [INFO]  		# callref 0400
  12:26:23.988609  [INFO]  		# cause 200
  12:26:23.988687  [INFO]  		# Call-ID len (32)
  12:26:23.988781  [INFO]  		# Display name ''
  12:26:23.988861  [INFO]  		# SDP len (184)
  12:26:23.988981  [INFO]  SIP. Callref 0400. Response 200 in state 'SIPT_DELIVERED' call-ID: 5Nni8od6FBdDdiChZnfEqyLonSjUdoAi
  12:26:23.989158  [INFO]  SIP. Callref 0400. Set Media-Info. Count[1]
  12:26:23.989265  [INFO]  SIP. Callref 0400. Media-Info (answer): [1.1.1.1]
  12:26:23.989358  [INFO]  SIP. Callref 0400. 
			 o= 0 0 IN IP4 1.1.1.1
			 c=IN IP4 1.1.1.1
  12:26:23.989518  [INFO]  Valid
			 m[0]: audio 42176 RTP/AVP 8 0 18
			 a[0]:8 G711.A [set]
			 a[1]:0 G711.U [set]
			 a[2]:18 G729.B [set]
			 a[3]:sendrecv
  12:26:23.989721  [INFO]  SIP. Callref 0400. Media negotiate. Interface: 0
  12:26:23.989832  [INFO]  SIP. Callref 0400. Media negotiate <answer>. [1.1.1.1]. List-count: 1
  12:26:23.989922  [INFO]  SIP. Callref 0400. Media negotiate. Codec found: G711.A. PT 8. idx [0]. sendrecv
  12:26:23.990014  [INFO]  Port SIPT:0400. Set IP-conn [001f] sendrecv
  12:26:23.990095  [INFO]  Conn[001f]: SendRecv set 'sendrecv' in state 'Disabled'
  12:26:23.990194  [INFO]  Port SIPT:0400. IP-conn [001f]. Set Tune
  12:26:23.990279  [INFO]  Conn[001f]: Set tune: vad 'off', cng 'off', ecan 'on'
			 pte [20], rtcp_period [0], rtcp_ctrl [0], dscp [0]
			 dtmf_mode 'inband' dtmf_pt rx [101] dtmf_pt tx [101] fax_mode [3]
			 use_vbd 'off' vbd_codec [2] vbd_pt [0]
			 NAT: N, t38transit: 0
  12:26:23.990471  [INFO]  SIP. Callref 0400. Use codec: G711.A sendrecv. PT 8.
  12:26:23.990570  [INFO]  Port SIPT:0400. IP-conn [001f]. Set Media-Info: [1.1.1.1:42176]
  12:26:23.990661  [INFO]  Conn[001f]: Set media-info in state 'Disabled'. [1.1.1.1:42176]
  12:26:23.990754  [INFO]  Conn[001f]: [2.2.2.2:10006] <-> [1.1.1.1:42176]
  12:26:23.990839  [INFO]  Conn[001f]: Codec: G.711A , payload [8], DTMF-PT: 101
  12:26:23.990941  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:23.991088  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:23.991179  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:23.991407  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:23.991621  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:23.991722  [INFO]  Conn[001f]: MAC not found after ping
  12:26:23.991809  [INFO]  Conn[001f]: Deactivate in state 'Disabled'
  12:26:23.994624  [INFO]  Conn[001f]: set need-mac flag (info-ex)
  12:26:23.994731  [INFO]  Conn[001f]: Set codecs-info in state 'Disabled'. Count: 3
  12:26:23.994819  [INFO]  Conn[001f]: G711.A PT [8]
  12:26:23.994902  [INFO]  Conn[001f]: G711.U PT [0]
  12:26:23.994983  [INFO]  Conn[001f]: G729.B PT [18]
  12:26:23.995069  [INFO]  SIP. Callref 0400. Set display name: <>(0)
  12:26:23.995155  [INFO]  SIP. Callref 0400. Connect in state 'SIPT_DELIVERED'
  12:26:23.995237  [INFO]  SIP. Callref 0400. New state 'SIPT_ACTIVE'
  12:26:23.995331  [INFO]  Port SIPT:0400. Answer
  12:26:23.995425  [INFO]  Port SIPT:0400. Set RADIUS-answer flag
  12:26:23.995512  [INFO]  Port SIPT:0400. Set state 'Talk'
  12:26:23.995605  [INFO]  Port PRI:00:00. Answer from peer (SIPT:0400)
  12:26:23.995689  [INFO]  Port PRI:00:00. Self: ext, Peer: ext.
  12:26:23.995770  [INFO]  Port SIPT:0400. Self: ext, Peer: ext.
  12:26:23.995878  [INFO]  Port PRI:00:00. Set RADIUS-talk flag
  12:26:23.995968  [INFO]  Port PRI:00:00. Set state 'Talk'
  12:26:23.996056  [INFO]  Q931. Line 00/00. Call 8011. Answer
  12:26:23.996143  [INFO]  Port SIPT:0400. Dir[1], obci-req[0]. Call-ind: change[0], obci[0:0], isdn: allway[1], fwd_acs[0]
  12:26:23.996237  [INFO]  Q931. Line 00/00. Call 8011. Send 'Answer'. PI-sent[2]
  12:26:23.996329  [INFO]  Port PRI:00:00. LinkUp to SIPT:0400
  12:26:23.996416  [INFO]  Port SIPT:0400. Activate IP-conn [001f]
  12:26:23.996500  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:23.996593  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:23.996738  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:23.996833  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:23.997029  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:23.997163  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:23.997256  [INFO]  Conn[001f]: MAC not found after ping
  12:26:23.997355  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:23.997440  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:23.997528  [INFO]  Q931. Line 00/00. Call 8011. State 'Active'
  12:26:23.997637  [INFO]  Q931. Line 00/00. Call 8011. TX 6
DUMP:	08.02.80.11.07.
		0x0011 (to orig), MSG=CONNECT: 
---------------------------------
  12:26:24.007513  [INFO]  Port PRI:00:00. LinkUp to SIPT:0400
  12:26:24.007717  [INFO]  Port SIPT:0400. Activate IP-conn [001f]
  12:26:24.007813  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.007911  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.008060  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.008154  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.008349  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.008485  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.008579  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.008660  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.008741  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.015206  [INFO]  Q931. Line 00/00. Call 8011. RX 5
DUMP:	08.02.00.11.0F.
		0x0011 (from orig), MSG=CONNECT ACKNOWLEDGE: 
---------------------------------
  12:26:24.044523  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.044745  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.044846  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.044943  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.045092  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.045260  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.045475  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.045617  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.045834  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.045940  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.046025  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.046135  [INFO]  Conn[001f]: set rtp-cfg. <2.2.2.2:10006>:[02.31.52.63.74.80] <-> <1.1.1.1:42176>:[00.00.00.00.00.00]. flg[1]
  12:26:24.154403  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.154625  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.154724  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.154823  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.154972  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.155065  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.155310  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.155459  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.155552  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.155635  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.155717  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.264413  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.264632  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.264729  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.264828  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.264975  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.265069  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.265267  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.265403  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.265497  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.265579  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.265659  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.376047  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.376269  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.376369  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.376468  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.376617  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.376711  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.376911  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.377048  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.377140  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.377223  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.377304  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.484414  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.484640  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.484739  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.484839  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.484985  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.485079  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.485281  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.485416  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.485508  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.485665  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.485763  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.594388  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.594614  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.594713  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.594811  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.594959  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.595053  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.595253  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.595390  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.595481  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.595564  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.595645  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.704427  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.704651  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.704750  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.704848  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.704996  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.705091  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.705290  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.705427  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.705521  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.705602  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.705682  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.814340  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.814608  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.814711  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.814810  [INFO]  Conn[001f]: try get remote MAC for <1.1.1.1>
  12:26:24.814957  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.815051  [INFO]  Conn[001f]: MAC not found. Try ping
  12:26:24.815250  [INFO]  Conn[001f]: normal ping dest IP addr <1.1.1.1> (FOR FIND REMOTE MAC)
  12:26:24.819994  [INFO]  arp: failed to get route entry. but got default-gw addr <2.2.2.1>
  12:26:24.820156  [INFO]  Conn[001f]: MAC not found after ping
  12:26:24.820247  [ERR ]  Conn[001f]: can't find remote MAC. 
  12:26:24.820331  [INFO]  Conn[001f]: set need-mac flag (mac-check)
  12:26:24.924435  [INFO]  Conn[001f]: Need-mac flag set. Try process
  12:26:24.924659  [INFO]  Conn[001f]: Process reactivate for voice. In state 'Disabled'
  12:26:24.924758  [INFO]  Conn[001f]: try activate. state 'Disabled'.has media
  12:26:24.924846  [ERR ]  Conn[001f]: can't find remote MAC. retries exhausted. Release
  12:26:24.924950  [INFO]  Port SIPT:0400. Release, cause 'Temporary failure', dcl '0', loc '0', peer 'PRI:00:00'
  12:26:24.925051  [INFO]  Port SIPT:0400. Save CDR. disabled. [0x0]
  12:26:24.925141  [INFO]  Port SIPT:0400. Set state 'Release'
  12:26:24.925234  [INFO]  Port SIPT:0400. Release peer 'PRI:00:00', cause 'Temporary failure', dcl '4', loc '0'
  12:26:24.925327  [INFO]  Port PRI:00:00. Disconnect from SIPT:0400, cause 'Temporary failure', dcl '4', loc '0'
  12:26:24.925418  [INFO]  Port PRI:00:00. Disconnect, cause 'Temporary failure', dcl '4', loc '0'
  12:26:24.925514  [INFO]  Port PRI:00:00. Save CDR. disabled. [0x0]
  12:26:24.925603  [INFO]  Port PRI:00:00. Set signal: 'BUSY'
  12:26:24.925695  [INFO]  Q931. Line 00/00. Call 8011. State 'Disconnect Request'
  12:26:24.925786  [INFO]  Q931. Line 00/00. Call 8011. Timer 'T305' (30 sec) start in state 'Disconnect Request'
  12:26:24.925915  [INFO]  Q931. Line 00/00. Call 8011. TX 10
DUMP:	08.02.80.11.45.08.02.80.A9.
		0x0011 (to orig), MSG=DISCONNECT: 
		[cause: Temporary failure (location=user, std=CCITT)]
---------------------------------
  12:26:24.926289  [INFO]  Port PRI:00:00. Set state 'Release'
  12:26:24.926411  [INFO]  Port SIPT:0400. Free IP-conn [001f]
  12:26:24.926504  [INFO]  Conn[001f]: Destroy in state 'Disabled'. [check]
  12:26:24.926588  [INFO]  Port SIPT:0400. Destroy Conn[001f].
  12:26:24.926668  [INFO]  Conn[001f]: Set state 'Disabled'->'DeallocReq'
  12:26:24.926874  [INFO]  Port SIPT:0400. Disconnect, cause 'Temporary failure', dcl '0', loc '0'
  12:26:24.926988  [INFO]  Port SIPT:0400. Save CDR. disabled. [0x0]
  12:26:24.927121  [INFO]  Port SIPT:0400. Set signal: 'BUSY'
  12:26:24.927223  [INFO]  SIP. Callref 0400. Release, cause 'Temporary failure', loc '0'
  12:26:24.927306  [INFO]  SIP. Callref 0400. Disconnect. cause 'Temporary failure', loc '0', reply '0'
  12:26:24.927559  [INFO]  Port SIPT:0400. Release, cause 'Temporary failure', dcl '0', loc '0'
  12:26:24.927679  [INFO]  Port SIPT:0400. Save CDR. disabled. [0x0]
  12:26:24.927789  [INFO]  SIP. Callref 0400. New state 'SIPT_RELEASE_REQUEST'
  12:26:24.927880  [INFO]  SIP. Callref 0400. Disconnect
  12:26:24.927985  [INFO]  PBXIPC-SIP. TX. rel-req 
  12:26:24.928091  [INFO]  		# callref 0400
  12:26:24.928179  [INFO]  		# q850-cause 41
  12:26:24.928259  [INFO]  		# cause 503
  12:26:24.928673  [INFO]  Conn[001f]: Destroyed
  12:26:24.928828  [INFO]  Conn[001f]: clr rtp-cfg. <2.2.2.2:10006>:[02.31.52.63.74.80] <-> <1.1.1.1:42176>:[00.00.00.00.00.00]. flg[1]
  12:26:24.928938  [INFO]  Conn[001f]: Set state 'DeallocReq'->'Idle'
  12:26:24.945214  [INFO]  PBXIPC-SIP. RX. rel-cplt 
  12:26:24.945389  [INFO]  		# callref 0400
  12:26:24.945476  [INFO]  		# cause 200
  12:26:24.945632  [INFO]  SIP. Callref 0400. Release complete
  12:26:24.945736  [INFO]  SIP. Callref 0400. New state 'SIPT_IDLE'
  12:26:24.945836  [INFO]  Port SIPT:0400. Release Complete, cause 'Normal call clearing', dcl '0', loc '0'
  12:26:24.945934  [INFO]  Port SIPT:0400. Save CDR. disabled. [0x0]
  12:26:24.946042  [INFO]  Port SIPT:0400. Set state 'Ready'
  12:26:25.802274  [INFO]  Port PRI:00:00. Signal BUSY active
SMG-2. SN: VI45000362 Software Version: V.3.1.1.1089. 2/S Build: Jun 30 2015 10:43:35
Log stopped at:  12:26:28.567573
