View Issue Details
ID | Project | Category | View Status | Date Submitted | Last Update |
---|---|---|---|---|---|
0000350 | easycwmp | Bug | public | 2018-08-10 15:02 | 2018-10-01 09:01 |
Reporter | okraits | Assigned To | |||
Priority | high | Severity | major | Reproducibility | always |
Status | new | Resolution | open | ||
OS | OpenWRT | OS Version | LEDE 17.01 | ||
Summary | 0000350: easycwmp doesn't send a DownloadResponse for the second of two consecutive downloads | ||||
Description | If two download requests appear within a short period of time, then easycwmp doesn't send a DownloadResponse for the second download request. Thus the ACS thinks that the download request wasn't successful and retries the whole session. | ||||
Steps To Reproduce | Send two download requests to easycwmp closely one after the other. | ||||
Additional Information | Attached you find the ACS log and the easycwmp log. | ||||
Tags | No tags attached. | ||||
e-mail notification | |||||
|
easycwmp.log (11,464 bytes)
Fri Aug 10 14:25:55 2018 daemon.notice easycwmpd: add event '2 PERIODIC' Fri Aug 10 14:25:55 2018 daemon.notice easycwmpd: start session Fri Aug 10 14:25:55 2018 daemon.notice easycwmpd: configured acs url https://host:7547/genieacs Fri Aug 10 14:25:55 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:25:56 2018 daemon.notice easycwmpd: external: execute inform parameter Fri Aug 10 14:25:57 2018 daemon.notice easycwmpd: send Inform Fri Aug 10 14:25:57 2018 daemon.notice easycwmpd: receive InformResponse from the ACS Fri Aug 10 14:25:57 2018 daemon.notice easycwmpd: send empty message to the ACS Fri Aug 10 14:25:57 2018 daemon.notice easycwmpd: receive empty message from the ACS Fri Aug 10 14:25:57 2018 daemon.notice easycwmpd: external: execute apply service Fri Aug 10 14:25:58 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:25:58 2018 daemon.notice easycwmpd: end session success Fri Aug 10 14:26:55 2018 daemon.notice easycwmpd: add event '2 PERIODIC' Fri Aug 10 14:26:55 2018 daemon.notice easycwmpd: start session Fri Aug 10 14:26:55 2018 daemon.notice easycwmpd: configured acs url https://host:7547/genieacs Fri Aug 10 14:26:55 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:26:56 2018 daemon.notice easycwmpd: external: execute inform parameter Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: send Inform Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: receive InformResponse from the ACS Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: send empty message to the ACS Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: received Download method from the ACS Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: add download: delay = 0 sec, url = https://host:7567/test, FileType = '12 Other File', CommandKey = '16523cdd9bd0000' Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: send DownloadResponse to the ACS Fri Aug 10 14:26:58 2018 daemon.notice easycwmpd: received Download method from the ACS Fri Aug 10 14:26:58 2018 daemon.notice easycwmpd: add download: delay = 0 sec, url = https://host:7567/test_1533903958.1886573.sh, FileType = '11 Shell Script', CommandKey = '16523cdd9bd0100' Fri Aug 10 14:26:58 2018 daemon.notice easycwmpd: send DownloadResponse to the ACS Fri Aug 10 14:27:05 2018 daemon.notice easycwmpd: add download: delay = -8 sec, url = https://host:7567/test, FileType = '12 Other File', CommandKey = '16523cdd9bd0000' Fri Aug 10 14:27:05 2018 daemon.notice easycwmpd: add download: delay = -7 sec, url = https://host:7567/test_1533903958.1886573.sh, FileType = '11 Shell Script', CommandKey = '16523cdd9bd0100' Fri Aug 10 14:27:05 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: init periodic inform: reference time = n/a, interval = 60 Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: daemon started Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: external: execute inform device_id Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: add event '1 BOOT' Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: interface eth0 has ip 10.2.30.1 Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: http server initialized Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: entering main loop Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: start download url = https://host:7567/test, FileType = '12 Other File', CommandKey = '16523cdd9bd0000' Fri Aug 10 14:27:06 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:07 2018 daemon.notice easycwmpd: external: execute download Fri Aug 10 14:27:08 2018 daemon.notice easycwmpd: add event '7 TRANSFER COMPLETE' Fri Aug 10 14:27:08 2018 daemon.notice easycwmpd: add event 'M Download' Fri Aug 10 14:27:08 2018 daemon.notice easycwmpd: external: execute apply download 12 Other File Fri Aug 10 14:27:15 2018 daemon.notice easycwmpd: add download: delay = -17 sec, url = https://host:7567/test_1533903958.1886573.sh, FileType = '', CommandKey = '16523cdd9bd0100' Fri Aug 10 14:27:15 2018 daemon.notice easycwmpd: add event '1 BOOT' Fri Aug 10 14:27:15 2018 daemon.notice easycwmpd: add event '7 TRANSFER COMPLETE' Fri Aug 10 14:27:15 2018 daemon.notice easycwmpd: add event 'M Download' Fri Aug 10 14:27:15 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: init periodic inform: reference time = n/a, interval = 60 Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: daemon started Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: external: execute inform device_id Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: add event '1 BOOT' Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: interface eth0 has ip 10.2.30.1 Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: http server initialized Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: entering main loop Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: start download url = https://host:7567/test_1533903958.1886573.sh, FileType = '', CommandKey = '16523cdd9bd0100' Fri Aug 10 14:27:16 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: external: execute download Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: add event '7 TRANSFER COMPLETE' Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: add event 'M Download' Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: download error: 'Download failure' Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: start session Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: configured acs url https://host:7547/genieacs Fri Aug 10 14:27:17 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:18 2018 daemon.notice easycwmpd: external: execute inform parameter Fri Aug 10 14:27:18 2018 daemon.notice easycwmpd: send Inform Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: receive InformResponse from the ACS Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: send RPC ACS TransferComplete Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: receive TransferCompleteResponse from the ACS Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: send RPC ACS TransferComplete Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: receive TransferCompleteResponse from the ACS Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: send empty message to the ACS Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: received Download method from the ACS Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: add download: delay = 0 sec, url = https://host:7567/test, FileType = '12 Other File', CommandKey = '16523ce2d060000' Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: send DownloadResponse to the ACS Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: received Download method from the ACS Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: add download: delay = 0 sec, url = https://host:7567/test_1533903958.1886573.sh, FileType = '11 Shell Script', CommandKey = '16523ce2d060100' Fri Aug 10 14:27:19 2018 daemon.notice easycwmpd: send DownloadResponse to the ACS Fri Aug 10 14:27:26 2018 daemon.notice easycwmpd: add download: delay = -7 sec, url = https://host:7567/test, FileType = '12 Other File', CommandKey = '16523ce2d060000' Fri Aug 10 14:27:26 2018 daemon.notice easycwmpd: add download: delay = -7 sec, url = https://host:7567/test_1533903958.1886573.sh, FileType = '11 Shell Script', CommandKey = '16523ce2d060100' Fri Aug 10 14:27:26 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: init periodic inform: reference time = n/a, interval = 60 Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: daemon started Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: external: execute inform device_id Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: add event '1 BOOT' Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: interface eth0 has ip 10.2.30.1 Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: http server initialized Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: entering main loop Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: start download url = https://host:7567/test, FileType = '12 Other File', CommandKey = '16523ce2d060000' Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: external: execute download Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: add event '7 TRANSFER COMPLETE' Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: add event 'M Download' Fri Aug 10 14:27:27 2018 daemon.notice easycwmpd: external: execute apply download 12 Other File Fri Aug 10 14:27:35 2018 daemon.notice easycwmpd: add download: delay = -16 sec, url = https://host:7567/test_1533903958.1886573.sh, FileType = '', CommandKey = '16523ce2d060100' Fri Aug 10 14:27:35 2018 daemon.notice easycwmpd: add event '1 BOOT' Fri Aug 10 14:27:35 2018 daemon.notice easycwmpd: add event '7 TRANSFER COMPLETE' Fri Aug 10 14:27:35 2018 daemon.notice easycwmpd: add event 'M Download' Fri Aug 10 14:27:35 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: init periodic inform: reference time = n/a, interval = 60 Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: daemon started Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: external: execute inform device_id Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: add event '1 BOOT' Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: interface eth0 has ip 10.2.30.1 Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: http server initialized Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: entering main loop Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: start download url = https://host:7567/test_1533903958.1886573.sh, FileType = '', CommandKey = '16523ce2d060100' Fri Aug 10 14:27:36 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: external: execute download Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: add event '7 TRANSFER COMPLETE' Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: add event 'M Download' Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: download error: 'Download failure' Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: start session Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: configured acs url https://host:7547/genieacs Fri Aug 10 14:27:37 2018 daemon.notice easycwmpd: external script init Fri Aug 10 14:27:38 2018 daemon.notice easycwmpd: external: execute inform parameter Fri Aug 10 14:27:38 2018 daemon.notice easycwmpd: send Inform Fri Aug 10 14:27:39 2018 daemon.notice easycwmpd: LibCurl Error: The requested URL returned error: 502 Bad Gateway Fri Aug 10 14:27:39 2018 daemon.notice easycwmpd: sending http message failed Fri Aug 10 14:27:39 2018 daemon.notice easycwmpd: sending Inform failed Fri Aug 10 14:27:39 2018 daemon.notice easycwmpd: external: execute apply service Fri Aug 10 14:27:39 2018 daemon.notice easycwmpd: external script exit Fri Aug 10 14:27:39 2018 daemon.notice easycwmpd: end session failed Fri Aug 10 14:27:39 2018 daemon.notice easycwmpd: retry session in 7 sec, RetryCount = 1 |
|
acs.log (21,647 bytes)
# REQUEST Fri Aug 10 2018 14:25:57 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"3065","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\""} <?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">354</cwmp:ID> </soap_env:Header> <soap_env:Body> <cwmp:Inform> <DeviceId> <Manufacturer>WE</Manufacturer> <OUI>000000</OUI> <ProductClass>Homer</ProductClass> <SerialNumber>serial</SerialNumber> </DeviceId> <Event soap_enc:arrayType="cwmp:EventStruct[1]"> <EventStruct> <EventCode>2 PERIODIC</EventCode> <CommandKey /> </EventStruct> </Event> <MaxEnvelopes>1</MaxEnvelopes> <CurrentTime>2018-08-10T14:25:56+02:00</CurrentTime> <RetryCount>0</RetryCount> <ParameterList soap_enc:arrayType="cwmp:ParameterValueStruct[11]"> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.Manufacturer</Name> <Value xsi:type="xsd:string">WE</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ManufacturerOUI</Name> <Value xsi:type="xsd:string">000000</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ProductClass</Name> <Value xsi:type="xsd:string">Homer</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SerialNumber</Name> <Value xsi:type="xsd:string">serial</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SpecVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.ManagementServer.ConnectionRequestURL</Name> <Value xsi:type="xsd:string">http://10.2.30.1:7547/</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.ManagementServer.ParameterKey</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.1.ExternalIPAddress</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> </ParameterList> </cwmp:Inform> </soap_env:Body> </soap_env:Envelope> # RESPONSE Fri Aug 10 2018 14:25:57 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\"","Set-Cookie":"session=73ac121c4940ee9a"} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">354</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:InformResponse><MaxEnvelopes>1</MaxEnvelopes></cwmp:InformResponse></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:25:57 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"0","cookie":"session=73ac121c4940ee9a","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\""} # RESPONSE Fri Aug 10 2018 14:25:57 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Connection":"close"} # REQUEST Fri Aug 10 2018 14:26:57 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"3065","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\""} <?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">355</cwmp:ID> </soap_env:Header> <soap_env:Body> <cwmp:Inform> <DeviceId> <Manufacturer>WE</Manufacturer> <OUI>000000</OUI> <ProductClass>Homer</ProductClass> <SerialNumber>serial</SerialNumber> </DeviceId> <Event soap_enc:arrayType="cwmp:EventStruct[1]"> <EventStruct> <EventCode>2 PERIODIC</EventCode> <CommandKey /> </EventStruct> </Event> <MaxEnvelopes>1</MaxEnvelopes> <CurrentTime>2018-08-10T14:26:56+02:00</CurrentTime> <RetryCount>0</RetryCount> <ParameterList soap_enc:arrayType="cwmp:ParameterValueStruct[11]"> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.Manufacturer</Name> <Value xsi:type="xsd:string">WE</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ManufacturerOUI</Name> <Value xsi:type="xsd:string">000000</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ProductClass</Name> <Value xsi:type="xsd:string">Homer</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SerialNumber</Name> <Value xsi:type="xsd:string">serial</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SpecVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.ManagementServer.ConnectionRequestURL</Name> <Value xsi:type="xsd:string">http://10.2.30.1:7547/</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.ManagementServer.ParameterKey</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.1.ExternalIPAddress</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> </ParameterList> </cwmp:Inform> </soap_env:Body> </soap_env:Envelope> # RESPONSE Fri Aug 10 2018 14:26:57 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\"","Set-Cookie":"session=ff31495f7038ec82"} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">355</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:InformResponse><MaxEnvelopes>1</MaxEnvelopes></cwmp:InformResponse></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:26:57 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"0","cookie":"session=ff31495f7038ec82","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\""} # RESPONSE Fri Aug 10 2018 14:26:57 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\""} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">16523cdd9bd0000</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:Download><CommandKey>16523cdd9bd0000</CommandKey><FileType>12 Other File</FileType><URL>https://host:7567/test</URL><Username/><Password/><FileSize>14</FileSize><TargetFileName/><DelaySeconds>0</DelaySeconds><SuccessURL/><FailureURL/></cwmp:Download></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:26:58 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"678","cookie":"session=ff31495f7038ec82","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\"","soapaction":""} <?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">16523cdd9bd0000</cwmp:ID> </soap_env:Header> <soap_env:Body> <cwmp:DownloadResponse> <Status>1</Status> <StartTime>0001-01-01T00:00:00Z</StartTime> <CompleteTime>0001-01-01T00:00:00Z</CompleteTime> </cwmp:DownloadResponse> </soap_env:Body> </soap_env:Envelope> # RESPONSE Fri Aug 10 2018 14:26:58 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\""} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">16523cdd9bd0100</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:Download><CommandKey>16523cdd9bd0100</CommandKey><FileType>11 Shell Script</FileType><URL>https://host:7567/test_1533903958.1886573.sh</URL><Username/><Password/><FileSize>0</FileSize><TargetFileName/><DelaySeconds>0</DelaySeconds><SuccessURL/><FailureURL/></cwmp:Download></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"3420","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\""} <?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">1</cwmp:ID> </soap_env:Header> <soap_env:Body> <cwmp:Inform> <DeviceId> <Manufacturer>WE</Manufacturer> <OUI>000000</OUI> <ProductClass>Homer</ProductClass> <SerialNumber>serial</SerialNumber> </DeviceId> <Event soap_enc:arrayType="cwmp:EventStruct[4]"> <EventStruct> <EventCode>1 BOOT</EventCode> <CommandKey /> </EventStruct> <EventStruct> <EventCode>7 TRANSFER COMPLETE</EventCode> <CommandKey /> </EventStruct> <EventStruct> <EventCode>M Download</EventCode> <CommandKey>16523cdd9bd0000</CommandKey> </EventStruct> <EventStruct> <EventCode>M Download</EventCode> <CommandKey>16523cdd9bd0100</CommandKey> </EventStruct> </Event> <MaxEnvelopes>1</MaxEnvelopes> <CurrentTime>2018-08-10T14:27:18+02:00</CurrentTime> <RetryCount>0</RetryCount> <ParameterList soap_enc:arrayType="cwmp:ParameterValueStruct[11]"> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.Manufacturer</Name> <Value xsi:type="xsd:string">WE</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ManufacturerOUI</Name> <Value xsi:type="xsd:string">000000</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ProductClass</Name> <Value xsi:type="xsd:string">Homer</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SerialNumber</Name> <Value xsi:type="xsd:string">serial</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.DeviceInfo.SpecVersion</Name> <Value xsi:type="xsd:string">1.0</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.ManagementServer.ConnectionRequestURL</Name> <Value xsi:type="xsd:string">http://10.2.30.1:7547/</Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.ManagementServer.ParameterKey</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> <ParameterValueStruct> <Name>InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.1.ExternalIPAddress</Name> <Value xsi:type="xsd:string"></Value> </ParameterValueStruct> </ParameterList> </cwmp:Inform> </soap_env:Body> </soap_env:Envelope> # RESPONSE Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\"","Set-Cookie":"session=672c8647267e015c"} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">1</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:InformResponse><MaxEnvelopes>1</MaxEnvelopes></cwmp:InformResponse></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"784","cookie":"session=672c8647267e015c","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\"","soapaction":""} <?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">2</cwmp:ID> </soap_env:Header> <soap_env:Body> <cwmp:TransferComplete> <CommandKey>16523cdd9bd0000</CommandKey> <FaultStruct> <FaultCode>0</FaultCode> <FaultString /> </FaultStruct> <StartTime>2018-08-10T14:27:07+02:00</StartTime> <CompleteTime>2018-08-10T14:27:15+02:00</CompleteTime> </cwmp:TransferComplete> </soap_env:Body> </soap_env:Envelope> # RESPONSE Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\""} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">2</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:TransferCompleteResponse/></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"815","cookie":"session=672c8647267e015c","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\"","soapaction":""} <?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">3</cwmp:ID> </soap_env:Header> <soap_env:Body> <cwmp:TransferComplete> <CommandKey>16523cdd9bd0100</CommandKey> <FaultStruct> <FaultCode>9010</FaultCode> <FaultString>Download failure</FaultString> </FaultStruct> <StartTime>2018-08-10T14:27:17+02:00</StartTime> <CompleteTime>2018-08-10T14:27:17+02:00</CompleteTime> </cwmp:TransferComplete> </soap_env:Body> </soap_env:Envelope> # RESPONSE Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\""} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">3</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:TransferCompleteResponse/></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"0","cookie":"session=672c8647267e015c","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\""} # RESPONSE Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\""} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">16523ce2d060000</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:Download><CommandKey>16523ce2d060000</CommandKey><FileType>12 Other File</FileType><URL>https://host:7567/test</URL><Username/><Password/><FileSize>14</FileSize><TargetFileName/><DelaySeconds>0</DelaySeconds><SuccessURL/><FailureURL/></cwmp:Download></soap-env:Body></soap-env:Envelope> # REQUEST Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"host":"host","connection":"close","content-length":"678","cookie":"session=672c8647267e015c","user-agent":"easycwmp","content-type":"text/xml; charset=\"utf-8\"","soapaction":""} <?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">16523ce2d060000</cwmp:ID> </soap_env:Header> <soap_env:Body> <cwmp:DownloadResponse> <Status>1</Status> <StartTime>0001-01-01T00:00:00Z</StartTime> <CompleteTime>0001-01-01T00:00:00Z</CompleteTime> </cwmp:DownloadResponse> </soap_env:Body> </soap_env:Envelope> # RESPONSE Fri Aug 10 2018 14:27:19 GMT+0200 (CEST) {"Server":"GenieACS/1.1.1","SOAPServer":"GenieACS/1.1.1","Content-Type":"text/xml; charset=\"utf-8\""} <?xml version="1.0" encoding="UTF-8"?> <soap-env:Envelope xmlns:soap-enc="http://schemas.xmlsoap.org/soap/encoding/" xmlns:soap-env="http://schemas.xmlsoap.org/soap/envelope/" 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">16523ce2d060100</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:Download><CommandKey>16523ce2d060100</CommandKey><FileType>11 Shell Script</FileType><URL>https://host:7567/test_1533903958.1886573.sh</URL><Username/><Password/><FileSize>0</FileSize><TargetFileName/><DelaySeconds>0</DelaySeconds><SuccessURL/><FailureURL/></cwmp:Download></soap-env:Body></soap-env:Envelope> |
|
I checked the easycwmp.log. The easycwmp is sending the download response for each download request: Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: received Download method from the ACS Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: add download: delay = 0 sec, url = https://host:7567/test, FileType = '12 Other File', CommandKey = '16523cdd9bd0000' Fri Aug 10 14:26:57 2018 daemon.notice easycwmpd: send DownloadResponse to the ACS Fri Aug 10 14:26:58 2018 daemon.notice easycwmpd: received Download method from the ACS Fri Aug 10 14:26:58 2018 daemon.notice easycwmpd: add download: delay = 0 sec, url = https://host:7567/test_1533903958.1886573.sh, FileType = '11 Shell Script', CommandKey = '16523cdd9bd0100' Fri Aug 10 14:26:58 2018 daemon.notice easycwmpd: send DownloadResponse to the ACS |
|
Well, it says so in the log but if you look at the SOAP XML log in acs.log you see that easycwmp actually doesn't send a DownloadResponse for the second download request (although it sends one for the first download request) but already a Download Event in the next inform without having confirmed the second download request. |
|
do you have pcap file? |
|
|
|
Please see attached file dump.pcap. |
|
Any news on this one? |
|
Could you please have a look at this issue? I would greatly appreciate it. |
Date Modified | Username | Field | Change |
---|---|---|---|
2018-08-10 15:02 | okraits | New Issue | |
2018-08-10 15:02 | okraits | File Added: easycwmp.log | |
2018-08-10 15:02 | okraits | File Added: acs.log | |
2018-08-15 11:01 | mohamed.kallel | Note Added: 0000902 | |
2018-08-15 11:02 | mohamed.kallel | Note Edited: 0000902 | |
2018-08-27 11:05 | okraits | Note Added: 0000903 | |
2018-08-27 11:52 | mohamed.kallel | Note Added: 0000904 | |
2018-08-28 14:20 | okraits | File Added: dump.pcap | |
2018-08-28 14:20 | okraits | Note Added: 0000906 | |
2018-09-03 15:34 | okraits | Note Added: 0000909 | |
2018-10-01 09:01 | okraits | Note Added: 0000919 |