View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0008358CTT UA Scripts1 - Script Issuepublic2022-09-27 12:292023-02-18 06:11
ReporterBernd Edlinger Assigned ToSebastian Allmendinger  
PrioritynormalSeverityminorReproducibilityalways
Status closedResolutionfixed 
Product Version1.04.500 
Target Version1.03.502Fixed in Version1.03.502 
Summary0008358: Session Services / Session Base / 012.js gets very slow when run a second time
Description

Observed this with CTT v1.04.11-01.00.500-20220807

I run a full test run including this script it takes exactly 60 seconds on the first test run,
but gets very slow when running test full test suite again.
The scritpt output window shows
ActivateSession( LocaleIds #1; UserIdentityToken: Anonymous_Channel ( ClientSignature=http://www.w3.org/2001/04/xmldsig-more#rsa-sha256, UserTokenSignature= ) ).Response.ResponseHeader.ServiceResult: Good (0x00000000) as expected.
Audit::PushAuditRecord - Thread and/or Subscription id is not initialized yet
ActivateSession( LocaleIds #1; UserIdentityToken: Anonymous_Channel ( ClientSignature=http://www.w3.org/2001/04/xmldsig-more#rsa-sha256, UserTokenSignature= ) ).Response.ResponseHeader.ServiceResult: Good (0x00000000) as expected.
Read( NodesToRead #2; TimestampsToReturn: 1; MaxAge: 0 ).Response.ResponseHeader.ServiceResult: Good (0x00000000) as expected.
Audit::PushAuditRecord - Thread and/or Subscription id is not initialized yet
CreateSession( EndpointUrl=opc.tcp://10.20.240.22:4880/Softing/OpcUa/TestServer; SessionName: UaCttSession_1168; RequestedSessionTimeout: 600000 ).Response.ResponseHeader.ServiceResult: Good (0x00000000) as expected.
Audit::PushAuditRecord - Thread and/or Subscription id is not initialized yet
ActivateSession( LocaleIds #1; UserIdentityToken: Anonymous_Channel ( ClientSignature=http://www.w3.org/2001/04/xmldsig-more#rsa-sha256, UserTokenSignature= ) ).Response.ResponseHeader.ServiceResult: Good (0x00000000) as expected.

and each session takes exactly 1 second, so the whole test case takes 1000/60 = 17 minutes
and after that the Issue 0005626 is often reproduced with Session Change User / 002.js and 004.js

TagsNo tags attached.
Files Affected

/maintree/Subscription Services/Subscription Minimum 1/Test Cases/002.js

Activities

Bernd Edlinger

2022-09-28 12:25

reporter   ~0017875

This is a capture, and the reason is twofold, first we get very slow answering the ReadRequest for the SessionDiagnostics,
and on the other hand, the CTT should not leave the session diagnostics enabled after the first test run completes:

301644 2022-09-28 12:24:34,497961876 169.254.237.100 10.20.240.22 OpcUa 1726 UA Secure Conversation Message: CreateSessionRequest
301660 2022-09-28 12:24:34,506934469 10.20.240.22 169.254.237.100 OpcUa 1482 UA Secure Conversation Message: CreateSessionResponse
301662 2022-09-28 12:24:34,539858597 169.254.237.100 10.20.240.22 OpcUa 575 UA Secure Conversation Message: ActivateSessionRequest
301665 2022-09-28 12:24:34,542596485 169.254.237.100 10.20.240.22 OpcUa 238 UA Secure Conversation Message: ReadRequest
301667 2022-09-28 12:24:35,421152636 10.20.240.22 169.254.237.100 OpcUa 172 UA Secure Conversation Message: ReadResponse
301668 2022-09-28 12:24:35,436249347 169.254.237.100 10.20.240.22 OpcUa 1726 UA Secure Conversation Message: CreateSessionRequest
301684 2022-09-28 12:24:35,445094481 10.20.240.22 169.254.237.100 OpcUa 1482 UA Secure Conversation Message: CreateSessionResponse
301686 2022-09-28 12:24:35,477931965 169.254.237.100 10.20.240.22 OpcUa 575 UA Secure Conversation Message: ActivateSessionRequest
301687 2022-09-28 12:24:35,478997306 10.20.240.22 169.254.237.100 OpcUa 182 UA Secure Conversation Message: ActivateSessionResponse
301688 2022-09-28 12:24:35,480750109 169.254.237.100 10.20.240.22 OpcUa 238 UA Secure Conversation Message: ReadRequest
301690 2022-09-28 12:24:36,426294323 10.20.240.22 169.254.237.100 OpcUa 172 UA Secure Conversation Message: ReadResponse
301691 2022-09-28 12:24:36,443367175 169.254.237.100 10.20.240.22 OpcUa 1726 UA Secure Conversation Message: CreateSessionRequest
301707 2022-09-28 12:24:36,452858496 10.20.240.22 169.254.237.100 OpcUa 1482 UA Secure Conversation Message: CreateSessionResponse
301709 2022-09-28 12:24:36,486855692 169.254.237.100 10.20.240.22 OpcUa 574 UA Secure Conversation Message: ActivateSessionRequest
301711 2022-09-28 12:24:36,488075854 10.20.240.22 169.254.237.100 OpcUa 182 UA Secure Conversation Message: ActivateSessionResponse
301712 2022-09-28 12:24:36,489895031 169.254.237.100 10.20.240.22 OpcUa 238 UA Secure Conversation Message: ReadRequest
301714 2022-09-28 12:24:37,426652834 10.20.240.22 169.254.237.100 OpcUa 172 UA Secure Conversation Message: ReadResponse
301715 2022-09-28 12:24:37,443973182 169.254.237.100 10.20.240.22 OpcUa 1726 UA Secure Conversation Message: CreateSessionRequest

Bernd Edlinger

2022-09-29 10:26

reporter   ~0017887

Note: Subscription / Subscription Minimum 1 / 002.js leaves the Diagnostics enabled,
when this test case is disabled, the runtime of the next Session Services / Session Base / 012.js
is again quick.

Sebastian Allmendinger

2023-01-31 06:33

developer   ~0018622

The test script '/maintree/Subscription Services/Subscription Minimum 1/Test Cases/002.js' has been updated to revert the value of the EnabledFlag in case that the value has been changed by the test script.

Paul Hunkar

2023-02-18 06:11

administrator   ~0018743

Reviewed in call (2023-02-10) agreed to changes, closed issue

Issue History

Date Modified Username Field Change
2022-09-27 12:29 Bernd Edlinger New Issue
2022-09-28 12:25 Bernd Edlinger Note Added: 0017875
2022-09-29 10:26 Bernd Edlinger Note Added: 0017887
2022-11-18 16:53 Paul Hunkar Assigned To => Yannik Klaass
2022-11-18 16:53 Paul Hunkar Status new => assigned
2022-11-19 10:31 Sebastian Allmendinger Assigned To Yannik Klaass => Sebastian Allmendinger
2023-01-31 06:28 Sebastian Allmendinger Files Affected => /maintree/Subscription Services/Subscription Minimum 1/Test Cases/002.js
2023-01-31 06:33 Sebastian Allmendinger Status assigned => resolved
2023-01-31 06:33 Sebastian Allmendinger Resolution open => fixed
2023-01-31 06:33 Sebastian Allmendinger Note Added: 0018622
2023-02-18 06:09 Paul Hunkar Project Compliance Test Tool (CTT) Unified Architecture => CTT UA Scripts
2023-02-18 06:11 Paul Hunkar Product Version => 1.04.500
2023-02-18 06:11 Paul Hunkar Fixed in Version => 1.03.502
2023-02-18 06:11 Paul Hunkar Target Version => 1.03.502
2023-02-18 06:11 Paul Hunkar Status resolved => closed
2023-02-18 06:11 Paul Hunkar Note Added: 0018743