SIP DECT D100 on Essential 10.1

[libram11]

I'm having some trouble with a SIP Dect on an IPO. I know they are terrible for having issues. The configs have been looked over by 4 different techs including myself and support outlets and we all see it as being correct.

The license is in place, the line is SIP Dect, the extension is SIP Dect, the user has an 8 digit non repeating login, the SIP registrar is on, and the customer network has been bypassed to eliminate any problems but still having the issue.

So heres what is happening. The d100 boots correctly, it communicates with the IPO and its firmware is updated, the IPO system status sees the base and traffic between the two can be seen on monitor.

The d160 registers to the base and can be seen as a handset in status. At this point the d160 has a logon button, when pressed it asks for the extension (4003) then the password (4003 in the example provided but later changed to 28338141 with no change in result) the d160 displays connecting for a while and then goes to no service with the config button lit but it does not actually open config.

Pasted below are the logs from the d100 showing bootup and an attempted logon by HS2 using extension 4003 and password 4003, if you will note the d100 communicates, reads files, basically does what it should but then always throws a timeout on register and says its user input. Can anyone give me any ideas? Can you see something I don't from the log?

Also the time and date show completely wrong in the log, wouldn't it be set via IP from the IPO?


[2000/01/01 00:00:00.379] 0000 HS9 EVENT_ENTER_TESTMODE
[2000/01/01 00:00:03.727] 0001 [NetStack Initialize] Stored IP address(10.32.13.241) assigned.
[2000/01/01 00:00:04.989] 0002 HS2 [PRESS] RADIO_DISC
[2000/01/01 00:00:13.728] 0003 Try to Connect Configuration Server...
[2000/01/01 00:00:13.851] 0004 [/D100.cfg] reading.
[2000/01/01 00:00:13.958] 0005 - finished.
[2000/01/01 00:00:13.959] 0006 [/D100prov.txt] reading.
[2000/01/01 00:00:14.055] 0007 - OK. There is no difference between Provisioning File and current settings.
[2000/01/01 00:00:14.055] 0008 [/D100settings.txt] reading.
[2000/01/01 00:00:14.145] 0009 - finished.
[2000/01/01 00:00:14.198] 0010 [D100_Italian.lng] reading.
[2000/01/01 00:00:14.913] 0011 - finished.
[2000/01/01 00:00:14.913] 0012 [D100_German.lng] reading.
[2000/01/01 00:00:15.624] 0013 - finished.
[2000/01/01 00:00:15.625] 0014 [D100_Spanish.lng] reading.
[2000/01/01 00:00:16.335] 0015 - finished.
[2000/01/01 00:00:16.335] 0016 [D100_French.lng] reading.
[2000/01/01 00:00:17.048] 0017 - finished.
[2000/01/01 00:00:18.007] 0018 - succes.
[2000/01/01 00:00:18.008] 0019 Try to Connect SIP Server...
[2000/01/01 00:00:18.036] 0020 - succes.
[2000/01/01 00:00:18.037] 0021 HS2 EVENT_SIP_SERVER_RECONNECT
[2000/01/01 00:00:18.039] Initialize D100 Base System Finish.
Firmware Version : D100_1.2.7_0.9.6
Hardware Version : 4
NV Version : 16
Firmware Side : 2 - 1
(Base Information)
Serial ID : SID:0000000000001240EF3DAB75
MAC Address : 00:E0:11:0C:BD:56
RF ID : 01:85:89:E9:40
CLOCK ADJUST : 03:03:04
BANDGAP ADJUST : MST-06, SLV-08
MARKET(0:GL/1:EU): 0
UserAgent(0100): 0
BootStrap(0:Normal): 0
Number of Regist : 1
(Handset Information)
LOGIN MAP : 0x00
REGISTRATION INFO:
[1] ffffffffffffffffffffffffffffffffffffffffff
[2] 018571c055f3fa1e0e29c3c28d442c5d995a5df453
[3] ffffffffffffffffffffffffffffffffffffffffff
[4] ffffffffffffffffffffffffffffffffffffffffff
[5] ffffffffffffffffffffffffffffffffffffffffff
[6] ffffffffffffffffffffffffffffffffffffffffff
[7] ffffffffffffffffffffffffffffffffffffffffff
[8] ffffffffffffffffffffffffffffffffffffffffff
USER INFO :
[1] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[2] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[3] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[4] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[5] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[6] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[7] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[8] 0000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000000
[2000/01/01 00:00:19.483] 0022 HS1 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.483] 0023 HS2 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.484] 0024 HS3 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.484] 0025 HS4 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.485] 0026 HS5 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.485] 0027 HS6 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.486] 0028 HS7 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.486] 0029 HS8 EVENT_SIP_SERVICE_IN
[2000/01/01 00:00:19.487] 0030 HS1 EVENT_INITLOGOUT
[2000/01/01 00:00:19.490] 0031 HS2 EVENT_INITLOGOUT
[2000/01/01 00:00:19.493] 0032 HS3 EVENT_INITLOGOUT
[2000/01/01 00:00:19.495] 0033 HS4 EVENT_INITLOGOUT
[2000/01/01 00:00:19.496] 0034 HS5 EVENT_INITLOGOUT
[2000/01/01 00:00:19.498] 0035 HS6 EVENT_INITLOGOUT
[2000/01/01 00:00:19.501] 0036 HS7 EVENT_INITLOGOUT
[2000/01/01 00:00:19.503] 0037 HS8 EVENT_INITLOGOUT
[2000/01/01 00:00:22.349] 0038 HS2 [PRESS] LOGIN
[2000/01/01 00:00:23.969] 0039 HS2 [PRESS] 4
[2000/01/01 00:00:24.469] 0040 HS2 [PRESS] 0
[2000/01/01 00:00:24.769] 0041 HS2 [PRESS] 0
[2000/01/01 00:00:25.149] 0042 HS2 [PRESS] 3
[2000/01/01 00:00:25.689] 0043 HS2 [PRESS] SELECT
[2000/01/01 00:00:26.769] 0044 HS2 [PRESS] 4
[2000/01/01 00:00:27.209] 0045 HS2 [PRESS] 0
[2000/01/01 00:00:27.469] 0046 HS2 [PRESS] 0
[2000/01/01 00:00:27.849] 0047 HS2 [PRESS] 3
[2000/01/01 00:00:28.389] 0048 HS2 [PRESS] SELECT
[2000/01/01 00:00:28.390] 0049 Hsif_menuLoggingInState
[2000/01/01 00:00:28.391] 0050 1st Login
[2000/01/01 00:00:28.393] 0051 [/D100.cfg] reading.
[2000/01/01 00:00:28.493] 0052 - finished.
[2000/01/01 00:00:28.493] 0053 [/D100prov.txt] reading.
[2000/01/01 00:00:28.587] 0054 - OK. There is no difference between Provisioning File and current settings.
[2000/01/01 00:00:28.587] 0055 [/D100settings.txt] reading.
[2000/01/01 00:00:28.683] 0056 - finished.
[2000/01/01 00:00:28.737] 0057 [D100_Italian.lng] reading.
[2000/01/01 00:00:29.479] 0058 - finished.
[2000/01/01 00:00:29.480] 0059 [D100_German.lng] reading.
[2000/01/01 00:00:30.216] 0060 - finished.
[2000/01/01 00:00:30.217] 0061 [D100_Spanish.lng] reading.
[2000/01/01 00:00:30.953] 0062 - finished.
[2000/01/01 00:00:30.954] 0063 [D100_French.lng] reading.
[2000/01/01 00:00:31.691] 0064 - finished.
[2000/01/01 00:00:32.660] 0065 [system/huntgroup/scn_huntgroup_list] reading.
[2000/01/01 00:00:32.696] 0066 - finished.
[2000/01/01 00:00:32.696] 0067 [system/dir/complete_dir_list] reading.
[2000/01/01 00:00:32.727] 0068 - finished.
[2000/01/01 00:00:32.727] 0069 [system/user/scn_user_list] reading.
[2000/01/01 00:00:32.835] 0070 - finished.
[2000/01/01 00:00:33.859] 0071 ### Login request [4003] - HsIf_CallHdl=0x4bd19c
[2000/01/01 00:00:33.860] 0072 [sipusers/D160_4003@10.32.13.240.cfg] reading.
[2000/01/01 00:00:33.951] 0073 - finished.
[2000/01/01 00:00:33.952] 0074 [D160_4003@10.32.13.240.keys] reading.
[2000/01/01 00:00:34.045] 0075 - finished.
[2000/01/01 00:01:06.232] 0076 HS2 EVENT_LOGIN_TIMEOUT
[2000/01/01 00:01:06.235] 0077 [E] Sipap_MtfRegClientStateChangedCB(term=4003) - failed to send REGISTER(reason=Transaction timeout) call Hsif_LoginTimeout
MTF_PRT 01/01/00 00:01:06 IPP_SIPCONT : WARN - handleRegStateFailed() msg=-1 name=4003
[2000/01/01 00:01:06.253] 0078 [E] Sipap_MtfRegClientStateChangedCB(term=4003) - State Terminated(reason=User request)
MTF_PRT 01/01/00 00:01:06 TIMER : WARN - RvTimerCancel(tqueue=ac6daf): Timer acf6f7 is not valid (event->id=4, timer->id=0, event->state=1)
[2000/01/01 00:01:06.261] 0079 ### Logout completed [4003]
[2000/01/01 00:01:07.265] 0080 HS2 EVENT_STATETIMEOUT
[2000/01/01 00:01:08.365] 0081 HS2 EVENT_STATETIMEOUT
[2000/01/01 00:01:08.366] 0082 Enter Retry Sequence.
[2000/01/01 00:01:08.368] 0083 HS2 [PRESS] END
[2000/01/01 00:01:08.368] 0084 HS2 EVENT_NO_SERVICE
[2000/01/01 00:01:08.369] 0085 Try to Connect Configuration Server...
[2000/01/01 00:01:08.370] 0086 [/D100.cfg] reading.
[2000/01/01 00:01:08.463] 0087 - finished.
[2000/01/01 00:01:08.463] 0088 [/D100prov.txt] reading.
[2000/01/01 00:01:08.562] 0089 - OK. There is no difference between Provisioning File and current settings.
[2000/01/01 00:01:08.562] 0090 [/D100settings.txt] reading.
[2000/01/01 00:01:08.657] 0091 - finished.
[2000/01/01 00:01:08.712] 0092 [D100_Italian.lng] reading.
[2000/01/01 00:01:09.427] 0093 - finished.
[2000/01/01 00:01:09.427] 0094 [D100_German.lng] reading.
[2000/01/01 00:01:10.140] 0095 - finished.
[2000/01/01 00:01:10.141] 0096 [D100_Spanish.lng] reading.
[2000/01/01 00:01:10.853] 0097 - finished.
[2000/01/01 00:01:10.854] 0098 [D100_French.lng] reading.
[2000/01/01 00:01:11.565] 0099 - finished.
[2000/01/01 00:01:12.539] 0100 - succes.
[2000/01/01 00:01:12.539] 0101 Try to Connect SIP Server...
[2000/01/01 00:01:12.567] 0102 - succes.
[2000/01/01 00:01:12.568] 0103 HS2 [PRESS] END
[2000/01/01 00:01:12.569] 0104 HS2 EVENT_SIP_SERVER_RECONNECT

 

[intrigrant]

Maybe a stupid question but did you enable the SIP Extension registrar on the LAN in Manager>System>LANx>Voip>SIP Registrar Enable (Default = Off)?
?

 

[libram11]

as noted above SIP registrar is enabled.

 

[intrigrant]

A log taken with sysmon would be helpfull as a description of hardware.
And use line breaks in your explanations to make it readable.

 

[libram11]

I'll get sysmon log next time I'm onsite, is the format easier to read through?

 

[intrigrant]

attach the logs as a text file, i have software which makes it easier to evaluate if it is a text file.

 

[libram11]

will do, do you care for the d100 log as a txt? its pretty easy to read through.