Here is a monitor trace:
********** SysMonitor v6.1 (15) **********
********** contact made with 10.0.0.161 at 17:19:29 15/10/2008 **********
********** System (10.0.0.161) has been up and running for 16mins and 13secs(973829mS) **********
********** Warning: TEXT File Logging selected **********
********** Warning: TEXT Logging to File STOPPED on 15/10/2008 17:19:29 **********
973829mS PRN: Monitor Started IP=10.0.0.160 IP 406 DS 4.1(15) DPNS
(IP Office: Supports Unicode, System Locale is enu)
973830mS PRN: LAW=U PRI=1, BRI=0, ALOG=0, ADSL=0 VCOMP=0, MDM=0, WAN=0, MODU=3 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) CALLS=1(TOT=13)
973835mS LIST2: IFACE(3) "LAN1","DialIn","RemoteManager"
974089mS RES: Wed 15/10/2008 17:19:30 FreeMem=46698864(8) CMMsg=4 (4) Buff=100 614 500 1067 5 Links=9051
********** Warning: Logging to Screen Stopped **********
1229053mS ISDNL1Rx: v=1 peb=1
0000 02 01 78 7c 08 02 38 9a 05 04 03 80 90 a2 18 03 ..x|..8.........
0010 a1 83 81 28 11 10 b1 57 49 52 45 4c 45 53 53 20 ...(...WIRELESS
0020 43 41 4c 4c 45 52 6c 0c 00 80 33 31 32 35 32 33 CALLERl...312523
0030 34 33 31 35 70 05 80 34 34 33 33 4315p..4433
1229053mS ISDNL2Rx: v=1 peb=1
0000 02 01 78 7c 08 02 38 9a 05 04 03 80 90 a2 18 03 ..x|..8.........
0010 a1 83 81 28 11 10 b1 57 49 52 45 4c 45 53 53 20 ...(...WIRELESS
0020 43 41 4c 4c 45 52 6c 0c 00 80 33 31 32 35 32 33 CALLERl...312523
0030 34 33 31 35 70 05 80 34 34 33 33 4315p..4433
1229053mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Remote)
Message Type = Setup
InformationElement = BearerCapability
0000 04 03 80 90 a2 .....
InformationElement = CHI
0000 18 03 a1 83 81 .....
InformationElement = Display
0000 28 11 10 b1 57 49 52 45 4c 45 53 53 20 43 41 4c (...WIRELESS CAL
0010 4c 45 52 LER
InformationElement = CallingPartyNumber
0000 6c 0c 00 80 33 31 32 35 32 33 34 33 31 35 l...3125234315
InformationElement = CalledPartyNumber
0000 70 05 80 34 34 33 33 p..4433
1229054mS ISDNL3Evt: v=1 stacknum=1 State, new=Present, old=NullState id=11
1229055mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 7a ...z
1229056mS CMLineRx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=1 id=11 in=1
Called[4433] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[3125234315] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=1
Display [?WIRELESS CALLER]
1229056mS PRN: Q931Trunk: Found QBChannel to match 0.1 --> 2.2
1229061mS CD: CALL: 1.11.1 BState=Idle Cut=1 Music=0.0 Aend="Line 1" (2.2) Bend="" [] (0.0) CalledNum=4433 () CallingNum=3125234315 (?WIRELESS CALLER) Internal=0 Time=3 AState=Idle
1229062mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: LOOKUP CALL ROUTE: type=0 called_party=4433 sub= calling=3125234315 dir=in complete=1 ses=0
1229063mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: SET BESTMATCH: length 0 vs 0 match= dest=*90
1229063mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: SET BESTMATCH: length 4 vs 0 match=4433 dest=*90
1229064mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: LOOKUP INCOMING CALL ROUTE: calling party is 3125234315. Matched profile Destination *90
1229064mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: ADD TARGET (N): number=*90 type=0 depth=1 nobar=1 setorig=1 ses=0
1229065mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: SYS SC: *90 3 sc=type=VoicemailCollect code=*90, num="#Short Codes.Attendant" callinfop->sending_complete=1 secondary_dialtone=
1229065mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: ADD VOICEMAIL TARGET
1229066mS CMTARGET: 0.1056.0 14 RAS.0: ADD PRIMARY
1229066mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: ADD VOICEMAIL TARGET: SUCCEEDED
1229067mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: INITIAL TARGETING SUCCEEDED
1229067mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: GetNoAnswerTimer:15
1229068mS CMLineTx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=11 in=1
BChan: slot=0 chan=1
1229069mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[#Short Codes.Attendant] Type=Voicemail (102) Reason=CMDRX_MailboxAccess SndComp Calling[3125234315] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=6 chan=8
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=3125234315
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32
IE CMIECallingPartyName (110) ?WIRELESS CALLER
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=4433
IE CMIERespondingPartyName (228) ?WIRELESS CALLER
Display [?WIRELESS CALLER>#Short Codes.At]
Timed: 15/10/08 17:23
Locale: enu
1229070mS CMMap: a=6.8 b=1.254 T
1229071mS CMExtnRx: v=RAS, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228) #Short Codes.Attendant
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#Short Codes.Attendant
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceVoicemail type=0 number=0 channel=8 rx_gain=32 tx_gain=32
1229072mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: CancelTimer CMTCNoAnswerTimeout
1229073mS CMLineTx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=0 id=11 in=1
BChan: slot=0 chan=1
1229075mS CD: CALL: 1.11.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (2.2) Bend="" [RAS] (0.0) CalledNum=*90 () CallingNum=3125234315 (?WIRELESS CALLER) Internal=0 Time=18 AState=Ringing
1229077mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 7a ...z
1229078mS CMMap: a=2.2 b=0.0 R1
1229079mS ISDNL3Evt: v=1 stacknum=1 State, new=ICProceeding, old=Present id=11
1229079mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Local)
Message Type = CallProceeding
InformationElement = CHI
0000 18 03 a9 83 81 .....
1229079mS ISDNL2Tx: v=1 peb=1
0000 00 01 7c 7a 08 02 b8 9a 02 18 03 a9 83 81 ..|z..........
1229079mS ISDNL3Evt: v=1 stacknum=1 State, new=Received, old=ICProceeding id=11
1229080mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Local)
Message Type = Alerting
1229080mS ISDNL2Tx: v=1 peb=1
0000 00 01 7e 7a 08 02 b8 9a 01 ..~z.....
1229080mS ISDNL1Tx: v=1 peb=1
0000 00 01 7c 7a 08 02 b8 9a 02 18 03 a9 83 81 ..|z..........
1229080mS ISDNL1Tx: v=1 peb=1
0000 00 01 7e 7a 08 02 b8 9a 01 ..~z.....
1229086mS CD: CALLSYNC: cs02
1229086mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 7e ...~
1229086mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 7e ...~
1229087mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 80 ....
1229087mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 80 ....
1229089mS RES: Wed 15/10/2008 17:23:45 FreeMem=46674420(6) CMMsg=4 (4) Buff=100 611 497 1068 5 Links=9040
1235071mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=1008 in=0
1235073mS CMLineTx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=0 id=11 in=1
BChan: slot=0 chan=1
Display [++f#Short Codes.Attendant,]
1235074mS CD: CALL: 1.11.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (2.2) Bend="#Short Codes.Attendant(#Short Codes.Attendant)" [VoiceMail] (6.8) CalledNum=#Short Codes.Attendant () CallingNum=3125234315 (?WIRELESS CALLER) Internal=0 Time=6017 AS 1235076mS CMMap: a=2.2 b=0.0 R0
1235076mS CMMap: a=2.2 b=0.0 R0
1235077mS CMMap: PCG::MapBChan pcp[59]b1r0 cp_b fee0b454 other_cp_b 0 type CGTypeSimple
1235077mS CMMap: PCG::MapBChan pcp[9]b1r0 cp_b fee3973c other_cp_b fee0b454 type CGTypeSimple
1235077mS CMMap: a=2.2 b=6.8 M1
1235078mS ISDNL3Evt: v=1 stacknum=1 State, new=ConnReq, old=Received id=11
1235078mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Local)
Message Type = Connect
1235078mS ISDNL2Tx: v=1 peb=1
0000 00 01 80 7a 08 02 b8 9a 07 ...z.....
1235078mS ISDNL1Tx: v=1 peb=1
0000 00 01 80 7a 08 02 b8 9a 07 ...z.....
1235084mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 82 ....
1235084mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 82 ....
1235089mS RES: Wed 15/10/2008 17:23:51 FreeMem=46679628(8) CMMsg=4 (4) Buff=100 615 499 1068 5 Links=9039
1235105mS ISDNL1Rx: v=1 peb=1
0000 02 01 7a 82 08 02 38 9a 0f ..z...8..
1235105mS ISDNL2Rx: v=1 peb=1
0000 02 01 7a 82 08 02 38 9a 0f ..z...8..
1235105mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Remote)
Message Type = ConnectAck
1235106mS ISDNL3Evt: v=1 stacknum=1 State, new=Active, old=ConnReq id=11
1235106mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 7c ...|
1235107mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 7c ...|
1235107mS CMLineRx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=1 id=11 in=1
1235108mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: ~CMTargetHandler
1235109mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[3125234315] Type=Unknown Plan=Unknown Pres=Allowed (0)
BChan: slot=6 chan=8
IE CMIERespondingPartyName (228) ?WIRELESS CALLER
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=3125234315
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32
1243827mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
1243839mS CD: CALL: 1.11.1 BState=Connected Cut=3 Music=0.0 Aend="Line 1" (2.2) Bend="#Short Codes.Attendant(#Short Codes.Attendant)" [VoiceMail] (6.8) CalledNum=#Short Codes.Attendant () CallingNum=3125234315 (?WIRELESS CALLER) Internal=0 Time=14781 A 1243858mS CMExtnRx: v=RAS, p1=0
1243858mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
1243859mS CMExtnRx: v=RAS, p1=0
CMTransfer
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[53] Type=Default (100) Reason=CMDRdirect SndComp
1243860mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: ADD TARGET (N): number=53 type=100 depth=1 nobar=1 setorig=1 ses=0
1243860mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: SET USER: Extn53 orig=1
1243860mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: ADD USER: Extn53 depth=2 disallow_cw=0 dnd=0 real_call=1 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
1243862mS CMTARGET: 0.1057.0 14 Extn53.0: ADD PRIMARY
1243862mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: PrepareTransferTargets Found 1 target
1243862mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: GetNoAnswerTimer:15
1243864mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[#Short Codes.Attendant] Type=Voicemail (102) Reason=CMDRX_MailboxAccess Calling[3125234315] Type=Unknown Plan=Unknown Pres=Allowed (0)
BChan: slot=6 chan=8
Cause=126, Transfer(IPO)
1243865mS CMMap: a=6.8 b=0.0 T0
1243866mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1008 in=0
BChan: slot=6 chan=8
Cause=126, Transfer(IPO)
1243868mS CMExtnTx: v=53, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=1057 in=0
Called[53] Type=Default (100) Reason=CMDRdirect SndComp Calling[3125234315] Type=Unknown Plan=Unknown Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224) Extn53
IE CMIECallingPartyName (110) ?WIRELESS CALLER
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=4433
IE CMIERespondingPartyName (228) ?WIRELESS CALLER
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=3125234315
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32
Display [?WIRELESS CALLER(#Short Codes.At]
Cause=126, Transfer(IPO)
Timed: 15/10/08 17:23
Locale: enu
1243869mS CMExtnRx: v=53, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=1057 in=0
1243872mS CMExtnTx: v=53, p1=0
CMFacility
Line: type=DigitalExtn 6 Call: lid=0 id=1057 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[3125234315] Type=Unknown Plan=Unknown Pres=Allowed (0)
IE CMIERespondingPartyName (228) ?WIRELESS CALLER
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:0 R:4) number=3125234315
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceISDNTrunk type=2 number=1 channel=1 rx_gain=32 tx_gain=32
Timed: 15/10/08 17:23
1243874mS CMMap: PCG::UnmapBChan pcp[9]b1r0 cp_b fee3973c other_cp_b fee0b454
1243874mS CMMap: a=2.2 b=6.8 M0
1243874mS CMMap: PCG::UnmapBChan pcp[59]b1r0 cp_b fee0b454 other_cp_b 0
1243874mS CMMap: a=2.2 b=0.0 R1
1243876mS CD: CALL: 1.11.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 1" (2.2) Bend="Extn53(53)" [Extn53(53)] (0.0) CalledNum=53 (Extn53) CallingNum=3125234315 (?WIRELESS CALLER) Internal=0 Time=14818 AState=Connected
1244089mS RES: Wed 15/10/2008 17:24:00 FreeMem=46676456(7) CMMsg=4 (4) Buff=100 615 498 1067 5 Links=9046
1244904mS ISDNL1Rx: v=1 peb=1
0000 02 01 01 83 ....
1244904mS ISDNL2Rx: v=1 peb=1
0000 02 01 01 83 ....
1244905mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 7d ...}
1244905mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 7d ...}
1249725mS ISDNL1Rx: v=1 peb=1
0000 02 01 7c 82 08 02 38 9a 45 08 02 82 90 ..|...8.E....
1249725mS ISDNL2Rx: v=1 peb=1
0000 02 01 7c 82 08 02 38 9a 45 08 02 82 90 ..|...8.E....
1249726mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Remote)
Message Type = Disconnect
InformationElement = CAUSE
0000 08 02 82 90 ....
1249726mS ISDNL3Evt: v=1 stacknum=1 State, new=ReleaseReq, old=Active id=11
1249726mS ISDNL3Tx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Local)
Message Type = Release
1249727mS ISDNL2Tx: v=1 peb=1
0000 00 01 82 7e 08 02 b8 9a 4d ...~....M
1249728mS ISDNL1Tx: v=1 peb=1
0000 00 01 82 7e 08 02 b8 9a 4d ...~....M
1249733mS ISDNL1Rx: v=1 peb=1
0000 00 01 01 84 ....
1249733mS ISDNL2Rx: v=1 peb=1
0000 00 01 01 84 ....
1249745mS ISDNL1Rx: v=1 peb=1
0000 02 01 7e 84 08 02 38 9a 5a ..~...8.Z
1249746mS ISDNL2Rx: v=1 peb=1
0000 02 01 7e 84 08 02 38 9a 5a ..~...8.Z
1249746mS ISDNL3Rx: v=1 peb=1
ISDN Layer3 Pcol=08(Q931) Reflen=2 ref=389A(Remote)
Message Type = ReleaseComplete
1249746mS ISDNL3Evt: v=1 stacknum=1 State, new=NullState, old=ReleaseReq id=11
1249747mS ISDNL2Tx: v=1 peb=1
0000 02 01 01 80 ....
1249747mS ISDNL1Tx: v=1 peb=1
0000 02 01 01 80 ....
1249748mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=1 id=11 in=1
BChan: slot=0 chan=1
Cause=16, Normal call clearing
1249748mS CMTARGET: 1.11.1 14 Q931 Trunk:1 CHAN=1: CancelTimer CMTCCoverageTimeout
1249751mS CMLOGGING: CALL:2008/10/1517:23,00:00:14,011,3125234315,I,53,4433,?WIRELESSCALLER,,,0,,""n/a,0
1249752mS CD: CALL: 1.11.1 BState=Ringing Cut=0 Music=0.0 Aend="Line 1" (2.2) Bend="Extn53(53)" [Extn53(53)] (0.0) CalledNum=53 (Extn53) CallingNum=3125234315 (?WIRELESS CALLER) Internal=0 Time=20694 AState=Idle
1249753mS CD: CALL: 1.11.1 Deleted
1249753mS CMTARGET: 1.11.1 -1 Q931 Trunk:1 CHAN=1: ~CMTargetHandler
1249755mS CMExtnTx: v=53, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=1057 in=0
Called[53] Type=Default (100) Reason=CMDRdirect Calling[3125234315] Type=Unknown Plan=Unknown Pres=Allowed (0)
Cause=16, Normal call clearing
Timed: 15/10/08 17:24
1249760mS CMMap: a=2.2 b=0.0 R0
1249767mS CD: CALLSYNC: cs02
1250089mS RES: Wed 15/10/2008 17:24:06 FreeMem=46698864(8) CMMsg=4 (4) Buff=100 615 499 1067 5 Links=9052