Skip to main content


Eclipse Community Forums
Forum Search:

Search      Help    Register    Login    Home
Home » Eclipse Projects » Eclipse Titan » IPL4 port dynamic error
IPL4 port dynamic error [message #1772264] Thu, 07 September 2017 08:44 Go to next message
Naum Spaseski is currently offline Naum SpaseskiFriend
Messages: 81
Registered: February 2016
Location: Sophia Antipolis
Member

After long time using IPL4 testport with oneM2M ATS, I have a rather strange error message when it is trying to connect:

MTC@307dd6847a49: Test case TC_CSE_DMR_CRE_001_ACP_CB started.
Testcase Started: TC_CSE_DMR_CRE_001_ACP_CB
MTC@307dd6847a49: Dynamic test case error: IPL4asp__PT_PROVIDER::user_map(mcaPort): Autoconnect: Can not connect: 99 Cannot assign requested address  (Cannot assign requested address)
MTC@307dd6847a49: Test case TC_CSE_DMR_CRE_001_ACP_CB finished. Verdict: error
Testcase Ended: TC_CSE_DMR_CRE_001_ACP_CB with verdict: error
MC@307dd6847a49: Test execution finished.


I can't find the error in the documentation, so I turned here for help.

Thank you in advance,

Naum
Re: IPL4 port dynamic error [message #1772265 is a reply to message #1772264] Thu, 07 September 2017 08:59 Go to previous messageGo to next message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
Hi Naum,

is this symptom persistent or transient?

It appears that either there already is an established connection from localIP:local port to remoteIP:remote port
by another process (maybe a previous instance of the TTCN-3 execution that did not terminate properly ? -kill all suspicious processes ) or this connection cannot be established for some other reason.

If you try to establish a TCP connection with a tool independent of Titan (say netcat ), what happens?

BR Elemer


Re: IPL4 port dynamic error [message #1772275 is a reply to message #1772264] Thu, 07 September 2017 11:52 Go to previous messageGo to next message
Gustavo Gonnet is currently offline Gustavo GonnetFriend
Messages: 34
Registered: October 2015
Location: Montreal, Quebec, Canada
Member
Hi Naum,
the times I got in trouble with this error was because I was making a mistake in the local IP address in the titan cfg file.
Does the computer running titan have an interface with such IP? You can find that out with ifconfig (linux) or ipconfig (win)
thanks,
Gustavo.
Re: IPL4 port dynamic error [message #1772398 is a reply to message #1772275] Fri, 08 September 2017 15:02 Go to previous messageGo to next message
Naum Spaseski is currently offline Naum SpaseskiFriend
Messages: 81
Registered: February 2016
Location: Sophia Antipolis
Member

Sorry, I'm late with the response. Thank you both for the suggestions.

The error is more weirder than that. Actually, the first test is OK, but the second has a problem. For details, see the log:

14:27:10.363325 OneM2M_Testcases.ttcn:17956 Test case TC_CSE_SEC_ACP_002_CRE started.
14:27:10.364298 OneM2M_Testcases.ttcn:17956 Port acPort was started.
14:27:10.364463 OneM2M_Testcases.ttcn:17956 Port mcaPort was started.
14:27:10.364494 OneM2M_Testcases.ttcn:17956 Port mccPort was started.
14:27:10.366417 OneM2M_Functions.ttcn:37 entering f__IPL4__PROVIDER__connect: :9999 -> 132.227.122.103:8080 / TCP
14:27:10.390759 OneM2M_Functions.ttcn:37 Port mcaPort was mapped to system:mcaPort.
14:27:10.391271 OneM2M_Functions.ttcn:38 Port acPort was mapped to system:acPort.
14:27:10.393626 OneM2M_Functions.ttcn:422 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int1 (1), to_ := "in-name", from_ := "admin:admin", requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAcp491940", resourceType := int1 (1), primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := omit, labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, choice := omit } }, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.412073 OneM2M_Functions.ttcn:422 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '504F5354202F696E2D6E616D6520485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D4C656E6774683A203236330D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B74793D310D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2061646D696E3A61646D696E0D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F6372656174654163703439313934300D0A0D0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C73223E202020203C70763E20202020202020203C6163723E2020202020202020202020203C61636F723E2A3C2F61636F723E2020202020202020202020203C61636F703E36333C2F61636F703E20202020202020203C2F6163723E202020203C2F70763E202020203C7076733E20202020202020203C6163723E2020202020202020202020203C61636F723E2A3C2F61636F723E2020202020202020202020203C61636F703E36333C2F61636F703E20202020202020203C2F6163723E202020203C2F7076733E3C2F6D326D3A6163703E'O ("POST /in-name HTTP/1.1\r\nAccept: application/xml\r\nContent-Length: 263\r\nContent-Type: application/xml;ty=1\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: admin:admin\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_createAcp491940\r\n\r\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\">    <pv>        <acr>            <acor>*</acor>            <acop>63</acop>        </acr>    </pv>    <pvs>        <acr>            <acor>*</acor>            <acop>63</acop>        </acr>    </pvs></m2m:acp>") }
14:27:10.448648 OneM2M_Functions.ttcn:424 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E312032303120437265617465640D0A436F6E74656E742D4C6F636174696F6E3A202F696E2D6373652F6163702D3631303231393632390D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F6372656174654163703439313934300D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030310D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B636861727365743D49534F2D383835392D310D0A436F6E74656E742D4C656E6774683A203433360D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A3C3F786D6C2076657273696F6E3D22312E302220656E636F64696E673D225554462D38223F3E0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C732220726E3D226163705F363130323139363239223E0A2020203C74793E313C2F74793E0A2020203C72693E2F696E2D6373652F6163702D3631303231393632393C2F72693E0A2020203C70693E2F696E2D6373653C2F70693E0A2020203C63743E3230313730393038543134323731303C2F63743E0A2020203C6C743E3230313730393038543134323731303C2F6C743E0A2020203C70763E0A2020202020203C6163723E0A2020202020202020203C61636F723E2A3C2F61636F723E0A2020202020202020203C61636F703E36333C2F61636F703E0A2020202020203C2F6163723E0A2020203C2F70763E0A2020203C7076733E0A2020202020203C6163723E0A2020202020202020203C61636F723E2A3C2F61636F723E0A2020202020202020203C61636F703E36333C2F61636F703E0A2020202020203C2F6163723E0A2020203C2F7076733E0A3C2F6D326D3A6163703E0A0A'O ("HTTP/1.1 201 Created\r\nContent-Location: /in-cse/acp-610219629\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_createAcp491940\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2001\r\nContent-Type: application/xml;charset=ISO-8859-1\r\nContent-Length: 436\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\" rn=\"acp_610219629\">\n   <ty>1</ty>\n   <ri>/in-cse/acp-610219629</ri>\n   <pi>/in-cse</pi>\n   <ct>20170908T142710</ct>\n   <lt>20170908T142710</lt>\n   <pv>\n      <acr>\n         <acor>*</acor>\n         <acop>63</acop>\n      </acr>\n   </pv>\n   <pvs>\n      <acr>\n         <acor>*</acor>\n         <acop>63</acop>\n      </acr>\n   </pvs>\n</m2m:acp>\n\n") } id 1
14:27:10.453167 OneM2M_Functions.ttcn:424 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAcp491940", primitiveContent := { accessControlPolicy := { resourceName := "acp_610219629", resourceType := int1 (1), resourceID := "/in-cse/acp-610219629", parentID := "/in-cse", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 1
14:27:10.453823 OneM2M_Functions.ttcn:425 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAcp491940", primitiveContent := { accessControlPolicy := { resourceName := "acp_610219629", resourceType := int1 (1), resourceID := "/in-cse/acp-610219629", parentID := "/in-cse", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 1
14:27:10.454295 OneM2M_Functions.ttcn:425 Message with id 1 was extracted from the queue of mcaPort.
14:27:10.456896 OneM2M_Functions.ttcn:422 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int1 (1), to_ := "in-name", from_ := "admin:admin", requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAcp491940", resourceType := int1 (1), primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := omit, labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, choice := omit } }, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.458454 OneM2M_Functions.ttcn:422 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '504F5354202F696E2D6E616D6520485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D4C656E6774683A203236330D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B74793D310D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2061646D696E3A61646D696E0D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F6372656174654163703439313934300D0A0D0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C73223E202020203C70763E20202020202020203C6163723E2020202020202020202020203C61636F723E2A3C2F61636F723E2020202020202020202020203C61636F703E36333C2F61636F703E20202020202020203C2F6163723E202020203C2F70763E202020203C7076733E20202020202020203C6163723E2020202020202020202020203C61636F723E2A3C2F61636F723E2020202020202020202020203C61636F703E36333C2F61636F703E20202020202020203C2F6163723E202020203C2F7076733E3C2F6D326D3A6163703E'O ("POST /in-name HTTP/1.1\r\nAccept: application/xml\r\nContent-Length: 263\r\nContent-Type: application/xml;ty=1\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: admin:admin\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_createAcp491940\r\n\r\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\">    <pv>        <acr>            <acor>*</acor>            <acop>63</acop>        </acr>    </pv>    <pvs>        <acr>            <acor>*</acor>            <acop>63</acop>        </acr>    </pvs></m2m:acp>") }
14:27:10.492309 OneM2M_Functions.ttcn:424 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E312032303120437265617465640D0A436F6E74656E742D4C6F636174696F6E3A202F696E2D6373652F6163702D3636353935393636350D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F6372656174654163703439313934300D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030310D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B636861727365743D49534F2D383835392D310D0A436F6E74656E742D4C656E6774683A203433360D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A3C3F786D6C2076657273696F6E3D22312E302220656E636F64696E673D225554462D38223F3E0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C732220726E3D226163705F363635393539363635223E0A2020203C74793E313C2F74793E0A2020203C72693E2F696E2D6373652F6163702D3636353935393636353C2F72693E0A2020203C70693E2F696E2D6373653C2F70693E0A2020203C63743E3230313730393038543134323731303C2F63743E0A2020203C6C743E3230313730393038543134323731303C2F6C743E0A2020203C70763E0A2020202020203C6163723E0A2020202020202020203C61636F723E2A3C2F61636F723E0A2020202020202020203C61636F703E36333C2F61636F703E0A2020202020203C2F6163723E0A2020203C2F70763E0A2020203C7076733E0A2020202020203C6163723E0A2020202020202020203C61636F723E2A3C2F61636F723E0A2020202020202020203C61636F703E36333C2F61636F703E0A2020202020203C2F6163723E0A2020203C2F7076733E0A3C2F6D326D3A6163703E0A0A'O ("HTTP/1.1 201 Created\r\nContent-Location: /in-cse/acp-665959665\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_createAcp491940\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2001\r\nContent-Type: application/xml;charset=ISO-8859-1\r\nContent-Length: 436\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\" rn=\"acp_665959665\">\n   <ty>1</ty>\n   <ri>/in-cse/acp-665959665</ri>\n   <pi>/in-cse</pi>\n   <ct>20170908T142710</ct>\n   <lt>20170908T142710</lt>\n   <pv>\n      <acr>\n         <acor>*</acor>\n         <acop>63</acop>\n      </acr>\n   </pv>\n   <pvs>\n      <acr>\n         <acor>*</acor>\n         <acop>63</acop>\n      </acr>\n   </pvs>\n</m2m:acp>\n\n") } id 2
14:27:10.493226 OneM2M_Functions.ttcn:424 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAcp491940", primitiveContent := { accessControlPolicy := { resourceName := "acp_665959665", resourceType := int1 (1), resourceID := "/in-cse/acp-665959665", parentID := "/in-cse", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 2
14:27:10.493491 OneM2M_Functions.ttcn:425 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAcp491940", primitiveContent := { accessControlPolicy := { resourceName := "acp_665959665", resourceType := int1 (1), resourceID := "/in-cse/acp-665959665", parentID := "/in-cse", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := { accessControlRule_list := { { accessControlOriginators := { "*" }, accessControlOperations := int63 (63), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 2
14:27:10.493688 OneM2M_Functions.ttcn:425 Message with id 2 was extracted from the queue of mcaPort.
14:27:10.494655 OneM2M_Functions.ttcn:203 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int1 (1), to_ := "in-name", from_ := "", requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAe177416", resourceType := int2 (2), primitiveContent := { aE := { resourceName := "MyAe", resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := omit, labels := omit, accessControlPolicyIDs := { "acp-610219629", "acp-665959665" }, expirationTime := omit, dynamicAuthorizationConsultationIDs := omit, announceTo := omit, announcedAttribute := omit, appName := omit, app_ID := "myAppId", aE_ID := omit, pointOfAccess := omit, ontologyRef := omit, nodeLink := omit, requestReachability := true, contentSerialization := omit, e2eSecInfo := omit, choice := omit } }, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.495132 OneM2M_Functions.ttcn:203 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '504F5354202F696E2D6E616D6520485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D4C656E6774683A203135380D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B74793D320D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A200D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F63726561746541653137373431360D0A0D0A3C6D326D3A616520786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C732220726E3D224D794165223E202020203C616370693E6163702D363130323139363239206163702D3636353935393636353C2F616370693E202020203C6170693E6D7941707049643C2F6170693E202020203C72723E747275653C2F72723E3C2F6D326D3A61653E'O ("POST /in-name HTTP/1.1\r\nAccept: application/xml\r\nContent-Length: 158\r\nContent-Type: application/xml;ty=2\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: \r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_createAe177416\r\n\r\n<m2m:ae xmlns:m2m=\"http://www.onem2m.org/xml/protocols\" rn=\"MyAe\">    <acpi>acp-610219629 acp-665959665</acpi>    <api>myAppId</api>    <rr>true</rr></m2m:ae>") }
14:27:10.525590 OneM2M_Functions.ttcn:206 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E312032303120437265617465640D0A436F6E74656E742D4C6F636174696F6E3A202F696E2D6373652F43414539303338343333330D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F63726561746541653137373431360D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030310D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B636861727365743D49534F2D383835392D310D0A436F6E74656E742D4C656E6774683A203431340D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A3C3F786D6C2076657273696F6E3D22312E302220656E636F64696E673D225554462D38223F3E0A3C6D326D3A616520786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C732220726E3D224D794165223E0A2020203C74793E323C2F74793E0A2020203C72693E2F696E2D6373652F43414539303338343333333C2F72693E0A2020203C70693E2F696E2D6373653C2F70693E0A2020203C63743E3230313730393038543134323731303C2F63743E0A2020203C6C743E3230313730393038543134323731303C2F6C743E0A2020203C616370693E2F696E2D6373652F6163702D363130323139363239202F696E2D6373652F6163702D363635393539363635202F696E2D6373652F6163702D3835313636383734303C2F616370693E0A2020203C65743E3230313830393038543134323731303C2F65743E0A2020203C6170693E6D7941707049643C2F6170693E0A2020203C6165693E43414539303338343333333C2F6165693E0A2020203C72723E747275653C2F72723E0A3C2F6D326D3A61653E0A0A'O ("HTTP/1.1 201 Created\r\nContent-Location: /in-cse/CAE90384333\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_createAe177416\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2001\r\nContent-Type: application/xml;charset=ISO-8859-1\r\nContent-Length: 414\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<m2m:ae xmlns:m2m=\"http://www.onem2m.org/xml/protocols\" rn=\"MyAe\">\n   <ty>2</ty>\n   <ri>/in-cse/CAE90384333</ri>\n   <pi>/in-cse</pi>\n   <ct>20170908T142710</ct>\n   <lt>20170908T142710</lt>\n   <acpi>/in-cse/acp-610219629 /in-cse/acp-665959665 /in-cse/acp-851668740</acpi>\n   <et>20180908T142710</et>\n   <api>myAppId</api>\n   <aei>CAE90384333</aei>\n   <rr>true</rr>\n</m2m:ae>\n\n") } id 3
14:27:10.526639 OneM2M_Functions.ttcn:206 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAe177416", primitiveContent := { aE := { resourceName := "MyAe", resourceType := int2 (2), resourceID := "/in-cse/CAE90384333", parentID := "/in-cse", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, accessControlPolicyIDs := { "/in-cse/acp-610219629", "/in-cse/acp-665959665", "/in-cse/acp-851668740" }, expirationTime := "20180908T142710", dynamicAuthorizationConsultationIDs := omit, announceTo := omit, announcedAttribute := omit, appName := omit, app_ID := "myAppId", aE_ID := "CAE90384333", pointOfAccess := omit, ontologyRef := omit, nodeLink := omit, requestReachability := true, contentSerialization := omit, e2eSecInfo := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 3
14:27:10.526809 OneM2M_Functions.ttcn:207 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_createAe177416", primitiveContent := { aE := { resourceName := "MyAe", resourceType := int2 (2), resourceID := "/in-cse/CAE90384333", parentID := "/in-cse", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, accessControlPolicyIDs := { "/in-cse/acp-610219629", "/in-cse/acp-665959665", "/in-cse/acp-851668740" }, expirationTime := "20180908T142710", dynamicAuthorizationConsultationIDs := omit, announceTo := omit, announcedAttribute := omit, appName := omit, app_ID := "myAppId", aE_ID := "CAE90384333", pointOfAccess := omit, ontologyRef := omit, nodeLink := omit, requestReachability := true, contentSerialization := omit, e2eSecInfo := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 3
14:27:10.526941 OneM2M_Functions.ttcn:207 Message with id 3 was extracted from the queue of mcaPort.
14:27:10.527158 OneM2M_Functions.ttcn:1985 Sent on acPort to system @OneM2M_Types.AcRequestPrimitive : { event := "AE-ID_changed", data := { charstring := "CAE90384333" } }
14:27:10.527620 OneM2M_Functions.ttcn:528 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int3 (3), to_ := "in-name/acp_610219629", from_ := "admin:admin", requestIdentifier := "m_updateAcp729567", resourceType := omit, primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := omit, labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "CAE90384333" }, accessControlOperations := int3 (3), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := omit, choice := omit } }, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.528070 OneM2M_Functions.ttcn:528 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '505554202F696E2D6E616D652F6163705F36313032313936323920485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D4C656E6774683A203137330D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C0D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2061646D696E3A61646D696E0D0A582D4D324D2D52493A206D5F7570646174654163703732393536370D0A0D0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C73223E202020203C70763E20202020202020203C6163723E2020202020202020202020203C61636F723E43414539303338343333333C2F61636F723E2020202020202020202020203C61636F703E333C2F61636F703E20202020202020203C2F6163723E202020203C2F70763E3C2F6D326D3A6163703E'O ("PUT /in-name/acp_610219629 HTTP/1.1\r\nAccept: application/xml\r\nContent-Length: 173\r\nContent-Type: application/xml\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: admin:admin\r\nX-M2M-RI: m_updateAcp729567\r\n\r\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\">    <pv>        <acr>            <acor>CAE90384333</acor>            <acop>3</acop>        </acr>    </pv></m2m:acp>") }
14:27:10.555641 OneM2M_Functions.ttcn:530 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E3120323030204F4B0D0A582D4D324D2D52493A206D5F7570646174654163703732393536370D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030340D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B636861727365743D49534F2D383835392D310D0A436F6E74656E742D4C656E6774683A203233370D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A3C3F786D6C2076657273696F6E3D22312E302220656E636F64696E673D225554462D38223F3E0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C73223E0A2020203C6C743E3230313730393038543134323731303C2F6C743E0A2020203C70763E0A2020202020203C6163723E0A2020202020202020203C61636F723E43414539303338343333333C2F61636F723E0A2020202020202020203C61636F703E333C2F61636F703E0A2020202020203C2F6163723E0A2020203C2F70763E0A3C2F6D326D3A6163703E0A0A'O ("HTTP/1.1 200 OK\r\nX-M2M-RI: m_updateAcp729567\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2004\r\nContent-Type: application/xml;charset=ISO-8859-1\r\nContent-Length: 237\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\">\n   <lt>20170908T142710</lt>\n   <pv>\n      <acr>\n         <acor>CAE90384333</acor>\n         <acop>3</acop>\n      </acr>\n   </pv>\n</m2m:acp>\n\n") } id 4
14:27:10.556835 OneM2M_Functions.ttcn:530 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2004 (2004), requestIdentifier := "m_updateAcp729567", primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "CAE90384333" }, accessControlOperations := int3 (3), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 4
14:27:10.557057 OneM2M_Functions.ttcn:531 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2004 (2004), requestIdentifier := "m_updateAcp729567", primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "CAE90384333" }, accessControlOperations := int3 (3), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 4
14:27:10.557353 OneM2M_Functions.ttcn:531 Message with id 4 was extracted from the queue of mcaPort.
14:27:10.557771 OneM2M_Functions.ttcn:528 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int3 (3), to_ := "in-name/acp_665959665", from_ := "admin:admin", requestIdentifier := "m_updateAcp426604", resourceType := omit, primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := omit, labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "CAE90384333" }, accessControlOperations := int60 (60), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := omit, choice := omit } }, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.558309 OneM2M_Functions.ttcn:528 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '505554202F696E2D6E616D652F6163705F36363539353936363520485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D4C656E6774683A203137340D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C0D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2061646D696E3A61646D696E0D0A582D4D324D2D52493A206D5F7570646174654163703432363630340D0A0D0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C73223E202020203C70763E20202020202020203C6163723E2020202020202020202020203C61636F723E43414539303338343333333C2F61636F723E2020202020202020202020203C61636F703E36303C2F61636F703E20202020202020203C2F6163723E202020203C2F70763E3C2F6D326D3A6163703E'O ("PUT /in-name/acp_665959665 HTTP/1.1\r\nAccept: application/xml\r\nContent-Length: 174\r\nContent-Type: application/xml\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: admin:admin\r\nX-M2M-RI: m_updateAcp426604\r\n\r\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\">    <pv>        <acr>            <acor>CAE90384333</acor>            <acop>60</acop>        </acr>    </pv></m2m:acp>") }
14:27:10.588364 OneM2M_Functions.ttcn:530 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E3120323030204F4B0D0A582D4D324D2D52493A206D5F7570646174654163703432363630340D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030340D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B636861727365743D49534F2D383835392D310D0A436F6E74656E742D4C656E6774683A203233380D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A3C3F786D6C2076657273696F6E3D22312E302220656E636F64696E673D225554462D38223F3E0A3C6D326D3A61637020786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C73223E0A2020203C6C743E3230313730393038543134323731303C2F6C743E0A2020203C70763E0A2020202020203C6163723E0A2020202020202020203C61636F723E43414539303338343333333C2F61636F723E0A2020202020202020203C61636F703E36303C2F61636F703E0A2020202020203C2F6163723E0A2020203C2F70763E0A3C2F6D326D3A6163703E0A0A'O ("HTTP/1.1 200 OK\r\nX-M2M-RI: m_updateAcp426604\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2004\r\nContent-Type: application/xml;charset=ISO-8859-1\r\nContent-Length: 238\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<m2m:acp xmlns:m2m=\"http://www.onem2m.org/xml/protocols\">\n   <lt>20170908T142710</lt>\n   <pv>\n      <acr>\n         <acor>CAE90384333</acor>\n         <acop>60</acop>\n      </acr>\n   </pv>\n</m2m:acp>\n\n") } id 5
14:27:10.589735 OneM2M_Functions.ttcn:530 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2004 (2004), requestIdentifier := "m_updateAcp426604", primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "CAE90384333" }, accessControlOperations := int60 (60), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 5
14:27:10.589908 OneM2M_Functions.ttcn:531 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2004 (2004), requestIdentifier := "m_updateAcp426604", primitiveContent := { accessControlPolicy := { resourceName := omit, resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := "20170908T142710", labels := omit, expirationTime := omit, announceTo := omit, announcedAttribute := omit, privileges := { accessControlRule_list := { { accessControlOriginators := { "CAE90384333" }, accessControlOperations := int60 (60), accessControlContexts_list := { }, accessControlAuthenticationFlag := omit, accessControlObjectDetails_list := { } } } }, selfPrivileges := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 5
14:27:10.590061 OneM2M_Functions.ttcn:531 Message with id 5 was extracted from the queue of mcaPort.
14:27:10.590478 OneM2M_Testcases.ttcn:18015 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int1 (1), to_ := "in-name/MyAe", from_ := "CAE90384333", requestIdentifier := "-m_createContainer488932", resourceType := int3 (3), primitiveContent := { container := { resourceName := "MyContainerResource", resourceType := omit, resourceID := omit, parentID := omit, creationTime := omit, lastModifiedTime := omit, labels := omit, accessControlPolicyIDs := omit, expirationTime := omit, dynamicAuthorizationConsultationIDs := omit, announceTo := omit, announcedAttribute := omit, stateTag := omit, creator := omit, maxNrOfInstances := omit, maxByteSize := omit, maxInstanceAge := omit, currentNrOfInstances := omit, currentByteSize := omit, locationID := omit, ontologyRef := omit, disableRetrieval := omit, choice := omit } }, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.590945 OneM2M_Testcases.ttcn:18015 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '504F5354202F696E2D6E616D652F4D79416520485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D4C656E6774683A2038330D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B74793D330D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2043414539303338343333330D0A582D4D324D2D52493A202D6D5F637265617465436F6E7461696E65723438383933320D0A0D0A3C6D326D3A636E7420786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C732220726E3D224D79436F6E7461696E65725265736F75726365222F3E'O ("POST /in-name/MyAe HTTP/1.1\r\nAccept: application/xml\r\nContent-Length: 83\r\nContent-Type: application/xml;ty=3\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: CAE90384333\r\nX-M2M-RI: -m_createContainer488932\r\n\r\n<m2m:cnt xmlns:m2m=\"http://www.onem2m.org/xml/protocols\" rn=\"MyContainerResource\"/>") }
14:27:10.618891 OneM2M_Testcases.ttcn:18017 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E312032303120437265617465640D0A436F6E74656E742D4C6F636174696F6E3A202F696E2D6373652F636E742D3933303938343836390D0A582D4D324D2D52493A202D6D5F637265617465436F6E7461696E65723438383933320D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030310D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C3B636861727365743D49534F2D383835392D310D0A436F6E74656E742D4C656E6774683A203539310D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A3C3F786D6C2076657273696F6E3D22312E302220656E636F64696E673D225554462D38223F3E0A3C6D326D3A636E7420786D6C6E733A6D326D3D22687474703A2F2F7777772E6F6E656D326D2E6F72672F786D6C2F70726F746F636F6C732220726E3D224D79436F6E7461696E65725265736F75726365223E0A2020203C74793E333C2F74793E0A2020203C72693E2F696E2D6373652F636E742D3933303938343836393C2F72693E0A2020203C70693E2F696E2D6373652F43414539303338343333333C2F70693E0A2020203C63743E3230313730393038543134323731303C2F63743E0A2020203C6C743E3230313730393038543134323731303C2F6C743E0A2020203C616370693E2F696E2D6373652F6163702D363130323139363239202F696E2D6373652F6163702D363635393539363635202F696E2D6373652F6163702D3835313636383734303C2F616370693E0A2020203C65743E3230313830393038543134323731303C2F65743E0A2020203C73743E303C2F73743E0A2020203C6D6E693E31303C2F6D6E693E0A2020203C6D62733E31303030303C2F6D62733E0A2020203C6D69613E303C2F6D69613E0A2020203C636E693E303C2F636E693E0A2020203C6362733E303C2F6362733E0A2020203C6F6C3E2F696E2D6373652F696E2D6E616D652F4D7941652F4D79436F6E7461696E65725265736F757263652F6F6C3C2F6F6C3E0A2020203C6C613E2F696E2D6373652F696E2D6E616D652F4D7941652F4D79436F6E7461696E65725265736F757263652F6C613C2F6C613E0A3C2F6D326D3A636E743E0A0A'O ("HTTP/1.1 201 Created\r\nContent-Location: /in-cse/cnt-930984869\r\nX-M2M-RI: -m_createContainer488932\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2001\r\nContent-Type: application/xml;charset=ISO-8859-1\r\nContent-Length: 591\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n<?xml version=\"1.0\" encoding=\"UTF-8\"?>\n<m2m:cnt xmlns:m2m=\"http://www.onem2m.org/xml/protocols\" rn=\"MyContainerResource\">\n   <ty>3</ty>\n   <ri>/in-cse/cnt-930984869</ri>\n   <pi>/in-cse/CAE90384333</pi>\n   <ct>20170908T142710</ct>\n   <lt>20170908T142710</lt>\n   <acpi>/in-cse/acp-610219629 /in-cse/acp-665959665 /in-cse/acp-851668740</acpi>\n   <et>20180908T142710</et>\n   <st>0</st>\n   <mni>10</mni>\n   <mbs>10000</mbs>\n   <mia>0</mia>\n   <cni>0</cni>\n   <cbs>0</cbs>\n   <ol>/in-cse/in-name/MyAe/MyContainerResource/ol</ol>\n   <la>/in-cse/in-name/MyAe/MyContainerResource/la</la>\n</m2m:cnt>\n\n") } id 6
14:27:10.621250 OneM2M_Testcases.ttcn:18017 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "-m_createContainer488932", primitiveContent := { container := { resourceName := "MyContainerResource", resourceType := int3 (3), resourceID := "/in-cse/cnt-930984869", parentID := "/in-cse/CAE90384333", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, accessControlPolicyIDs := { "/in-cse/acp-610219629", "/in-cse/acp-665959665", "/in-cse/acp-851668740" }, expirationTime := "20180908T142710", dynamicAuthorizationConsultationIDs := omit, announceTo := omit, announcedAttribute := omit, stateTag := 0, creator := omit, maxNrOfInstances := 10, maxByteSize := 10000, maxInstanceAge := 0, currentNrOfInstances := 0, currentByteSize := 0, locationID := omit, ontologyRef := omit, disableRetrieval := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 6
14:27:10.622723 OneM2M_Testcases.ttcn:18018 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2001 (2001), requestIdentifier := "-m_createContainer488932", primitiveContent := { container := { resourceName := "MyContainerResource", resourceType := int3 (3), resourceID := "/in-cse/cnt-930984869", parentID := "/in-cse/CAE90384333", creationTime := "20170908T142710", lastModifiedTime := "20170908T142710", labels := omit, accessControlPolicyIDs := { "/in-cse/acp-610219629", "/in-cse/acp-665959665", "/in-cse/acp-851668740" }, expirationTime := "20180908T142710", dynamicAuthorizationConsultationIDs := omit, announceTo := omit, announcedAttribute := omit, stateTag := 0, creator := omit, maxNrOfInstances := 10, maxByteSize := 10000, maxInstanceAge := 0, currentNrOfInstances := 0, currentByteSize := 0, locationID := omit, ontologyRef := omit, disableRetrieval := omit, choice := omit } }, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 6
14:27:10.622934 OneM2M_Testcases.ttcn:18018 Message with id 6 was extracted from the queue of mcaPort.
14:27:10.623249 OneM2M_Functions.ttcn:358 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int4 (4), to_ := "in-name/MyAe", from_ := "CAE90384333", requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource351213", resourceType := omit, primitiveContent := omit, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.623508 OneM2M_Functions.ttcn:358 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '44454C455445202F696E2D6E616D652F4D79416520485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C0D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2043414539303338343333330D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F64656C6574655265736F757263653335313231330D0A0D0A'O ("DELETE /in-name/MyAe HTTP/1.1\r\nAccept: application/xml\r\nContent-Type: application/xml\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: CAE90384333\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_deleteResource351213\r\n\r\n") }
14:27:10.653376 OneM2M_Functions.ttcn:361 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E3120323030204F4B0D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F64656C6574655265736F757263653335313231330D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030320D0A436F6E74656E742D4C656E6774683A20300D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A'O ("HTTP/1.1 200 OK\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_deleteResource351213\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2002\r\nContent-Length: 0\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n") } id 7
14:27:10.653939 OneM2M_Functions.ttcn:361 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2002 (2002), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource351213", primitiveContent := omit, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 7
14:27:10.654172 OneM2M_Functions.ttcn:362 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2002 (2002), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource351213", primitiveContent := omit, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 7
14:27:10.654399 OneM2M_Functions.ttcn:362 Message with id 7 was extracted from the queue of mcaPort.
14:27:10.654882 OneM2M_Functions.ttcn:358 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int4 (4), to_ := "in-name/acp_665959665", from_ := "admin:admin", requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource754137", resourceType := omit, primitiveContent := omit, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.655435 OneM2M_Functions.ttcn:358 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '44454C455445202F696E2D6E616D652F6163705F36363539353936363520485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C0D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2061646D696E3A61646D696E0D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F64656C6574655265736F757263653735343133370D0A0D0A'O ("DELETE /in-name/acp_665959665 HTTP/1.1\r\nAccept: application/xml\r\nContent-Type: application/xml\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: admin:admin\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_deleteResource754137\r\n\r\n") }
14:27:10.684278 OneM2M_Functions.ttcn:361 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E3120323030204F4B0D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F64656C6574655265736F757263653735343133370D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030320D0A436F6E74656E742D4C656E6774683A20300D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A'O ("HTTP/1.1 200 OK\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_deleteResource754137\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2002\r\nContent-Length: 0\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n") } id 8
14:27:10.684778 OneM2M_Functions.ttcn:361 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2002 (2002), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource754137", primitiveContent := omit, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 8
14:27:10.685665 OneM2M_Functions.ttcn:362 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2002 (2002), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource754137", primitiveContent := omit, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 8
14:27:10.686629 OneM2M_Functions.ttcn:362 Message with id 8 was extracted from the queue of mcaPort.
14:27:10.687375 OneM2M_Functions.ttcn:358 Sent on mcaPort to system @OneM2M_Types.MsgOut : { primitive := { requestPrimitive := { operation := int4 (4), to_ := "in-name/acp_610219629", from_ := "admin:admin", requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource786435", resourceType := omit, primitiveContent := omit, roleIDs := omit, originatingTimestamp := omit, requestExpirationTimestamp := omit, resultExpirationTimestamp := omit, operationExecutionTime := omit, responseType := omit, resultPersistence := omit, resultContent := omit, eventCategory := omit, deliveryAggregation := omit, groupRequestIdentifier := omit, filterCriteria := omit, discoveryResultType := omit, tokens := omit, tokenIDs := omit, localTokenIDs := omit, tokenRequestIndicator := omit } }, host := "132.227.122.103:8080", xmlNamespace := "m2m=\"http://www.onem2m.org/xml/protocols\"", protocolBinding := "HTTP", serialization := "XML", forcedFields := omit }
14:27:10.687752 OneM2M_Functions.ttcn:358 Outgoing message was mapped to @IPL4asp_Types.ASP_Send : { connId := -1, proto := { tcp := { } }, msg := '44454C455445202F696E2D6E616D652F6163705F36313032313936323920485454502F312E310D0A4163636570743A206170706C69636174696F6E2F786D6C0D0A436F6E74656E742D547970653A206170706C69636174696F6E2F786D6C0D0A486F73743A203133322E3232372E3132322E3130333A383038300D0A582D4D324D2D4F726967696E3A2061646D696E3A61646D696E0D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F64656C6574655265736F757263653738363433350D0A0D0A'O ("DELETE /in-name/acp_610219629 HTTP/1.1\r\nAccept: application/xml\r\nContent-Type: application/xml\r\nHost: 132.227.122.103:8080\r\nX-M2M-Origin: admin:admin\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_deleteResource786435\r\n\r\n") }
14:27:10.715135 OneM2M_Functions.ttcn:361 Message enqueued on mcaPort from system @IPL4asp_Types.ASP_RecvFrom : { connId := -1, remName := "132.227.122.103", remPort := 8080, locName := "172.18.0.2", locPort := 9999, proto := { tcp := { } }, userData := 0, msg := '485454502F312E3120323030204F4B0D0A582D4D324D2D52493A2054435F4353455F5345435F4143505F3030325F4352452D6D5F64656C6574655265736F757263653738363433350D0A582D4D324D2D4F726967696E3A202F696E2D6373650D0A582D4D324D2D5253433A20323030320D0A436F6E74656E742D4C656E6774683A20300D0A5365727665723A204A6574747928382E312E31362E763230313430393033290D0A0D0A'O ("HTTP/1.1 200 OK\r\nX-M2M-RI: TC_CSE_SEC_ACP_002_CRE-m_deleteResource786435\r\nX-M2M-Origin: /in-cse\r\nX-M2M-RSC: 2002\r\nContent-Length: 0\r\nServer: Jetty(8.1.16.v20140903)\r\n\r\n") } id 9
14:27:10.715656 OneM2M_Functions.ttcn:361 Incoming message was mapped to @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2002 (2002), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource786435", primitiveContent := omit, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 9
14:27:10.715837 OneM2M_Functions.ttcn:362 Receive operation on port mcaPort succeeded, message from system(): @OneM2M_Types.MsgIn : { primitive := { responsePrimitive := { responseStatusCode := int2002 (2002), requestIdentifier := "TC_CSE_SEC_ACP_002_CRE-m_deleteResource786435", primitiveContent := omit, to_ := omit, from_ := "/in-cse", originatingTimestamp := omit, resultExpirationTimestamp := omit, eventCategory := omit, contentStatus := omit, contentOffset := omit, assignedTokenIdentifiers := omit, tokenRequestInformation := omit } } } id 9
14:27:10.716012 OneM2M_Functions.ttcn:362 Message with id 9 was extracted from the queue of mcaPort.
14:27:10.716586 OneM2M_Functions.ttcn:137 Port mcaPort was unmapped from system:mcaPort.
14:27:10.716855 OneM2M_Functions.ttcn:138 Port acPort was unmapped from system:acPort.
14:27:10.717517 OneM2M_Testcases.ttcn:17957 Port acPort was stopped.
14:27:10.717706 OneM2M_Testcases.ttcn:17957 Port mcaPort was stopped.
14:27:10.717743 OneM2M_Testcases.ttcn:17957 Port mccPort was stopped.
14:27:10.717979 OneM2M_Testcases.ttcn:17957 Test case TC_CSE_SEC_ACP_002_CRE finished. Verdict: pass reason: "f_cse_createResource:INFO: Resource type 1 created successfuly"
14:27:10.718666 OneM2M_Testcases.ttcn:17971 Test case TC_CSE_SEC_ACP_002_DEL started.
14:27:10.718984 OneM2M_Testcases.ttcn:17971 Port acPort was started.
14:27:10.719079 OneM2M_Testcases.ttcn:17971 Port mcaPort was started.
14:27:10.719122 OneM2M_Testcases.ttcn:17971 Port mccPort was started.
14:27:10.719626 OneM2M_Functions.ttcn:37 entering f__IPL4__PROVIDER__connect: :9999 -> 132.227.122.103:8080 / TCP
14:27:10.720672 OneM2M_Functions.ttcn:37 Dynamic test case error: IPL4asp__PT_PROVIDER::user_map(mcaPort): Autoconnect: Can not connect: 99 Cannot assign requested address  (Cannot assign requested address)
14:27:10.723227 OneM2M_Testcases.ttcn:17972 Port acPort was stopped.
14:27:10.723338 OneM2M_Testcases.ttcn:17972 Port mcaPort was stopped.
14:27:10.723370 OneM2M_Testcases.ttcn:17972 Port mccPort was stopped.
14:27:10.724168 OneM2M_Testcases.ttcn:17972 Test case TC_CSE_SEC_ACP_002_DEL finished. Verdict: error
14:27:10.724775 OneM2M_Testcases.ttcn:17967 Test case TC_CSE_SEC_ACP_002_RET started.
14:27:10.724898 OneM2M_Testcases.ttcn:17967 Port acPort was started.
14:27:10.724941 OneM2M_Testcases.ttcn:17967 Port mcaPort was started.
14:27:10.724966 OneM2M_Testcases.ttcn:17967 Port mccPort was started.


Best regards,

Naum
Re: IPL4 port dynamic error [message #1772400 is a reply to message #1772398] Fri, 08 September 2017 15:09 Go to previous messageGo to next message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
Hi Naum,

could you insert a delay between the test cases?

What I believe it happens is that the port engages some kernel resources when mapped;
when unmapped, initiates release of these resources, but the new map comes very fast and finds these in a state different from idle.
A short delay maybe allows the disconnection to propagate through the whole chain.

BR
Elemer



Re: IPL4 port dynamic error [message #1772430 is a reply to message #1772400] Sat, 09 September 2017 07:44 Go to previous messageGo to next message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
Hi Naum,
with a bit more detail:

from the log it is apparent that you quickly try to map the port after it has been unmapped:
14:27:10.716586 OneM2M_Functions.ttcn:137 Port mcaPort was unmapped from system:mcaPort.
14:27:10.717706 OneM2M_Testcases.ttcn:17957 Port mcaPort was stopped.

:
14:27:10.719079 OneM2M_Testcases.ttcn:17971 Port mcaPort was started.
14:27:10.719626 OneM2M_Functions.ttcn:37 entering f__IPL4__PROVIDER__connect: :9999 -> 132.227.122.103:8080 / TCP


mcaPort is started 1.373 mseconds after it has been stopped.

Now , tearing down a TCP connection is a double-handshake sequence in which the remote end also participates:
Client                     Server
   +                           +
   |                           |
   |                           |
   |         FIN               |
   +--------------------------->
   |         ACK               |
   <---------------------------+
   |                           |
   |                           |
   |                           |
   |                           |
   |         FIN               |
   <---------------------------+
   |        ACK                |
   +--------------------------->
   |                           |
   |                           |
   |                           |
   |                           |
   |                           |
   +                           +

and it takes an (unpredictable ) amount of time , that could be longer than 1.4 msec.

If a new map tries to establish a new connection, it may found resource in an seized state, as the handshake did not finish:
       Client                     Server
          +                           +
          |                           |
unmap     |                           |
          |         FIN               |
          +--------------------------->
          |         ACK               |
          <---------------------------+
          |                           |
          |                           |
 map      |                           |
          |                           |
          |         FIN               |
          <---------------------------+
          |        ACK                |
          +--------------------------->
          |                           |
          |                           |
          |                           |
          |                           |
          |                           |
          +                           +


If you add some delay after unmap to permit the handshake to end, then you push the new map into a safe zone:

       Client                     Server
          +                           +
          |                           |
unmap     |                           |
          |         FIN               |
          +--------------------------->
          |         ACK               |
          <---------------------------+
          |                           |
          |                           |
          |                           |
          |                           |
          |         FIN               |
          <---------------------------+
          |        ACK                |
          +--------------------------->
          |                           |
          |                           |
          |                           |
 map      |                           |
          |                           |
          +                           +



BR

Elemer
Re: IPL4 port dynamic error [message #1772536 is a reply to message #1772430] Tue, 12 September 2017 07:55 Go to previous messageGo to next message
Naum Spaseski is currently offline Naum SpaseskiFriend
Messages: 81
Registered: February 2016
Location: Sophia Antipolis
Member

Hello Elemer,

I was wondering how I can make that "gap" longer without modifying the code (postamble or preamble), and I found that Titan supports executing external commands before and/or after a testcase, via the config file. So I used this config:

[EXTERNAL_COMMANDS]
BeginTestCase := "../external.sh"

and in the file I used the "sleep" command with "1s".

The result is that, it reduced the number of errors with that message, but it didn't resolved that problem completely. It is weird, because previously, in the previous versions of Titan, I did not have this problem.

Best regards,
Naum
Re: IPL4 port dynamic error [message #1772539 is a reply to message #1772536] Tue, 12 September 2017 08:20 Go to previous messageGo to next message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
Hi , Naum ,

sorry, can you elaborate?

Are you saying that this fault does not occur with previous Titan versions?

Are you also saying that inserting a delay does not solve the problem?
What do you mean by "not completely"? How long is the delay?
Did you try different values?


BR

Elemer






Re: IPL4 port dynamic error [message #1772543 is a reply to message #1772539] Tue, 12 September 2017 08:47 Go to previous messageGo to next message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
Hi Naum,

I suggest the following value for the delay:

ping the SUT node and see what's the response time;

for a TCP close you need double of that at a minimum.

To be on the safe side, take ten-twenty times that to account for network variations.
So for a 40-50 msec return time half a second/ a second delay is not absurd.

BR

Elemer


Re: IPL4 port dynamic error [message #1772552 is a reply to message #1772543] Tue, 12 September 2017 10:02 Go to previous messageGo to next message
Naum Spaseski is currently offline Naum SpaseskiFriend
Messages: 81
Registered: February 2016
Location: Sophia Antipolis
Member

Hello Elemer,

I made some further tests with the latest version of Titan, and some with a previous version (a commit from 10 august), and they were working in the same way - so my claims that it is the latest version isn't correct.

I tested a lot of things in the config file and the result is that I have to set up a default listening port even if the map behaviour for the port is "connect". Something like this:

*.mcaPort.defaultListeningPort:="0";
*.mcaPort.map_behavior :=  "connect"; 
*.mcaPort.RemoteHost :=  "127.0.0.1"; 
*.mcaPort.RemotePort :=  "8080"; 
*.mcaPort.lazy_conn_id_handling :=  "yes";


I don't understand why I have to put that listening port when I will make the connection, but at least, there is a solution.

Best regards,
Naum
Re: IPL4 port dynamic error [message #1772553 is a reply to message #1772552] Tue, 12 September 2017 10:07 Go to previous messageGo to next message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
Hi Naum,

interesting;

if you omit the listening port , then the connect fails systematically, or at random, in an unpredictable manner?


BR

Elemer
Re: IPL4 port dynamic error [message #1772554 is a reply to message #1772553] Tue, 12 September 2017 10:08 Go to previous messageGo to next message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
And also: do you need the delay or I was totally off the mark?
Re: IPL4 port dynamic error [message #1772565 is a reply to message #1772554] Tue, 12 September 2017 13:46 Go to previous messageGo to next message
Naum Spaseski is currently offline Naum SpaseskiFriend
Messages: 81
Registered: February 2016
Location: Sophia Antipolis
Member

When I don't use the "defaultListeningPort" (with Titan functional runtime and without delays), it fails almost systematically, except the first testcase.

Then, when I change to normal runtime of Titan (almost no change), and add the delays (with sleep), it lowers the number of errors by few, but it isn't perfect.

I confirm that the error is gone when using the functional runtime and the "defaultListeningPort".

Best regards,
Naum

[Updated on: Tue, 12 September 2017 14:10]

Report message to a moderator

Re: IPL4 port dynamic error [message #1772714 is a reply to message #1772565] Thu, 14 September 2017 08:52 Go to previous message
Elemer Lelik is currently offline Elemer LelikFriend
Messages: 854
Registered: January 2015
Senior Member
Hi all,


OK I was not entirely right about this:

the port configuration parameter defaultListeningPort plays a crucial role ;

-if it is set to "0", the test port will allocate dynamically a TCP port that is usable;
-if it is set to a value different from 0 (and of course less than 65536), the test port will try to use again and again the same TCP port
-if it is unset ( defaultListeningPort is not present in the config file), the test port will allocate TCP port #9999 (so it behaves as if defaultListeningPort would be set to "9999"
in the config file)

What happens when test cases which start with a map (which takes connect parameters from the config file) and end with an unmap are run in a sequence?
The first cycle is successful, but the second depends on the presence and value of the defaultListeningPort:
-if it is unset or set to a value different than zero , then the test port tries to seize that particular TCP port ; but as per the TCP standard, the port that has been used previously
has not yet been released by the kernel-although the TCP connection has been closed with FIN/ACK -FIN/ACK- and is in a waiting state for about two minutes.
So the new map is unsuccessful.
-if it is set to zero , then the test port will automatically chose a free local TCP port, and the subsequent test cases are successful


So the solution is to use defaultListeningPort set to "0"
*.mcaPort.defaultListeningPort:="0";
 


and no post -map or inter-testcase delay is needed.

If for some reason the user prefers a set local port , then a delay of about two minutes is needed.




Best regards

Elemer
Previous Topic:RAW coder: length field with offset
Next Topic:MQTT over Websocket in Titan
Goto Forum:
  


Current Time: Mon Nov 19 11:54:21 GMT 2018

Powered by FUDForum. Page generated in 0.03742 seconds
.:: Contact :: Home ::.

Powered by: FUDforum 3.0.2.
Copyright ©2001-2010 FUDforum Bulletin Board Software

Back to the top