Ayava IP Office 91.1.7 163 1

[NickAbb]

Anyone point me in the right direction. Trying to setup hunt group that will call list of external numbers. Set up dummy extension for each external number and calling the dummy extension number correctly calls the external number (call forwarding set to unconditional on the dummy extension).

E.g. Extn 701 Unconditional forwarding to 07789 xxxxxx - dialing extn 701 correctly calls the mobile number 07789 xxxxxx.

However when this dummy extension is attached to a new call group, dialing the group number does not call the external number.

E.g. Call Group 689 setup with only user in list 701 (which is ticked), dialing 689 rings out (as voice mail is not enabled for the group)

Obviously missing something simple....

Have disabled voice mail on the dummy extensions and on the call group. Can't see anything else obvious.

Setting up the 701 dummy extension to forward to an internal extension and adding it to the call group works.

E.g. Extn 701 Unconditional forwarding to 308 and add 701 to the 689 call group, dialing 689 correctly rings 308.

It appears that when the number to forward to is external (land line or mobile) then the forwarding (via the call group) does not work although when you dial the dummy extension directly it does.

 

[janni78]

Group can not be Collective and you must enable "Forward on Huntgroup calls" on the user.

Although it would probably be better to set the users up with Mobile Twinning as in that case the user would be busy when they answer one call and don't get another until the first one is ended.
Also twinning works with collective even though still no recommended.

"Trying is the first step to failure..." - Homer

 

[NickAbb]

Thanks Janni78,

I have got 'Forward on Hunt Group' set and group is set to sequential. Added a bit more to the original post:

Setting up the 701 dummy extension to forward to an internal extension and adding it to the call group works.

E.g. Extn 701 Unconditional forwarding to 308 and add 701 to the 689 call group, dialing 689 correctly rings 308.

It appears that when the number to forward to is external (land line or mobile) then the forwarding (via the call group) does not work although when you dial the dummy extension directly it does.

Thought about the mobile twinning option and am looking at that now.

 

[janni78]

If you're calling from an external number it might be that your service provider is blocking the call when you send the callers CLI.

Do a monitor trace to see where it goes wrong.

"Trying is the first step to failure..." - Homer

 

[TouchToneTommy]

Is Inhibit Off-Switch Forward/Transfer ticked under System > Telephony > Telephony?

 

[NickAbb]

Inhibit switch is off

Pretty sure provider isn't blocking as we had them take the block off when the system went in, but will check the logs just in case.

 

[NickAbb]

Ok,

Tested as follows:

System Telephony Inhibit flag unchecked

Test 1
One dummy user (Extn 705) that has unconditional forwarding set to internal extension 308.
One call group (698) with one user (705) defined. Voice mail on call group and user off. Ring Mode set to sequential.
Dial 698 (from internal extension) and extn 308 rings

Test 2
Change the call forwarding number on 705 from 308 to 07789 xxxxxx (external mobile number)
Dial 698 (from internal extension) and mobile does not ring

Test 3
Dial 705 (form internal extension) and mobile rings

So by my reckoning, test 1 proves the call group is working and test 3 proves it cannot be the provider blocking.

Looking at the trace, the only thing I can see different between test 1 and test 2 is that I get the following error when the call to 698 is placed.

19:43:18 2016876814mS CMMap: a=2.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f5639638) resource busy 1, total 32


Any ideas?

 

[janni78]

Do a default monitor trace when it doesn't work and attach it here if you need help reading it.

"Trying is the first step to failure..." - Homer

 

[NickAbb]

Thanks janni78, any advice you can give would be gratefully received.

Here's the log, scenario was extn 205 calling group 698. Group 698 has one user (purely for testing) which is a dummy extn 705 with unconditional forwarding to a mobile number. I have highlighted the relevant section in red.


********** SysMonitor v9.1.7.0 build 163 **********

********** contact made with 192.168.42.1 at 09:30:38 20/12/2016 **********

********** System (192.168.42.1) has been up and running for 23days, 22hrs, 1min and 49secs(2066509598mS) **********

********** Warning: TEXT File Logging selected **********


********** Warning: TEXT Logging to C:\Program Files (x86)\Avaya\IP Office\Monitor\\TMP3.$$$ File: STARTED on 20/12/2016 09:30:38 **********
2066509598mS PRN: Monitor Started IP=192.168.42.2 IP 500 V2 9.1.7.0 build 163 Chess Logistics
(Supports Unicode, System Locale is default)
2066509598mS PRN: LAW=A PRI=1, BRI=0, ALOG=0, VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) 1-X=1 CALLS=0(TOT=18678)
09:30:27 2066509751mS H323Evt: Recv: RegistrationRequest 192.168.42.6; Extn(204), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:27 2066509970mS RES: Tue 20/12/2016 09:30:27 FreeMem=53756556 Heap=51788832(4) Cache=1967724 MemObjs=17179(21667) CMMsg=13(13) ASN=0 Buff=5200 1364 1000 7420 5 Links=59365(61254) BTree=1268(2186) CPU=09.29% CPUStats=08.66%/1/4/1680/21422/27003/00.0
9%/1/02.09% MCR=0 MCW=0
09:30:27 2066509970mS RES2: IP 500 V2 9.1.7.0 build 163 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=101 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=92(TLS=7) TAPI=1 Partner=48 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:30:27 2066509970mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
09:30:27 2066509970mS RES5: CLog MemObjs=1142 FreePoolMem(Objs)=3016(58) TotalMem=252800
09:30:30 2066513598mS PRN: +++ START OF UDP MONITOR CLIENT DUMP +++
09:30:30 2066513598mS PRN: CLIENT: IPAddress=192.168.42.2 UDPPort=59402 LastReceived=2066509609(3989) LastSent=2066513598(0) LastOldUpdate=2066509598(4000) SentPackets=16 MissedPackets=0 OldSentPackets=0 OldMissedPackets=0
09:30:30 2066513598mS PRN: ++++ END OF UDP MONITOR CLIENT DUMP ++++
09:30:30 2066513998mS H323Evt: Recv: RegistrationRequest 192.168.42.11; Extn(201), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:31 2066514798mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:30:31 2066514798mS PRN: + loader: 1.35
09:30:31 2066514798mS PRN: + cpu: id 12 board 5 pld 25 type c10 options a802
09:30:31 2066514798mS PRN: + cpu: sn 14WZ283005WL
09:30:31 2066514798mS PRN: + fpga: id 1 issue 0 build 827
09:30:31 2066514798mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
09:30:31 2066514798mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
09:30:31 2066514798mS PRN: +------------------------------------------------------------
09:30:31 2066514798mS PRN: + Slot 1: Base PHONE2 Board=0x02 PLD=0x03
09:30:31 2066514798mS PRN: + Mezzanine PRIS U Board=0x03 PLD=0x0a
09:30:31 2066514798mS PRN: +------------------------------------------------------------
09:30:31 2066514798mS PRN: + Slot 2: Base VCM32 V2 Board=0x05 PLD=0x11
09:30:31 2066514798mS PRN: + Mezzanine NONE
09:30:31 2066514798mS PRN: +------------------------------------------------------------
09:30:31 2066514799mS PRN: + Slot 3: Base PHONE2 Board=0x02 PLD=0x03
09:30:31 2066514799mS PRN: + Mezzanine NONE
09:30:31 2066514799mS PRN: +------------------------------------------------------------
09:30:31 2066514799mS PRN: + Slot 4: Base NONE
09:30:31 2066514799mS PRN: + Mezzanine NONE
09:30:31 2066514799mS PRN: +------------------------------------------------------------
09:30:31 2066514799mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
09:30:31 2066514799mS PRN: +++++++++++++++++ LIST OF MEMORY CARDS ++++++++++++++++++
09:30:31 2066514799mS PRN: +------------------------------------------------------------
09:30:31 2066514799mS PRN: + System SD Card: MNTD
09:30:31 2066514799mS PRN: + CID Register: 0x284245202020202010932ee2f600e519
09:30:31 2066514799mS PRN: + CSD Register: 0x400e00325b5900003bad7f800a4000c5
09:30:31 2066514800mS PRN: + SCR Register: 0x0235800000000000
09:30:31 2066514800mS PRN: + OCR Register: 0xc0ff8000
09:30:31 2066514801mS PRN: + Status Register: 0x0000000003000000030390000805
09:30:31 2066514801mS PRN: +------------------------------------------------------------
09:30:31 2066514801mS PRN: + Optional SD Card: UMNT
09:30:31 2066514801mS PRN: +------------------------------------------------------------
09:30:31 2066514801mS PRN: ++++++++++++++ END OF LIST OF MEMORY CARDS +++++++++++++
09:30:31 2066514801mS PRN: ++++++++++++++++++++ MEMORY CAPTURE +++++++++++++++++++++
09:30:31 2066514801mS PRN: +------------------------------------------------------------
09:30:31 2066514801mS PRN: + 0x00000006, 0x00000000, 0x00000000, 0x00000021, 0x00000000
09:30:31 2066514801mS PRN: + 0x00000001, 0x00000000, 0x0000001f, 0x00000000, 0x00000000
09:30:31 2066514801mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000021, 0x00000000
09:30:31 2066514801mS PRN: + 0x00000005, 0x00000000, 0x00000000, 0x00000000, 0x00000000
09:30:31 2066514801mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000b8c
09:30:31 2066514801mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
09:30:31 2066514801mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
09:30:31 2066514802mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
09:30:31 2066514802mS PRN: + 0x00000000, 0x5447c09b, 0x41621906, 0x5bf6aef5, 0x86228e5b
09:30:31 2066514802mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
09:30:31 2066514802mS PRN: +------------------------------------------------------------
09:30:31 2066514802mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
09:30:32 2066515254mS H323Evt: Recv: RegistrationRequest 192.168.42.29; Extn(221), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:34 2066516953mS SIP Tx: TCP 192.168.54.32:5060 -> 185.137.19.209:48966
OPTIONS sip:205@ipo.chess.uk.com SIP/2.0
Via: SIP/2.0/TCP 77.75.187.244:5060;rport;branch=z9hG4bK433c9c5a69e3d1ee00eeb1e88951a3b6
From: <sip:Unknown@ipo.chess.uk.com>;tag=00b9b26d9bd0da6c
To: <sip:205@ipo.chess.uk.com>
Call-ID: c6ad11d5fd60be715512e56dd719c543
CSeq: 1388770926 OPTIONS
Contact: <sip:Unknown@77.75.187.244:5060;transport=tcp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
User-Agent: IP Office 9.1.7.0 build 163
Content-Length: 0

09:30:34 2066516971mS RES: Tue 20/12/2016 09:30:35 FreeMem=53712688 Heap=51756044(4) Cache=1956644 MemObjs=17274(21667) CMMsg=13(13) ASN=0 Buff=5200 1364 1000 7419 5 Links=59369(61254) BTree=1268(2186) CPU=09.07% CPUStats=08.66%/1/4/1680/21532/27003/00.0
1%/0/02.06% MCR=0 MCW=0
09:30:34 2066516971mS RES2: IP 500 V2 9.1.7.0 build 163 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=102 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=92(TLS=7) TAPI=1 Partner=48 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:30:34 2066516971mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
09:30:34 2066516971mS RES5: CLog MemObjs=1142 FreePoolMem(Objs)=3016(58) TotalMem=252800
09:30:35 2066517106mS SIP Rx: TCP 185.137.19.209:48966 -> 192.168.54.32:5060


SIP/2.0 200 OK
From: <sip:Unknown@ipo.chess.uk.com>;tag=00b9b26d9bd0da6c
To: <sip:205@ipo.chess.uk.com>
Call-ID: c6ad11d5fd60be715512e56dd719c543
CSeq: 1388770926 OPTIONS
Via: SIP/2.0/TCP 77.75.187.244:5060;branch=z9hG4bK433c9c5a69e3d1ee00eeb1e88951a3b6
Supported: eventlist,outbound,replaces
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,MESSAGE,REFER,INFO,PUBLISH,UPDATE
User-Agent: Avaya One X Mobile Android Generic 10.0.0.2.196 Sony F5321
Content-Type: application/sdp
Content-Length: 290

v=0
o= 0 1 IN IP4 10.9.6.62
s=-
c=IN IP4 10.9.6.62
t=0 0
m=audio 0 RTP/AVP 8 0 9 18 110 103 120
a=rtpmap:8 PCMA/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:18 G729/8000/1
a=rtpmap:110 G726-32/8000/1
a=rtpmap:103 ISAC/16000/1
a=rtpmap:120 telephone-event/8000
09:30:37 2066519617mS H323Evt: Recv: RegistrationRequest 192.168.42.21; Extn(211), time since last RRQ-KA 55005, Endpoints registered: 35; Endpoints in registration: 0
09:30:39 2066521169mS SIP Tx: TLS 192.168.54.32:5061 -> 2.99.246.40:17672
OPTIONS sip:235@ipo.chess.uk.com SIP/2.0
Via: SIP/2.0/TLS 77.75.187.244:5061;rport;branch=z9hG4bKfa73e1cd3c80723a9b7c21c5288dc370
From: <sip:Unknown@ipo.chess.uk.com>;tag=26a13383a14c2c86
To: <sip:235@ipo.chess.uk.com>
Call-ID: 8976e67723ec082dc300413312f58c6c
CSeq: 565884683 OPTIONS
Contact: <sip:Unknown@77.75.187.244:5061;transport=tls>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
User-Agent: IP Office 9.1.7.0 build 163
Content-Length: 0

09:30:39 2066521410mS SIP Rx: TLS 2.99.246.40:17672 -> 192.168.54.32:5061
SIP/2.0 200 OK
From: <sip:Unknown@ipo.chess.uk.com>;tag=26a13383a14c2c86
To: <sip:235@ipo.chess.uk.com>
Call-ID: 8976e67723ec082dc300413312f58c6c
CSeq: 565884683 OPTIONS
Via: SIP/2.0/TLS 77.75.187.244:5061;branch=z9hG4bKfa73e1cd3c80723a9b7c21c5288dc370
Supported: eventlist,outbound,replaces
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,MESSAGE,REFER,INFO,PUBLISH,UPDATE
User-Agent: Avaya One X Mobile Android Generic 10.0.0.2.196 motorola MotoE2(4G-LTE)
Content-Type: application/sdp
Content-Length: 294

v=0
o= 0 1 IN IP4 192.168.1.2
s=-
c=IN IP4 192.168.1.2
t=0 0
m=audio 0 RTP/AVP 8 0 9 18 110 103 120
a=rtpmap:8 PCMA/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:18 G729/8000/1
a=rtpmap:110 G726-32/8000/1
a=rtpmap:103 ISAC/16000/1
a=rtpmap:120 telephone-event/8000
09:30:40 2066522011mS H323Evt: Recv: RegistrationRequest 192.168.42.20; Extn(209), time since last RRQ-KA 55003, Endpoints registered: 35; Endpoints in registration: 0
09:30:40 2066522471mS RES: Tue 20/12/2016 09:30:40 FreeMem=53756556 Heap=51788832(4) Cache=1967724 MemObjs=17179(21667) CMMsg=13(13) ASN=0 Buff=5200 1364 1000 7420 5 Links=59365(61254) BTree=1268(2186) CPU=09.01% CPUStats=09.57%/4/8/1680/21308/27003/00.0
4%/0/02.05% MCR=0 MCW=0
09:30:40 2066522472mS RES2: IP 500 V2 9.1.7.0 build 163 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=100 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=92(TLS=7) TAPI=1 Partner=48 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:30:40 2066522472mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
09:30:40 2066522472mS RES5: CLog MemObjs=1142 FreePoolMem(Objs)=3016(58) TotalMem=252800
09:30:40 2066522631mS H323Evt: Recv: RegistrationRequest 192.168.42.16; Extn(205), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:41 2066523148mS H323Evt: Recv: RegistrationRequest 192.168.42.39; Extn(239), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:41 2066523242mS H323Evt: Recv: RegistrationRequest 192.168.42.48; Extn(307), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:41 2066523305mS H323Evt: Recv: RegistrationRequest 192.168.42.25; Extn(218), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:42 2066524273mS SIP Rx: TCP 185.137.19.209:48966 -> 192.168.54.32:5060
INVITE sip:698@ipo.chess.uk.com SIP/2.0
From: "Nick Abbott (Internal)" <sip:205@ipo.chess.uk.com>;tag=208025c4-47ac-4bc6-bdbe-2289f4663ff0
To: <sip:698@ipo.chess.uk.com>
Call-ID: 5e4b3090-17e1-4472-994b-6cd8cb60b0bf
CSeq: 1 INVITE
Max-Forwards: 70
Via: SIP/2.0/TCP 10.9.6.62:48966;branch=z9hG4bK50d4d1f6-b00a-44fb-b2cc-26ec7296a92d
Supported: eventlist,outbound,replaces
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,MESSAGE,REFER,INFO,PUBLISH,UPDATE
User-Agent: Avaya One X Mobile Android Generic 10.0.0.2.196 Sony F5321
Contact: <sip:205@10.9.6.62:48966;transport=tcp>;+sip.instance="<urn:uuid:3bc52cbd35192e74>"
Content-Type: application/sdp
Content-Length: 336

v=0
o=sip:205@10.9.6.62 3 2 IN IP4 10.9.6.62
s=-
c=IN IP4 10.9.6.62
b=TIAS:64000
t=0 0
m=audio 5004 RTP/AVP 8 0 9 18 110 103 120
a=sendrecv
a=rtpmap:8 PCMA/8000/1
a=rtpmap:0 PCMU/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:18 G729/8000/1
a=rtpmap:110 G726-32/8000/1
a=rtpmap:103 ISAC/16000/1
a=rtpmap:120 telephone-event/8000
09:30:42 2066524277mS CMCallEvt: 0000000000000000 0.101569.0 -1 BaseEP: NEW CMEndpoint f17a333c TOTAL NOW=1 CALL_LIST=0
09:30:42 2066524277mS CMCallEvt: 0000000000000000 0.101569.0 -1 Nick Abbott.-1: NEW CMExtnEndpoint f17a333c, Name=Nick Abbott, Extn=205, Phys Extn=205
09:30:42 2066524278mS CMCallEvt: CREATE CALL:18679 (f4e1434c)
09:30:42 2066524278mS CMCallEvt: 0000000000000000 0.101570.0 -1 BaseEP: NEW CMEndpoint f18e31e4 TOTAL NOW=2 CALL_LIST=0
09:30:42 2066524280mS CMExtnEvt: Nick Abbott: CMExtnHandler::SetCurrent( id: 0->101569 )
09:30:42 2066524282mS SIP Tx: TCP 192.168.54.32:5060 -> 185.137.19.209:48966
SIP/2.0 100 Trying
Via: SIP/2.0/TCP 10.9.6.62:48966;branch=z9hG4bK50d4d1f6-b00a-44fb-b2cc-26ec7296a92d
From: "Nick Abbott (Internal)" <sip:205@ipo.chess.uk.com>;tag=208025c4-47ac-4bc6-bdbe-2289f4663ff0
Call-ID: 5e4b3090-17e1-4472-994b-6cd8cb60b0bf
CSeq: 1 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
Server: IP Office 9.1.7.0 build 163
To: <sip:698@ipo.chess.uk.com>
Content-Length: 0

09:30:42 2066524285mS CMCallEvt: c0a82a0100018cc1 13661.101569.0 18679 Nick Abbott.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
09:30:42 2066524285mS CMExtnEvt: v=0 State, new=Dialling old=Idle,0,0,Nick Abbott
09:30:42 2066524286mS CMCallEvt: 0000000000000000 0.101571.0 -1 BaseEP: NEW CMEndpoint f4dd77e0 TOTAL NOW=3 CALL_LIST=1
09:30:42 2066524286mS CMCallEvt: c0a82a0100018cc1 13661.101569.0 18679 Nick Abbott.0: StateChange: END=A CMCSDialInitiated->CMCSDialled
09:30:42 2066524287mS CMExtnEvt: v=0 State, new=Proceeding old=Dialling,0,0,Nick Abbott
09:30:42 2066524288mS CMCallEvt: 0000000000000000 0.101570.0 18679 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
09:30:42 2066524289mS CMCallEvt: c0a82a0100018cc3 0.101571.0 18679 ACDTep(ProSKU Xmas2016): StateChange: END=T CMCSIdle->CMCSOffering
09:30:42 2066524289mS CMCallEvt: c0a82a0100018cc3 0.101571.0 18679 ACDTep(ProSKU Xmas2016): StateChange: END=T CMCSOffering->CMCSRinging
09:30:42 2066524289mS CMCallEvt: 0000000000000000 0.101570.0 18679 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
09:30:42 2066524290mS CMCallEvt: c0a82a0100018cc1 13661.101569.0 18679 Nick Abbott.0: StateChange: END=A CMCSDialled->CMCSRingBack
09:30:42 2066524290mS CMExtnEvt: v=0 State, new=Alerting old=Proceeding,0,0,Nick Abbott
09:30:42 2066524292mS SIP Tx: TCP 192.168.54.32:5060 -> 185.137.19.209:48966
SIP/2.0 180 Ringing
Via: SIP/2.0/TCP 10.9.6.62:48966;branch=z9hG4bK50d4d1f6-b00a-44fb-b2cc-26ec7296a92d
From: "Nick Abbott (Internal)" <sip:205@ipo.chess.uk.com>;tag=208025c4-47ac-4bc6-bdbe-2289f4663ff0
Call-ID: 5e4b3090-17e1-4472-994b-6cd8cb60b0bf
CSeq: 1 INVITE
Contact: "ProSKU Xmas2016" <sip:698@77.75.187.244:5060;transport=tcp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
P-Asserted-Identity: "ProSKU Xmas2016" <sip:698@77.75.187.244:5060>
Supported: timer,100rel
Server: IP Office 9.1.7.0 build 163
To: <sip:698@ipo.chess.uk.com>;tag=1362a60c650b0d56
Content-Length: 0

09:30:42 2066524294mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=16 AState=Ringing
09:30:42 2066524294mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,16,0,5,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
,
09:30:42 2066524295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=17 AState=Ringing
09:30:42 2066524296mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,17,0,6,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
,
09:30:42 2066524297mS CMMap: a=2.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f5639638) resource busy 1, total 32
09:30:42 2066524297mS CMCallEvt: 0000000000000000 0.101572.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:42 2066524298mS CMCallEvt: c0a82a0100018cc4 0.101572.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:42 2066524299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=21 AState=Ringing
09:30:42 2066524300mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,21,0,10,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,
09:30:42 2066524300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101572
09:30:42 2066524316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101572
09:30:42 2066524317mS CMCallEvt: c0a82a0100018cc4 0.101572.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:42 2066524318mS CMCallEvt: c0a82a0100018cc4 0.101572.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:42 2066524318mS CMCallEvt: c0a82a0100018cc4 0.101572.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:42 2066524329mS CMCallEvt: 0000000000000000 0.101573.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:42 2066524331mS CMCallEvt: c0a82a0100018cc5 0.101573.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:42 2066524331mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=53 AState=Ringing
09:30:42 2066524331mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,53,0,42,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,
09:30:42 2066524332mS PRN: CMEndpoint f17a333c(c0a82a0100018cc1 13661.101569.0 18679 Nick Abbott.0) AddTAPICall f179b648 size 0
09:30:42 2066524333mS PRN: CMEndpoint f4dd77e0(c0a82a0100018cc3 0.101571.0 18679 ACDTep(ProSKU Xmas2016)) AddTAPICall f16bfa58 size 0
09:30:42 2066524334mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101573
09:30:42 2066524354mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101573
09:30:42 2066524355mS CMCallEvt: c0a82a0100018cc5 0.101573.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:42 2066524355mS CMCallEvt: c0a82a0100018cc5 0.101573.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:42 2066524355mS CMCallEvt: c0a82a0100018cc5 0.101573.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:42 2066524366mS CMCallEvt: 0000000000000000 0.101574.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:42 2066524368mS CMCallEvt: c0a82a0100018cc6 0.101574.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:42 2066524368mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=90 AState=Ringing
09:30:42 2066524369mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,90,0,79,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,
09:30:42 2066524369mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101574
09:30:42 2066524390mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101574
09:30:42 2066524391mS CMCallEvt: c0a82a0100018cc6 0.101574.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:42 2066524391mS CMCallEvt: c0a82a0100018cc6 0.101574.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:42 2066524391mS CMCallEvt: c0a82a0100018cc6 0.101574.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:42 2066524402mS CMCallEvt: 0000000000000000 0.101575.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:42 2066524404mS CMCallEvt: c0a82a0100018cc7 0.101575.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:42 2066524404mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=126 AState=Ringing
09:30:42 2066524404mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,126,0,115,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,
09:30:42 2066524405mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101575
09:30:42 2066524425mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101575
09:30:42 2066524426mS CMCallEvt: c0a82a0100018cc7 0.101575.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:42 2066524426mS CMCallEvt: c0a82a0100018cc7 0.101575.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:42 2066524427mS CMCallEvt: c0a82a0100018cc7 0.101575.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:42 2066524438mS CMCallEvt: 0000000000000000 0.101576.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:42 2066524440mS CMCallEvt: c0a82a0100018cc8 0.101576.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:42 2066524440mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=162 AState=Ringing
09:30:42 2066524441mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,162,0,151,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,
09:30:42 2066524441mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101576
09:30:42 2066524442mS H323Evt: Recv: RegistrationRequest 192.168.42.38; Extn(235), time since last RRQ-KA 55008, Endpoints registered: 35; Endpoints in registration: 0
09:30:42 2066524460mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101576
09:30:42 2066524461mS CMCallEvt: c0a82a0100018cc8 0.101576.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:42 2066524462mS CMCallEvt: c0a82a0100018cc8 0.101576.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:42 2066524462mS CMCallEvt: c0a82a0100018cc8 0.101576.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:42 2066524473mS CMCallEvt: 0000000000000000 0.101577.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:42 2066524475mS CMCallEvt: c0a82a0100018cc9 0.101577.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:42 2066524475mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=197 AState=Ringing
09:30:42 2066524475mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,197,0,186,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,
09:30:42 2066524476mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101577
09:30:42 2066524495mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101577
09:30:42 2066524496mS CMCallEvt: c0a82a0100018cc9 0.101577.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:42 2066524497mS CMCallEvt: c0a82a0100018cc9 0.101577.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:42 2066524497mS CMCallEvt: c0a82a0100018cc9 0.101577.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:43 2066525295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=1017 AState=Ringing
09:30:43 2066525295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,1017,0,1006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:43 2066525296mS CMCallEvt: 0000000000000000 0.101578.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:43 2066525298mS CMCallEvt: c0a82a0100018cca 0.101578.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:43 2066525299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=1021 AState=Ringing
09:30:43 2066525299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,1021,0,1010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:43 2066525300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101578
09:30:43 2066525315mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101578
09:30:43 2066525317mS CMCallEvt: c0a82a0100018cca 0.101578.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:43 2066525317mS CMCallEvt: c0a82a0100018cca 0.101578.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:43 2066525317mS CMCallEvt: c0a82a0100018cca 0.101578.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:44 2066526295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=2017 AState=Ringing
09:30:44 2066526295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,2017,0,2006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:44 2066526297mS CMCallEvt: 0000000000000000 0.101579.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:44 2066526298mS CMCallEvt: c0a82a0100018ccb 0.101579.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:44 2066526299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=2021 AState=Ringing
09:30:44 2066526300mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,2021,0,2010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:44 2066526300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101579
09:30:44 2066526316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101579
09:30:44 2066526317mS CMCallEvt: c0a82a0100018ccb 0.101579.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:44 2066526317mS CMCallEvt: c0a82a0100018ccb 0.101579.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:44 2066526318mS CMCallEvt: c0a82a0100018ccb 0.101579.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:45 2066527223mS H323Evt: Recv: RegistrationRequest 192.168.42.43; Extn(248), time since last RRQ-KA 55003, Endpoints registered: 35; Endpoints in registration: 0
09:30:45 2066527295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=3017 AState=Ringing
09:30:45 2066527295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,3017,0,3006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:45 2066527296mS CMCallEvt: 0000000000000000 0.101580.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:45 2066527298mS CMCallEvt: c0a82a0100018ccc 0.101580.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:45 2066527299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=3021 AState=Ringing
09:30:45 2066527299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,3021,0,3010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:45 2066527300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101580
09:30:45 2066527316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101580
09:30:45 2066527317mS CMCallEvt: c0a82a0100018ccc 0.101580.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:45 2066527318mS CMCallEvt: c0a82a0100018ccc 0.101580.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:45 2066527318mS CMCallEvt: c0a82a0100018ccc 0.101580.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:45 2066527698mS H323Evt: Recv: RegistrationRequest 192.168.42.42; Extn(244), time since last RRQ-KA 55003, Endpoints registered: 35; Endpoints in registration: 0
09:30:45 2066527932mS H323Evt: Recv: RegistrationRequest 192.168.42.46; Extn(308), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:46 2066528295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=4017 AState=Ringing
09:30:46 2066528295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,4017,0,4006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:46 2066528296mS CMCallEvt: 0000000000000000 0.101581.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:46 2066528298mS CMCallEvt: c0a82a0100018ccd 0.101581.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:46 2066528299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=4021 AState=Ringing
09:30:46 2066528299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,4021,0,4010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:46 2066528300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101581
09:30:46 2066528316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101581
09:30:46 2066528317mS CMCallEvt: c0a82a0100018ccd 0.101581.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:46 2066528318mS CMCallEvt: c0a82a0100018ccd 0.101581.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:46 2066528318mS CMCallEvt: c0a82a0100018ccd 0.101581.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:47 2066529011mS H323Evt: Recv: RegistrationRequest 192.168.42.26; Extn(219), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:47 2066529295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=5017 AState=Ringing
09:30:47 2066529295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,5017,0,5006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:47 2066529296mS CMCallEvt: 0000000000000000 0.101582.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:47 2066529298mS CMCallEvt: c0a82a0100018cce 0.101582.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:47 2066529299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=5021 AState=Ringing
09:30:47 2066529299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,5021,0,5010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:47 2066529300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101582
09:30:47 2066529317mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101582
09:30:47 2066529318mS CMCallEvt: c0a82a0100018cce 0.101582.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:47 2066529318mS CMCallEvt: c0a82a0100018cce 0.101582.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:47 2066529318mS CMCallEvt: c0a82a0100018cce 0.101582.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:47 2066529965mS H323Evt: Recv: RegistrationRequest 192.168.42.12; Extn(225), time since last RRQ-KA 54999, Endpoints registered: 35; Endpoints in registration: 0
09:30:48 2066530261mS H323Evt: Recv: RegistrationRequest 192.168.42.30; Extn(222), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:48 2066530295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=6017 AState=Ringing
09:30:48 2066530295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,6017,0,6006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:48 2066530296mS CMCallEvt: 0000000000000000 0.101583.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:48 2066530298mS CMCallEvt: c0a82a0100018ccf 0.101583.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:48 2066530299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=6021 AState=Ringing
09:30:48 2066530299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,6021,0,6010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:48 2066530300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101583
09:30:48 2066530316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101583
09:30:48 2066530317mS CMCallEvt: c0a82a0100018ccf 0.101583.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:48 2066530317mS CMCallEvt: c0a82a0100018ccf 0.101583.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:48 2066530318mS CMCallEvt: c0a82a0100018ccf 0.101583.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:48 2066530677mS H323Evt: Recv: RegistrationRequest 192.168.42.50; Extn(226), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:49 2066531295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=7017 AState=Ringing
09:30:49 2066531295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,7017,0,7006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:49 2066531297mS CMCallEvt: 0000000000000000 0.101584.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:49 2066531298mS CMCallEvt: c0a82a0100018cd0 0.101584.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:49 2066531299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=7021 AState=Ringing
09:30:49 2066531300mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,7021,0,7010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:49 2066531300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101584
09:30:49 2066531316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101584
09:30:49 2066531317mS CMCallEvt: c0a82a0100018cd0 0.101584.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:49 2066531317mS CMCallEvt: c0a82a0100018cd0 0.101584.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:49 2066531318mS CMCallEvt: c0a82a0100018cd0 0.101584.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:49 2066531707mS H323Evt: Recv: RegistrationRequest 192.168.42.31; Extn(223), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:50 2066532295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=8017 AState=Ringing
09:30:50 2066532295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,8017,0,8006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:50 2066532297mS CMCallEvt: 0000000000000000 0.101585.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:50 2066532298mS CMCallEvt: c0a82a0100018cd1 0.101585.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:50 2066532299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=8021 AState=Ringing
09:30:50 2066532299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,8021,0,8010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:50 2066532300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101585
09:30:50 2066532316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101585
09:30:50 2066532317mS CMCallEvt: c0a82a0100018cd1 0.101585.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:50 2066532318mS CMCallEvt: c0a82a0100018cd1 0.101585.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:50 2066532318mS CMCallEvt: c0a82a0100018cd1 0.101585.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:50 2066532917mS H323Evt: Recv: RegistrationRequest 192.168.42.33; Extn(227), time since last RRQ-KA 55003, Endpoints registered: 35; Endpoints in registration: 0
09:30:51 2066533295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=9017 AState=Ringing
09:30:51 2066533295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,9017,0,9006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,,,
,,,,,,
09:30:51 2066533296mS CMCallEvt: 0000000000000000 0.101586.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:51 2066533298mS CMCallEvt: c0a82a0100018cd2 0.101586.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:51 2066533299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=9021 AState=Ringing
09:30:51 2066533299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,9021,0,9010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,,
09:30:51 2066533300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101586
09:30:51 2066533316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101586
09:30:51 2066533317mS CMCallEvt: c0a82a0100018cd2 0.101586.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:51 2066533317mS CMCallEvt: c0a82a0100018cd2 0.101586.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:51 2066533318mS CMCallEvt: c0a82a0100018cd2 0.101586.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:51 2066533906mS H323Evt: Recv: RegistrationRequest 192.168.42.22; Extn(213), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:52 2066534295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=10017 AState=Ringing
09:30:52 2066534295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,10017,0,10006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,
09:30:52 2066534296mS CMCallEvt: 0000000000000000 0.101587.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:52 2066534298mS CMCallEvt: c0a82a0100018cd3 0.101587.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:52 2066534299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=10021 AState=Ringing
09:30:52 2066534299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,10021,0,10010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,
,,,,,,,,,,,
09:30:52 2066534301mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101587
09:30:52 2066534316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101587
09:30:52 2066534317mS CMCallEvt: c0a82a0100018cd3 0.101587.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:52 2066534317mS CMCallEvt: c0a82a0100018cd3 0.101587.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:52 2066534318mS CMCallEvt: c0a82a0100018cd3 0.101587.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:53 2066535059mS PRN: Optimizing BTree Lists Completed...Started:2066535059 Duration:0 Size:132 Attempted:132
09:30:53 2066535295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=11017 AState=Ringing
09:30:53 2066535295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,11017,0,11006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,
09:30:53 2066535297mS CMCallEvt: 0000000000000000 0.101588.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:53 2066535298mS CMCallEvt: c0a82a0100018cd4 0.101588.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:53 2066535299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=11021 AState=Ringing
09:30:53 2066535300mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,11021,0,11010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,
,,,,,,,,,,,
09:30:53 2066535301mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101588
09:30:53 2066535316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101588
09:30:53 2066535317mS CMCallEvt: c0a82a0100018cd4 0.101588.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:53 2066535318mS CMCallEvt: c0a82a0100018cd4 0.101588.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:53 2066535318mS CMCallEvt: c0a82a0100018cd4 0.101588.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:54 2066536295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=12017 AState=Ringing
09:30:54 2066536295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,12017,0,12006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,
09:30:54 2066536297mS CMCallEvt: 0000000000000000 0.101589.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:54 2066536298mS CMCallEvt: c0a82a0100018cd5 0.101589.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:54 2066536299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=12021 AState=Ringing
09:30:54 2066536299mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,12021,0,12010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,
,,,,,,,,,,,
09:30:54 2066536300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101589
09:30:54 2066536316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101589
09:30:54 2066536317mS CMCallEvt: c0a82a0100018cd5 0.101589.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:54 2066536317mS CMCallEvt: c0a82a0100018cd5 0.101589.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:54 2066536317mS CMCallEvt: c0a82a0100018cd5 0.101589.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:55 2066537295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=13017 AState=Ringing
09:30:55 2066537295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,13017,0,13006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,
09:30:55 2066537297mS CMCallEvt: 0000000000000000 0.101590.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:55 2066537298mS CMCallEvt: c0a82a0100018cd6 0.101590.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:55 2066537299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=13021 AState=Ringing
09:30:55 2066537300mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,13021,0,13010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,
,,,,,,,,,,,
09:30:55 2066537301mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101590
09:30:55 2066537316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101590
09:30:55 2066537317mS CMCallEvt: c0a82a0100018cd6 0.101590.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:55 2066537317mS CMCallEvt: c0a82a0100018cd6 0.101590.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:55 2066537318mS CMCallEvt: c0a82a0100018cd6 0.101590.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:56 2066538295mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=14017 AState=Ringing
09:30:56 2066538295mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,14017,0,14006,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,
09:30:56 2066538297mS CMCallEvt: 0000000000000000 0.101591.0 -1 BaseEP: NEW CMEndpoint f169e658 TOTAL NOW=4 CALL_LIST=1
09:30:56 2066538298mS CMCallEvt: c0a82a0100018cd7 0.101591.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSIdle->CMCSOffering
09:30:56 2066538299mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [Line 1] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=0 Time=14021 AState=Ringing
09:30:56 2066538300mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,Line 1,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,0,14021,0,14010,0,0,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,
,,,,,,,,,,,
09:30:56 2066538300mS ISDNL3Evt: v=1 stacknum=1 State, new=Initiated, old=NullState id=101591
09:30:56 2066538316mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=Initiated id=101591
09:30:56 2066538317mS CMCallEvt: c0a82a0100018cd7 0.101591.0 18679 Q931 Trunk:1 CHAN=-1: StateChange: END=T CMCSOffering->CMCSCompleted
09:30:56 2066538318mS CMCallEvt: c0a82a0100018cd7 0.101591.0 -1 Q931 Trunk:1 CHAN=-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:56 2066538318mS CMCallEvt: c0a82a0100018cd7 0.101591.0 -1 BaseEP: DELETE CMEndpoint f169e658 TOTAL NOW=3 CALL_LIST=1
09:30:56 2066538333mS H323Evt: Recv: RegistrationRequest 192.168.42.23; Extn(215), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:56 2066538458mS SIP Rx: TCP 185.137.19.209:48966 -> 192.168.54.32:5060
CANCEL sip:698@ipo.chess.uk.com SIP/2.0
From: "Nick Abbott (Internal)" <sip:205@ipo.chess.uk.com>;tag=208025c4-47ac-4bc6-bdbe-2289f4663ff0
To: <sip:698@ipo.chess.uk.com>
Call-ID: 5e4b3090-17e1-4472-994b-6cd8cb60b0bf
CSeq: 1 CANCEL
Max-Forwards: 70
Via: SIP/2.0/TCP 10.9.6.62:48966;branch=z9hG4bK50d4d1f6-b00a-44fb-b2cc-26ec7296a92d
Supported: eventlist,outbound,replaces
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,MESSAGE,REFER,INFO,PUBLISH,UPDATE
User-Agent: Avaya One X Mobile Android Generic 10.0.0.2.196 Sony F5321
Contact: <sip:205@10.9.6.62:48966;transport=tcp>;+sip.instance="<urn:uuid:3bc52cbd35192e74>"
Content-Length: 0

09:30:56 2066538461mS SIP Tx: TCP 192.168.54.32:5060 -> 185.137.19.209:48966
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.9.6.62:48966;branch=z9hG4bK50d4d1f6-b00a-44fb-b2cc-26ec7296a92d
From: "Nick Abbott (Internal)" <sip:205@ipo.chess.uk.com>;tag=208025c4-47ac-4bc6-bdbe-2289f4663ff0
Call-ID: 5e4b3090-17e1-4472-994b-6cd8cb60b0bf
CSeq: 1 CANCEL
Supported: timer,100rel
Server: IP Office 9.1.7.0 build 163
To: <sip:698@ipo.chess.uk.com>;tag=1362a60c650b0d56
Content-Length: 0

09:30:56 2066538462mS SIP Tx: TCP 192.168.54.32:5060 -> 185.137.19.209:48966
SIP/2.0 487 Request Terminated
Via: SIP/2.0/TCP 10.9.6.62:48966;branch=z9hG4bK50d4d1f6-b00a-44fb-b2cc-26ec7296a92d
From: "Nick Abbott (Internal)" <sip:205@ipo.chess.uk.com>;tag=208025c4-47ac-4bc6-bdbe-2289f4663ff0
Call-ID: 5e4b3090-17e1-4472-994b-6cd8cb60b0bf
CSeq: 1 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,SUBSCRIBE,REGISTER,PUBLISH,UPDATE
Supported: timer,100rel
Server: IP Office 9.1.7.0 build 163
To: <sip:698@ipo.chess.uk.com>;tag=1362a60c650b0d56
Content-Length: 0

09:30:56 2066538463mS CMCallEvt: c0a82a0100018cc1 13661.101569.0 18679 Nick Abbott.0: StateChange: END=A CMCSRingBack->CMCSCompleted
09:30:56 2066538464mS CMExtnEvt: v=0 State, new=PortRecoverDelay old=Alerting,0,0,Nick Abbott
09:30:56 2066538465mS CMLOGGING: CALL:2016/12/2009:30,00:00:00,014,205,O,698,698,NickAbbott,,,1,,"",0,n/a
09:30:56 2066538465mS CD: CALL: 13661.101569.0 BState=Ringing Cut=1 Music=2.0 Aend="Nick Abbott(205)" (0.0) Bend="ProSKU Xmas2016(698)" [] (0.0) CalledNum=698 (ProSKU Xmas2016) CallingNum=205 (Nick Abbott) Internal=1 Time=14187 AState=Ringing
09:30:56 2066538465mS CD2: CALL:S 13661.101569.0,0.101570.0,1,1,1,2,0,0,Nick Abbott(205),,RAS,0.0,0.0,100.100,698,100.101,205,,,100,698,100,,0,16,0,1,0.0,,ProSKU Xmas2016,,ProSKU Xmas2016,,,1,14187,0,0,0,14174,eng,,0,,0,0,0,0,,18679,,,,,,,,,,,,,,,,,,,,,,
,,,,,,,,
09:30:56 2066538465mS CD: CALL: 13661.101569.0 Deleted
09:30:56 2066538466mS CD2: CALL 13661.101569.0,0.101570.0,18679,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
09:30:56 2066538466mS PRN: CMEndpoint f4dd77e0(c0a82a0100018cc3 0.101571.0 18679 ACDTep(ProSKU Xmas2016)) RemoveTAPICall f16bfa58 size 1
09:30:56 2066538466mS PRN: size now 0
09:30:56 2066538466mS CMCallEvt: c0a82a0100018cc3 0.101571.0 18679 ACDTep(ProSKU Xmas2016): StateChange: END=??? CMCSRinging->CMCSDelete
09:30:56 2066538466mS CMCallEvt: c0a82a0100018cc3 0.101571.0 -1 BaseEP: DELETE CMEndpoint f4dd77e0 TOTAL NOW=2 CALL_LIST=0
09:30:56 2066538466mS CMExtnEvt: Nick Abbott: CALL LOST (CMCauseNormal)
09:30:56 2066538466mS CMExtnEvt: Nick Abbott: Extn(205) Calling Party Number(205) Type(CMNTypeInternal)
09:30:56 2066538467mS PRN: CMEndpoint f17a333c(c0a82a0100018cc1 13661.101569.0 -1 Nick Abbott.0) RemoveTAPICall f179b648 size 1
09:30:56 2066538467mS PRN: size now 0
09:30:56 2066538467mS CMExtnEvt: Nick Abbott: CMExtnHandler::SetCurrent( id: 101569->0 )
09:30:56 2066538467mS CMCallEvt: c0a82a0100018cc1 13661.101569.0 -1 Nick Abbott.-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:30:56 2066538467mS CMCallEvt: 0000000000000000 0.101570.0 -1 BaseEP: DELETE CMEndpoint f18e31e4 TOTAL NOW=1 CALL_LIST=0
09:30:56 2066538467mS CMCallEvt: END CALL:18679 (f4e1434c)
09:30:56 2066538469mS CMCallEvt: c0a82a0100018cc1 13661.101569.0 -1 BaseEP: DELETE CMEndpoint f17a333c TOTAL NOW=0 CALL_LIST=0
09:30:56 2066538470mS CMMap: a=2.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f5639638) resource busy 0, total 32
09:30:56 2066538471mS PRN: Config Write Wake Up
09:30:56 2066538510mS RES: Tue 20/12/2016 09:30:56 FreeMem=53717328 Heap=51755396(4) Cache=1961932 MemObjs=17266(21667) CMMsg=13(13) ASN=0 Buff=5200 1364 1000 7420 5 Links=59370(61254) BTree=1271(2186) CPU=10.18% CPUStats=09.72%/5/9/1680/21339/27003/00.1
3%/0/02.08% MCR=0 MCW=0
09:30:56 2066538510mS RES2: IP 500 V2 9.1.7.0 build 163 Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=108 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=92(TLS=7) TAPI=1 Partner=48 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:30:56 2066538510mS RES4: XML MemObjs=63 PoolMem=4748404(2) FreePoolMem=4733100(0)
09:30:56 2066538510mS RES5: CLog MemObjs=1142 FreePoolMem(Objs)=3016(58) TotalMem=252800
09:30:56 2066538517mS SIP Rx: TCP 185.137.19.209:48966 -> 192.168.54.32:5060
ACK sip:698@77.75.187.244:5060;transport=tcp SIP/2.0
From: "Nick Abbott (Internal)" <sip:205@ipo.chess.uk.com>;tag=208025c4-47ac-4bc6-bdbe-2289f4663ff0
To: <sip:698@ipo.chess.uk.com>;tag=1362a60c650b0d56
Call-ID: 5e4b3090-17e1-4472-994b-6cd8cb60b0bf
CSeq: 1 ACK
Max-Forwards: 70
Via: SIP/2.0/TCP 10.9.6.62:48966;branch=z9hG4bK50d4d1f6-b00a-44fb-b2cc-26ec7296a92d
Supported: eventlist,outbound,replaces
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,MESSAGE,REFER,INFO,PUBLISH,UPDATE
User-Agent: Avaya One X Mobile Android Generic 10.0.0.2.196 Sony F5321
Contact: <sip:205@10.9.6.62:48966;transport=tcp>;+sip.instance="<urn:uuid:3bc52cbd35192e74>"
Content-Length: 0

09:30:56 2066538971mS PRN: Updates IO list size 1 updated list size 1
09:30:56 2066538971mS PRN: Sending Updates out to f4ee9158 started
09:30:56 2066538971mS PRN: Sending Updates out to f4ee9158 finished
09:30:56 2066538972mS PRN: Config Write Completed
09:30:57 2066539132mS H323Evt: Recv: RegistrationRequest 192.168.42.41; Extn(241), time since last RRQ-KA 55004, Endpoints registered: 35; Endpoints in registration: 0
09:30:58 2066540282mS H323Evt: Recv: RegistrationRequest 192.168.42.13; Extn(202), time since last RRQ-KA 55003, Endpoints registered: 35; Endpoints in registration: 0
09:30:58 2066540467mS CMExtnEvt: Nick Abbott: Recover Timer reason=CMTRWrapUp
09:30:58 206654

 

[janni78]

So you're calling from an Mobile client to the huntgroup and calling out through PRI?

Something is missing in this so go to filter in Monitor, press default, then enable ISDN L3 Rx/Tx, and disable all SIP as it isn't relevant in this case.

Attach it as a text file in the post instead, makes it easier to read.

"Trying is the first step to failure..." - Homer

 

[NickAbb]

Hi janni78

I have attached the log, as you correct determined am working off site today so calling from Avaya Mobile App twinned with my desk extension 205. Scenario same as before:

Call group 698 from extn 205. Group 698 has one dummy extension (705) with unconditional forward to mobile number.

http://files.engineering.com/getfil...-4d23-b73a-1f35438ea922&file=201612101057.log

Thanks for your help.

 

[NickAbb]

Hi janni78,

For clarity, the 01618882585 number in this log is the DDI for my extn (205)

 

[janni78]

But you don't have a 0 in front of your phonenumbers in USA do you?
Shouldn't you just send the 10 digit number without 0?

You're sending both called number and calling number with a 0 in front.

"Trying is the first step to failure..." - Homer

 

[NickAbb]

Hi Janni78,

Am in the UK ......

Dialing the dummy extension 705 correctly forwards the call to the mobile so I think the number is OK (with the leading zero), its only when I call the group 698 containing the dummy extension 705 its not forwarded. If i replace the mobile number with an internal extension, then it's fine.

 

[janni78]

Ok, thought the US in your profile meant that =)

It's seems like the IPO is disconnecting the call.
Do you have "Partial Rerouting" enabled on the PRI?

Can you do the same trace when dialing the extension directly?
Also try putting the HG in ICR and dial it from an external number.

"Trying is the first step to failure..." - Homer

 

[NickAbb]

'Supports Partial Rerouting' is unchecked in the PRI configuration - should it be checked?

Attached is the log when dialing the 705 extn directly.

http://files.engineering.com/getfil...-4f45-b705-e2b4924e6aff&file=201612125933.log

 

[janni78]

Seems like the UU and STATE messages when going through the huntgroup messes it up.
These aren't present when calling the user directly.

I would probably try upgrading to 9.1.9 before taking it with Avaya, the STATE from the service provider might be the one causing the issue.

10:11:06 2068948769mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=005F(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a3 .....
InformationElement = CallingPartyNumber
0000 6c 0d 00 80 30 31 36 31 38 38 38 32 35 38 35 l...01618882585
InformationElement = CalledPartyNumber
0000 70 0c 80 30 37 37 38 35 33 37 37 37 34 30 p..07785377740
InformationElement = UU
0000 7e 05 36 39 38 ff ff ~.698..
InformationElement = Sending Complete

10:11:06 2068948786mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=005F(Local)
Message Type = Status
InformationElement = CAUSE
0000 08 03 82 e4 7e ....~
InformationElement = STATE
0000 14 01 00

"Trying is the first step to failure..." - Homer

 

[NickAbb]

Tried your other test by putting the HG (698) in the ICR and same result, calling the incoming line does not forward to mobile. If i put the dummy extn on the ICR then it does call the mobile .....

 

[janni78]

You can also check with the Service Provider why the STATE message is sent in one case but not the other.

"Trying is the first step to failure..." - Homer

 

[NickAbb]

Thanks for all your help Janni78. Will get the upgrade, check if it is still an issue and then contact Avaya support - looks like it is plan B for the Xmas break (not that I have a plan B .....)