Jump to content

Snom 821 provisioning on Vodia 0.58


mikl

Recommended Posts

Hello,
On a Vodia 0.58, I can not provision a Snom 821
Log Snom 821 :
Jun 27 10:07:05 [iNFO ] PHN: Config setup: code: 200, uri: http://FQDN_IPBX:80/prov/snom821.htm
Jun 27 10:07:05 [NOTICE] CFG: read_xml_settings: found phone-settings XML header
Jun 27 10:07:05 [NOTICE] CFG: read_xml_settings: found one byte encoding: 1
Jun 27 10:07:05 [DEBUG2] CFG: settings::read_one_setting: setting_server = 'http://FQDN_IPBX/prov/snom821-{mac}.htm'set.need_apply: 1, finished: 0, need reboot to apply: 0
Jun 27 10:07:05 [NOTICE] PHN: Config setup: found xml style settings
Jun 27 10:07:05 [NOTICE] PHN: last prov successful:1; uri: >http://FQDN_IPBX/prov/snom821-{mac}.htm<;default uri: >http://provisioning.snom.com/snom821/snom821.php?mac={mac}<
Jun 27 10:07:05 [iNFO ] PHN: ProvisioningReplacements: URL >http://FQDN_IPBX:80/prov/snom821-%7Bmac%7D.htm<
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replace >{mac}< in >http://FQDN_IPBX:80/prov/snom821-%7Bmac%7D.htm<
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replaced result: >http://FQDN_IPBX:80/prov/snom821-0004134B415E.htm<
Jun 27 10:07:05 [iNFO ] PHN: ProvisioningReplacements step 2 result: >http://FQDN_IPBX:80/prov/snom821-0004134B415E.htm<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables in >http://FQDN_IPBX:80/prov/snom821-0004134B415E.htm<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables replaced: >http://FQDN_IPBX:80/prov/snom821-0004134B415E.htm<
Jun 27 10:07:05 [NOTICE] PHN: Fetching URL: http://FQDN_IPBX:80/prov/snom821-0004134B415E.htm
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Send TCP none
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Socket 20 connecting
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Socket 20 connected
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Socket 20 disconnected
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Socket 20 deleted
Jun 27 10:07:05 [iNFO ] PHN: Config setup: code: 200, uri: http://FQDN_IPBX:80/prov/snom821-0004134B415E.htm
Jun 27 10:07:05 [NOTICE] CFG: read_xml_settings: found setting-files XML header
Jun 27 10:07:05 [NOTICE] CFG: read_xml_settings: found one byte encoding: 1
Jun 27 10:07:05 [NOTICE] CFG: read_setting_file_list: added URL: https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?model=snom821
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replace >{mac}< in >https://FQDN_IPBX:443/prov/snom_820_phone-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replaced result: >https://FQDN_IPBX:443/prov/snom_820_phone-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables in >https://FQDN_IPBX:443/prov/snom_820_phone-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables replaced: >https://FQDN_IPBX:443/prov/snom_820_phone-0004134B415E.xml?...<
Jun 27 10:07:05 [NOTICE] CFG: read_setting_file_list: added URL: https://FQDN_IPBX:443/prov/snom_820_phone-0004134B415E.xml?model=snom821
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replace >{mac}< in >https://FQDN_IPBX:443/prov/snom_820_buttons-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replaced result: >https://FQDN_IPBX:443/prov/snom_820_buttons-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables in >https://FQDN_IPBX:443/prov/snom_820_buttons-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables replaced: >https://FQDN_IPBX:443/prov/snom_820_buttons-0004134B415E.xml?...<
Jun 27 10:07:05 [NOTICE] CFG: read_setting_file_list: added URL: https://FQDN_IPBX:443/prov/snom_820_buttons-0004134B415E.xml?model=snom821
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replace >{mac}< in >https://FQDN_IPBX:443/prov/snom_branding-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: Uri Replaced result: >https://FQDN_IPBX:443/prov/snom_branding-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables in >https://FQDN_IPBX:443/prov/snom_branding-0004134B415E.xml?...<
Jun 27 10:07:05 [DEBUG0] PHN: ReplaceQueryVariables replaced: >https://FQDN_IPBX:443/prov/snom_branding-0004134B415E.xml?...<
Jun 27 10:07:05 [NOTICE] CFG: read_setting_file_list: added URL: https://FQDN_IPBX:443/prov/snom_branding-0004134B415E.xml?model=snom870
Jun 27 10:07:05 [NOTICE] PHN: Config setup: found xml style settings
Jun 27 10:07:05 [NOTICE] PHN: last prov successful:1; uri: >http://FQDN_IPBX/prov/snom821-{mac}.htm<;default uri: >http://provisioning.snom.com/snom821/snom821.php?mac={mac}<
Jun 27 10:07:05 [NOTICE] PHN: Fetching URL: https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Send TCP none
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Socket 21 connecting
Jun 27 10:07:05 [DEBUG2] TLS: 0x8688f0 SSL_connect/0 before/connect initialization (0)
Jun 27 10:07:05 [DEBUG2] TLS: 0x8688f0 SSL_connect/0 unknown state (0)
Jun 27 10:07:05 [DEBUG2] WEBCLIENT: Socket 21 connected
Jun 27 10:07:06 [DEBUG2] TLS: 0x8688f0 SSL_connect/0 SSLv3 read server hello A (0)
Jun 27 10:07:06 [ERROR ] TLS: Error 20 at depth 0: unable to get local issuer certificate
Jun 27 10:07:06 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:06 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:06 [DEBUG1] TLS: Found trusted cert, fingerprint 22058adb18d9ea211dbd3f0b88ef2765d4e95a43
Jun 27 10:07:06 [DEBUG1] TLS: Continue with cert due to explicit trust
Jun 27 10:07:06 [ERROR ] TLS: Error 27 at depth 0: certificate not trusted
Jun 27 10:07:06 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:06 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:06 [DEBUG1] TLS: 0x8688f0 SSL Alert write/27:fatal:bad certificate
Jun 27 10:07:06 [DEBUG2] TLS: 0x8688f0 SSL_connect/27 error in SSLv3 read server certificate B
Jun 27 10:07:06 [DEBUG2] TLS: 0x8688f0 SSL_connect/27 error in SSLv3 read server certificate B
Jun 27 10:07:06 [ERROR ] TLS: Error 20 at depth 0: unable to get local issuer certificate
Jun 27 10:07:06 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:06 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:06 [DEBUG1] TLS: Found trusted cert, fingerprint 22058adb18d9ea211dbd3f0b88ef2765d4e95a43
Jun 27 10:07:06 [DEBUG1] TLS: Continue with cert due to explicit trust
Jun 27 10:07:06 [ERROR ] TLS: Error 27 at depth 0: certificate not trusted
Jun 27 10:07:06 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:06 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:06 [DEBUG1] TLS: 0x8688f0 SSL Alert write/27:fatal:bad certificate
Jun 27 10:07:06 [DEBUG2] TLS: 0x8688f0 SSL_connect/27 error in SSLv3 read server certificate B
Jun 27 10:07:06 [ERROR ] PHN: TPL: Socket Error: 21/36/connected, Tls error, closing
Jun 27 10:07:06 [DEBUG2] WEBCLIENT: Socket 21 disconnected
Jun 27 10:07:06 [DEBUG2] WEBCLIENT: socket:21 conn_id:4 disconnected tls:163.172.205.75:443
Jun 27 10:07:06 [NOTICE] WEBCLIENT: on_tcp_close conn_id:4
Jun 27 10:07:06 [DEBUG2] WEBCLIENT: Socket 21 deleted
Jun 27 10:07:06 [iNFO ] PHN: Config setup: code: 500, uri: https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...
Jun 27 10:07:06 [NOTICE] PHN: Config setup: return code 500; requeueing >https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...<'>https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...<;attempt: 1, state: 28, duration: 0/68
Jun 27 10:07:08 [DEBUG0] PHN: TaskMon: LCS/335 PollEvent took 2036 msecs, started Jun 27 10:07:06
Jun 27 10:07:08 [DEBUG0] PHN: LoopMon: LCS/335 took 2162 (232) msecs, started Jun 27 10:07:05, 1 tasks
Jun 27 10:07:08 [DEBUG2] PHN: TMR: setting up 'wizard_checker' 0x7a424c to fire periodically every 500 ms
Jun 27 10:07:08 [DEBUG0] PHN: MbParser:: enter
Jun 27 10:07:08 [DEBUG0] PHN: MbParser:: selected_tree = /SnomIPPhoneBatch/
Jun 27 10:07:08 [DEBUG0] PHN: MbParser:: using cached xml
Jun 27 10:07:08 [DEBUG0] PHN: MbParser:: first time read -> caching xml
Jun 27 10:07:08 [iNFO ] PHN: Xpath applies: /SnomIPPhoneBatch/
Jun 27 10:07:08 [iNFO ] PHN: Xpath contains unrecognized steps, aborting
Jun 27 10:07:08 [DEBUG0] PHN: MbParser:: found tree = SnomIPPhoneText
Jun 27 10:07:08 [DEBUG0] PHN: MbParserMainTag:: tree = SnomIPPhoneText
Jun 27 10:07:08 [NOTICE] PHN: Fetching URL: https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...
Jun 27 10:07:08 [DEBUG2] WEBCLIENT: Send TCP none
Jun 27 10:07:08 [DEBUG2] WEBCLIENT: Socket 22 connecting
Jun 27 10:07:08 [DEBUG2] PHN: TMR: setting up 'gui_state.cpp (806): [static void gui_object::DisplayStateCbAtNextLoop(void*, void*)]' 0x4089e2fc to fire periodically every 500 ms
Jun 27 10:07:08 [ERROR ] TLS: BIO_new_bio_pair code 336134278, error:14090086:SSL routines:SSL3_GET_SERVER_CERTIFICATE:certificate verify failed
Jun 27 10:07:08 [DEBUG2] TLS: 0x8669b8 SSL_connect/0 before/connect initialization (0)
Jun 27 10:07:08 [DEBUG2] TLS: 0x8669b8 SSL_connect/0 unknown state (0)
Jun 27 10:07:08 [DEBUG2] WEBCLIENT: Socket 22 connected
Jun 27 10:07:08 [DEBUG2] TLS: 0x8669b8 SSL_connect/0 SSLv3 read server hello A (0)
Jun 27 10:07:08 [ERROR ] TLS: Error 20 at depth 0: unable to get local issuer certificate
Jun 27 10:07:08 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:08 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:08 [DEBUG1] TLS: Found trusted cert, fingerprint 22058adb18d9ea211dbd3f0b88ef2765d4e95a43
Jun 27 10:07:08 [DEBUG1] TLS: Continue with cert due to explicit trust
Jun 27 10:07:08 [ERROR ] TLS: Error 27 at depth 0: certificate not trusted
Jun 27 10:07:08 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:08 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:08 [DEBUG1] TLS: 0x8669b8 SSL Alert write/27:fatal:bad certificate
Jun 27 10:07:08 [DEBUG2] TLS: 0x8669b8 SSL_connect/27 error in SSLv3 read server certificate B
Jun 27 10:07:08 [DEBUG2] TLS: 0x8669b8 SSL_connect/27 error in SSLv3 read server certificate B
Jun 27 10:07:08 [ERROR ] TLS: Error 20 at depth 0: unable to get local issuer certificate
Jun 27 10:07:08 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:08 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:08 [DEBUG1] TLS: Found trusted cert, fingerprint 22058adb18d9ea211dbd3f0b88ef2765d4e95a43
Jun 27 10:07:08 [DEBUG1] TLS: Continue with cert due to explicit trust
Jun 27 10:07:08 [ERROR ] TLS: Error 27 at depth 0: certificate not trusted
Jun 27 10:07:08 [ERROR ] TLS: Cert s: /OU=Domain Control Validated/OU=EssentialSSL Wildcard/CN=*.onetwotelecom.fr
Jun 27 10:07:08 [ERROR ] TLS: Cert i: /C=GB/ST=Greater Manchester/L=Salford/O=COMODO CA Limited/CN=COMODO RSA Domain Validation Secure Server CA
Jun 27 10:07:08 [DEBUG1] TLS: 0x8669b8 SSL Alert write/27:fatal:bad certificate
Jun 27 10:07:08 [DEBUG2] TLS: 0x8669b8 SSL_connect/27 error in SSLv3 read server certificate B
Jun 27 10:07:08 [ERROR ] PHN: TPL: Socket Error: 22/36/connected, Tls error, closing
Jun 27 10:07:08 [DEBUG2] WEBCLIENT: Socket 22 disconnected
Jun 27 10:07:08 [DEBUG2] WEBCLIENT: socket:22 conn_id:5 disconnected tls:163.172.205.75:443
Jun 27 10:07:08 [NOTICE] WEBCLIENT: on_tcp_close conn_id:5
Jun 27 10:07:08 [DEBUG2] WEBCLIENT: Socket 22 deleted
Jun 27 10:07:08 [iNFO ] PHN: Config setup: code: 500, uri: https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...
Jun 27 10:07:08 [NOTICE] PHN: Config setup: return code 500; requeueing >https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...<'>https://FQDN_IPBX:443/prov/snom_models-0004134B415E.xml?...<;attempt: 2, state: 28, duration: 2/68
Jun 27 10:07:10 [DEBUG0] PHN: TaskMon: LCS/345 PollEvent took 2031 msecs, started Jun 27 10:07:08
Jun 27 10:07:10 [DEBUG0] PHN: LoopMon: LCS/345 took 2157 (174) msecs, started Jun 27 10:07:08, 1 tasks
Jun 27 10:07:10 [DEBUG2] PHN: TMR: 'inhibit_refreshing' 0x880334
Jun 27 10:07:10 [DEBUG0] PHN: MbParser:: enter
Jun 27 10:07:10 [DEBUG0] PHN: MbParser:: selected_tree = /SnomIPPhoneBatch/
Jun 27 10:07:10 [DEBUG0] PHN: MbParser:: using cached xml
Jun 27 10:07:10 [DEBUG0] PHN: MbParser:: first time read -> caching xml
Jun 27 10:07:10 [iNFO ] PHN: Xpath applies: /SnomIPPhoneBatch/

 

 

It seems that the SSL certificate is a problem, whereas it is normally valid.
reagrds
Link to comment
Share on other sites

On another vodia 0.58 with a Snom 760.

 

 

1/1/1970 00:00:55 [NOTICE] PHN: Setting server prio 1, type redirection, url: >http://ipbx.moncentrex.com/prov/snom760.htm<
1/1/1970 00:00:55 [NOTICE] PHN: Fetching URL: http://ipbx.moncentrex.com:80/prov/snom760.htm
1/1/1970 00:00:56 [NOTICE] CFG: read_xml_settings: found phone-settings XML header
1/1/1970 00:00:56 [NOTICE] CFG: read_xml_settings: found one byte encoding: 1
1/1/1970 00:00:56 [NOTICE] PHN: Config setup: found xml style settings
1/1/1970 00:00:56 [NOTICE] PHN: last prov successful:1; uri: >http://ipbx.moncentrex.com/prov/snom760-{mac}.htm<;default uri: >http://provisioning.snom.com/snom760/snom760.php?mac={mac}<
1/1/1970 00:00:56 [NOTICE] PHN: Fetching URL: http://ipbx.moncentrex.com:80/prov/snom760-000413710AF9.htm
1/1/1970 00:00:56 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.199
1/1/1970 00:00:56 [NOTICE] CFG: read_xml_settings: found setting-files XML header
1/1/1970 00:00:56 [NOTICE] CFG: read_xml_settings: found one byte encoding: 1
1/1/1970 00:00:56 [NOTICE] CFG: read_setting_file_list: added URL: https://ipbx.moncentrex.com:443/prov/snom_models-000413710AF9.xml?model=snom760
1/1/1970 00:00:56 [NOTICE] CFG: read_setting_file_list: added URL: https://ipbx.moncentrex.com:443/prov/snom_760_phone-000413710AF9.xml?model=snom760
1/1/1970 00:00:56 [NOTICE] CFG: read_setting_file_list: added URL: https://ipbx.moncentrex.com:443/prov/snom_760_buttons-000413710AF9.xml?model=snom760
1/1/1970 00:00:56 [NOTICE] CFG: read_setting_file_list: added URL: https://ipbx.moncentrex.com:443/prov/snom_branding-000413710AF9.xml?model=snom760
1/1/1970 00:00:56 [NOTICE] PHN: Config setup: found xml style settings
1/1/1970 00:00:56 [NOTICE] PHN: last prov successful:1; uri: >http://ipbx.moncentrex.com/prov/snom760-{mac}.htm<;default uri: >http://provisioning.snom.com/snom760/snom760.php?mac={mac}<
1/1/1970 00:00:56 [NOTICE] PHN: Fetching URL: https://ipbx.moncentrex.com:443/prov/snom_models-000413710AF9.xml?model=snom760
1/1/1970 00:00:57 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.124
1/1/1970 00:01:00 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.124
1/1/1970 00:01:03 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.129
1/1/1970 00:01:03 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.129
1/1/1970 00:01:18 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.123
1/1/1970 00:01:20 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.123
1/1/1970 00:01:20 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.199
1/1/1970 00:01:22 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.123
1/1/1970 00:01:23 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.123
1/1/1970 00:01:25 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.123
1/1/1970 00:01:26 [NOTICE] TOOLS: SNMP: Received packet from non-trusted source 192.168.1.123
1/1/1970 00:01:26 [ALERT ] PHN: WEBCLIENT: request https://ipbx.moncentrex.com:443/prov/snom_models-000413710AF9.xml?model=snom760 stopped due to no response from server for con_id:3
1/1/1970 00:01:26 [NOTICE] PHN: Config setup: return code 408; requeueing >https://ipbx.moncentrex.com:443/prov/snom_models-000413710AF9.xml?model=snom760<; attempt: 1, state: 29, duration: 30/94

 

 

 

The "snom_models-000413710AF9.xml" file is almost empty :


<?xml version="1.0" encoding="utf-8"?>
<phone-settings>
</phone-settings>

 

 

 

And this is also the case for the other vodia with the Snom 821, the snom_models-0004134B415E.xml is empty.

Link to comment
Share on other sites

I had the same issue also, perhaps significantly, with a Comodo EssentialSSL wildcard certificate.

 

I did two things, one of which fixed it but can't be sure which!

 

1. I uploaded the various intermediate certificates that came with the Comodo SSL certificate to the server as Trusted Root CA for server authentication

2. I updated the phone firmware to the latest version (for the 870 in my case)

 

After that everything seems to work fine! Hope this helps you.

Link to comment
Share on other sites

Join the conversation

You can post now and register later. If you have an account, sign in now to post with your account.
Note: Your post will require moderator approval before it will be visible.

Guest
Reply to this topic...

×   Pasted as rich text.   Paste as plain text instead

  Only 75 emoji are allowed.

×   Your link has been automatically embedded.   Display as a link instead

×   Your previous content has been restored.   Clear editor

×   You cannot paste images directly. Upload or insert images from URL.

×
×
  • Create New...