This looks like an issue on the CPE itself. Its trying to acquire a lock add the interface and times out after 60 seconds. Reboot the CPE and try again.
On Tue, Oct 10, 2017 at 11:31 AM, Margerita <[email protected]> wrote: > Ok, > > I thought it will be easier to just see what Genie sends to CPE. > Here is part of log captured from CPE where AddObj and 9005 is visible: > > tr69c:57.262:do_response:549:=====>ENTER. data->pc=0x581714 > tr69c:57.262:cmsTmr_cancel:264:canceled event timer_response, count=1 > tr69c:57.262:proto_ParseResponse:1376:proto_ParseResponse(protocol="HTTP/1.1", > status=200 message="OK") > tr69c:57.264:postComplete:990:=====>ENTER > tr69c:57.264:cmsTmr_cancel:260:no events to cancel (func=0x40be28 > data=0x4444d4) > tr69c:57.264:postComplete:1000:Connection = keep-alive > tr69c:57.264:readResponse:791:calling readLengthMsg for Download with > content_length=609 > tr69c:57.265:readLengthMsg:565:soapBuf bufCnt=609 readLth=609 > tr69c:57.265:proto_Skip:1529:proto_Skip() read all from fd and ignore > tr69c:57.265:proto_Skip:1556:proto_Skip() done.ret=1 > tr69c:57.265:xmlWrite:116: ---- nxml_write(): len 609 > tr69c:57.266:xmlWrite:119: ---- skip <?xml header > tr69c:57.266:xmlTagBegin:233:tag=soap-env:Envelope lth=17 xmllevel=0 > tr69c:57.266:fEnvelope:1028:name=soap-env:Envelope, ttype=1, value=(null) > tr69c:57.266:fEnvelope:1031:TAGBEGIN ------ > tr69c:57.266:xmlTagBegin:233:tag=soap-env:Header lth=15 xmllevel=1 > tr69c:57.267:xmlTagBegin:233:tag=cwmp:ID lth=7 xmllevel=2 > tr69c:57.267:fIDValue:1016:name=cwmp:ID, ttype=1, value=(null) > tr69c:57.267:xmlAttr:339:xmlAttr attr=soap-env:mustUnderstand found at > level 3 > tr69c:57.267:xmlValue:396:xmlValue attr=mustUnderstand value=1 found at > level 3 > tr69c:57.267:fIDValue:1016:name=ID, ttype=3, value=15f07bb155c108 > tr69c:57.268:xmlData:445:xmlDATA tag=ID data=15f07bb155c108 found at > level 3 > tr69c:57.268:fIDValue:1016:name=cwmp:ID, ttype=2, value=(null) > tr69c:57.268:xmlTagEnd:293:xmlTagEnd tag=cwmp:ID found at level 2 > tr69c:57.268:xmlTagEnd:293:xmlTagEnd tag=soap-env:Header found at level 1 > tr69c:57.268:xmlTagBegin:233:tag=soap-env:Body lth=13 xmllevel=1 > tr69c:57.269:fBody:982:name=soap-env:Body, ttype=1, value=(null) > tr69c:57.269:xmlTagBegin:233:tag=cwmp:AddObject lth=14 xmllevel=2 > tr69c:57.269:fAddObject:669:name=cwmp:AddObject, ttype=1, value=(null) > tr69c:57.269:xmlTagBegin:233:tag=ObjectName lth=10 xmllevel=3 > tr69c:57.269:fObjectName:660:name=ObjectName, ttype=1, value=(null) > tr69c:57.270:fObjectName:660:name=ObjectName, ttype=3, > value=InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1. > WANIPConnection.1. > tr69c:57.270:xmlData:445:xmlDATA tag=ObjectName > data=InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.1. > found at level 4 > tr69c:57.270:fObjectName:660:name=ObjectName, ttype=2, value=(null) > tr69c:57.270:xmlTagEnd:293:xmlTagEnd tag=ObjectName found at level 3 > tr69c:57.270:xmlTagBegin:233:tag=ParameterKey lth=12 xmllevel=3 > tr69c:57.271:fSetParameterKey:749:name=ParameterKey, ttype=1, value=(null) > tr69c:57.271:xmlTagEnd:300:xmlTagEnd shortform tag=ParameterKey xmllevel=3 > tr69c:57.271:fSetParameterKey:749:name=, ttype=2, value=(null) > tr69c:57.271:fAddObject:669:name=cwmp:AddObject, ttype=2, value=(null) > tr69c:57.271:xmlTagEnd:293:xmlTagEnd tag=cwmp:AddObject found at level 2 > tr69c:57.272:fBody:982:name=soap-env:Body, ttype=2, value=(null) > tr69c:57.272:xmlTagEnd:293:xmlTagEnd tag=soap-env:Body found at level 1 > tr69c:57.272:fEnvelope:1028:name=soap-env:Envelope, ttype=2, value=(null) > tr69c:57.272:fEnvelope:1043:TAGEND ----- > tr69c:57.272:xmlTagEnd:293:xmlTagEnd tag=soap-env:Envelope found at level > 0 > tr69c:57.272:runRPC:3048:=====>ENTER: rcpMethod=7 > tr69c:57.273:cmsLck_acquireLockWithTimeoutTraced:95:acquired lock. > callerFuncName runRPC; timeout 6000 milliseconds > tr69c:57.273:writeSoapFault:713:=====>ENTER > tr69c:57.274:sendToAcs:1371:=====>ENTER > tr69c:57.274:wget_ClearPostHdrs:941:=====>ENTER > tr69c:57.274:wget_AddPostHdr:933:=====>ENTER > tr69c:57.274:updateAuthorizationHdr:422:=====>ENTER > tr69c:57.274:wget_PostData:845:=====>ENTER > tr69c:57.274:do_send_request:499:=====>ENTER. keepConn=1 status=0 > tr69c:57.275:proto_SendRequest:1092:proto_SendRequest(POST / HTTP/1.1) > tr69c:57.275:stopListener:170:removed listener on fd=6 > tr69c:57.276:setListenerType:235:Adding listener on fd=6 type=read > handle=0x581b14 > tr69c:57.276:cmsTmr_set:205:added event timer_response, expires in > 60000ms (at 117.276), func=0x414680 data=0x581b14 count=2 > tr69c:57.277:doAddObject:2222:Fault 9005 > tr69c:57.277:cmsLck_releaseLockTraced:140:lock hold time=4ms, acquiring > lock callerFuncName runRPC; releasing lock callerFuncName runRPC; > tr69c:57.277:runRPC:3301:=====>EXIT, rpcStatus=0 > > br > > -- > Michal > > W dniu 2017-10-10 o 20:40, Dan Morphis pisze: > > THe wireshark/tcpdump logs aren't want you want to check. You want to > check the syslog on the CPE itself. > > -dan > > On Tue, Oct 10, 2017 at 10:27 AM, Margerita <polanski.michal.mail@gmail. > com> wrote: > >> Hi, >> >> declare >> ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.*", >> null, {path: 1}); or path: 2 or path: 3 works OK and creates 1 or 2 or 3 >> interfaces. >> Problem occures later, I checked with tcpdump and it looks, that Genie >> sends: >> >> InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANI >> PConnection.1. >> instead of >> InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANI >> PConnection.1.Name >> >> and empty parameter/value. >> >> Attached screenshot from wireshark - response for this was 9005 >> >> br, >> >> W dniu 2017-10-10 o 18:57, Dan Morphis pisze: >> >> What do the logs on the CPE have in them? >> >> On Tue, Oct 10, 2017 at 1:23 AM, Margerita <[email protected] >> m> wrote: >> >>> Thanks Dan, >>> >>> I got your idea here, but I can't get it to work this way. I got 9005 >>> error even with smallest configuration like this: >>> >>> declare >>> ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.*", >>> null, {path: 1}); >>> declare ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WA >>> NIPConnection.*.[Name:Routed]", {path:1}, {path: 1}); >>> >>> any ideas? >>> >>> -- >>> Michal >>> >>> W dniu 2017-10-05 o 18:54, Dan Morphis pisze: >>> >>> Try this :) >>> >>> declare >>> ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.*", >>> null, {path: 2}); //Tell genie we want two interfaces >>> declare ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WA >>> NIPConnection.*.[ConnectionType:IP_Routed,Name:Routed,Enable:true]", >>> {path: 1}, {path: 1}); >>> declare ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WA >>> NIPConnection.*.[ConnectionType:IP_Bridged,Name:Bridged,Enable:true]", >>> {path: 1}, {path: 1}); >>> >>> That will create two interfaces. If there are more interfaces than that, >>> they will be deleted. If there are less than 2, then interfaces will be >>> added until the count is 2. Then it will set the parameters to whats in the >>> brackets. >>> >>> -dan >>> >>> On Thu, Oct 5, 2017 at 4:33 AM, Margerita <[email protected] >>> m> wrote: >>> >>>> Hi, >>>> >>>> I would like to create two interfaces using Provision: >>>> >>>> declare >>>> ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.*", >>>> null, {path: 1}); >>>> declare ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WA >>>> NIPConnection.*.ConnectionType", null, {value: "IP_Routed"}); >>>> declare >>>> ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.*", >>>> null, {path: 1}); >>>> declare ("InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WA >>>> NIPConnection.*.ConnectionType", null, {value: "IP_Bridged"}); >>>> >>>> and want to achieve the following effect: >>>> >>>> InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANI >>>> PConnection.1 >>>> InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.1.ConnectionType >>>> IP_Routed >>>> InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANI >>>> PConnection.2 >>>> InternetGatewayDevice.WANDevice.1.WANConnectionDevice.1.WANIPConnection.2.ConnectionType >>>> IP_Bridged >>>> >>>> but whatever I put in path values, I always finish with only one >>>> interface or with two interfaces but both 'IP_Bridged'. >>>> What is the correct method for achieving my goal? >>>> >>>> -- >>>> Michal >>>> _______________________________________________ >>>> Users mailing list >>>> [email protected] >>>> http://lists.genieacs.com/mailman/listinfo/users >>>> >>> >>> >>> >>> _______________________________________________ >>> Users mailing >>> [email protected]http://lists.genieacs.com/mailman/listinfo/users >>> >>> >>> >>> _______________________________________________ >>> Users mailing list >>> [email protected] >>> http://lists.genieacs.com/mailman/listinfo/users >>> >>> >> >> >> _______________________________________________ >> Users mailing >> [email protected]http://lists.genieacs.com/mailman/listinfo/users >> >> >> >> _______________________________________________ >> Users mailing list >> [email protected] >> http://lists.genieacs.com/mailman/listinfo/users >> >> > > > _______________________________________________ > Users mailing > [email protected]http://lists.genieacs.com/mailman/listinfo/users > > > > _______________________________________________ > Users mailing list > [email protected] > http://lists.genieacs.com/mailman/listinfo/users > >
_______________________________________________ Users mailing list [email protected] http://lists.genieacs.com/mailman/listinfo/users
