Solved W52P won't provision after updating to 15.5

[JoelyB]

Server: Windows 2016 VM running 3CX.
4 x T46G & 2 x W52P / W52H

After updating 3CX server to 15.5, I needed to reprovision the phones to push some config changes. The T46Gs worked with no issues at all, but the W52Ps just won't provision at all. I've done a manual firmware update to 25.81.0.30 downloaded from https://www.yealink.com/products_48.html but provisioning still fails.
I tried downloading the cfg file from 3CX and manually uploading it to the unit, which takes the file but account registration still fails. I enabled logging to 6 on W52P and collected the following errors:

<134>Oct 25 20:00:51 LIBD[783]: DCMN<6+info  > Connecting 192.168.17.21:5000
<134>Oct 25 20:00:51 LIBD[783]: DCMN<6+info  > Connecting IP = 192.168.17.21, Port = 5000
<134>Oct 25 20:00:51 LIBD[783]: HTTP<6+info  > Request Line: GET /provisioning/49as2t6mb9a/y000000000025.cfg HTTP/1.1
<134>Oct 25 20:00:51 LIBD[783]: HTTP<6+info  > Host: 192.168.17.21:5000
<134>Oct 25 20:00:51 LIBD[783]: HTTP<6+info  > User-Agent: Yealink W52P 25.81.0.30 00:15:65:a4:f7:f8
<134>Oct 25 20:00:51 LIBD[783]: HTTP<6+info  > process response
<133>Oct 25 20:00:51 LIBD[783]: HTTP<5+notice> response code: 403
<134>Oct 25 20:00:51 LIBD[783]: HTTP<6+info  > Content-Length: 0
<133>Oct 25 20:00:51 LIBD[783]: HTTP<5+notice> response process finish!
<131>Oct 25 20:00:51 LIBD[783]: HTTP<3+error > Client error, ret = 403
<131>Oct 25 20:00:51 ATP [783]: ATP <3+error > http to file failed, code = 403, msg = Forbidden, retry = 1
<132>Oct 25 20:00:51 ATP [783]: ATP <4+warnin> Download com.cfg fail

I've removed and re-added the DECT units in 3CX but still with no success.
Anyone got any suggestions?
Cheers...

 

[cobaltit]

The W52 and 3CX on the same subnet? Have you tried using that same URL in a browser to verify the config file is accessible?

 

[JoelyB]

Yes, they're both on the same subnet. As the auto provision would not work I downloaded the config file using the URL. Downloaded no problem, applied it to the W52 which appears to load it with no problems, but looking at the account page on the W52 it shows that registration fails. The above events are what is listed in the W52 log. Double checked the username/passwords in both the config file and on the web UI for the W52, all match. Also tried re-entering them into the W52 UI. Still fails with the above errors.

 

[Saqqara]

What version have you upgrade from, as in 15.5 the phones are configured under FXS/DECT settings.

https://www.3cx.com/sip-phones/manually-provision-yealink-dect/

Also check the IP address has not been blacklisted

 

[JoelyB]

I believe it was 15.3.. Yes, I originally created them in the FXS/DECT section, so I removed and re-created the configuration again, which is where I've been getting the provisioning URL from.

 

[JoelyB]

So just tried the following:

• Factory reset the W52P
• Removed the device from FXS/DECT
• Recreated the W52 under FXS/DECT as per https://www.3cx.com/sip-phones/manually-provision-yealink-dect/
• Copied / pasted the URL into the W52 UI, confirmed and then auto provision.

Still failed, here's the log:

<134>Oct 26 07:19:00 Log [935]: WEB <6+info  > Setting autoProvision writeConfig start...
<134>Oct 26 07:19:00 Log [1174]: ETLL<6+info  > mkit_reply return [0], msgReply[0x60d03]: 0. result[0]
<134>Oct 26 07:19:00 Log [935]: ETLL<6+info  > mkit_call return(0,0)
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[bEnablePNP] level[8] is same [1]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[bEnableDHCPOption] level[8] is same [1]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[listUserOptions] level[8] is same []
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[dhcp_option_value] key[option60_value] level[8] is same [yealink]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[strServerURL] level[8] is same [http://192.168.17.21:5000/provisioning/49as2t6mb9a/001565A4F7F8.cfg]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[strUserName] level[8] is same []
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[nRepeatMinutes] level[8] is same [1440]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[strWeeklyBeginTime] level[8] is same [00:00]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[strWeeklyEndTime] level[8] is same [00:00]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[strWeeklyMask] level[8] is same [0123456]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[FlexibleInterval] level[8] is same [30]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[strFlexibleBeginTime] level[8] is same [02:00]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[strFlexibleEndTime] level[8] is same []
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[bEnablePowerOn] level[8] is same [1]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[bEnableWeekly] level[8] is same [0]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[bEnableRepeat] level[8] is same [0]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[bEnableFlexible] level[8] is same [0]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[nWeeklyUpgradeInterval] level[8] is same [0]
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[nInactivityTimeExpire] level[8] is same [0]
<134>Oct 26 07:19:00 Log [935]: WEB <6+info  > Crypto get plain data : session id is equal
<134>Oct 26 07:19:00 Log [935]: CPSR<6+info  > SetETLString file[/config/system/system.ini] section[AutoProvision] key[TimeOut] level[8] is same [5]
<134>Oct 26 07:19:00 ATP [826]: ATP <6+info  > Try upgrade by dhcp options
<134>Oct 26 07:19:00 ATP [826]: ATP <6+info  > Upgrade by DHCP option Fail
<134>Oct 26 07:19:00 ATP [826]: ATP <6+info  > Upgrade by static config
<134>Oct 26 07:19:00 Log [935]: WEB <6+info  > autop now...
<134>Oct 26 07:19:00 Log [935]: WEB <6+info  > Setting autoProvision has been changed!
<134>Oct 26 07:19:00 Log [1174]: FWUP<6+info  > IsUpdating [0]
<134>Oct 26 07:19:00 Log [1174]: ETLL<6+info  > mkit_reply return [0], msgReply[0x60d21]: 0. result[0]
<134>Oct 26 07:19:00 Log [1174]: ETLL<6+info  > mkit_reply return [0], msgReply[0x60d03]: 0. result[0]
<134>Oct 26 07:19:00 Log [935]: ETLL<6+info  > set string path[/tmp/log/webstep.log]:section:[web] key:[step] --> [3]
<134>Oct 26 07:19:00 LIBD[826]: DCMN<6+info  > Connecting IP = 192.168.17.21, Port = 5000
<134>Oct 26 07:19:00 Log [935]: ETLL<6+info  > set string path[/tmp/log/webstep.log]:section:[web] key:[step] --> [3]
<134>Oct 26 07:19:00 LIBD[826]: HTTP<6+info  > Request Line: GET /provisioning/49as2t6mb9a/001565A4F7F8.cfg HTTP/1.1
<134>Oct 26 07:19:00 LIBD[826]: HTTP<6+info  > Host: 192.168.17.21:5000
<134>Oct 26 07:19:00 LIBD[826]: HTTP<6+info  > User-Agent: Yealink W52P 25.81.0.30 00:15:65:a4:f7:f8
<134>Oct 26 07:19:00 LIBD[826]: HTTP<6+info  > process response
<133>Oct 26 07:19:00 LIBD[826]: HTTP<5+notice> response code: 403
<134>Oct 26 07:19:00 LIBD[826]: HTTP<6+info  > Content-Length: 0
<133>Oct 26 07:19:00 LIBD[826]: HTTP<5+notice> response process finish!
<131>Oct 26 07:19:00 LIBD[826]: HTTP<3+error > Client error, ret = 403
<131>Oct 26 07:19:00 ATP [826]: ATP <3+error > http to file failed, code = 403, msg = Forbidden, retry = 1
<132>Oct 26 07:19:00 ATP [826]: ATP <4+warnin> Download fail
<134>Oct 26 07:19:00 ATP [826]: ATP <6+info  > Param err!
<134>Oct 26 07:19:00 ATP [826]: ATP <6+info  > send end provision!!

 

[YiannisH_3CX]

Also check the IP address has not been blacklisted

Have you checked the IP of the device is not blacklisted?

 

[JoelyB]

Thanks Saqqara / Yannish, managed to completely overlook that one! Forgot that the server will blacklist an internal IP... Added to my check list!

 

[YiannisH_3CX]

Glad to see the issue has been resolved