CWMP - Worker died - UTF-8 Encoding Problem

Fri Jul 22 09:55:15 EDT 2016

Hi,

i have sometimes problems with a box from Arcadyan.

CWMP LOG:

22 Jul 13:34:05 - 001A2A-VDSL_IAD_WLAN-4033005757: Started task getParameterValues(579204adb013859b2267b073) 
(node) util.error is deprecated. Use console.error instead.  
2016-07-22T11:34:05.205Z - Error: Input is not proper UTF-8, indicate encoding !
Bytes: 0xFE 0x38 0x0D 0x0A

    at Error (native)    
    at Object.module.exports.fromXml (/usr/lib/node_modules/genieacs/node_modules/libxmljs/lib/document.js:146:21)
    at Object.exports.request (/usr/lib/node_modules/genieacs/lib/soap.js:373:20)
    at /usr/lib/node_modules/genieacs/lib/cwmp.js:642:26
    at /usr/lib/node_modules/genieacs/lib/cwmp.js:579:12
    at bound (domain.js:287:14)
    at runBound (domain.js:300:12)
    at try_callback (/usr/lib/node_modules/genieacs/node_modules/redis/index.js:592:9)
    at RedisClient.return_reply (/usr/lib/node_modules/genieacs/node_modules/redis/index.js:685:13)
    at HiredisReplyParser.<anonymous> (/usr/lib/node_modules/genieacs/node_modules/redis/index.js:321:14)

22 Jul 13:34:05 - Worker 9863 died (0)

I think its related to the Object InternetGatewayDevice.DeviceInfo.DeviceLog

That’s the TCP Stream:

POST / HTTP/1.1
Accept: */*
Host: 192.18.0.101:7547
Connection: Keep-Alive
Content-type: text/xml; charset=UTF-8
Content-length: 2703
SOAPAction: ""

<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" xmlns:SOAP-ENC="http://schemas.xmlsoap.org/soap/encoding/" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:cwmp="urn:dslforum-org:cwmp-1-0" xmlns="urn:dslforum-org:cwmp-1-0">
 <SOAP-ENV:Header>
  <cwmp:ID SOAP-ENV:mustUnderstand="1">117244</cwmp:ID>
 </SOAP-ENV:Header>
 <SOAP-ENV:Body>
  <cwmp:Inform>
   <DeviceId>
    <Manufacturer>Arcadyan</Manufacturer>
    <OUI>001A2A</OUI>
    <ProductClass>VDSL_IAD_WLAN</ProductClass>
    <SerialNumber>xxx</SerialNumber>
   </DeviceId>
   <Event SOAP-ENC:arrayType="cwmp:EventStruct[1]">
    <EventStruct>
     <EventCode>6 CONNECTION REQUEST</EventCode>
     <CommandKey></CommandKey>
    </EventStruct>
   </Event>
   <MaxEnvelopes>1</MaxEnvelopes>
   <CurrentTime>2016-07-20T12:20:24</CurrentTime>
   <RetryCount>0</RetryCount>
   <ParameterList SOAP-ENC:arrayType="cwmp:ParameterValueStruct[8]">
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceSummary</Name>
     <Value xsi:type="xsd:string">InternetGatewayDevice:1.4[](Baseline:2,EthernetLAN:2,WiFiLAN:2,WiFiWMM:1,WiFiWPS:1,ADSLWAN:1,ADSL2WAN:1,QoS:2,Time:2,IPPing:1),VoiceService:1.0[1](Endpoint:1,SIPEndpoint:1,TAEndpoint:1)</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name>
     <Value xsi:type="xsd:string">VGV7510KW22</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name>
     <Value xsi:type="xsd:string">ARC-1.01.25-VDSL_IAD_WLAN</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.SpecVersion</Name>
     <Value xsi:type="xsd:string">1.0</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name>
     <Value xsi:type="xsd:string"></Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.ManagementServer.ParameterKey</Name>
     <Value xsi:type="xsd:string"></Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.ManagementServer.ConnectionRequestURL</Name>
     <Value xsi:type="xsd:string">http://192.18.1.96:7170/ConnectionRequest</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANPPPConnection.1.ExternalIPAddress</Name>
     <Value xsi:type="xsd:string">192.18.1.96</Value>
    </ParameterValueStruct>
   </ParameterList>
  </cwmp:Inform>
 </SOAP-ENV:Body>
</SOAP-ENV:Envelope>
HTTP/1.1 200 OK
Server: GenieACS/1.0.5
SOAPServer: GenieACS/1.0.5
Content-Type: text/xml; charset="utf-8"
Set-Cookie: session=d259689098b97c89
Content-Length: 527
Date: Wed, 20 Jul 2016 10:20:24 GMT
Connection: keep-alive

<?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-0"><soap-env:Header><cwmp:ID soap-env:mustUnderstand="1">117244</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:InformResponse><MaxEnvelopes>1</MaxEnvelopes></cwmp:InformResponse></soap-env:Body></soap-env:Envelope>
POST / HTTP/1.1
Accept: */*
Host: 192.18.0.101:7547
Connection: Keep-Alive
Content-type: text/xml; charset=UTF-8
Content-length: 0
Cookie: session=d259689098b97c89
SOAPAction: ""

HTTP/1.1 200 OK
Server: GenieACS/1.0.5
SOAPServer: GenieACS/1.0.5
Content-Type: text/xml; charset="utf-8"
Content-Length: 640
Date: Wed, 20 Jul 2016 10:20:24 GMT
Connection: keep-alive

<?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-0"><soap-env:Header><cwmp:ID soap-env:mustUnderstand="1">578f50689063f22224684468</cwmp:ID></soap-env:Header><soap-env:Body><cwmp:GetParameterValues><ParameterNames soap-enc:arrayType="xsd:string[1]"><string>InternetGatewayDevice.DeviceInfo</string></ParameterNames></cwmp:GetParameterValues></soap-env:Body></soap-env:Envelope>
POST / HTTP/1.1
Accept: */*
Host: 192.18.0.101:7547
Connection: Keep-Alive
Content-type: text/xml; charset=UTF-8
Content-length: 7158
Cookie: session=d259689098b97c89
SOAPAction: ""

<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/" xmlns:SOAP-ENC="http://schemas.xmlsoap.org/soap/encoding/" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xmlns:xsd="http://www.w3.org/2001/XMLSchema" xmlns:cwmp="urn:dslforum-org:cwmp-1-0" xmlns="urn:dslforum-org:cwmp-1-0">
 <SOAP-ENV:Header>
  <cwmp:ID SOAP-ENV:mustUnderstand="1">578f50689063f22224684468</cwmp:ID>
 </SOAP-ENV:Header>
 <SOAP-ENV:Body>
  <cwmp:GetParameterValuesResponse>
   <ParameterList SOAP-ENC:arrayType="cwmp:ParameterValueStruct[18]">
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.Manufacturer</Name>
     <Value xsi:type="xsd:string">Arcadyan</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.ManufacturerOUI</Name>
     <Value xsi:type="xsd:string">001A2A</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.ModelName</Name>
     <Value xsi:type="xsd:string">WLAN 6431</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.Description</Name>
     <Value xsi:type="xsd:string">4 Port VDSL IAD</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.ProductClass</Name>
     <Value xsi:type="xsd:string">VDSL_IAD_WLAN</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.SerialNumber</Name>
     <Value xsi:type="xsd:string">4063006066</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.HardwareVersion</Name>
     <Value xsi:type="xsd:string">VGV7510KW22</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.SoftwareVersion</Name>
     <Value xsi:type="xsd:string">ARC-1.01.25-VDSL_IAD_WLAN</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.ModemFirmwareVersion</Name>
     <Value xsi:type="xsd:string">5.6.7.6.1.7</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.SpecVersion</Name>
     <Value xsi:type="xsd:string">1.0</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.ProvisioningCode</Name>
     <Value xsi:type="xsd:string"></Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.UpTime</Name>
     <Value xsi:type="xsd:unsignedInt">947</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.FirstUseDate</Name>
     <Value xsi:type="xsd:dateTime">2016-07-20T12:05:36</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.DeviceLog</Name>
     <Value xsi:type="xsd:string">
1       Startup: 20.07.2016, 12, 04, 38
2       Uptime: 00,00,0000, 00, 15, 47
3       DSL-Training: 20.07.2016, 12, 05, 14
4       DSL-Downstream: 93507 kbit/s, 0.1dB, 9dB
5       DSL-Upstream: 38943 kbit/s, 0.0dB, 4294967232dB
6       
7       VoIP-PPP last connection: 20.07.2016, 12, 05, 20
8       TR-069 last connection: 20.07.2016, 12, 20, 24, Method Inform
9       
10      

  20/07/2016 12:20:06 NTP Date/Time updated.
  20/07/2016 12:20:07 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:19:54 NTP Date/Time updated.
  20/07/2016 12:19:53 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:19:36 NTP Date/Time updated.
  20/07/2016 12:19:36 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:19:06 NTP Date/Time updated.
  20/07/2016 12:19:06 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:18:36 NTP Date/Time updated.
  20/07/2016 12:18:36 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:18:15 If(PPPoE2,12) receive PADT
  20/07/2016 12:18:06 If(PPPoE2,12) receive PADT
  20/07/2016 12:18:06 NTP Date/Time updated.
  20/07/2016 12:18:06 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:17:36 NTP Date/Time updated.
  20/07/2016 12:17:36 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:17:31 NTP Date/Time updated.
  20/07/2016 12:17:31 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:17:09 NTP Date/Time updated.
  20/07/2016 12:17:09 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:13:04 52:54:00:4B:28:C1 released
  20/07/2016 12:12:55 NTP Date/Time updated.
  20/07/2016 12:12:55 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:12:06 NTP Date/Time updated.
  20/07/2016 12:12:07 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:12:03 NTP Date/Time updated.
  20/07/2016 12:12:02 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:08:04 52:54:00:4B:28:C1 released
  20/07/2016 12:07:55 NTP Date/Time updated.
  20/07/2016 12:07:56 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:07:45 52:54:00:4B:28:C1 released
  20/07/2016 12:07:28 NTP Date/Time updated.
  20/07/2016 12:07:27 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:06:14 If(PPPoE2,12) receive PADT
  20/07/2016 12:05:44 52:54:00:4B:28:C1 released
  20/07/2016 12:05:41 NTP Date/Time updated.
  20/07/2016 12:05:41 Get system time from NTP server:192.18.0.1.
  20/07/2016 12:05:30 VoIP PPP Disconnect Scheduled Time 05:44, weekday:5.
  20/07/2016 12:05:28 If(PPPoE2,12) receive PADT
  20/07/2016 12:05:27 NTP Date/Time updated.
  06/06/2014 00:00:50 Get system time from NTP server:192.18.0.1.
  06/06/2014 00:00:49 [NTP DNS Query] time.sip.de = 192.18.0.1
  06/06/2014 00:00:43 If(PPPoE2) PPP connection ok !
  06/06/2014 00:00:41 If(PPPoE2) get primary DNS IP:192.18.0.1
  06/06/2014 00:00:41 If(PPPoE2) get IP:192.18.1.96
  06/06/2014 00:00:41 If(PPPoE2) start PPP
  06/06/2014 00:00:41 If(PPPoE2,12) receive PADS
  06/06/2014 00:00:41 If(PPPoE2,12) send PADR
  06/06/2014 00:00:41 If(PPPoE2,12) receive PADO
  06/06/2014 00:00:41 If(PPPoE2,12) send PADI
  06/06/2014 00:00:41 If(PPPoE2) Dial PPP
  06/06/2014 00:00:37 If(PPPoE1) stop
  06/06/2014 00:00:37 .8
  06/06/2014 00:00:37 
  06/06/2014 00:00:36 VDSL > Media up (US/DS : 37882/90331 kbps)</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.VendorConfigFile.1.Name</Name>
     <Value xsi:type="xsd:string">config.cfg</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.VendorConfigFile.1.Version</Name>
     <Value xsi:type="xsd:string">1.01.25</Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.VendorConfigFile.1.Date</Name>
     <Value xsi:type="xsd:dateTime"></Value>
    </ParameterValueStruct>
    <ParameterValueStruct>
     <Name>InternetGatewayDevice.DeviceInfo.VendorConfigFile.1.Description</Name>
     <Value xsi:type="xsd:string">None</Value>
    </ParameterValueStruct>
   </ParameterList>
  </cwmp:GetParameterValuesResponse>
 </SOAP-ENV:Body>
</SOAP-ENV:Envelope>
HTTP/1.1 500 Internal Server Error
Connection: close
Date: Wed, 20 Jul 2016 10:20:24 GMT
Transfer-Encoding: chunked

51
Error: Input is not proper UTF-8, indicate encoding !
Bytes: 0xFE 0x38 0x0D 0x0A

0

Some Details about my setup:

genieacs at 1.0.7 /usr/lib/node_modules/genieacs
├─┬ hiredis at 0.4.1
│ ├── bindings at 1.2.1
│ └── nan at 2.3.4
├─┬ libxmljs at 0.14.3
│ └── nan at 2.0.7
├─┬ mongodb at 1.4.40
│ ├─┬ bson at 0.2.22
│ │ └── nan at 1.8.4
│ ├─┬ kerberos at 0.0.11
│ │ └── nan at 1.8.4
│ └─┬ readable-stream at 2.1.4
│   ├── buffer-shims at 1.0.0
│   ├── core-util-is at 1.0.2
│   ├── inherits at 2.0.1
│   ├── isarray at 1.0.0
│   ├── process-nextick-args at 1.0.7
│   ├── string_decoder at 0.10.31
│   └── util-deprecate at 1.0.2
└── redis at 0.12.1

Any ideas for a fix or workaround? Maybe can we exclude that Object? Have somebody knowledge to update the libxmljs to a newer version?

Mit freundlichen Internet Gruessen/Best Regards
JM-DATA: IT-SOLUTIONS & TELECOM

Ihr,
Michael Neumann

______________________________________________________

J M - D A T A   T e l e k o m   G m b H
Austria, 4050 Traun, Hackl-Straße 1, Object 2
T +43 50 / 30 50 80 - 0  F +43 50 / 30 50 80 - 20
E office at JM-DATA.at   W http://www.JM-DATA.at
______________________________________________________

* IT-SOLUTIONS & TELECOM *
______________________________________________________
Es gelten die AGBs von JM-DATA GmbH - http://www.JM-DATA.at/agb
Terms and conditions apply to JM-DATA GmbH - http://www.JM-DATA.at/agb

Besuchen Sie uns auch auf Facebook: http://www.facebook.com/jmdata