View Issue Details

IDProjectCategoryView StatusLast Update
0000243easycwmpHelppublic2017-06-08 14:01
Reportersunnyjames99 Assigned Tomohamed.kallel  
PrioritynormalSeverityminorReproducibilityalways
Status resolvedResolutionno change required 
OSUbuntuOS Version12.04 
Summary0000243: [easycwmp] NOTICE - LibCurl Error: The requested URL returned error: 500
DescriptionWe 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!!
Additional Information2017-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
TagsNo tags attached.
e-mail notification

Activities

sunnyjames99

2017-06-06 08:53

reporter  

Desktop.rar (46,723 bytes)

mohamed.kallel

2017-06-06 10:22

administrator   ~0000738

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

sunnyjames99

2017-06-07 03:18

reporter   ~0000745

Hi, kallel

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

Best Regards

mohamed.kallel

2017-06-07 10:22

administrator   ~0000746

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

sunnyjames99

2017-06-08 03:46

reporter   ~0000747

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!

Issue History

Date Modified Username Field Change
2017-06-06 08:53 sunnyjames99 New Issue
2017-06-06 08:53 sunnyjames99 File Added: Desktop.rar
2017-06-06 10:22 mohamed.kallel Note Added: 0000738
2017-06-07 03:18 sunnyjames99 Note Added: 0000745
2017-06-07 10:22 mohamed.kallel Note Added: 0000746
2017-06-08 03:46 sunnyjames99 Note Added: 0000747
2017-06-08 14:01 mohamed.kallel Status new => resolved
2017-06-08 14:01 mohamed.kallel Resolution open => no change required
2017-06-08 14:01 mohamed.kallel Assigned To => mohamed.kallel