easycwmp Log

root@yjkwon-VirtualBox:~/tmp# rm /tmp/yj.log ; easycwmpd -f -b
2016-09-07 21:14:32 [easycwmp] NOTICE - add event '0 BOOTSTRAP'
2016-09-07 21:14:32 [easycwmp] NOTICE - add event '1 BOOT'
2016-09-07 21:14:32 [easycwmp] NOTICE - add event '2 PERIODIC'
2016-09-07 21:14:32 [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.3.145:80/web
config_init_acs(165):: easycwmp.@acs[0].username=admin
config_init_acs(171):: easycwmp.@acs[0].password=xaps
config_init_acs(177):: easycwmp.@acs[0].periodic_enable=1
config_init_acs(183):: easycwmp.@acs[0].periodic_interval=100
config_init_acs(190):: easycwmp.@acs[0].periodic_time=0001-01-01T00:00:00Z
2016-09-07 21:14:32 [easycwmp] NOTICE - init periodic inform: reference time = -62135627272, interval = 100
2016-09-07 21:14:32 [easycwmp] NOTICE - external: execute update_value_change  
2016-09-07 21:14:32 [easycwmp] NOTICE - daemon started
2016-09-07 21:14:32 [easycwmp] NOTICE - external: execute inform device_id 
2016-09-07 21:14:32 [easycwmp] NOTICE - external script exit
2016-09-07 21:14:32 [easycwmp] NOTICE - add event '1 BOOT'
2016-09-07 21:14:32 [easycwmp] NOTICE - interface eth0 has ip 192.168.2.145
main(320): ubus initialization failed
+++ HTTP SERVER CONFIGURATION +++
ip: '192.168.2.145'
port: '7547'
--- HTTP SERVER CONFIGURATION ---
2016-09-07 21:14:32 [easycwmp] NOTICE - http server initialized
2016-09-07 21:14:32 [easycwmp] NOTICE - entering main loop
2016-09-07 21:14:32 [easycwmp] NOTICE - start session
+++ HTTP CLIENT CONFIGURATION +++
http_client_init(50):: url: http://192.168.3.145:80/web
http_client_init(56):: ssl_verify: SSL certificate validation disabled.
--- HTTP CLIENT CONFIGURATION ---
2016-09-07 21:14:32 [easycwmp] NOTICE - configured acs url http://192.168.3.145:80/web
2016-09-07 21:14:32 [easycwmp] NOTICE - external script init
2016-09-07 21:14:32 [easycwmp] NOTICE - external: execute inform parameter 
2016-09-07 21:14:32 [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">1</cwmp:ID>
  </soap_env:Header>
  <soap_env:Body>
   <cwmp:Inform>
    <DeviceId>
     <Manufacturer>easycwmp</Manufacturer>
     <OUI>easycwmp</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>2016-09-07T21:14:32+09:00</CurrentTime>
    <RetryCount>0</RetryCount>
    <ParameterList soap_enc:arrayType="cwmp:ParameterValueStruct[11]">
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name>
      <Value xsi:type="xsd:string">example_hw_version</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.Manufacturer</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ManufacturerOUI</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ProductClass</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name>
      <Value xsi:type="xsd:string">1234567890</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.SerialNumber</Name>
      <Value xsi:type="xsd:string">FFFFFF123456</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name>
      <Value xsi:type="xsd:string">example_sw_version</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://192.168.2.145: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">192.168.2.145</Value>
     </ParameterValueStruct>
    </ParameterList>
   </cwmp:Inform>
  </soap_env:Body>
 </soap_env:Envelope>
--- SEND HTTP REQUEST ---
* Hostname was NOT found in DNS cache
*   Trying 192.168.3.145...
* Connected to 192.168.3.145 (192.168.3.145) port 80 (#0)
> POST /web HTTP/1.1
Host: 192.168.3.145
User-Agent: easycwmp
Content-Type: text/xml; charset="utf-8"
SOAPAction:
Content-Length: 3314
Expect: 100-continue

< HTTP/1.1 100 Continue
< HTTP/1.1 302 Found
* Server Apache-Coyote/1.1 is not blacklisted
< Server: Apache-Coyote/1.1
< Location: http://192.168.3.145/web/
< Content-Length: 0
< Date: Wed, 07 Sep 2016 12:14:32 GMT
* HTTP error before end of send, stop sending
< 
* Closing connection 0
+++ HTTP CLIENT CONFIGURATION +++
http_client_init(50):: url: http://192.168.3.145/web/
http_client_init(56):: ssl_verify: SSL certificate validation disabled.
--- HTTP CLIENT CONFIGURATION ---
2016-09-07 21:14:32 [easycwmp] NOTICE - configured acs url http://192.168.3.145/web/
+++ 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">1</cwmp:ID>
  </soap_env:Header>
  <soap_env:Body>
   <cwmp:Inform>
    <DeviceId>
     <Manufacturer>easycwmp</Manufacturer>
     <OUI>easycwmp</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>2016-09-07T21:14:32+09:00</CurrentTime>
    <RetryCount>0</RetryCount>
    <ParameterList soap_enc:arrayType="cwmp:ParameterValueStruct[11]">
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name>
      <Value xsi:type="xsd:string">example_hw_version</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.Manufacturer</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ManufacturerOUI</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ProductClass</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name>
      <Value xsi:type="xsd:string">1234567890</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.SerialNumber</Name>
      <Value xsi:type="xsd:string">FFFFFF123456</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name>
      <Value xsi:type="xsd:string">example_sw_version</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://192.168.2.145: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">192.168.2.145</Value>
     </ParameterValueStruct>
    </ParameterList>
   </cwmp:Inform>
  </soap_env:Body>
 </soap_env:Envelope>
--- SEND HTTP REQUEST ---
* Hostname was NOT found in DNS cache
*   Trying 192.168.3.145...
* Connected to 192.168.3.145 (192.168.3.145) port 80 (#1)
> POST /web/ HTTP/1.1
Host: 192.168.3.145
User-Agent: easycwmp
Content-Type: text/xml; charset="utf-8"
SOAPAction:
Content-Length: 3314
Expect: 100-continue

< HTTP/1.1 100 Continue
< HTTP/1.1 302 Found
* Server Apache-Coyote/1.1 is not blacklisted
< Server: Apache-Coyote/1.1
* Added cookie JSESSIONID="D6DB809F396BE31E58957FB5B330A362" for domain 192.168.3.145, path /, expire 0
< Set-Cookie: JSESSIONID=D6DB809F396BE31E58957FB5B330A362; Path=/
< Location: http://192.168.3.145/web/login
< Content-Length: 0
< Date: Wed, 07 Sep 2016 12:14:32 GMT
* HTTP error before end of send, stop sending
< 
* Closing connection 1
+++ HTTP CLIENT CONFIGURATION +++
http_client_init(50):: url: http://192.168.3.145/web/login
http_client_init(56):: ssl_verify: SSL certificate validation disabled.
--- HTTP CLIENT CONFIGURATION ---
2016-09-07 21:14:32 [easycwmp] NOTICE - configured acs url http://192.168.3.145/web/login
+++ 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">1</cwmp:ID>
  </soap_env:Header>
  <soap_env:Body>
   <cwmp:Inform>
    <DeviceId>
     <Manufacturer>easycwmp</Manufacturer>
     <OUI>easycwmp</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>2016-09-07T21:14:32+09:00</CurrentTime>
    <RetryCount>0</RetryCount>
    <ParameterList soap_enc:arrayType="cwmp:ParameterValueStruct[11]">
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name>
      <Value xsi:type="xsd:string">example_hw_version</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.Manufacturer</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ManufacturerOUI</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ProductClass</Name>
      <Value xsi:type="xsd:string">easycwmp</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name>
      <Value xsi:type="xsd:string">1234567890</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.SerialNumber</Name>
      <Value xsi:type="xsd:string">FFFFFF123456</Value>
     </ParameterValueStruct>
     <ParameterValueStruct>
      <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name>
      <Value xsi:type="xsd:string">example_sw_version</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://192.168.2.145: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">192.168.2.145</Value>
     </ParameterValueStruct>
    </ParameterList>
   </cwmp:Inform>
  </soap_env:Body>
 </soap_env:Envelope>
--- SEND HTTP REQUEST ---
* Hostname was NOT found in DNS cache
*   Trying 192.168.3.145...
* Connected to 192.168.3.145 (192.168.3.145) port 80 (#2)
> POST /web/login HTTP/1.1
Host: 192.168.3.145
User-Agent: easycwmp
Content-Type: text/xml; charset="utf-8"
SOAPAction:
Content-Length: 3314
Expect: 100-continue

< HTTP/1.1 100 Continue
< HTTP/1.1 200 OK
* Server Apache-Coyote/1.1 is not blacklisted
< Server: Apache-Coyote/1.1
* Added cookie JSESSIONID="C9B2C1AE3EFA5DC6C7CC1E07BD35718B" for domain 192.168.3.145, path /, expire 0
< Set-Cookie: JSESSIONID=C9B2C1AE3EFA5DC6C7CC1E07BD35718B; Path=/
< Content-Type: text/html;charset=utf-8
< Transfer-Encoding: chunked
< Date: Wed, 07 Sep 2016 12:14:32 GMT
< 
* Connection #2 to host 192.168.3.145 left intact
+++ RECEIVED HTTP RESPONSE +++
<!DOCTYPE html PUBLIC "-//W3C//DTD XHTML 1.0 Transitional//EN" "http://www.w3.org/TR/xhtml1/DTD/xhtml1-transitional.dtd">
<html xmlns="http://www.w3.org/1999/xhtml" xml:lang="en" lang="en">
	<head>
		<title>FreeACS Web Web | login</title>
		<meta http-equiv="content-type" content="text/html; charset=UTF-8"/>
		<link rel="icon" type="image/png" href="images/fusion.ico">
		<link rel="stylesheet" type="text/css" href="css/xapsweb.css" />
		<link rel="stylesheet" type="text/css" href="css/context.css" />
		<link rel="stylesheet" type="text/css" href="css/colors/default.css" />
		<link rel="stylesheet" href="css/smoothness/jquery-ui-1.8.11.custom.css" type="text/css" />
		<script src="javascript/jquery-1.4.4.js"></script>
		<script src="javascript/jquery.sha1.js"></script>
		<script>
			jQuery(document).ready(function($){
				$("input[type='text']:first", document.form1).focus();
				$("#loginForm").submit(function(){
					$loginPassword = $("input[name='password']:first");
					if($loginPassword.val()!=""){
						$loginPassword.val($.sha1($loginPassword.val()));
						return true;
					}
					return false;
				});
			});
		</script>
	</head>
	<body>
		<center>
			<div class="center_box" id="bodylogon">
				<form action="/web/login" id="loginForm" method="post" class="unit">
					<fieldset id="logondiv">
						<legend>FreeACS</legend>
						<table>
							<tr>
								<td align="right">
									User:
								</td>
								<td>
									<input name="username" type="text" maxlength="50" style="width:120px;" />
								</td>
							</tr>
							<tr>
								<td align="right">
									Password:
								</td>
								<td>
									<input name="password" type="password" maxlength="50" style="width:120px;" />
								</td>
							</tr>
							<tr>
								<td align="right" colspan="2">
									<input name="login" type="submit" value="Login" />
								</td>
							</tr>
						</table>
					</fieldset>
					<font color="red"><b></b></font>
				</form>
			</div>
		</center>
	</body>
</html>
--- RECEIVED HTTP RESPONSE ---
mxml: Mismatched close tag </head> under parent <link>!
rpc_inform(229): parse Inform xml message from ACS failed
2016-09-07 21:14:32 [easycwmp] NOTICE - sending Inform failed
2016-09-07 21:14:32 [easycwmp] NOTICE - external: execute apply service 
2016-09-07 21:14:32 [easycwmp] NOTICE - external script exit
2016-09-07 21:14:32 [easycwmp] NOTICE - end session failed
2016-09-07 21:14:32 [easycwmp] NOTICE - retry session in 7 sec, RetryCount = 1
^C2016-09-07 21:14:34 [easycwmp] NOTICE - exiting