EasyCwmp - easycwmp
View Issue Details
0000243easycwmp[All Projects] Helppublic2017-06-06 08:532017-06-08 14:01
sunnyjames99 
mohamed.kallel 
normalminoralways
resolvedno change required 
Ubuntu12.04
0000243: [easycwmp] NOTICE - LibCurl Error: The requested URL returned error: 500
We are using Openacs and easycwmp1.5.3 for TR069 experiment. Install according to README file and did not show any errors.

But the error message is shown below:
--- SEND HTTP REQUEST ---
* About to connect() to 192.168.0.25 port 8080 (#0)
* Trying 192.168.0.25... * connected
> POST /openacs/acs HTTP/1.1
Host: 192.168.0.25:8080
User-Agent: easycwmp
Content-Type: text/xml; charset="utf-8"
Content-Length: 2825
Expect: 100-continue

< HTTP/1.1 100 Continue
* The requested URL returned error: 500
* Closing connection #0
2017-06-06 11:18:39 [easycwmp] NOTICE - LibCurl Error: The requested URL returned error: 500
2017-06-06 11:18:39 [easycwmp] NOTICE - sending http message failed
rpc_inform(218): sending Inform http message failed
2017-06-06 11:18:39 [easycwmp] NOTICE - sending Inform failed
2017-06-06 11:18:39 [easycwmp] NOTICE - external: execute apply service
2017-06-06 11:18:39 [easycwmp] NOTICE - external script exit
2017-06-06 11:18:39 [easycwmp] NOTICE - end session failed
2017-06-06 11:18:39 [easycwmp] NOTICE - retry session in 7 sec, RetryCount = 1
2017-06-06 11:18:46 [easycwmp] NOTICE - start session
+++ HTTP CLIENT CONFIGURATION +++
http_client_init(50):: url: http://192.168.0.25:8080/openacs/acs [^]
http_client_init(56):: ssl_verify: SSL certificate validation disabled.
--- HTTP CLIENT CONFIGURATION ---
2017-06-06 11:18:46 [easycwmp] NOTICE - configured acs url http://192.168.0.25:8080/openacs/acs [^]
2017-06-06 11:18:46 [easycwmp] NOTICE - external script init
2017-06-06 11:18:47 [easycwmp] NOTICE - external: execute inform parameter
2017-06-06 11:18:47 [easycwmp] NOTICE - send Inform

Please let us know that we have missed anything in the configuration.

thanks!!
2017-06-06 11:18:38 [easycwmp] NOTICE - add event '0 BOOTSTRAP'
2017-06-06 11:18:38 [easycwmp] NOTICE - add event '1 BOOT'
2017-06-06 11:18:39 [easycwmp] NOTICE - external script init
config_init_local(55):: easycwmp.@local[0].interface=eth0
config_init_local(65):: easycwmp.@local[0].port=7547
config_init_local(83):: easycwmp.@local[0].ubus_socket=/var/run/ubus.sock
config_init_local(71):: easycwmp.@local[0].username=easycwmp
config_init_local(77):: easycwmp.@local[0].password=easycwmp
config_init_local(94):: easycwmp.@local[0].logging_level=3
config_init_acs(159):: easycwmp.@acs[0].url=http://192.168.0.25:8080/openacs/acs [^]
config_init_acs(165):: easycwmp.@acs[0].username=openacs
config_init_acs(171):: easycwmp.@acs[0].password=openacs
config_init_acs(177):: easycwmp.@acs[0].periodic_enable=1
config_init_acs(183):: easycwmp.@acs[0].periodic_interval=180
config_init_acs(190):: easycwmp.@acs[0].periodic_time=0001-01-01T00:00:00Z
2017-06-06 11:18:39 [easycwmp] NOTICE - init periodic inform: reference time = -62135625960, interval = 180
2017-06-06 11:18:39 [easycwmp] NOTICE - external: execute update_value_change
2017-06-06 11:18:39 [easycwmp] NOTICE - daemon started
2017-06-06 11:18:39 [easycwmp] NOTICE - external: execute inform device_id
2017-06-06 11:18:39 [easycwmp] NOTICE - external script exit
2017-06-06 11:18:39 [easycwmp] NOTICE - add event '1 BOOT'
2017-06-06 11:18:39 [easycwmp] NOTICE - interface eth0 has ip 192.168.0.25
No tags attached.
rar Desktop.rar (46,723) 2017-06-06 08:53
http://support.easycwmp.org/file_download.php?file_id=106&type=bug
Issue History
2017-06-06 08:53sunnyjames99New Issue
2017-06-06 08:53sunnyjames99File Added: Desktop.rar
2017-06-06 10:22mohamed.kallelNote Added: 0000738
2017-06-07 03:18sunnyjames99Note Added: 0000745
2017-06-07 10:22mohamed.kallelNote Added: 0000746
2017-06-08 03:46sunnyjames99Note Added: 0000747
2017-06-08 14:01mohamed.kallelStatusnew => resolved
2017-06-08 14:01mohamed.kallelResolutionopen => no change required
2017-06-08 14:01mohamed.kallelAssigned To => mohamed.kallel

Notes
(0000738)
mohamed.kallel   
2017-06-06 10:22   
It's OpenACS issue.
OpenAcs does not accept directely a new equipment. A new equipment should make 3 to 5 retry tr-069 sessions and then it will works fine.
Please let the cwmp client continu the retry session. and you will see in the 5th retry the session works fine
(0000745)
sunnyjames99   
2017-06-07 03:18   
Hi, kallel

Is it right to change option logging_level '3' to option logging_level '5' in /etc/config/easycwmp ?

Best Regards
(0000746)
mohamed.kallel   
2017-06-07 10:22   
You can see all possible logging levels in the original uci config file of easycwmp:


# easycwmp uci configuration

config local
    option interface eth0
    option port 7547
    option ubus_socket /var/run/ubus.sock
    option date_format %FT%T%z
    option username easycwmp
    option password easycwmp
    option provisioning_code ''
#Logging levels: Critic=0, Warning=1, Notice=2, Info=3, Debug=4 <------------here
    option logging_level '3'

config acs
    option url http://192.168.1.110:8080/openacs/acs [^]
    option username easycwmp
    option password easycwmp
    option parameter_key ''
    option periodic_enable '1'
    option periodic_interval '100'
    option periodic_time '0001-01-01T00:00:00Z'

config device
    option manufacturer easycwmp
    option oui FFFFFF
    option product_class easycwmp
    option serial_number FFFFFF123456
    option hardware_version example_hw_version
option software_version example_sw_version
(0000747)
sunnyjames99   
2017-06-08 03:46   
The current situation:
-----------------------------------------------------------------------------
--- SEND HTTP REQUEST ---
* About to connect() to 192.168.0.25 port 8080 (#0)
* Trying 192.168.0.25... * connected
> POST /openacs/acs HTTP/1.1
Host: 192.168.0.25:8080
User-Agent: easycwmp
Content-Type: text/xml; charset="utf-8"
Content-Length: 2925
Expect: 100-continue

< HTTP/1.1 100 Continue
* The requested URL returned error: 500
* Closing connection #0
2017-06-08 09:26:00 [easycwmp] NOTICE - LibCurl Error: The requested URL returned error: 500
2017-06-08 09:26:00 [easycwmp] NOTICE - sending http message failed
rpc_inform(218): sending Inform http message failed
2017-06-08 09:26:00 [easycwmp] NOTICE - sending Inform failed
2017-06-08 09:26:00 [easycwmp] NOTICE - external: execute apply service
2017-06-08 09:26:00 [easycwmp] NOTICE - external script exit
2017-06-08 09:26:00 [easycwmp] NOTICE - end session failed
2017-06-08 09:26:00 [easycwmp] NOTICE - retry session in 240 sec, RetryCount = 6
2017-06-08 09:27:00 [easycwmp] NOTICE - add event '2 PERIODIC'
2017-06-08 09:27:00 [easycwmp] NOTICE - start session
+++ HTTP CLIENT CONFIGURATION +++
http_client_init(50):: url: http://192.168.0.25:8080/openacs/acs [^]
http_client_init(56):: ssl_verify: SSL certificate validation disabled.
--- HTTP CLIENT CONFIGURATION ---
2017-06-08 09:27:00 [easycwmp] NOTICE - configured acs url http://192.168.0.25:8080/openacs/acs [^]
2017-06-08 09:27:00 [easycwmp] NOTICE - external script init
2017-06-08 09:27:00 [easycwmp] NOTICE - external: execute inform parameter
2017-06-08 09:27:00 [easycwmp] NOTICE - send Inform
+++ SEND HTTP REQUEST +++
<?xml version="1.0" encoding="UTF-8" standalone="no"?>
 <soap_env:Envelope
xmlns:soap_env="http://schemas.xmlsoap.org/soap/envelope/" [^]
xmlns:soap_enc="http://schemas.xmlsoap.org/soap/encoding/" [^]
xmlns:xsd="http://www.w3.org/2001/XMLSchema" [^]
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" [^]
xmlns:cwmp="urn:dslforum-org:cwmp-1-2">
  <soap_env:Header>
   <cwmp:ID soap_env:mustUnderstand="1">7</cwmp:ID>
  </soap_env:Header>
  <soap_env:Body>
   <cwmp:Inform>
    <DeviceId>
     <Manufacturer>easycwmp</Manufacturer>
     <OUI>FFFFFF</OUI>
     <ProductClass>easycwmp</ProductClass>
     <SerialNumber>FFFFFF123456</SerialNumber>
    </DeviceId>
    <Event soap_enc:arrayType="cwmp:EventStruct[3]">
     <EventStruct>
      <EventCode>0 BOOTSTRAP</EventCode>
      <CommandKey />
     </EventStruct>
     <EventStruct>
      <EventCode>1 BOOT</EventCode>
      <CommandKey />
     </EventStruct>
     <EventStruct>
      <EventCode>2 PERIODIC</EventCode>
      <CommandKey />
     </EventStruct>
    </Event>
    <MaxEnvelopes>1</MaxEnvelopes>
    <CurrentTime>2017-06-08T09:27:00+08:00</CurrentTime>
    <RetryCount>6</RetryCount>
    <ParameterList soap_enc:arrayType="cwmp:ParameterValueStruct[10]">
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.SpecVersion</Name>
      <Value xsi:type="xsd:string">1.0</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.ProvisioningCode</Name>
      <Value xsi:type="xsd:string"></Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.Manufacturer</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.ManufacturerOUI</Name>
      <Value xsi:type="xsd:string">FFFFFF</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.ProductClass</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.SerialNumber</Name>
      <Value xsi:type="xsd:string">FFFFFF123456</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.HardwareVersion</Name>
      <Value xsi:type="xsd:string">example_hw_version</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.DeviceInfo.SoftwareVersion</Name>
      <Value xsi:type="xsd:string">example_sw_version</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.ManagementServer.ConnectionRequestURL</Name>
      <Value xsi:type="xsd:string">http://192.168.0.25:7547/</Value> [^]
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>Device.ManagementServer.ParameterKey</Name>
      <Value xsi:type="xsd:string">1</Value>
     </ParameterValueStruct>
    </ParameterList>
   </cwmp:Inform>
  </soap_env:Body>
 </soap_env:Envelope>
-----------------------------------------------------------------------------
We think it should be connected, but OpenACS still did not find CPE.
We will test again with GenieACS, thanks!