Plugin Diagnostic Event on Axis Camera saying method execution took too long for engine::doobtaindevice agent on starting of my analytical plugin
Getting this error on starting of my plugin in plugin settings every time i only have a map added to the doobtaindevice method to hold camera detail and i am using 5.1.3.38 version of nx client and server in win 10 pro
but i see this after integrating live to my code i think
-
Hi Subramanian
Thanks for your question.
Could you clarify whether you are using your own custom plugin or the built-in Nx AXIS plugin?The warning message suggests that your implementation may have taken longer than expected while binding the deviceAgent to the device. If that’s the case, the issue might be related to your Engine.cpp or Engine.h.
Given the available details, we’re like unable to draw a clear conclusion. Additionally, it appears that the mediaserver version is quite old (5.1.3), so I would recommend updating your system to the latest version (6.0.4, or 6.1.0 if you’re open to using the beta). The newer versions include several important fixes and improvements.
In summary, here are a few steps to consider:
- Update the system to version 6.0.4 or 6.1.0 (beta).
- Review logs during the creation and binding process of the deviceAgent.
- Test with different devices, such as AXIS or other brands.
- If the issue persists, please refer to the debugging guide and provide logs.
- Likely you may try to use the STUB plugin example to identify if the issue is on plugin side/mediaserver side/custom plugin or the performance of hardware.
Thanks.
0 -
i dont see any problem other then starting late and unfortunately upgrade is not an option for me
following is the warning generated time with client log in VERBOSE
-------------------2025-06-11 17:28:49.748 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Live update request
2025-06-11 17:28:49.748 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Requesting events:
from: 11.06.2025 11:51:50.214 UTC
to: DATETIME_NOW
type: anyEvent
subtype:
sort: AscendingOrder
limit: 150
2025-06-11 17:28:49.748 7688 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Issuing request GET /ec2/getEvents to https://localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:28:49.749 7688 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <107> https://localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:28:49.749 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Sending request GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1 (url https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150) via reused connection
2025-06-11 17:28:49.749 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Request has been successfully sent to https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 from 127.0.0.1:50697. GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1
2025-06-11 17:28:49.751 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Response headers from https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 has been successfully read: HTTP/1.1 200 OK
2025-06-11 17:28:49.751 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): 631 message body bytes have been received from https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:28:49.751 5620 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <107> Got serialized reply. OS error: 0, HTTP status: OK
2025-06-11 17:28:49.751 5620 VERBOSE rest::ServerConnection [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <107>: Reply success for 2ms
2025-06-11 17:28:49.765 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Set fetched time window:
from: 0
to: 11.06.2025 11:51:50.214 UTC
2025-06-11 17:28:49.765 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Live update commit
2025-06-11 17:28:49.766 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Committing no events
2025-06-11 17:28:50.707 744c DEBUG QnResourceDiscoveryManager(0x1db4f744120): Network addresses are up to date: { fd7a:115c:a1e0::1, fe80::d8ff:5060:f230:9168%50, 100.64.0.1, 192.168.0.181 }
2025-06-11 17:28:50.708 744c VERBOSE nx::vms::common::SystemSettings(0x1db46e9fc90): Providing local System ID {b20560ec-cc60-48b2-bfe8-2c71cf446679}
2025-06-11 17:28:50.708 744c DEBUG QnResourceDiscoveryManager(0x1db4f744120): Searcher Desktop camera took 0 ms to find 1 resources
2025-06-11 17:28:50.708 744c VERBOSE nx::vms::common::SystemSettings(0x1db46e9fc90): Providing local System ID {b20560ec-cc60-48b2-bfe8-2c71cf446679}
2025-06-11 17:28:52.647 7688 DEBUG QnCloudStatusWatcherPrivate(0x1db4d4db700): Try update systems list: has request is false
2025-06-11 17:28:54.528 52d4 VERBOSE nx::vms::discovery::UdpMulticastFinder(0x1db4d4dcaa0): From 100.64.0.1:51734 got: {"brand":"hanwha","cloudHost":"sync.wavevms.com","cloudSystemId":"","customization":"hanwha","ecDbReadOnly":false,"hwPlatform":"unknown","id":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","localSystemId":"{b20560ec-cc60-48b2-bfe8-2c71cf446679}","name":"Server DESKTOP-3AK97A3","port":7001,"protoVersion":5107,"realm":"VMS","remoteAddresses":["fe80::d8ff:5060:f230:9168%50","192.168.0.181","49.37.193.131","fd7a:115c:a1e0::1","100.64.0.1"],"runtimeId":"{a2f7a4c7-d698-470a-add5-eda40bc6e7bf}","serverFlags":"SF_HasPublicIP|SF_SupportsTranscoding","sslAllowed":true,"synchronizedTimeMs":"1749642731605","systemName":"DESKTOP-3AK97A3","type":"Media Server","version":"5.1.3.38363"}
2025-06-11 17:28:54.529 52d4 VERBOSE nx::vms::discovery::ModuleConnector::Module(0x1db4f580130, 5f1d5954-2ac0-9e13-6d51-306021d9c455): Add endpoints { 100.64.0.1:7001, 192.168.0.181:7001, 49.37.193.131:7001, [fd7a:115c:a1e0::1]:7001, [fe80::d8ff:5060:f230:9168%50]:7001 }
2025-06-11 17:28:54.529 52d4 VERBOSE nx::vms::discovery::UdpMulticastFinder(0x1db4d4dcaa0): From 192.168.0.181:51735 got: {"brand":"hanwha","cloudHost":"sync.wavevms.com","cloudSystemId":"","customization":"hanwha","ecDbReadOnly":false,"hwPlatform":"unknown","id":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","localSystemId":"{b20560ec-cc60-48b2-bfe8-2c71cf446679}","name":"Server DESKTOP-3AK97A3","port":7001,"protoVersion":5107,"realm":"VMS","remoteAddresses":["fe80::d8ff:5060:f230:9168%50","192.168.0.181","49.37.193.131","fd7a:115c:a1e0::1","100.64.0.1"],"runtimeId":"{a2f7a4c7-d698-470a-add5-eda40bc6e7bf}","serverFlags":"SF_HasPublicIP|SF_SupportsTranscoding","sslAllowed":true,"synchronizedTimeMs":"1749642731605","systemName":"DESKTOP-3AK97A3","type":"Media Server","version":"5.1.3.38363"}
2025-06-11 17:28:54.530 52d4 VERBOSE nx::vms::discovery::ModuleConnector::Module(0x1db4f580130, 5f1d5954-2ac0-9e13-6d51-306021d9c455): Add endpoints { 100.64.0.1:7001, 192.168.0.181:7001, 49.37.193.131:7001, [fd7a:115c:a1e0::1]:7001, [fe80::d8ff:5060:f230:9168%50]:7001 }
2025-06-11 17:29:02.643 7688 DEBUG QnCloudStatusWatcherPrivate(0x1db4d4db700): Try update systems list: has request is false
2025-06-11 17:29:03.651 6b60 DEBUG HTTP: Received request from 127.0.0.1:7001:
KEEP-ALIVE * RTSP/1.0cSeq: 30
-------------------
2025-06-11 17:29:04.530 52d4 VERBOSE nx::vms::discovery::UdpMulticastFinder(0x1db4d4dcaa0): From 100.64.0.1:51734 got: {"brand":"hanwha","cloudHost":"sync.wavevms.com","cloudSystemId":"","customization":"hanwha","ecDbReadOnly":false,"hwPlatform":"unknown","id":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","localSystemId":"{b20560ec-cc60-48b2-bfe8-2c71cf446679}","name":"Server DESKTOP-3AK97A3","port":7001,"protoVersion":5107,"realm":"VMS","remoteAddresses":["fe80::d8ff:5060:f230:9168%50","192.168.0.181","49.37.193.131","fd7a:115c:a1e0::1","100.64.0.1"],"runtimeId":"{a2f7a4c7-d698-470a-add5-eda40bc6e7bf}","serverFlags":"SF_HasPublicIP|SF_SupportsTranscoding","sslAllowed":true,"synchronizedTimeMs":"1749642731605","systemName":"DESKTOP-3AK97A3","type":"Media Server","version":"5.1.3.38363"}
2025-06-11 17:29:04.530 52d4 VERBOSE nx::vms::discovery::ModuleConnector::Module(0x1db4f580130, 5f1d5954-2ac0-9e13-6d51-306021d9c455): Add endpoints { 100.64.0.1:7001, 192.168.0.181:7001, 49.37.193.131:7001, [fd7a:115c:a1e0::1]:7001, [fe80::d8ff:5060:f230:9168%50]:7001 }
2025-06-11 17:29:04.530 52d4 VERBOSE nx::vms::discovery::UdpMulticastFinder(0x1db4d4dcaa0): From 192.168.0.181:51735 got: {"brand":"hanwha","cloudHost":"sync.wavevms.com","cloudSystemId":"","customization":"hanwha","ecDbReadOnly":false,"hwPlatform":"unknown","id":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","localSystemId":"{b20560ec-cc60-48b2-bfe8-2c71cf446679}","name":"Server DESKTOP-3AK97A3","port":7001,"protoVersion":5107,"realm":"VMS","remoteAddresses":["fe80::d8ff:5060:f230:9168%50","192.168.0.181","49.37.193.131","fd7a:115c:a1e0::1","100.64.0.1"],"runtimeId":"{a2f7a4c7-d698-470a-add5-eda40bc6e7bf}","serverFlags":"SF_HasPublicIP|SF_SupportsTranscoding","sslAllowed":true,"synchronizedTimeMs":"1749642731605","systemName":"DESKTOP-3AK97A3","type":"Media Server","version":"5.1.3.38363"}
2025-06-11 17:29:04.530 52d4 VERBOSE nx::vms::discovery::ModuleConnector::Module(0x1db4f580130, 5f1d5954-2ac0-9e13-6d51-306021d9c455): Add endpoints { 100.64.0.1:7001, 192.168.0.181:7001, 49.37.193.131:7001, [fd7a:115c:a1e0::1]:7001, [fe80::d8ff:5060:f230:9168%50]:7001 }
2025-06-11 17:29:04.742 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Live update request
2025-06-11 17:29:04.743 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Requesting events:
from: 11.06.2025 11:51:50.214 UTC
to: DATETIME_NOW
type: anyEvent
subtype:
sort: AscendingOrder
limit: 150
2025-06-11 17:29:04.743 7688 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Issuing request GET /ec2/getEvents to https://localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:29:04.743 7688 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <108> https://localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:29:04.743 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Sending request GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1 (url https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150) via reused connection
2025-06-11 17:29:04.744 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Request has been successfully sent to https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 from 127.0.0.1:50697. GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1
2025-06-11 17:29:04.744 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Connection is closed in state receivingResponse. Url https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150. An established connection was aborted by the software in your host machine.
2025-06-11 17:29:04.744 5620 VERBOSE nx::network::ssl::StreamSocket(0x1db72b48200): Client-side. Local 0.0.0.0, remote 0.0.0.0, socket nx::network::cloud::CloudStreamSocket(0x1db7773b130), transforming channel nx::network::aio::StreamTransformingAsyncChannel(0x1db5dce4290)
2025-06-11 17:29:04.744 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Opening connection to localhost:7001. url https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150, socket 18446744073709551615
2025-06-11 17:29:04.744 5620 VERBOSE nx::network::cloud::CloudStreamSocket(0x1db7773b130): connectAsync. localhost:7001
2025-06-11 17:29:04.744 5620 VERBOSE nx::network::cloud::detail::CloudStreamSocketConnector(0x1db7343a4b0): Connecting to localhost:7001 with timeout 1m
2025-06-11 17:29:04.744 5620 VERBOSE nx::network::cloud::detail::CloudStreamSocketConnector(0x1db7343a4b0): Address resolved. Code: 0, entries: 1
2025-06-11 17:29:04.745 5620 VERBOSE nx::network::cloud::AnyAccessibleAddressConnector(0x1db782e5a70): Connecting to { direct:localhost({ port=7001 }) } with timeout 1m
2025-06-11 17:29:04.745 5620 VERBOSE nx::network::cloud::AnyAccessibleAddressConnector(0x1db782e5a70): Trying direct connection to localhost:7001
2025-06-11 17:29:04.745 5620 VERBOSE nx::network::TCPSocket(0x1db776d6c90): Connect to localhost:7001 completed with result The operation completed successfully.
2025-06-11 17:29:04.745 5620 VERBOSE nx::network::cloud::AnyAccessibleAddressConnector(0x1db782e5a70): Connection completed with result The operation completed successfully. , type direct
2025-06-11 17:29:04.745 5620 VERBOSE nx::network::cloud::AnyAccessibleAddressConnector(0x1db782e5a70): Reporting connect success. Address { direct:localhost({ port=7001 }) }
2025-06-11 17:29:04.745 5620 VERBOSE nx::network::cloud::detail::CloudStreamSocketConnector(0x1db7343a4b0): Connect to localhost:7001 completed with The operation completed successfully.
2025-06-11 17:29:04.746 5620 VERBOSE nx::network::cloud::CloudStreamSocket(0x1db7773b130): Connect completed with result 0
2025-06-11 17:29:04.746 5620 VERBOSE nx::network::cloud::CloudStreamSocket(0x1db7773b130): Got connection to []
2025-06-11 17:29:04.750 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Connect to https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 completed with result 0
2025-06-11 17:29:04.750 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Sending request GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1 (url https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150)
2025-06-11 17:29:04.750 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Request has been successfully sent to https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 from 127.0.0.1:50756. GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1
2025-06-11 17:29:04.752 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Response headers from https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 has been successfully read: HTTP/1.1 200 OK
2025-06-11 17:29:04.752 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): 631 message body bytes have been received from https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:29:04.752 5620 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <108> Got serialized reply. OS error: 0, HTTP status: OK
2025-06-11 17:29:04.752 5620 VERBOSE rest::ServerConnection [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <108>: Reply success for 9ms
2025-06-11 17:29:04.758 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Set fetched time window:
from: 0
to: 11.06.2025 11:51:50.214 UTC
2025-06-11 17:29:04.758 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Live update commit
2025-06-11 17:29:04.758 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Committing no events
2025-06-11 17:29:05.704 744c DEBUG QnResourceDiscoveryManager(0x1db4f744120): Network addresses are up to date: { fd7a:115c:a1e0::1, fe80::d8ff:5060:f230:9168%50, 100.64.0.1, 192.168.0.181 }
2025-06-11 17:29:05.704 744c VERBOSE nx::vms::common::SystemSettings(0x1db46e9fc90): Providing local System ID {b20560ec-cc60-48b2-bfe8-2c71cf446679}
2025-06-11 17:29:05.705 744c DEBUG QnResourceDiscoveryManager(0x1db4f744120): Searcher Desktop camera took 0 ms to find 1 resources
2025-06-11 17:29:05.705 744c VERBOSE nx::vms::common::SystemSettings(0x1db46e9fc90): Providing local System ID {b20560ec-cc60-48b2-bfe8-2c71cf446679}
2025-06-11 17:29:06.742 7688 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Issuing request GET /ec2/bookmarks/tags to https://localhost:7001/ec2/bookmarks/tags?format=ubjson&limit=20
2025-06-11 17:29:06.742 7688 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <109> https://localhost:7001/ec2/bookmarks/tags?format=ubjson&limit=20
2025-06-11 17:29:06.742 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Sending request GET /ec2/bookmarks/tags?format=ubjson&limit=20 HTTP/1.1 (url https://admin@localhost:7001/ec2/bookmarks/tags?format=ubjson&limit=20) via reused connection
2025-06-11 17:29:06.742 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Request has been successfully sent to https://admin@localhost:7001/ec2/bookmarks/tags?format=ubjson&limit=20 from 127.0.0.1:50756. GET /ec2/bookmarks/tags?format=ubjson&limit=20 HTTP/1.1
2025-06-11 17:29:06.744 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Response headers from https://admin@localhost:7001/ec2/bookmarks/tags?format=ubjson&limit=20 has been successfully read: HTTP/1.1 200 OK
2025-06-11 17:29:06.744 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): 4 message body bytes have been received from https://admin@localhost:7001/ec2/bookmarks/tags?format=ubjson&limit=20
2025-06-11 17:29:06.744 5620 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <109> Got 4 byte(s) reply of content type application/ubjson. OS error: 0, HTTP status: OK
2025-06-11 17:29:06.744 5620 VERBOSE rest::ServerConnection [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <109>: Reply success for 1ms
2025-06-11 17:29:07.323 2980 VERBOSE nx::network::websocket::WebSocket(0x1db50ae6980): Got frame. Type: 2, size from header: 0
2025-06-11 17:29:07.324 5efc VERBOSE nx::vms::common::SystemSettings(0x1db46e9fc90): Providing local System ID {b20560ec-cc60-48b2-bfe8-2c71cf446679}
2025-06-11 17:29:07.324 5efc VERBOSE nx::p2p::MessageBus(0x1db559bc170): Got tran: {5ef4d669-670e-4a3e-ac6c-2d8036f7c9bf} <--- {5f1d5954-2ac0-9e13-6d51-306021d9c455}. Command: broadcastAction. Seq: 0. timestamp: (0, 0). Created by: {5f1d5954-2ac0-9e13-6d51-306021d9c455}(dbId={00000000-0000-0000-0000-000000000000}).
2025-06-11 17:29:07.325 7688 VERBOSE QnWorkbenchNotificationsHandler(0x1db500adff0): An action is received: {actionType: showPopupAction, toggleState: Undefined, receivedFromRemoteHost: true, resources: none, params: {"needConfirmation":false,"actionResourceId":"{00000000-0000-0000-0000-000000000000}","url":"","emailAddress":"","fps":10,"streamQuality":"highest","recordAfter":0,"relayOutputId":"","sayText":"","tags":"","text":"","durationMs":5000,"additionalResources":[],"allUsers":true,"forced":true,"presetId":"","useSource":false,"recordBeforeMs":1000,"playToClient":true,"contentType":"","actionId":"{56735f54-27c7-413b-bfa6-d01b8866d023}","authType":"authBasicAndDigest","httpMethod":""}, runtimeParams: {"eventType":"pluginDiagnosticEvent","eventTimestampUsec":1749643147566000,"eventResourceId":"{12756c84-4c64-cfe5-0da4-6d0d0bfee1fd}","resourceName":"","sourceServerId":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","reasonCode":"none","inputPortId":"","caption":"Issue with Analytics Plugin detected","description":"Plugin failed to start working with Device. Technical details: Method Engine::obtainDeviceAgent() of Engine of insites implemented in the Plugin incorrectly: Method execution took too long, details: Device: AXISP1375E ({072cb529-ce4d-8935-91a1-7e83e1162320})","metadata":{"cameraRefs":[],"instigators":[],"allUsers":false,"level":"warning"},"omitDbLogging":false,"analyticsPluginId":"","analyticsEngineId":"{00000000-0000-0000-0000-000000000000}","objectTrackId":"{00000000-0000-0000-0000-000000000000}","key":"","attributes":[],"progress":0.0}, ruleId: 86754159-bf73-a590-6919-4ed5805eb85a, aggregationCount: 1}
2025-06-11 17:29:07.325 7688 VERBOSE nx::vms::event: QnUserResource(0x1db55932670, admin) has permission for the event from nx::vms::common::AnalyticsEngineResource(0x1db4f4de9e0, [insites {12756c84-4c64-cfe5-0da4-6d0d0bfee1fd}])
2025-06-11 17:29:07.325 7688 VERBOSE QnWorkbenchNotificationsHandler(0x1db500adff0): A notification is added: {actionType: showPopupAction, toggleState: Undefined, receivedFromRemoteHost: true, resources: none, params: {"needConfirmation":false,"actionResourceId":"{00000000-0000-0000-0000-000000000000}","url":"","emailAddress":"","fps":10,"streamQuality":"highest","recordAfter":0,"relayOutputId":"","sayText":"","tags":"","text":"","durationMs":5000,"additionalResources":[],"allUsers":true,"forced":true,"presetId":"","useSource":false,"recordBeforeMs":1000,"playToClient":true,"contentType":"","actionId":"{56735f54-27c7-413b-bfa6-d01b8866d023}","authType":"authBasicAndDigest","httpMethod":""}, runtimeParams: {"eventType":"pluginDiagnosticEvent","eventTimestampUsec":1749643147566000,"eventResourceId":"{12756c84-4c64-cfe5-0da4-6d0d0bfee1fd}","resourceName":"","sourceServerId":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","reasonCode":"none","inputPortId":"","caption":"Issue with Analytics Plugin detected","description":"Plugin failed to start working with Device. Technical details: Method Engine::obtainDeviceAgent() of Engine of insites implemented in the Plugin incorrectly: Method execution took too long, details: Device: AXISP1375E ({072cb529-ce4d-8935-91a1-7e83e1162320})","metadata":{"cameraRefs":[],"instigators":[],"allUsers":false,"level":"warning"},"omitDbLogging":false,"analyticsPluginId":"","analyticsEngineId":"{00000000-0000-0000-0000-000000000000}","objectTrackId":"{00000000-0000-0000-0000-000000000000}","key":"","attributes":[],"progress":0.0}, ruleId: 86754159-bf73-a590-6919-4ed5805eb85a, aggregationCount: 1}
2025-06-11 17:29:07.326 7688 VERBOSE nx::vms::client::desktop::NotificationListModel::Private(0x1db4fd6c680): Received action: showPopupAction, id: {56735f54-27c7-413b-bfa6-d01b8866d023}
2025-06-11 17:29:07.326 7688 VERBOSE nx::vms::client::desktop::EventRibbon(0x1db50b17880): 1 tiles inserted at position 8, new count is 9, update nx::vms::client::desktop::EventRibbon::UpdateMode::instant, scrollDown is false
2025-06-11 17:29:07.371 2980 VERBOSE nx::network::websocket::WebSocket(0x1db50ae6980): Got frame. Type: 2, size from header: 0
2025-06-11 17:29:07.371 5efc VERBOSE nx::vms::common::SystemSettings(0x1db46e9fc90): Providing local System ID {b20560ec-cc60-48b2-bfe8-2c71cf446679}
2025-06-11 17:29:07.371 5efc VERBOSE nx::p2p::MessageBus(0x1db559bc170): Got tran: {5ef4d669-670e-4a3e-ac6c-2d8036f7c9bf} <--- {5f1d5954-2ac0-9e13-6d51-306021d9c455}. Command: serverRuntimeEvent. Seq: 0. timestamp: (0, 0). Created by: {5f1d5954-2ac0-9e13-6d51-306021d9c455}(dbId={00000000-0000-0000-0000-000000000000}).
2025-06-11 17:29:07.372 3d54 VERBOSE nx::network::http::AsyncClient(0x1db6dc79b50): Response headers from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D has been successfully read: HTTP/1.1 200 OK
2025-06-11 17:29:07.372 3d54 VERBOSE nx::network::http::AsyncClient(0x1db6dc79b50): 1490 message body bytes have been received from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D
2025-06-11 17:29:07.372 3d54 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <92> Got serialized reply. OS error: 0, HTTP status: OK
2025-06-11 17:29:07.372 3d54 VERBOSE rest::ServerConnection [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <92>: Reply success for 55000ms
2025-06-11 17:29:07.373 5620 VERBOSE nx::network::http::AsyncClient(0x1db6dc7a770): Response headers from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D has been successfully read: HTTP/1.1 200 OK
2025-06-11 17:29:07.373 5620 VERBOSE nx::network::http::AsyncClient(0x1db6dc7a770): 1490 message body bytes have been received from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D
2025-06-11 17:29:07.373 5620 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <95> Got serialized reply. OS error: 0, HTTP status: OK
2025-06-11 17:29:07.374 5620 VERBOSE rest::ServerConnection [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <95>: Reply success for 54923ms
2025-06-11 17:29:07.374 2980 VERBOSE nx::network::http::AsyncClient(0x1db6dc79540): Response headers from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D has been successfully read: HTTP/1.1 200 OK
2025-06-11 17:29:07.375 2980 VERBOSE nx::network::http::AsyncClient(0x1db6dc79540): 1490 message body bytes have been received from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D
2025-06-11 17:29:07.375 2980 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <102> Got serialized reply. OS error: 0, HTTP status: OK
2025-06-11 17:29:07.375 2980 VERBOSE rest::ServerConnection [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <102>: Reply success for 22703ms
2025-06-11 17:29:07.375 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager(0x1db4f4fcf80): Settings update received for AXISP1375E - insites
2025-06-11 17:29:07.375 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Store settings for AXISP1375E - insites, response is actual: true model changed: true, values changed: true, status 3
2025-06-11 17:29:07.376 2980 VERBOSE nx::network::http::AsyncClient(0x1db72b7adc0): Response headers from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D has been successfully read: HTTP/1.1 200 OK
2025-06-11 17:29:07.376 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Updated model:
{"filledCheckItems":[],"items":[{"caption":"Customer Site","defaultValue":"0","description":"insites Site Name","enabled":false,"name":"SiteName","type":"TextField","validationErrorMessage":"","validationRegex":"","validationRegexFlags":""},{"caption":"Camera Type","defaultValue":"Color","description":"Is the camera a normal color video camera or a thermal camera?","isActive":true,"itemCaptions":{},"name":"cameraType","range":["Color","Thermal"],"type":"ComboBox"},{"caption":"Clip Duration (seconds)","defaultValue":0,"description":"Length in seconds of clip to send to insites on motion detection, 0 = system default","isActive":true,"maxValue":10,"minValue":0,"name":"clipDuration","type":"SpinBox"},{"caption":"Event Cool Down (seconds)","defaultValue":0,"description":"After sending an event clip to Insites, the NX plug-in shall pause for a cool-down period before sending a new event that is associated with the same camera (10 - 300 seconds), 0 = system default","isActive":true,"maxValue":300,"minValue":0,"name":"EventCoolDown","type":"SpinBox"}],"type":"Settings"}
2025-06-11 17:29:07.376 2980 VERBOSE nx::network::http::AsyncClient(0x1db72b7adc0): 1490 message body bytes have been received from https://admin@localhost:7001/ec2/deviceAnalyticsSettings?analyticsEngineId=%7B12756c84-4c64-cfe5-0da4-6d0d0bfee1fd%7D&deviceId=%7B072cb529-ce4d-8935-91a1-7e83e1162320%7D
2025-06-11 17:29:07.376 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Updated values:
{"EventCoolDown":60,"SiteName":"Omeondevtest","cameraType":"Color","clipDuration":10}
2025-06-11 17:29:07.376 2980 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <105> Got serialized reply. OS error: 0, HTTP status: OK
2025-06-11 17:29:07.376 2980 VERBOSE rest::ServerConnection [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <105>: Reply success for 22643ms
2025-06-11 17:29:07.388 7688 VERBOSE nx::vms::client::desktop::CameraSettingsDialogStore(0x1db61d7fe40): State changed to: {"hasChanges":false,"singleCameraProperties":{"id":"{072cb529-ce4d-8935-91a1-7e83e1162320}","name":{"base":"AXISP1375E"},"firmware":"10.12.182","model":"AXISP1375E","vendor":"Axis","macAddress":"AC-CC-8E-D8-FC-95","ipAddress":"192.168.0.96","baseCameraUrl":"http://192.168.0.96:80","settingsUrlPath":"","webPageLabelText":"<a href=\"http://192.168.0.96\">http://192.168.0.96</a>","settingsUrl":"http://192.168.0.96/","overrideXmlHttpRequestTimeout":0,"overrideHttpUserAgent":"","isOnline":true,"fixupRequestUrls":true,"hasVideo":true,"editableStreamUrls":false,"networkLink":false,"usbDevice":false,"primaryStreamResolution":"QSize(1920, 1080)","secondaryStreamResolution":"QSize(640, 360)"},"devicesDescription":{"isDtsBased":"None","isVirtualCamera":"None","isIoModule":"None","isArecontCamera":"None","supportsAudio":"All","supportsVideo":"All","supportsWebPage":"All","isAudioForced":"None","supportsAudioOutput":"All","hasMotion":"All","hasObjectDetection":"All","hasDualStreamingCapability":"All","hasRemoteArchiveCapability":"None","canSwitchPtzPresetTypes":"None","canForcePanTiltCapabilities":"None","canForceZoomCapability":"None","hasPanTiltCapabilities":"None","canAdjustPtzSensitivity":"None","hasCustomMediaPortCapability":"All","hasCustomMediaPort":"None","supportsSchedule":"All","isUdpMulticastTransportAllowed":"None","maxFps":30},"motion":{"enabled":{"base":true},"supportsSoftwareDetection":true,"currentSensitivity":5,"stream":{"base":"secondary"},"forced":{"base":false},"dependingOnDualStreaming":"All"}}
2025-06-11 17:29:07.391 7688 DEBUG QQmlPrivate::QQmlElement<nx::vms::client::core::MediaPlayer>(0x1db5d8978a0): setVideoQuality(1): no change, ignoring
2025-06-11 17:29:07.392 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Received reply 92 (success: true)
2025-06-11 17:29:07.392 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Store settings for AXISP1375E - insites, response is actual: true model changed: false, values changed: false, status 3
2025-06-11 17:29:07.392 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Received reply 95 (success: true)
2025-06-11 17:29:07.392 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Store settings for AXISP1375E - insites, response is actual: true model changed: false, values changed: false, status 3
2025-06-11 17:29:07.392 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Received reply 102 (success: true)
2025-06-11 17:29:07.393 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Store settings for AXISP1375E - insites, response is actual: true model changed: false, values changed: false, status 3
2025-06-11 17:29:07.393 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Received reply 105 (success: true)
2025-06-11 17:29:07.393 7688 VERBOSE nx::vms::client::desktop::AnalyticsSettingsManager::Private(0x1db4f509980): Store settings for AXISP1375E - insites, response is actual: true model changed: false, values changed: false, status 3
2025-06-11 17:29:12.638 7688 DEBUG QnCloudStatusWatcherPrivate(0x1db4d4db700): Try update systems list: has request is false
2025-06-11 17:29:14.530 52d4 VERBOSE nx::vms::discovery::UdpMulticastFinder(0x1db4d4dcaa0): From 100.64.0.1:51734 got: {"brand":"hanwha","cloudHost":"sync.wavevms.com","cloudSystemId":"","customization":"hanwha","ecDbReadOnly":false,"hwPlatform":"unknown","id":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","localSystemId":"{b20560ec-cc60-48b2-bfe8-2c71cf446679}","name":"Server DESKTOP-3AK97A3","port":7001,"protoVersion":5107,"realm":"VMS","remoteAddresses":["fe80::d8ff:5060:f230:9168%50","192.168.0.181","49.37.193.131","fd7a:115c:a1e0::1","100.64.0.1"],"runtimeId":"{a2f7a4c7-d698-470a-add5-eda40bc6e7bf}","serverFlags":"SF_HasPublicIP|SF_SupportsTranscoding","sslAllowed":true,"synchronizedTimeMs":"1749642731605","systemName":"DESKTOP-3AK97A3","type":"Media Server","version":"5.1.3.38363"}
2025-06-11 17:29:14.530 52d4 VERBOSE nx::vms::discovery::ModuleConnector::Module(0x1db4f580130, 5f1d5954-2ac0-9e13-6d51-306021d9c455): Add endpoints { 100.64.0.1:7001, 192.168.0.181:7001, 49.37.193.131:7001, [fd7a:115c:a1e0::1]:7001, [fe80::d8ff:5060:f230:9168%50]:7001 }
2025-06-11 17:29:14.530 52d4 VERBOSE nx::vms::discovery::UdpMulticastFinder(0x1db4d4dcaa0): From 192.168.0.181:51735 got: {"brand":"hanwha","cloudHost":"sync.wavevms.com","cloudSystemId":"","customization":"hanwha","ecDbReadOnly":false,"hwPlatform":"unknown","id":"{5f1d5954-2ac0-9e13-6d51-306021d9c455}","localSystemId":"{b20560ec-cc60-48b2-bfe8-2c71cf446679}","name":"Server DESKTOP-3AK97A3","port":7001,"protoVersion":5107,"realm":"VMS","remoteAddresses":["fe80::d8ff:5060:f230:9168%50","192.168.0.181","49.37.193.131","fd7a:115c:a1e0::1","100.64.0.1"],"runtimeId":"{a2f7a4c7-d698-470a-add5-eda40bc6e7bf}","serverFlags":"SF_HasPublicIP|SF_SupportsTranscoding","sslAllowed":true,"synchronizedTimeMs":"1749642731605","systemName":"DESKTOP-3AK97A3","type":"Media Server","version":"5.1.3.38363"}
2025-06-11 17:29:14.530 52d4 VERBOSE nx::vms::discovery::ModuleConnector::Module(0x1db4f580130, 5f1d5954-2ac0-9e13-6d51-306021d9c455): Add endpoints { 100.64.0.1:7001, 192.168.0.181:7001, 49.37.193.131:7001, [fd7a:115c:a1e0::1]:7001, [fe80::d8ff:5060:f230:9168%50]:7001 }
2025-06-11 17:29:18.650 6b60 DEBUG HTTP: Received request from 127.0.0.1:7001:
KEEP-ALIVE * RTSP/1.0cSeq: 31
-------------------
2025-06-11 17:29:19.752 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Live update request
2025-06-11 17:29:19.752 7688 VERBOSE nx::vms::client::desktop::EventSearchListModel(0x1db50a8d200): Requesting events:
from: 11.06.2025 11:51:50.214 UTC
to: DATETIME_NOW
type: anyEvent
subtype:
sort: AscendingOrder
limit: 150
2025-06-11 17:29:19.752 7688 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Issuing request GET /ec2/getEvents to https://localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:29:19.753 5620 VERBOSE nx::network::http::AsyncClient(0x1db6dc7a770): Deleting the instance...
2025-06-11 17:29:19.753 3d54 VERBOSE nx::network::http::AsyncClient(0x1db6dc79b50): Deleting the instance...
2025-06-11 17:29:19.753 7688 VERBOSE rest::ServerConnection(0x1db5081c9d0) [{5f1d5954-2ac0-9e13-6d51-306021d9c455}]: <110> https://localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150
2025-06-11 17:29:19.753 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Sending request GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1 (url https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150) via reused connection
2025-06-11 17:29:19.753 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Request has been successfully sent to https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 from 127.0.0.1:50756. GET /ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150 HTTP/1.1
2025-06-11 17:29:19.753 5620 VERBOSE nx::network::http::AsyncClient(0x1db55bf2940): Connection is closed in state receivingResponse. Url https://admin@localhost:7001/ec2/getEvents?event_type=anyEvent&eventsOnly&format=ubjson&from=1749642710214&limit=150. An established connection was aborted by the software in your host machine.
2025-06-11 17:29:19.754 5620 VERBOSE nx::network::ssl::StreamSocket(0x1db6dcefec0): Client-side. Local 0.0.0.0, remote 0.0.0.0, socket nx::network::cloud::CloudStreamSocket(0x1db7773c120), transforming channel nx::network::aio::StreamTransformingAsyncChannel(0x1db5dce6290)0 -
Hi Subramanian,
Thanks for the information. However, we’ll also need the server logs to proceed.
Please share them in a file or ideally, the entire log folder and let us know the timestamp of the event, so we can quickly locate the relevant entries.
In addition, it would be very helpful if you could provide a minimal workable version of the plugin that reproduces the issue. Based on your description, a code-level review may be required, and without the ability to replicate the issue on our side, it may be difficult to identify the root cause.
We’ve seen the following error message:
"caption": "Issue with Analytics Plugin detected", "description": "Plugin failed to start working with Device. Technical details: Method Engine::obtainDeviceAgent() of Engine of insites implemented in the Plugin incorrectly: Method execution took too long"If you’re okay with it, we can convert this post into a support ticket for better tracking.
Also, I noticed there was a duplicated post. I’ll go ahead and merge the posts and remove the duplicate.
Thanks again,
0 -
Hello,
Subramanian, I'm pasting your message from another thread of yours, which seems to be a duplicate for the current one.
Is there a way to suppress this error because my analytical plugin take some time to return a device agent object via engine .its taking more then a minute so this warning is showed in the client but it didn't affect the plugin it start after a minute and runs as expected
what is the response time limit for a engine to return object to the client, is it possible to increase the time for warning?
or its just a warning we can ignore?Appearance of this message and the information you've shared
.its taking more then a minute so this warning is showed in the client but it didn't affect the plugin it start after a minute and runs as expected
I would consider as an indication to refactoring your plugin code.
The obtainDeviceAgent() method creates a DeviceAgent class instance and returns a pointer to the instance. I would recommend refactoring the code to make the obviously heavy class initialization asynchronous, and thus to return faster from the DeviceAgent class constructor. For example, you could create a thread to perform initialization in the background. On any errors occurring during the initialization, you could notify be creating and sending a plugin diagnostic event.
0
Please sign in to leave a comment.
Comments
4 comments