*17 busy

[Martin DC]

Hi ya,

I wonder if you would be able to give me some advice.

1) does "ip office v3 r7.0 preferred" come with any VM licenses at all?

2) I think it comes with 4; if so, what is going wrong here?

This is regarding a system in my geek lab.
Bought on ebay to play with VM Pro

system = ip office r7.0
license = preferred / valid / no additional licenses

Currently set to "embedded"
No VM Pro server set.

system has been defaulted.

only one extention configured

Problem
--------

dial *17

result = number busy



24/06/21 14:08:39-880ms Extension = 78011, Digit dialed, Digit = *
24/06/21 14:08:39-892ms Call Ref = 2, Originator State = Dialling, Type = User, Destination Type = none
24/06/21 14:08:40-249ms Extension = 78011, Digit dialed, Digit = 1
24/06/21 14:08:40-509ms Extension = 78011, Digit dialed, Digit = 7
24/06/21 14:08:40-509ms Call Ref = 2, Short Code Matched = System, *17
24/06/21 14:08:40-511ms Extension = 78011, State = Busy
24/06/21 14:08:40-512ms Extension = 78011, Button = 1, Idle
24/06/21 14:08:44-133ms Extension = 78011, Pressed Fixed Feature, Button = Drop
24/06/21 14:08:44-137ms Extension = 78011, State = Busy Wrap Up
24/06/21 14:08:46-136ms Extension = 78011, State = Idle

-------------------------

********** Warning: Logging to Screen Started **********
682714mS PRN: 78011: Digit Pressed *
682715mS CMCallEvt: 0.1014.0 -1 BaseEP: NEW CMEndpoint f590a130 TOTAL NOW=1 CALL_LIST=0
682715mS CMCallEvt: 0.1014.0 -1 hopevmpro.-1: NEW CMExtnEndpoint f590a130, Name=hopevmpro, Extn=78011, Phys Extn=78011
682716mS CMCallEvt: CREATE CALL:7 (f5909824)
682716mS CMCallEvt: 0.1015.0 -1 BaseEP: NEW CMEndpoint f5908410 TOTAL NOW=2 CALL_LIST=0
682718mS CMExtnEvt: hopevmpro: CMExtnHandler::SetCurrent( id: 0->1014 )
682719mS CMExtnRx: v=78011, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=251 id=1014 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[78011] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 12 item(s)
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=hopevmpro
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Hope VM Pro
682719mS CMCallEvt: 251.1014.0 7 hopevmpro.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
682719mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,hopevmpro
682720mS CMTARGET: 251.1014.0 7 hopevmpro.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=78011 dir=out complete=0 ses=0
682720mS CMTARGET: 251.1014.0 7 hopevmpro.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
682720mS CMCallEvt: 251.1014.0 7 hopevmpro.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
682723mS CMExtnTx: v=78011, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1014 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 24/06/21 14:15
682723mS CMExtnTx: v=78011, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
682724mS CMExtnTx: v=78011, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=251 id=1014 in=0
Timed: 24/06/21 14:15
682724mS CMExtnRx: v=78011, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1014 in=0
Called[*] Type=Default (100) Reason=CMDRdirect Keypad[*]
682724mS CMTARGET: 251.1014.0 7 hopevmpro.0: CancelTimer CMTCDialToneTimeout
682724mS CMTARGET: 251.1014.0 7 hopevmpro.0: LOOKUP CALL ROUTE: type=100 called_party=* sub= calling=78011 dir=out complete=0 ses=0
682725mS CMTARGET: 251.1014.0 7 hopevmpro.0: ADD TARGET (N): number=* type=100 depth=1 nobar=1 setorig=1 ses=0
682727mS CD: CALL: 251.1014.0 BState=Idle Cut=0 Music=0.0 Aend="hopevmpro(78011)" (0.0) Bend="" [] (0.0) CalledNum=* () CallingNum=78011 (hopevmpro) Internal=1 Time=11 AState=Dialling
683046mS RES: Thu 24/6/2021 14:15:15 FreeMem=71559028(2) CMMsg=5 (5) Buff=5200 960 999 7479 5 Links=9970
683046mS RES2: IP 500 V2 7.0(36) Tasks=35 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
683051mS PRN: 78011: Digit Pressed 1
683052mS CMExtnRx: v=78011, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1014 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
683052mS CMTARGET: 251.1014.0 7 hopevmpro.0: LOOKUP CALL ROUTE: type=100 called_party=*1 sub= calling=78011 dir=out complete=0 ses=0
683052mS CMTARGET: 251.1014.0 7 hopevmpro.0: ADD TARGET (N): number=*1 type=100 depth=1 nobar=1 setorig=1 ses=0
683354mS PRN: 78011: Digit Pressed 7
683355mS CMExtnRx: v=78011, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=251 id=1014 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
683355mS CMTARGET: 251.1014.0 7 hopevmpro.0: LOOKUP CALL ROUTE: type=100 called_party=*17 sub= calling=78011 dir=out complete=0 ses=0
683355mS CMTARGET: 251.1014.0 7 hopevmpro.0: ADD TARGET (N): number=*17 type=100 depth=1 nobar=1 setorig=1 ses=0
683355mS CMTARGET: 251.1014.0 7 hopevmpro.0: SYS SC: *17 3 sc=type=VoicemailCollect code=*17, num="?"U callinfop->sending_complete=0 secondary_dialtone=
683356mS CMTARGET: 251.1014.0 7 hopevmpro.0: ADD VM TARGET
683357mS CMTARGET: 251.1014.0 7 hopevmpro.0: ADD VM TARGET: FAILED availability=0
683357mS CMTARGET: 251.1014.0 7 hopevmpro.0: CancelTimer CMTCDialToneTimeout
683357mS CMTARGET: 251.1014.0 7 hopevmpro.0: NO INITIAL TARGETS: ??
683357mS CMTARGET: 251.1014.0 7 hopevmpro.0: SELECT: TRY VOICEMAIL orig_hg() orig_user()
683357mS CMTARGET: 251.1014.0 7 hopevmpro.0: Fallback() targeting failed
683358mS CMLOGGING: CALL:2021/06/2414:15,00:00:00,000,78011,O,*17,*17,hopevmpro,,,1,,""n/a,0
683359mS CD: CALL: 251.1014.0 BState=Idle Cut=0 Music=0.0 Aend="hopevmpro(78011)" (0.0) Bend="" [] (0.0) CalledNum=*17 () CallingNum=78011 (hopevmpro) Internal=1 Time=643 AState=Dialling
683359mS CD: CALL: 251.1014.0 Deleted
683359mS CMExtnEvt: hopevmpro: CALL LOST (CMCauseBusy)
683359mS CMExtnEvt: hopevmpro: Extn(78011) Calling Party Number(78011) Type(CMNTypeInternal)
683359mS CMCallEvt: 251.1014.0 -1 hopevmpro.0: StateChange: END=X CMCSDialling->CMCSCompletedTone
683360mS CMExtnEvt: v=1 State, new=CMESCompleted old=Dialling,0,0,hopevmpro
683360mS CMCallEvt: 0.1015.0 -1 BaseEP: DELETE CMEndpoint f5908410 TOTAL NOW=1 CALL_LIST=0
683361mS CMCallEvt: END CALL:7 (f5909824)
683362mS CMMap: PCG::AddToneGenerator g B1[4] for cp[69]b0r1 append pcp[70]b0r1 (total 1)
683362mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[70]b0r1 Configure 0.0
683362mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[70]b0r1 ConnectIndication 0.0
683363mS CMMap: a=0.0 b=0.0 pcp[69]b0r1 RTPB1
684231mS H323Evt: Recv: RegistrationRequest 138.20.208.140; Endpoints registered: 1; Endpoints in registration: 0
684389mS H323Evt: RTP(50r): 138.20.208.248/46750 138.20.208.140/49684 CODEC=G729A8K(6) PKTSZ=20 RFC2833=off AGE=1661 SENT=51 (avg size=20) RECV=50 (avg size=20)
684395mS PRN: 78011: Feature Key Pressed 6
684396mS CMExtnTx: v=78011, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=251 id=1014 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
Timed: 24/06/21 14:15
684396mS CMExtnTx: v=78011, p1=8000
CMFacility


-------------------

 

[critchey]

Open system status, look under voicemail, and see if the voicemail says embedded or none. If it is none the voicemail isn't running which is why you are getting busy.

The truth is just an excuse for lack of imagination.

 

[Okkie26]

As mentioned it's a embedded.

In the call log I see it's an IPO500V2.

Try to pull and replace the SD card at the back of the unit.

 

[Martin DC]

the voicemail isn't running.

I also should mention that there are not cards in this box.

 

[critchey]

When you say there are no cards in the box do you mean no SD card? Cause that would certainly keep embedded voicemail from running...

The truth is just an excuse for lack of imagination.

 

[Okkie26]

There must be a SD card in the back. Otherwise the system is dong nothing.

Check the status of the card. Maybe it's full or switched off..

 

[Martin DC]

when I say cards I meant 'VCM 32' etc.

There is a card in sd slot0

I presuming something is wrong with the sd card.

It's not write protected (via the little switch).

Is it possible boot a system from the second sd slot?

Thanks again.

 

[critchey]

Nope cant boot from the secondary SD slot... that slot is all but useless imo.

You could try to reformat and recreate the SD card but you will need a R7.0 manager to do so along with a SD card reader.

The truth is just an excuse for lack of imagination.

 

[Okkie26]

You can check the state of the SD card in System Status.

VCM32 is only needed for IP trunks and/or IP phones

 

[critchey]

I would also check system status and see if there are any errors for voicemail full or voicemail nearly full. That could also keep the voicemail from starting.

The truth is just an excuse for lack of imagination.

 

[budbyrd]

This might be a silly question, but does the ext you are calling from have a VM box turned on? If it doesn't that would explain why the SC can't find it.

Dermis and feline can be divorced by manifold methods.*
*(Disclaimer for all advise given)--'Version Dependent'

 

[Okkie26]

That doesn't matter.

Besides, Martin mentioned that no VM is present according to SSA.

 

[budbyrd]

The only two things I have ever seen that kills embedded is a full SD or a mailbox with 254+ messages. Defaulting the IPO doesn't ensure housekeeping will work with embedded. There could be a box with 254+ messages stuck in it. I would DTE in and debug. ivm-msg-del-all is the command to do a complete wipe of all messages. I have had a few systems I have had to do this with.

Dermis and feline can be divorced by manifold methods.*
*(Disclaimer for all advise given)--'Version Dependent'

 

[Martin DC]

Thank you for that reply

I shall giventuwt a go

 

[budbyrd]

By R8.1 they fixed the 254+ killing embedded. I ran into that Friday. It actually says you can't leave a msg because the box is full. In R6 and R7 it was a problem.

Dermis and feline can be divorced by manifold methods.*
*(Disclaimer for all advise given)--'Version Dependent'

 

[Martin DC]

Hi ya,

Thank you all for all you help.
You guys are awesome.
My unit is now working (so far).

I wasn’t very scientific in my troubleshooting in the end.
I tried deleting all voicemails… got an error for that; which I presume was something to do with either being in debug or not being in debug.
Then I decided the “shutting down card, removing card, rebooting etc” trick
Then I just started playing with commands on the console… I thought that there might be some corrupt config on either what I would call “nvram” or perhaps the SD card.
I also stuff in a few line cards that I managed to get hold off… and reset all the passwords…
After doing all that; it started working and I’m VERY PLEASED!!!

 

[Martin DC]

I am now interested in the interaction between the nvram (think avaya call it NAND) and the SD card.

I would be intersted in the process and what gets stored where and how it's used.

Looks like config is stored on the SD and copied to NAND on boot; along with possibly ip500v2.bin