[genieacs] SAGEMCOM Fast2764 won't complete CWMP session (#74)

Wed Feb 25 16:50:44 EST 2015

Jorge,
  I hope you don't mind, but I connected my CPE to your ACS so I could see
for myself whats going on.

There is an issue with you proxy/acs implementation. Check your apache
logs, and your Genie cwmp logs. The proxy is returning a HTTP 503 error.

POST / HTTP/1.1
Host: acs.wifigvt.com.br:80
User-Agent: CV2_TR69_CPE_04_00
Connection: keep-alive
SOAPAction:
Content-Type: text/xml
Content-Length: 3316

<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-0">
    ***snip***
    </SOAP-ENV:Body>
</SOAP-ENV:Envelope>

*HTTP/1.1 503 Service Unavailable*
Content-Type: text/html; charset=iso-8859-1
Date: Wed, 25 Feb 2015 21:46:14 GMT
*Server: Apache*
Content-Length: 299
Connection: keep-alive

On Wed, Feb 25, 2015 at 12:44 PM, Dan Morphis <dan at milkcarton.com> wrote:

> Ignore my previous message.
>
> I saw the refreshObject and thought it was timing out on the refresh.
>
> I reviewed the logs on pastebin and it looks like you have a proxy setup
> between the ACS and the CPE. Is it possible to test without the proxy in
> the middle?
>
> -dan
>
> On Wed, Feb 25, 2015 at 12:37 PM, Dan Morphis <dan at milkcarton.com> wrote:
>
>> Lets take this to the mailing list.
>>
>> Try adjusting the GET_PARAMETER_NAMES_DEPTH_THRESHOLD and SESSION_TIMEOUT
>> values. The wiki
>> <https://github.com/zaidka/genieacs/wiki/GenieACS-General-Config> has
>> information on more information on the values to try. Remember to restart
>> the cwmp worker after adjusting the values.
>>
>> -dan
>>
>>
>> On Wed, Feb 25, 2015 at 12:14 PM, Jorge Pereira <notifications at github.com
>> > wrote:
>>
>>> I'm not sure if this is a client issue, or a problem with GenieACS - but
>>> I don't have another ACS to test against.
>>>
>>> The Inform on boot from the SAGEMCOM Fast2764 never completes, so the
>>> client just sits retrying. The full parameter list does not get downloaded.
>>>
>>> From genieacs-cwmp output:
>>> grep 001BBF-Fast2764GV-NQ1121407033212 /var/log/genieacs-cwmp.log
>>>
>>> 25 Feb 18:02:55 - 001BBF-Fast2764GV-NQ1121407033212: Inform (2
>>> PERIODIC); retry count 0
>>> 25 Feb 18:02:55 - 001BBF-Fast2764GV-NQ1121407033212: New device
>>> registered
>>> 25 Feb 18:02:56 - 001BBF-Fast2764GV-NQ1121407033212: Started task
>>> refreshObject(54ee387f298c11d31080c7bc)
>>> 25 Feb 18:02:56 - 001BBF-Fast2764GV-NQ1121407033212: Fault response for
>>> task 54ee387f298c11d31080c7bc. Retrying after 300 seconds.
>>> 25 Feb 18:04:58 - 001BBF-Fast2764GV-NQ1121407033212: Inform (2
>>> PERIODIC); retry count 0
>>> 25 Feb 18:04:58 - 001BBF-Fast2764GV-NQ1121407033212: New device
>>> registered
>>> 25 Feb 18:04:58 - 001BBF-Fast2764GV-NQ1121407033212: Started task
>>> refreshObject(54ee38fa298c11d31080c7be)
>>> 25 Feb 18:04:58 - 001BBF-Fast2764GV-NQ1121407033212: Fault response for
>>> task 54ee38fa298c11d31080c7be. Retrying after 300 seconds.
>>> 25 Feb 18:07:00 - 001BBF-Fast2764GV-NQ1121407033212: Inform (2
>>> PERIODIC); retry count 0
>>> 25 Feb 18:08:54 - 001BBF-Fast2764GV-NQ1121407033212: Inform (2
>>> PERIODIC); retry count 0
>>>
>>> below the content of debug or can see in textbin link at
>>> http://pastebin.com/c4aKz6p6
>>>
>>> [root at GVT-PROD-ACS-1A debug]# cat
>>> $PWD/001BBF-Fast2764GV-NQ1121407033212.dump
>>> REQUEST Wed Feb 25 2015 18:02:55 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.246","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"3009"}
>>> <?xml version="1.0" encoding="UTF-8"?>SOAP-ENV:HeaderSAGEM_ID_7588
>>> /cwmp:ID/SOAP-ENV:HeaderSOAP-ENV:Bodycwmp:InformSAGEMCOM001BBFFast2764GVNQ11214070332122
>>> PERIODIC12015-02-25T21:04:28Z0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANPPPConnection.1.ExternalIPAddress177.204.117.111InternetGatewayDevice.ManagementServer.ConnectionRequestURL
>>> http://177.204.117.111:58603/bc0ixdwivirge3m3InternetGatewayDevice.DeviceInfo.HardwareVersion2764-000000-001InternetGatewayDevice.DeviceInfo.SoftwareVersionv82B0-v8480InternetGatewayDevice.DeviceInfo.SpecVersion1.0InternetGatewayDevice.DeviceInfo.ProvisioningCodeInternetGatewayDevice.X_001BBF_WanModeadslInternetGatewayDevice.DeviceSummaryInternetGatewayDevice:1.0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.4.WANIPConnection.1.ExternalIPAddress1.0.0.1InternetGatewayDevice.WANDevice.1.WANConnectionDevice.3.WANIPCon
>>> nection.1.ExternalIPAddress10.20.72.215InternetGatewayDevice.WANDevice.1.WANConnectionDevice.2.WANIPConnection.1.ExternalIPAddress10.148.50.4InternetGatewayDevice.ManagementServer.ParameterKey
>>> /cwmp:Inform/SOAP-ENV:Body/SOAP-ENV:Envelope
>>> RESPONSE Wed Feb 25 2015 18:02:55 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0","Content-Type":"text/xml;
>>> charset=\"utf-8\"","Set-Cookie":"session=f99df6e334865d75"}
>>> <?xml version="1.0" encoding="UTF-8"?>
>>> soap-env:HeaderSAGEM_ID_7588/cwmp:ID/soap-env:Header
>>> soap-env:Bodycwmp:InformResponse1/cwmp:InformResponse/soap-env:Body
>>> /soap-env:Envelope
>>> REQUEST Wed Feb 25 2015 18:02:56 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","cookie":"session=f99df6e334865d75","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.246","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"0"}
>>> RESPONSE Wed Feb 25 2015 18:02:56 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0","Content-Type":"text/xml;
>>> charset=\"utf-8\""}
>>> <?xml version="1.0" encoding="UTF-8"?>
>>> soap-env:Header54ee387f298c11d31080c7bc/cwmp:ID/soap-env:Header
>>> soap-env:Bodycwmp:GetParameterNames0/cwmp:GetParameterNames
>>> /soap-env:Body/soap-env:Envelope
>>> REQUEST Wed Feb 25 2015 18:02:56 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","cookie":"session=f99df6e334865d75","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.246","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"687"}
>>> <?xml version="1.0"
>>> encoding="UTF-8"?>SOAP-ENV:Header54ee387f298c11d31080c7bc/cwmp:ID
>>> /SOAP-ENV:HeaderSOAP-ENV:BodySOAP-ENV:FaultClientCWMP
>>> faultcwmp:Fault9005Invalid parameter name/cwmp:Fault/SOAP-ENV:Fault
>>> /SOAP-ENV:Body/SOAP-ENV:Envelope
>>> RESPONSE Wed Feb 25 2015 18:02:56 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0"}
>>> REQUEST Wed Feb 25 2015 18:04:58 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.196","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"3009"}
>>> <?xml version="1.0" encoding="UTF-8"?>SOAP-ENV:HeaderSAGEM_ID_7589
>>> /cwmp:ID/SOAP-ENV:HeaderSOAP-ENV:Bodycwmp:InformSAGEMCOM001BBFFast2764GVNQ11214070332122
>>> PERIODIC12015-02-25T21:06:31Z0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANPPPConnection.1.ExternalIPAddress177.204.117.111InternetGatewayDevice.ManagementServer.ConnectionRequestURL
>>> http://177.204.117.111:58603/bc0ixdwivirge3m3InternetGatewayDevice.DeviceInfo.HardwareVersion2764-000000-001InternetGatewayDevice.DeviceInfo.SoftwareVersionv82B0-v8480InternetGatewayDevice.DeviceInfo.SpecVersion1.0InternetGatewayDevice.DeviceInfo.ProvisioningCodeInternetGatewayDevice.X_001BBF_WanModeadslInternetGatewayDevice.DeviceSummaryInternetGatewayDevice:1.0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.4.WANIPConnection.1.ExternalIPAddress1.0.0.1InternetGatewayDevice.WANDevice.1.WANConnectionDevice.3.WANIPCon
>>> nection.1.ExternalIPAddress10.20.72.215InternetGatewayDevice.WANDevice.1.WANConnectionDevice.2.WANIPConnection.1.ExternalIPAddress10.148.50.4InternetGatewayDevice.ManagementServer.ParameterKey
>>> /cwmp:Inform/SOAP-ENV:Body/SOAP-ENV:Envelope
>>> RESPONSE Wed Feb 25 2015 18:04:58 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0","Content-Type":"text/xml;
>>> charset=\"utf-8\"","Set-Cookie":"session=e77c6e211bca4181"}
>>> <?xml version="1.0" encoding="UTF-8"?>
>>> soap-env:HeaderSAGEM_ID_7589/cwmp:ID/soap-env:Header
>>> soap-env:Bodycwmp:InformResponse1/cwmp:InformResponse/soap-env:Body
>>> /soap-env:Envelope
>>> REQUEST Wed Feb 25 2015 18:04:58 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","cookie":"session=e77c6e211bca4181","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.196","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"0"}
>>> RESPONSE Wed Feb 25 2015 18:04:58 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0","Content-Type":"text/xml;
>>> charset=\"utf-8\""}
>>> <?xml version="1.0" encoding="UTF-8"?>
>>> soap-env:Header54ee38fa298c11d31080c7be/cwmp:ID/soap-env:Header
>>> soap-env:Bodycwmp:GetParameterNames0/cwmp:GetParameterNames
>>> /soap-env:Body/soap-env:Envelope
>>> REQUEST Wed Feb 25 2015 18:04:58 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","cookie":"session=e77c6e211bca4181","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.196","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"687"}
>>> <?xml version="1.0"
>>> encoding="UTF-8"?>SOAP-ENV:Header54ee38fa298c11d31080c7be/cwmp:ID
>>> /SOAP-ENV:HeaderSOAP-ENV:BodySOAP-ENV:FaultClientCWMP
>>> faultcwmp:Fault9005Invalid parameter name/cwmp:Fault/SOAP-ENV:Fault
>>> /SOAP-ENV:Body/SOAP-ENV:Envelope
>>> RESPONSE Wed Feb 25 2015 18:04:58 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0"}
>>> REQUEST Wed Feb 25 2015 18:07:00 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.246","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"3009"}
>>> <?xml version="1.0" encoding="UTF-8"?>SOAP-ENV:HeaderSAGEM_ID_7590
>>> /cwmp:ID/SOAP-ENV:HeaderSOAP-ENV:Bodycwmp:InformSAGEMCOM001BBFFast2764GVNQ11214070332122
>>> PERIODIC12015-02-25T21:08:35Z0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANPPPConnection.1.ExternalIPAddress177.204.117.111InternetGatewayDevice.ManagementServer.ConnectionRequestURL
>>> http://177.204.117.111:58603/bc0ixdwivirge3m3InternetGatewayDevice.DeviceInfo.HardwareVersion2764-000000-001InternetGatewayDevice.DeviceInfo.SoftwareVersionv82B0-v8480InternetGatewayDevice.DeviceInfo.SpecVersion1.0InternetGatewayDevice.DeviceInfo.ProvisioningCodeInternetGatewayDevice.X_001BBF_WanModeadslInternetGatewayDevice.DeviceSummaryInternetGatewayDevice:1.0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.4.WANIPConnection.1.ExternalIPAddress1.0.0.1InternetGatewayDevice.WANDevice.1.WANConnectionDevice.3.WANIPCon
>>> nection.1.ExternalIPAddress10.20.72.215InternetGatewayDevice.WANDevice.1.WANConnectionDevice.2.WANIPConnection.1.ExternalIPAddress10.148.50.4InternetGatewayDevice.ManagementServer.ParameterKey
>>> /cwmp:Inform/SOAP-ENV:Body/SOAP-ENV:Envelope
>>> RESPONSE Wed Feb 25 2015 18:07:00 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0","Content-Type":"text/xml;
>>> charset=\"utf-8\"","Set-Cookie":"session=c624f8a1d8383b10"}
>>> <?xml version="1.0" encoding="UTF-8"?>
>>> soap-env:HeaderSAGEM_ID_7590/cwmp:ID/soap-env:Header
>>> soap-env:Bodycwmp:InformResponse1/cwmp:InformResponse/soap-env:Body
>>> /soap-env:Envelope
>>> REQUEST Wed Feb 25 2015 18:07:01 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","cookie":"session=c624f8a1d8383b10","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.246","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"0"}
>>> RESPONSE Wed Feb 25 2015 18:07:01 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0"}
>>> REQUEST Wed Feb 25 2015 18:08:54 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.246","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"3009"}
>>> <?xml version="1.0" encoding="UTF-8"?>SOAP-ENV:HeaderSAGEM_ID_7591
>>> /cwmp:ID/SOAP-ENV:HeaderSOAP-ENV:Bodycwmp:InformSAGEMCOM001BBFFast2764GVNQ11214070332122
>>> PERIODIC12015-02-25T21:10:29Z0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANPPPConnection.1.ExternalIPAddress177.204.117.111InternetGatewayDevice.ManagementServer.ConnectionRequestURL
>>> http://177.204.117.111:58603/bc0ixdwivirge3m3InternetGatewayDevice.DeviceInfo.HardwareVersion2764-000000-001InternetGatewayDevice.DeviceInfo.SoftwareVersionv82B0-v8480InternetGatewayDevice.DeviceInfo.SpecVersion1.0InternetGatewayDevice.DeviceInfo.ProvisioningCodeInternetGatewayDevice.X_001BBF_WanModeadslInternetGatewayDevice.DeviceSummaryInternetGatewayDevice:1.0InternetGatewayDevice.WANDevice.1.WANConnectionDevice.4.WANIPConnection.1.ExternalIPAddress1.0.0.1InternetGatewayDevice.WANDevice.1.WANConnectionDevice.3.WANIPCon
>>> nection.1.ExternalIPAddress10.20.72.215InternetGatewayDevice.WANDevice.1.WANConnectionDevice.2.WANIPConnection.1.ExternalIPAddress10.148.50.4InternetGatewayDevice.ManagementServer.ParameterKey
>>> /cwmp:Inform/SOAP-ENV:Body/SOAP-ENV:Envelope
>>> RESPONSE Wed Feb 25 2015 18:08:54 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0","Content-Type":"text/xml;
>>> charset=\"utf-8\"","Set-Cookie":"session=36cd64876b619a99"}
>>> <?xml version="1.0" encoding="UTF-8"?>
>>> soap-env:HeaderSAGEM_ID_7591/cwmp:ID/soap-env:Header
>>> soap-env:Bodycwmp:InformResponse1/cwmp:InformResponse/soap-env:Body
>>> /soap-env:Envelope
>>> REQUEST Wed Feb 25 2015 18:08:55 GMT-0300 (BRT)
>>>
>>> {"host":"localhost:7547","content-type":"text/xml;
>>> charset=utf-8","cookie":"session=36cd64876b619a99","soapaction":"","user-agent":"gSOAP/2.7","x-forwarded-for":"177.204.117.111,
>>> 172.19.1.246","x-forwarded-port":"80","x-forwarded-proto":"http","x-forwarded-host":"
>>> acs.wifigvt.com.br","x-forwarded-server":"acs.wifigvt.com.br
>>> ","connection":"Keep-Alive","content-length":"0"}
>>> RESPONSE Wed Feb 25 2015 18:08:55 GMT-0300 (BRT)
>>>
>>> {"Server":"GenieACS/1.0.0","SOAPServer":"GenieACS/1.0.0"}
>>>
>>> [root at GVT-PROD-ACS-1A debug]#
>>>
>>> —
>>> Reply to this email directly or view it on GitHub
>>> <https://github.com/zaidka/genieacs/issues/74>.
>>>
>>
>>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.genieacs.com/pipermail/users/attachments/20150225/70e2e1c5/attachment-0001.html>