Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

nodeopcua client stack fails to report new subscription ID recreated back to application, after reconnection to server #1368

Open
Graos opened this issue May 16, 2024 · 0 comments

Comments

@Graos
Copy link

Graos commented May 16, 2024

nodeopcua Client: 2.124.0

Steps to follow to reproduce the issue:

Connect to server.
Create a single Data subscription to the server. (e.g. Subscription ID: 174570)
Close the server. This results in connection attempt from client to server.
Start the server again. Client automatically connects to server and creates a new subscription (e.g. Subscription ID: 511925) . But this new subscription ID is not been notified to the application.

Note: termination of the new subscription ID 511925 is notified to the application.

Log snippet for the sequence of events
16/05/2024, 07:10:41.448[Info]: --> Data subscription ID created: 174570
16/05/2024, 07:10:41.448[Info]: --> revised maxKeepAliveCount: 3,requested: 3
16/05/2024, 07:10:41.448[Info]: --> revised lifetimeCount: 1000,requested: 1000
16/05/2024, 07:10:41.449[Info]: --> revised publishingInterval: 2000,requested: 2000
16/05/2024, 07:10:41.450[Info]: --> keepalive received for 174570
16/05/2024, 07:10:43.436[Info]: --> ns=2;i=6130 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.436[Info]: --> ns=2;i=6131 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.436[Info]: --> ns=2;i=6132 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6133 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6134 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6135 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6136 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6137 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6138 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6139 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6140 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6141 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6142 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6143 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.437[Info]: --> ns=2;i=6144 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.438[Info]: --> ns=2;i=6145 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.438[Info]: --> ns=2;i=6146 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:43.438[Info]: --> ns=2;i=6147 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:10:47.446[Info]: --> keepalive received for 174570
16/05/2024, 07:10:48.935[Info]: --> connection to OPC UA server: lost
16/05/2024, 07:10:51.966[Warning]: --> connection attempt 0 to OPC UA server: opc.tcp://localhost:26543 with next attempt delay:1000 ms
16/05/2024, 07:10:55.23[Warning]: --> connection attempt 1 to OPC UA server: opc.tcp://localhost:26543 with next attempt delay:2000 ms
16/05/2024, 07:10:59.80[Warning]: --> connection attempt 2 to OPC UA server: opc.tcp://localhost:26543 with next attempt delay:4000 ms
16/05/2024, 07:11:03.87[Info]: --> Re-connected to OPC UA server:
16/05/2024, 07:11:03.156[Info]: --> ns=2;i=6130 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.156[Info]: --> ns=2;i=6131 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.156[Info]: --> ns=2;i=6132 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.156[Info]: --> ns=2;i=6133 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.156[Info]: --> ns=2;i=6134 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.156[Info]: --> ns=2;i=6135 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6136 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6137 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6138 value has changed to Variant(Scalar, value: 4294967295)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6139 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6140 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6141 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6142 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6143 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6144 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6145 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6146 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:03.157[Info]: --> ns=2;i=6147 value has changed to Variant(Scalar, value: 1.0199999809265137)
16/05/2024, 07:11:07.164[Info]: --> keepalive received for 511925
16/05/2024, 07:11:13.203[Info]: --> keepalive received for 511925
16/05/2024, 07:11:16.786[Info]: --> Data subscription ID:511925 is terminated.
16/05/2024, 07:11:16.787[Warning]: --> changed subscription Id 511925 is not notified. differs from original SubId :174570

Wireshark capture snapshot:

image

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant