PaulConnect
Systems Engineer
Hi All,
I have a unit that is not playing MoH when callers are put on hold. Unit is an IP 500 V2 running version 9.0.0.829. The music file has been converted to the correct format and loaded into the SD card via the Embedded File Management option in Manager. We have loaded the file here onto our in house system and the file plays fine so I know the format of the file is correct. Users on the non-working system are using 1616's. We have rebooted the system various times but it is still not playing. The system is set to look for a WAV file for the on hold option as per the telephony tab in system in manager.
We have run Monitor while putting a call on hold and the following output is what we got:
IE CMIEDeviceDetail (231) 0a00210100000003 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=2 channel=0 features=0x21 rx_gain=32 tx_gain=32 ep_callid=3 ipaddr=10.0.33.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
09:15:29 156790321mS CD: CALL: 2.3.1 BState=Connected Cut=3 Music=0.0 Aend="Line 2" (1.2) Bend="" [Dining Room(106)] (0.0) CalledNum=100 (BQ All) CallingNum= () Internal=0 Time=9630 AState=Connected
09:15:29 156790323mS CMExtnEvt: Hummingbird: Recover Timer reason=CMTRWrapUp
09:15:29 156790323mS CMExtnEvt: v=7 State, new=Idle old=PortRecoverDelay,0,0,Hummingbird
09:15:29 156790324mS CMExtnEvt: Brown Booby: Recover Timer reason=CMTRWrapUp
09:15:29 156790324mS CMExtnEvt: v=9 State, new=Idle old=PortRecoverDelay,0,0,Brown Booby
09:15:29 156790325mS CMExtnEvt: Snowy Egret: Recover Timer reason=CMTRWrapUp
09:15:29 156790325mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Snowy Egret
09:15:29 156790326mS CMExtnEvt: Den: Recover Timer reason=CMTRWrapUp
09:15:29 156790326mS CMExtnEvt: v=14 State, new=Idle old=PortRecoverDelay,0,0,Den
09:15:29 156790326mS CMExtnEvt: DECT Phone 1: Recover Timer reason=CMTRWrapUp
09:15:29 156790326mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,DECT Phone 1
09:15:29 156790327mS CMExtnEvt: Ken BQ: Recover Timer reason=CMTRWrapUp
09:15:29 156790327mS CMExtnEvt: v=15 State, new=Idle old=PortRecoverDelay,0,0,Ken BQ
09:15:29 156790327mS CMExtnEvt: Blue Heron: Recover Timer reason=CMTRWrapUp
09:15:29 156790327mS CMExtnEvt: v=12 State, new=Idle old=PortRecoverDelay,0,0,Blue Heron
09:15:29 156790328mS CMExtnEvt: Whistling Duck: Recover Timer reason=CMTRWrapUp
09:15:29 156790328mS CMExtnEvt: v=6 State, new=Idle old=PortRecoverDelay,0,0,Whistling Duck
09:15:29 156790329mS CMExtnEvt: Staff Room BQ: Recover Timer reason=CMTRWrapUp
09:15:29 156790329mS CMExtnEvt: v=3 State, new=Idle old=PortRecoverDelay,0,0,Staff Room BQ
09:15:29 156790330mS CMExtnEvt: Robins Nest: Recover Timer reason=CMTRWrapUp
09:15:29 156790330mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Robins Nest
09:15:29 156790338mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=0 id=1315 in=0
IE CMIEPrimitiveData (7)
09:15:29 156790339mS CD: CALL: 255.1294.0 BState=Connected Cut=3 Music=0.0 Aend="West Wing Desk(114)" (0.0) Bend="Tyler(431)" [Line 20] (270.1) CalledNum=431 (Tyler) CallingNum=114 (West Wing Desk) Internal=0 Time=13394 AState=Connected
09:15:38 156799249mS RES: Thu 19/7/2018 09:15:38 FreeMem=47559816 46871712(2) CachedMem=688104 CMMsg=6(7) Buff=5200 1369 1000 7462 5 Links=25862 BTree=12998 CPU=02.36% CPUStats=1/3/5444/21458/23290/0/1 MCR=0 MCW=0
09:15:38 156799249mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=82 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=55(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:15:38 156799249mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:15:41 156802821mS H323Evt: Shared tcp socket for line 17 disconnected
09:15:41 156802838mS H323Evt: Shared tcp socket for line 18 disconnected
09:15:41 156802851mS H323Evt: Shared tcp socket for line 19 disconnected
09:15:47 156808899mS H323Evt: Recv: RegistrationRequest 10.0.33.108; Endpoints registered: 16; Endpoints in registration: 0
09:15:48 156809344mS PRN: Dining Room: Feature Key Pressed 2CPLFeatureHold
09:15:48 156809345mS CMExtnRx: v=106, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RemoteHold.Invoke.CodePageUnknown
invokeId 14653
09:15:48 156809347mS CMExtnTx: v=106, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 19/07/18 09:15
09:15:48 156809347mS CMExtnTx: v=106, p1=8012
CMFacility
Line: type=IPLine 250 Call: lid=263 id=4 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
09:15:48 156809347mS CMExtnEvt: Dining Room: CMExtnHandler::SetCurrent( id: 1306->0 )
09:15:48 156809348mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Connected,0,0,Dining Room
09:15:48 156809349mS CMLineTx: v=2
CMFacility
Line: type=AnalogueLine 2 Call: lid=2 id=3 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
HoldNotific.Invoke.CodePageUnknown
invokeId 14654
09:15:48 156809350mS CD: CALL: 2.3.1 BState=Connected Cut=1 Music=1.0 Aend="Line 2" (1.2) Bend="" [Dining Room(106)] (0.0) CalledNum=100 (BQ All) CallingNum= () Internal=0 Time=28659 AState=Connected
09:15:48 156809351mS CMMap: PCG::UnmapBChan pcp[101]b1r0 cp_b f52cba74 other_cp_b f52fbdfc
09:15:48 156809351mS CMMap: a=2.1 b=1.2 M02
09:15:48 156809351mS H323Evt: SetOperational 84 10.0.33.1:49168 to 0
09:15:48 156809351mS CMMap: PCG::UnmapBChan pcp[360]b0r1 cp_b 0 other_cp_b 0
09:15:48 156809351mS CMMap: a=1.2 b=0.0 H1
09:15:48 156809352mS CMMap: PlatformConnectionAudioSAP:isconnectVoice rtpRelay ID: 2 Close success
09:15:48 156809754mS RES: Thu 19/7/2018 09:15:48 FreeMem=47567512 46871712(2) CachedMem=695800 CMMsg=6(7) Buff=5200 1371 1000 7461 5 Links=25874 BTree=12998 CPU=04.40% CPUStats=8/17/5444/20972/23290/0/0 MCR=0 MCW=0
09:15:48 156809754mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=87 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=52(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:15:48 156809754mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:15:49 156810496mS H323Evt: Recv: RegistrationRequest 10.0.33.137; Endpoints registered: 16; Endpoints in registration: 0
09:15:50 156811111mS H323Evt: Recv: RegistrationRequest 10.0.33.106; Endpoints registered: 16; Endpoints in registration: 0
09:15:50 156811348mS CMExtnEvt: Dining Room: Recover Timer reason=CMTRWrapUp
09:15:50 156811348mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Dining Room
09:15:50 156811349mS CMExtnTx: v=106, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Dining Room Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dining Room Msgs=0]
Timed: 19/07/18 09:15
09:15:50 156811926mS H323Evt: Recv: RegistrationRequest 10.0.33.136; Endpoints registered: 16; Endpoints in registration: 0
09:15:51 156812051mS H323Evt: Recv: RegistrationRequest 10.0.33.115; Endpoints registered: 16; Endpoints in registration: 0
09:15:52 156813419mS H323Evt: Recv: RegistrationRequest 10.0.33.101; Endpoints registered: 16; Endpoints in registration: 0
09:15:55 156816237mS H323Evt: Recv: RegistrationRequest 10.0.33.111; Endpoints registered: 16; Endpoints in registration: 0
09:15:55 156816274mS H323Evt: Recv: RegistrationRequest 10.0.33.116; Endpoints registered: 16; Endpoints in registration: 0
09:15:56 156817602mS H323Evt: Recv: RegistrationRequest 10.0.33.109; Endpoints registered: 16; Endpoints in registration: 0
09:15:56 156817720mS H323Evt: Recv: RegistrationRequest 10.0.33.103; Endpoints registered: 16; Endpoints in registration: 0
09:15:57 156818255mS RES: Thu 19/7/2018 09:15:57 FreeMem=47561344 46871712(2) CachedMem=689632 CMMsg=6(7) Buff=5200 1369 1000 7461 5 Links=25868 BTree=12998 CPU=03.08% CPUStats=1/4/5444/21370/23290/0/0 MCR=0 MCW=0
09:15:57 156818255mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=83 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=55(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:15:57 156818255mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:15:59 156820356mS H323Evt: Recv: RegistrationRequest 10.0.33.113; Endpoints registered: 16; Endpoints in registration: 0
09:15:59 156820408mS H323Evt: Recv: RegistrationRequest 10.0.33.104; Endpoints registered: 16; Endpoints in registration: 0
09:16:00 156821310mS H323Evt: Recv: RegistrationRequest 10.0.33.105; Endpoints registered: 16; Endpoints in registration: 0
09:16:00 156821821mS H323Evt: Shared tcp socket for line 17 disconnected
09:16:00 156821838mS H323Evt: Shared tcp socket for line 18 disconnected
09:16:00 156821851mS H323Evt: Shared tcp socket for line 19 disconnected
09:16:01 156822372mS H323Evt: Recv: RegistrationRequest 10.0.33.110; Endpoints registered: 16; Endpoints in registration: 0
09:16:01 156822842mS H323Evt: Recv: RegistrationRequest 10.0.33.102; Endpoints registered: 16; Endpoints in registration: 0
09:16:05 156826349mS CMExtnEvt: Dining Room: Recover Timer reason=CMTRHoldTimeout
09:16:05 156826349mS CMExtnEvt: Dining Room: CMExtnHandler::SetCurrent( id: 0->1306 )
09:16:05 156826349mS CMExtnEvt: v=4 State, new=RingRecover old=Idle,0,0,Dining Room
09:16:05 156826350mS CMCallEvt: 263.1306.0 60 Dining Room.0: REVCALL INIT display=<
09:16:05 156826351mS CMExtnTx: v=106, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
Called[106] Type=Reminder (123) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIECallSignalChannelData (8) unknown
Display [<]
Cause=120, Reminder(IPO)
Timed: 19/07/18 09:16
09:16:05 156826351mS CMExtnRx: v=106, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
********** SysMonitor v9.1.1.0 build 10 [connected to 10.0.33.1 (Bananaquit)] **********
09:16:05 156826757mS RES: Thu 19/7/2018 09:16:05 FreeMem=47568648 46871712(2) CachedMem=696936 CMMsg=6(7) Buff=5200 1372 1000 7461 5 Links=25878 BTree=12998 CPU=03.74% CPUStats=5/9/5444/20944/23290/0/0 MCR=0 MCW=0
09:16:05 156826757mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=103 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=52(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:16:05 156826757mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:16:05 156826775mS PRN: Monitor Status IP 500 V2 9.0.0.0 build 829
09:16:05 156826775mS PRN: LAW=A PRI=0, BRI=0, ALOG=4, VCOMP=42, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=3 TYP=2) 1-X=0 CALLS=2(TOT=60)
09:16:08 156829056mS CMExtnRx: v=106, p1=0
CMConnect
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEFastStartInfoData (6) 10 item(s)
09:16:08 156829056mS CMCallEvt: 263.1306.0 60 Dining Room.0: REVCALL COMPLETE state=1 substate=2
09:16:08 156829056mS CMLineTx: v=2
CMFacility
Line: type=AnalogueLine 2 Call: lid=2 id=3 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RetrieveNotific.Invoke.CodePageUnknown
invokeId 14655
09:16:08 156829060mS CMLineTx: v=2
CMFacility
Line: type=AnalogueLine 2 Call: lid=2 id=3 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Dining Room
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Dining Room
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=106
IE CMIEDeviceDetail (231) 0a0021010000051a LOCALE=enu HW=15 VER=9 class=CMDeviceH323Phone type=88 number=8012 channel=0 features=0x10000400 rx_gain=32 tx_gain=32 ep_callid=1306 ipaddr=10.0.33.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=BQ All
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=100
09:16:08 156829060mS CMExtnEvt: v=4 State, new=Connected old=RingRecover,0,0,Dining Room
09:16:08 156829062mS CMExtnTx: v=106, p1=0
CMConnectAck
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
Called[106] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=120, Reminder(IPO)
Timed: 19/07/18 09:16
09:16:08 156829062mS CMExtnTx: v=106, p1=8012
CMFacility
Line: type=IPLine 250 Call: lid=263 id=4 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
09:16:08 156829065mS CD: CALL: 2.3.1 BState=Connected Cut=3 Music=0.0 Aend="Line 2" (1.2) Bend="" [Dining Room(106)] (0.0) CalledNum=100 (BQ All) CallingNum= () Internal=0 Time=48374 AState=Connected
09:16:08 156829066mS CMMap: a=1.2 b=0.0 H0
09:16:08 156829066mS CMMap: PCG::MapBChan pcp[101]b1r0 cp_b f52cba74 other_cp_b 0 type CGTypeSimple
09:16:08 156829066mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[360]b0r1) true
09:16:08 156829067mS CMMap: PCG::MapBChan pcp[360]b0r1 cp_b f52fbdfc other_cp_b f52cba74 type CGTypeSimple
09:16:08 156829067mS CMMap: a=1.2 b=2.1 M12
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::Bind pcp[360]b0r1 to 2.1
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[360]b0r1 Configure 2.1
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelay ID: 2
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 10.0.33.1:49168, Remote IP addr 10.0.33.106:49564
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.5:32768, telogyHost IP addr 1.1.1.1:30
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[360]b0r1 ConnectIndication 2.1
09:16:08 156829068mS H323Evt: SetOperational 84 10.0.33.1:49168 to 1
09:16:09 156830331mS H323Evt: Removing inactive H323 socket from 10.0.200.100:56571
Any and all suggestions are greatly appreciated.
I have a unit that is not playing MoH when callers are put on hold. Unit is an IP 500 V2 running version 9.0.0.829. The music file has been converted to the correct format and loaded into the SD card via the Embedded File Management option in Manager. We have loaded the file here onto our in house system and the file plays fine so I know the format of the file is correct. Users on the non-working system are using 1616's. We have rebooted the system various times but it is still not playing. The system is set to look for a WAV file for the on hold option as per the telephony tab in system in manager.
We have run Monitor while putting a call on hold and the following output is what we got:
IE CMIEDeviceDetail (231) 0a00210100000003 LOCALE=enu HW=15 VER=9 class=CMDeviceAlogTrunk type=0 number=2 channel=0 features=0x21 rx_gain=32 tx_gain=32 ep_callid=3 ipaddr=10.0.33.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
09:15:29 156790321mS CD: CALL: 2.3.1 BState=Connected Cut=3 Music=0.0 Aend="Line 2" (1.2) Bend="" [Dining Room(106)] (0.0) CalledNum=100 (BQ All) CallingNum= () Internal=0 Time=9630 AState=Connected
09:15:29 156790323mS CMExtnEvt: Hummingbird: Recover Timer reason=CMTRWrapUp
09:15:29 156790323mS CMExtnEvt: v=7 State, new=Idle old=PortRecoverDelay,0,0,Hummingbird
09:15:29 156790324mS CMExtnEvt: Brown Booby: Recover Timer reason=CMTRWrapUp
09:15:29 156790324mS CMExtnEvt: v=9 State, new=Idle old=PortRecoverDelay,0,0,Brown Booby
09:15:29 156790325mS CMExtnEvt: Snowy Egret: Recover Timer reason=CMTRWrapUp
09:15:29 156790325mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,Snowy Egret
09:15:29 156790326mS CMExtnEvt: Den: Recover Timer reason=CMTRWrapUp
09:15:29 156790326mS CMExtnEvt: v=14 State, new=Idle old=PortRecoverDelay,0,0,Den
09:15:29 156790326mS CMExtnEvt: DECT Phone 1: Recover Timer reason=CMTRWrapUp
09:15:29 156790326mS CMExtnEvt: v=1 State, new=Idle old=PortRecoverDelay,0,0,DECT Phone 1
09:15:29 156790327mS CMExtnEvt: Ken BQ: Recover Timer reason=CMTRWrapUp
09:15:29 156790327mS CMExtnEvt: v=15 State, new=Idle old=PortRecoverDelay,0,0,Ken BQ
09:15:29 156790327mS CMExtnEvt: Blue Heron: Recover Timer reason=CMTRWrapUp
09:15:29 156790327mS CMExtnEvt: v=12 State, new=Idle old=PortRecoverDelay,0,0,Blue Heron
09:15:29 156790328mS CMExtnEvt: Whistling Duck: Recover Timer reason=CMTRWrapUp
09:15:29 156790328mS CMExtnEvt: v=6 State, new=Idle old=PortRecoverDelay,0,0,Whistling Duck
09:15:29 156790329mS CMExtnEvt: Staff Room BQ: Recover Timer reason=CMTRWrapUp
09:15:29 156790329mS CMExtnEvt: v=3 State, new=Idle old=PortRecoverDelay,0,0,Staff Room BQ
09:15:29 156790330mS CMExtnEvt: Robins Nest: Recover Timer reason=CMTRWrapUp
09:15:29 156790330mS CMExtnEvt: v=2 State, new=Idle old=PortRecoverDelay,0,0,Robins Nest
09:15:29 156790338mS CMLineRx: v=20
CMFacility
Line: type=IPLine 20 Call: lid=0 id=1315 in=0
IE CMIEPrimitiveData (7)
09:15:29 156790339mS CD: CALL: 255.1294.0 BState=Connected Cut=3 Music=0.0 Aend="West Wing Desk(114)" (0.0) Bend="Tyler(431)" [Line 20] (270.1) CalledNum=431 (Tyler) CallingNum=114 (West Wing Desk) Internal=0 Time=13394 AState=Connected
09:15:38 156799249mS RES: Thu 19/7/2018 09:15:38 FreeMem=47559816 46871712(2) CachedMem=688104 CMMsg=6(7) Buff=5200 1369 1000 7462 5 Links=25862 BTree=12998 CPU=02.36% CPUStats=1/3/5444/21458/23290/0/1 MCR=0 MCW=0
09:15:38 156799249mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=82 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=55(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:15:38 156799249mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:15:41 156802821mS H323Evt: Shared tcp socket for line 17 disconnected
09:15:41 156802838mS H323Evt: Shared tcp socket for line 18 disconnected
09:15:41 156802851mS H323Evt: Shared tcp socket for line 19 disconnected
09:15:47 156808899mS H323Evt: Recv: RegistrationRequest 10.0.33.108; Endpoints registered: 16; Endpoints in registration: 0
09:15:48 156809344mS PRN: Dining Room: Feature Key Pressed 2CPLFeatureHold
09:15:48 156809345mS CMExtnRx: v=106, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RemoteHold.Invoke.CodePageUnknown
invokeId 14653
09:15:48 156809347mS CMExtnTx: v=106, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 19/07/18 09:15
09:15:48 156809347mS CMExtnTx: v=106, p1=8012
CMFacility
Line: type=IPLine 250 Call: lid=263 id=4 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
09:15:48 156809347mS CMExtnEvt: Dining Room: CMExtnHandler::SetCurrent( id: 1306->0 )
09:15:48 156809348mS CMExtnEvt: v=4 State, new=PortRecoverDelay old=Connected,0,0,Dining Room
09:15:48 156809349mS CMLineTx: v=2
CMFacility
Line: type=AnalogueLine 2 Call: lid=2 id=3 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
HoldNotific.Invoke.CodePageUnknown
invokeId 14654
09:15:48 156809350mS CD: CALL: 2.3.1 BState=Connected Cut=1 Music=1.0 Aend="Line 2" (1.2) Bend="" [Dining Room(106)] (0.0) CalledNum=100 (BQ All) CallingNum= () Internal=0 Time=28659 AState=Connected
09:15:48 156809351mS CMMap: PCG::UnmapBChan pcp[101]b1r0 cp_b f52cba74 other_cp_b f52fbdfc
09:15:48 156809351mS CMMap: a=2.1 b=1.2 M02
09:15:48 156809351mS H323Evt: SetOperational 84 10.0.33.1:49168 to 0
09:15:48 156809351mS CMMap: PCG::UnmapBChan pcp[360]b0r1 cp_b 0 other_cp_b 0
09:15:48 156809351mS CMMap: a=1.2 b=0.0 H1
09:15:48 156809352mS CMMap: PlatformConnectionAudioSAP:isconnectVoice rtpRelay ID: 2 Close success
09:15:48 156809754mS RES: Thu 19/7/2018 09:15:48 FreeMem=47567512 46871712(2) CachedMem=695800 CMMsg=6(7) Buff=5200 1371 1000 7461 5 Links=25874 BTree=12998 CPU=04.40% CPUStats=8/17/5444/20972/23290/0/0 MCR=0 MCW=0
09:15:48 156809754mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=87 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=52(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:15:48 156809754mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:15:49 156810496mS H323Evt: Recv: RegistrationRequest 10.0.33.137; Endpoints registered: 16; Endpoints in registration: 0
09:15:50 156811111mS H323Evt: Recv: RegistrationRequest 10.0.33.106; Endpoints registered: 16; Endpoints in registration: 0
09:15:50 156811348mS CMExtnEvt: Dining Room: Recover Timer reason=CMTRWrapUp
09:15:50 156811348mS CMExtnEvt: v=4 State, new=Idle old=PortRecoverDelay,0,0,Dining Room
09:15:50 156811349mS CMExtnTx: v=106, p1=0
CMVoiceMailStatus
Line: type=IPLine 250 Call: lid=0 id=-1 in=0
Called[Dining Room Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Dining Room Msgs=0]
Timed: 19/07/18 09:15
09:15:50 156811926mS H323Evt: Recv: RegistrationRequest 10.0.33.136; Endpoints registered: 16; Endpoints in registration: 0
09:15:51 156812051mS H323Evt: Recv: RegistrationRequest 10.0.33.115; Endpoints registered: 16; Endpoints in registration: 0
09:15:52 156813419mS H323Evt: Recv: RegistrationRequest 10.0.33.101; Endpoints registered: 16; Endpoints in registration: 0
09:15:55 156816237mS H323Evt: Recv: RegistrationRequest 10.0.33.111; Endpoints registered: 16; Endpoints in registration: 0
09:15:55 156816274mS H323Evt: Recv: RegistrationRequest 10.0.33.116; Endpoints registered: 16; Endpoints in registration: 0
09:15:56 156817602mS H323Evt: Recv: RegistrationRequest 10.0.33.109; Endpoints registered: 16; Endpoints in registration: 0
09:15:56 156817720mS H323Evt: Recv: RegistrationRequest 10.0.33.103; Endpoints registered: 16; Endpoints in registration: 0
09:15:57 156818255mS RES: Thu 19/7/2018 09:15:57 FreeMem=47561344 46871712(2) CachedMem=689632 CMMsg=6(7) Buff=5200 1369 1000 7461 5 Links=25868 BTree=12998 CPU=03.08% CPUStats=1/4/5444/21370/23290/0/0 MCR=0 MCW=0
09:15:57 156818255mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=83 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=55(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:15:57 156818255mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:15:59 156820356mS H323Evt: Recv: RegistrationRequest 10.0.33.113; Endpoints registered: 16; Endpoints in registration: 0
09:15:59 156820408mS H323Evt: Recv: RegistrationRequest 10.0.33.104; Endpoints registered: 16; Endpoints in registration: 0
09:16:00 156821310mS H323Evt: Recv: RegistrationRequest 10.0.33.105; Endpoints registered: 16; Endpoints in registration: 0
09:16:00 156821821mS H323Evt: Shared tcp socket for line 17 disconnected
09:16:00 156821838mS H323Evt: Shared tcp socket for line 18 disconnected
09:16:00 156821851mS H323Evt: Shared tcp socket for line 19 disconnected
09:16:01 156822372mS H323Evt: Recv: RegistrationRequest 10.0.33.110; Endpoints registered: 16; Endpoints in registration: 0
09:16:01 156822842mS H323Evt: Recv: RegistrationRequest 10.0.33.102; Endpoints registered: 16; Endpoints in registration: 0
09:16:05 156826349mS CMExtnEvt: Dining Room: Recover Timer reason=CMTRHoldTimeout
09:16:05 156826349mS CMExtnEvt: Dining Room: CMExtnHandler::SetCurrent( id: 0->1306 )
09:16:05 156826349mS CMExtnEvt: v=4 State, new=RingRecover old=Idle,0,0,Dining Room
09:16:05 156826350mS CMCallEvt: 263.1306.0 60 Dining Room.0: REVCALL INIT display=<
09:16:05 156826351mS CMExtnTx: v=106, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
Called[106] Type=Reminder (123) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIECallSignalChannelData (8) unknown
Display [<]
Cause=120, Reminder(IPO)
Timed: 19/07/18 09:16
09:16:05 156826351mS CMExtnRx: v=106, p1=0
CMAlerting
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
********** SysMonitor v9.1.1.0 build 10 [connected to 10.0.33.1 (Bananaquit)] **********
09:16:05 156826757mS RES: Thu 19/7/2018 09:16:05 FreeMem=47568648 46871712(2) CachedMem=696936 CMMsg=6(7) Buff=5200 1372 1000 7461 5 Links=25878 BTree=12998 CPU=03.74% CPUStats=5/9/5444/20944/23290/0/0 MCR=0 MCW=0
09:16:05 156826757mS RES2: IP 500 V2 9.0.0.0 build 829 Tasks=61 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=103 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=0 TCP=52(TLS=2) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:16:05 156826757mS RES4: XML MemObjs=56 PoolMem=2097152(1) FreeMem=2081932(1)
09:16:05 156826775mS PRN: Monitor Status IP 500 V2 9.0.0.0 build 829
09:16:05 156826775mS PRN: LAW=A PRI=0, BRI=0, ALOG=4, VCOMP=42, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=3 TYP=2) 1-X=0 CALLS=2(TOT=60)
09:16:08 156829056mS CMExtnRx: v=106, p1=0
CMConnect
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEFastStartInfoData (6) 10 item(s)
09:16:08 156829056mS CMCallEvt: 263.1306.0 60 Dining Room.0: REVCALL COMPLETE state=1 substate=2
09:16:08 156829056mS CMLineTx: v=2
CMFacility
Line: type=AnalogueLine 2 Call: lid=2 id=3 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
RetrieveNotific.Invoke.CodePageUnknown
invokeId 14655
09:16:08 156829060mS CMLineTx: v=2
CMFacility
Line: type=AnalogueLine 2 Call: lid=2 id=3 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Dining Room
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Dining Room
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=106
IE CMIEDeviceDetail (231) 0a0021010000051a LOCALE=enu HW=15 VER=9 class=CMDeviceH323Phone type=88 number=8012 channel=0 features=0x10000400 rx_gain=32 tx_gain=32 ep_callid=1306 ipaddr=10.0.33.1 apps=0 loc=0 em_loc=0 features2=0x0 is_spcall=0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=BQ All
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=100
09:16:08 156829060mS CMExtnEvt: v=4 State, new=Connected old=RingRecover,0,0,Dining Room
09:16:08 156829062mS CMExtnTx: v=106, p1=0
CMConnectAck
Line: type=IPLine 250 Call: lid=263 id=1306 in=0
Called[106] Type=Unknown (0) Reason=CMDRdirect Calling[] Type=Unknown Plan=Unknown Pres=NAInterworking (2)
Cause=120, Reminder(IPO)
Timed: 19/07/18 09:16
09:16:08 156829062mS CMExtnTx: v=106, p1=8012
CMFacility
Line: type=IPLine 250 Call: lid=263 id=4 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
09:16:08 156829065mS CD: CALL: 2.3.1 BState=Connected Cut=3 Music=0.0 Aend="Line 2" (1.2) Bend="" [Dining Room(106)] (0.0) CalledNum=100 (BQ All) CallingNum= () Internal=0 Time=48374 AState=Connected
09:16:08 156829066mS CMMap: a=1.2 b=0.0 H0
09:16:08 156829066mS CMMap: PCG::MapBChan pcp[101]b1r0 cp_b f52cba74 other_cp_b 0 type CGTypeSimple
09:16:08 156829066mS CMMap: a=1.2 b=0.0 PCGS CPReserveCodec (pcp[360]b0r1) true
09:16:08 156829067mS CMMap: PCG::MapBChan pcp[360]b0r1 cp_b f52fbdfc other_cp_b f52cba74 type CGTypeSimple
09:16:08 156829067mS CMMap: a=1.2 b=2.1 M12
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::Bind pcp[360]b0r1 to 2.1
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[360]b0r1 Configure 2.1
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelay ID: 2
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 10.0.33.1:49168, Remote IP addr 10.0.33.106:49564
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.5:32768, telogyHost IP addr 1.1.1.1:30
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success
09:16:08 156829067mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[360]b0r1 ConnectIndication 2.1
09:16:08 156829068mS H323Evt: SetOperational 84 10.0.33.1:49168 to 1
09:16:09 156830331mS H323Evt: Removing inactive H323 socket from 10.0.200.100:56571
Any and all suggestions are greatly appreciated.