You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@celix.apache.org by GitBox <gi...@apache.org> on 2022/02/08 11:17:07 UTC
[GitHub] [celix] pnoltes opened a new issue #397: sporadic failure of test_cxx_remote_services_integration test
pnoltes opened a new issue #397:
URL: https://github.com/apache/celix/issues/397
There seems to be a race condition in the C++ remote service implementation or test setup, because the integration test sporadicly fails.
```
2022-02-08T11:09:42.2473802Z Start 51: test_cxx_remote_services_integration
2022-02-08T11:09:42.2474107Z
2022-02-08T11:09:42.2474690Z 51: Test command: /home/runner/work/celix/celix/build/bundles/cxx_remote_services/integration/gtest/test_cxx_remote_services_integrationd
2022-02-08T11:09:42.2475472Z 51: Test timeout computed to be: 10000000
2022-02-08T11:09:42.2586658Z 51: Running main() from /home/runner/work/celix/celix/build/_deps/googletest-src/googletest/src/gtest_main.cc
2022-02-08T11:09:42.2587376Z 51: [==========] Running 2 tests from 1 test suite.
2022-02-08T11:09:42.2588136Z 51: [----------] Global test environment set-up.
2022-02-08T11:09:42.2588833Z 51: [----------] 2 tests from RemoteServicesIntegrationTestSuite
2022-02-08T11:09:42.2589631Z 51: [ RUN ] RemoteServicesIntegrationTestSuite.StartStopFrameworks
2022-02-08T11:09:42.2590366Z 51: [2022-02-08T11:09:42] [ info] [celix_framework] [framework_start:521] Celix framework started
2022-02-08T11:09:42.2591316Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] [framework_start:522] Celix framework started with uuid 62db93bc-def4-43f7-8de8-478dcc1d35a8
2022-02-08T11:09:42.2592017Z 51: [2022-02-08T11:09:42] [ info] [celix_framework] [framework_start:521] Celix framework started
2022-02-08T11:09:42.2592955Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] [framework_start:522] Celix framework started with uuid 5a8982d7-d85d-4683-a5cd-7e94aa2ab656
2022-02-08T11:09:42.2650190Z 51: [2022-02-08T11:09:42] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using 127.0.0.1 for service annunciation
2022-02-08T11:09:42.2650933Z 51: [2022-02-08T11:09:42] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using base till max port: 5501 till 6000
2022-02-08T11:09:42.2651813Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_topology_manager] Added zmq PSA
2022-02-08T11:09:42.2803214Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.2804492Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.2805920Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.2807011Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service celix::rsa::IEndpointAnnouncer for component celix::rsa::ConfiguredDiscoveryManager (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e)
2022-02-08T11:09:42.2808056Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service celix::rsa::IConfiguredDiscoveryManager for component celix::rsa::ConfiguredDiscoveryManager (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e)
2022-02-08T11:09:42.3049945Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=2bda1750-9217-4685-8bbb-2197db5db568) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3051166Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=2bda1750-9217-4685-8bbb-2197db5db568) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3052337Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=2bda1750-9217-4685-8bbb-2197db5db568) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.3057231Z 51: [2022-02-08T11:09:42] [ debug] [celix_framework] [celix_bundleContext_trackServicesWithOptionsInternal:1391] Starting a tracker for any services
2022-02-08T11:09:42.3361420Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Invoke.descriptor'
2022-02-08T11:09:42.3368872Z 51:
2022-02-08T11:09:42.3372264Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Invoke with id 1779227116 and version
2022-02-08T11:09:42.3375500Z 51: [2022-02-08T11:09:42] [ info] [celix_framework] Starting TestExportImportRemoteServiceFactory
2022-02-08T11:09:42.3378322Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$result$Event.descriptor'
2022-02-08T11:09:42.3378727Z 51:
2022-02-08T11:09:42.3380722Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$result$Event with id 1456719160 and version
2022-02-08T11:09:42.3381434Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Return.descriptor'
2022-02-08T11:09:42.3381826Z 51:
2022-02-08T11:09:42.3383381Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Return with id 2120707264 and version
2022-02-08T11:09:42.3386923Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:42.3388091Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:42.3394734Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:42.3396425Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:42.3556449Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3557822Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.3559270Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.3560430Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:42.3562227Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:42.3563370Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3564705Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.3566188Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service ICalculator for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa)
2022-02-08T11:09:42.3574669Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3578830Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Adding new request for publisher default/test_return
2022-02-08T11:09:42.3579860Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Created new topic sender entry default:test_return
2022-02-08T11:09:42.3581700Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.3582483Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_zmq_v2] [PSA_ZMQ] pubsub_zmqAdmin_matchPublisher
2022-02-08T11:09:42.3583589Z 51: [Debug] PubSub: Could not load properties for publication on scope default / topic test_return. Searched location .serverCache/bundle7/version0.0/./META-INF/topics/pub/test_return.properties, bundleId=7
2022-02-08T11:09:42.3584497Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_topology_manager] Found PSA match for publisher with scope/topic default/test_return. PSA svc id is 7
2022-02-08T11:09:42.3585712Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.3586705Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type ICalculator
2022-02-08T11:09:42.3587855Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type ICalculator
2022-02-08T11:09:42.3606424Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type pubsub.publisher
2022-02-08T11:09:42.3609925Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type pubsub.publisher
2022-02-08T11:09:42.3612639Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3617212Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.3646616Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service pubsub.subscriber for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f)
2022-02-08T11:09:42.3647363Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Adding subscriber default/test_invoke
2022-02-08T11:09:42.3647986Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Created new topic receiver entry default:test_invoke
2022-02-08T11:09:42.3648563Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_zmq_v2] [PSA_ZMQ] pubsub_zmqAdmin_matchSubscriber
2022-02-08T11:09:42.3649374Z 51: [Debug] PubSub: Could not load properties for subscription on scope default / topic test_invoke. Searched location .serverCache/bundle7/version0.0/./META-INF/topics/sub/test_invoke.properties, bundleId=7
2022-02-08T11:09:42.3650190Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_topology_manager] Found PSA match for subscriber with scope/topic default/test_invoke. PSA svc id is 7
2022-02-08T11:09:42.3654098Z 51: [2022-02-08T11:09:42] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using 127.0.0.1 for service annunciation
2022-02-08T11:09:42.3654773Z 51: [2022-02-08T11:09:42] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using base till max port: 5501 till 6000
2022-02-08T11:09:42.3655384Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_topology_manager] Added zmq PSA
2022-02-08T11:09:42.3808785Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3810234Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.3811498Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.3812589Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service celix::rsa::IEndpointAnnouncer for component celix::rsa::ConfiguredDiscoveryManager (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e)
2022-02-08T11:09:42.3813844Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service celix::rsa::IConfiguredDiscoveryManager for component celix::rsa::ConfiguredDiscoveryManager (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e)
2022-02-08T11:09:42.4057231Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4059446Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4061304Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.4062494Z 51: [2022-02-08T11:09:42] [ debug] [celix_framework] [celix_bundleContext_trackServicesWithOptionsInternal:1391] Starting a tracker for any services
2022-02-08T11:09:42.4364911Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Invoke.descriptor'
2022-02-08T11:09:42.4365521Z 51:
2022-02-08T11:09:42.4366782Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Invoke with id 1779227116 and version
2022-02-08T11:09:42.4367790Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$result$Event.descriptor'
2022-02-08T11:09:42.4368916Z 51:
2022-02-08T11:09:42.4369544Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$result$Event with id 1456719160 and version
2022-02-08T11:09:42.4370244Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Return.descriptor'
2022-02-08T11:09:42.4370589Z 51:
2022-02-08T11:09:42.4371192Z 51: [2022-02-08T11:09:42] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Return with id 2120707264 and version
2022-02-08T11:09:42.4375498Z 51: [2022-02-08T11:09:42] [ info] [celix_framework] Starting TestExportImportRemoteServiceFactory
2022-02-08T11:09:42.4388537Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:42.4389702Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:42.4390813Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:42.4391931Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:42.4507324Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4537152Z 51: [2022-02-08T11:09:42] [ trace] [celix::rsa::ConfiguredDiscoveryManager] Created endpoint description from .clientCache/bundle8/version0.0/META-INF/discovery/endpoint_discovery.json: Endpoint[service.exported.interfaces=ICalculator, service.imported.configs=pubsub, endpoint.topic=test, endpoint.id=id-01, objectClass=ICalculator, service.imported=true, endpoint.scope=default, endpoint.framework.uuid=62db93bc-def4-43f7-8de8-478dcc1d35a8, ]
2022-02-08T11:09:42.4538483Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::EndpointDescription
2022-02-08T11:09:42.4539636Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::EndpointDescription
2022-02-08T11:09:42.4540397Z 51: [2022-02-08T11:09:42] [ debug] [celix::rsa::RemoteServiceAdmin] Adding endpoint id-01, created export service for ICalculator
2022-02-08T11:09:42.4541199Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=648f7034-e4b7-4d44-9517-edd380674e83) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4541853Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Adding new request for publisher default/test_invoke
2022-02-08T11:09:42.4542396Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Created new topic sender entry default:test_invoke
2022-02-08T11:09:42.4543310Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.4543904Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_zmq_v2] [PSA_ZMQ] pubsub_zmqAdmin_matchPublisher
2022-02-08T11:09:42.4544637Z 51: [Debug] PubSub: Could not load properties for publication on scope default / topic test_invoke. Searched location .clientCache/bundle7/version0.0/./META-INF/topics/pub/test_invoke.properties, bundleId=7
2022-02-08T11:09:42.4545382Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_topology_manager] Found PSA match for publisher with scope/topic default/test_invoke. PSA svc id is 7
2022-02-08T11:09:42.4546231Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.4549547Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:42.4550603Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:42.4559290Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type pubsub.publisher
2022-02-08T11:09:42.4560075Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] [frameworkActivator_stop:1685] Start shutdown thread for framework 5a8982d7-d85d-4683-a5cd-7e94aa2ab656
2022-02-08T11:09:42.4562765Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type pubsub.publisher
2022-02-08T11:09:42.4564649Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=648f7034-e4b7-4d44-9517-edd380674e83) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4566623Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] [framework_shutdown:1354] Celix framework shutting down
2022-02-08T11:09:42.4567938Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=648f7034-e4b7-4d44-9517-edd380674e83) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.4569220Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4570209Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Unregistering service ICalculator for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa)
2022-02-08T11:09:42.4573460Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling remove service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type ICalculator
2022-02-08T11:09:42.4574845Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4575681Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Unregistering service pubsub.subscriber for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f)
2022-02-08T11:09:42.4576694Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Removing subscriber default/test_invoke
2022-02-08T11:09:42.4578058Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type ICalculator
2022-02-08T11:09:42.4583022Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4583989Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:42.4584946Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4585889Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:42.4586857Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling remove service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.4587755Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.4588679Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling remove service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:42.4589610Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component CalculatorImpl (uuid=0196b724-66a4-4057-acdf-50128fcdfbaa) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:42.4606313Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling remove service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type pubsub.publisher
2022-02-08T11:09:42.4608016Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type pubsub.publisher
2022-02-08T11:09:42.4609052Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Removing request for publisher default/test_return
2022-02-08T11:09:42.4609934Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling remove service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.4611031Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=c8dfc124-92d5-4fb0-8000-79010bf08e9f) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:42.4611802Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service pubsub.subscriber for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83)
2022-02-08T11:09:42.4612715Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling remove service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:42.4613672Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:42.4614700Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling remove service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:42.4615654Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=2bda1750-9217-4685-8bbb-2197db5db568) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:42.4616415Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service ICalculator for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83)
2022-02-08T11:09:42.4616983Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Adding subscriber default/test_return
2022-02-08T11:09:42.4617516Z 51: [2022-02-08T11:09:42] [ trace] [celix_psa_topology_manager] Created new topic receiver entry default:test_return
2022-02-08T11:09:42.4618220Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling set service for component CalculatorConsumer (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) on service dependency with type ICalculator
2022-02-08T11:09:42.4619027Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Calling add service for component CalculatorConsumer (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) on service dependency with type ICalculator
2022-02-08T11:09:42.4619965Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4620962Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:42.4621839Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Async registering service celix::IShellCommand for component CalculatorConsumer (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a)
2022-02-08T11:09:42.4622421Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_zmq_v2] [PSA_ZMQ] pubsub_zmqAdmin_matchSubscriber
2022-02-08T11:09:42.4623150Z 51: [Debug] PubSub: Could not load properties for subscription on scope default / topic test_return. Searched location .clientCache/bundle7/version0.0/./META-INF/topics/sub/test_return.properties, bundleId=7
2022-02-08T11:09:42.4623882Z 51: [2022-02-08T11:09:42] [ debug] [celix_psa_topology_manager] Found PSA match for subscriber with scope/topic default/test_return. PSA svc id is 7
2022-02-08T11:09:42.4624824Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=2bda1750-9217-4685-8bbb-2197db5db568) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4625974Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=2bda1750-9217-4685-8bbb-2197db5db568) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4626973Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=2bda1750-9217-4685-8bbb-2197db5db568) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:42.4628231Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4630537Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Unregistering service celix::rsa::IEndpointAnnouncer for component celix::rsa::ConfiguredDiscoveryManager (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e)
2022-02-08T11:09:42.4632046Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] Unregistering service celix::rsa::IConfiguredDiscoveryManager for component celix::rsa::ConfiguredDiscoveryManager (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e)
2022-02-08T11:09:42.4634085Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:42.4635564Z 51: [2022-02-08T11:09:42] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=1e6e98f2-4cea-4eb6-8c72-e9b7d1ef3c5e) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:43.3677198Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_topology_manager] Removing zmq PSA
2022-02-08T11:09:43.3682960Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] [framework_shutdown:1414] Joined event loop thread for framework 5a8982d7-d85d-4683-a5cd-7e94aa2ab656
2022-02-08T11:09:43.3710227Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] [frameworkActivator_stop:1685] Start shutdown thread for framework 62db93bc-def4-43f7-8de8-478dcc1d35a8
2022-02-08T11:09:43.3715519Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] [framework_shutdown:1354] Celix framework shutting down
2022-02-08T11:09:43.3717372Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3718508Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Unregistering service celix::IShellCommand for component CalculatorConsumer (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a)
2022-02-08T11:09:43.3719562Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3720698Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:43.3721673Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling remove service for component CalculatorConsumer (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) on service dependency with type ICalculator
2022-02-08T11:09:43.3722587Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component CalculatorConsumer (uuid=4f967d3e-a9ef-4786-ba7d-3003aadeff7a) on service dependency with type ICalculator
2022-02-08T11:09:43.3723690Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling remove service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:43.3725184Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:43.3726312Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling remove service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:43.3727408Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:43.3728599Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling remove service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.3729532Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=648f7034-e4b7-4d44-9517-edd380674e83) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3730334Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Unregistering service pubsub.subscriber for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83)
2022-02-08T11:09:43.3731041Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Unregistering service ICalculator for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83)
2022-02-08T11:09:43.3731613Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Removing subscriber default/test_return
2022-02-08T11:09:43.3732424Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.3733707Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling remove service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:43.3734644Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:43.3735614Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=648f7034-e4b7-4d44-9517-edd380674e83) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3736522Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=648f7034-e4b7-4d44-9517-edd380674e83) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:43.3737357Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling remove service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type pubsub.publisher
2022-02-08T11:09:43.3738159Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=648f7034-e4b7-4d44-9517-edd380674e83) on service dependency with type pubsub.publisher
2022-02-08T11:09:43.3738772Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Removing request for publisher default/test_invoke
2022-02-08T11:09:43.3740196Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3743723Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3745054Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:43.3746318Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling remove service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::EndpointDescription
2022-02-08T11:09:43.3747697Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=b60c2ac3-b7b9-4849-9db6-e9c9a7c1d921) on service dependency with type celix::rsa::EndpointDescription
2022-02-08T11:09:43.3749054Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e) from state DM_CMP_STATE_TRACKING_OPTIONAL to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3750315Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Unregistering service celix::rsa::IEndpointAnnouncer for component celix::rsa::ConfiguredDiscoveryManager (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e)
2022-02-08T11:09:43.3751514Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Unregistering service celix::rsa::IConfiguredDiscoveryManager for component celix::rsa::ConfiguredDiscoveryManager (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e)
2022-02-08T11:09:43.3752866Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.3754110Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=af8ebf55-042f-41ce-88b7-3e97413d389e) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INACTIVE
2022-02-08T11:09:43.4650364Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_topology_manager] Removing zmq PSA
2022-02-08T11:09:43.4704172Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] [framework_shutdown:1414] Joined event loop thread for framework 62db93bc-def4-43f7-8de8-478dcc1d35a8
2022-02-08T11:09:43.4704893Z 51: [ OK ] RemoteServicesIntegrationTestSuite.StartStopFrameworks (1207 ms)
2022-02-08T11:09:43.4705541Z 51: [ RUN ] RemoteServicesIntegrationTestSuite.InvokeRemoteCalcService
2022-02-08T11:09:43.4706308Z 51: [2022-02-08T11:09:43] [ info] [celix_framework] [framework_start:521] Celix framework started
2022-02-08T11:09:43.4707084Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] [framework_start:522] Celix framework started with uuid 85ffce68-6f70-4ea0-9ae1-d153729fa53a
2022-02-08T11:09:43.4707771Z 51: [2022-02-08T11:09:43] [ info] [celix_framework] [framework_start:521] Celix framework started
2022-02-08T11:09:43.4708508Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] [framework_start:522] Celix framework started with uuid 9739600f-28ec-45b9-887f-88890377d0d9
2022-02-08T11:09:43.4752826Z 51: [2022-02-08T11:09:43] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using 127.0.0.1 for service annunciation
2022-02-08T11:09:43.4753782Z 51: [2022-02-08T11:09:43] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using base till max port: 5501 till 6000
2022-02-08T11:09:43.4754966Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_topology_manager] Added zmq PSA
2022-02-08T11:09:43.4892932Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=548ed61a-1138-4dca-a078-632cf81806f0) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.4894572Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=548ed61a-1138-4dca-a078-632cf81806f0) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.4896127Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=548ed61a-1138-4dca-a078-632cf81806f0) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.4897293Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service celix::rsa::IEndpointAnnouncer for component celix::rsa::ConfiguredDiscoveryManager (uuid=548ed61a-1138-4dca-a078-632cf81806f0)
2022-02-08T11:09:43.4898730Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service celix::rsa::IConfiguredDiscoveryManager for component celix::rsa::ConfiguredDiscoveryManager (uuid=548ed61a-1138-4dca-a078-632cf81806f0)
2022-02-08T11:09:43.5119038Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=bcb0ce1a-76aa-48b9-b083-ca277a2447a8) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5121079Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=bcb0ce1a-76aa-48b9-b083-ca277a2447a8) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5122862Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=bcb0ce1a-76aa-48b9-b083-ca277a2447a8) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.5124091Z 51: [2022-02-08T11:09:43] [ debug] [celix_framework] [celix_bundleContext_trackServicesWithOptionsInternal:1391] Starting a tracker for any services
2022-02-08T11:09:43.5428875Z 51: [2022-02-08T11:09:43] [ info] [celix_framework] Starting TestExportImportRemoteServiceFactory
2022-02-08T11:09:43.5429898Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Invoke.descriptor'
2022-02-08T11:09:43.5430723Z 51:
2022-02-08T11:09:43.5431521Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Invoke with id 1779227116 and version
2022-02-08T11:09:43.5432656Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$result$Event.descriptor'
2022-02-08T11:09:43.5450523Z 51:
2022-02-08T11:09:43.5503390Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$result$Event with id 1456719160 and version
2022-02-08T11:09:43.5504047Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Return.descriptor'
2022-02-08T11:09:43.5504341Z 51:
2022-02-08T11:09:43.5504871Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Return with id 2120707264 and version
2022-02-08T11:09:43.5505841Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=bcb0ce1a-76aa-48b9-b083-ca277a2447a8) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:43.5506833Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=bcb0ce1a-76aa-48b9-b083-ca277a2447a8) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:43.5508054Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=bcb0ce1a-76aa-48b9-b083-ca277a2447a8) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:43.5509032Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=bcb0ce1a-76aa-48b9-b083-ca277a2447a8) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:43.5569701Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5570727Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component CalculatorImpl (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.5571840Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component CalculatorImpl (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.5572789Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component CalculatorImpl (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:43.5573702Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component CalculatorImpl (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:43.5574619Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5575591Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorImpl' (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.5576392Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service ICalculator for component CalculatorImpl (uuid=bd93b5e9-9755-483f-884a-0ceba2bf341f)
2022-02-08T11:09:43.5577226Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5577907Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Adding new request for publisher default/test_return
2022-02-08T11:09:43.5578451Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Created new topic sender entry default:test_return
2022-02-08T11:09:43.5579286Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.5579897Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_zmq_v2] [PSA_ZMQ] pubsub_zmqAdmin_matchPublisher
2022-02-08T11:09:43.5580717Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component ExportedCalculator (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.5581532Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) on service dependency with type ICalculator
2022-02-08T11:09:43.5582333Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component ExportedCalculator (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) on service dependency with type ICalculator
2022-02-08T11:09:43.5583481Z 51: [Debug] PubSub: Could not load properties for publication on scope default / topic test_return. Searched location .serverCache/bundle7/version0.0/./META-INF/topics/pub/test_return.properties, bundleId=7
2022-02-08T11:09:43.5584295Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_topology_manager] Found PSA match for publisher with scope/topic default/test_return. PSA svc id is 7
2022-02-08T11:09:43.5596515Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ExportedCalculator (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) on service dependency with type pubsub.publisher
2022-02-08T11:09:43.5597399Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component ExportedCalculator (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) on service dependency with type pubsub.publisher
2022-02-08T11:09:43.5598545Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5599553Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ExportedCalculator' (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.5600399Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service pubsub.subscriber for component ExportedCalculator (uuid=5ee79c42-ecef-4bcc-973e-0f0666cf6ad8)
2022-02-08T11:09:43.5600997Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Adding subscriber default/test_invoke
2022-02-08T11:09:43.5601513Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Created new topic receiver entry default:test_invoke
2022-02-08T11:09:43.5602022Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_zmq_v2] [PSA_ZMQ] pubsub_zmqAdmin_matchSubscriber
2022-02-08T11:09:43.5602757Z 51: [Debug] PubSub: Could not load properties for subscription on scope default / topic test_invoke. Searched location .serverCache/bundle7/version0.0/./META-INF/topics/sub/test_invoke.properties, bundleId=7
2022-02-08T11:09:43.5603481Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_topology_manager] Found PSA match for subscriber with scope/topic default/test_invoke. PSA svc id is 7
2022-02-08T11:09:43.5635813Z 51: [2022-02-08T11:09:43] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using 127.0.0.1 for service annunciation
2022-02-08T11:09:43.5636528Z 51: [2022-02-08T11:09:43] [ info] [celix_psa_zmq_v2] [PSA_ZMQ] Using base till max port: 5501 till 6000
2022-02-08T11:09:43.5637379Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_topology_manager] Added zmq PSA
2022-02-08T11:09:43.5773228Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=e78c3ad4-fb88-4b47-9731-c2ff1f0ed792) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5774813Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=e78c3ad4-fb88-4b47-9731-c2ff1f0ed792) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.5776553Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::ConfiguredDiscoveryManager' (uuid=e78c3ad4-fb88-4b47-9731-c2ff1f0ed792) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.5777902Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service celix::rsa::IEndpointAnnouncer for component celix::rsa::ConfiguredDiscoveryManager (uuid=e78c3ad4-fb88-4b47-9731-c2ff1f0ed792)
2022-02-08T11:09:43.5779424Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service celix::rsa::IConfiguredDiscoveryManager for component celix::rsa::ConfiguredDiscoveryManager (uuid=e78c3ad4-fb88-4b47-9731-c2ff1f0ed792)
2022-02-08T11:09:43.5998347Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.6000229Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.6001958Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'celix::rsa::RemoteServiceAdmin' (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.6003348Z 51: [2022-02-08T11:09:43] [ debug] [celix_framework] [celix_bundleContext_trackServicesWithOptionsInternal:1391] Starting a tracker for any services
2022-02-08T11:09:43.6306855Z 51: [2022-02-08T11:09:43] [ info] [celix_framework] Starting TestExportImportRemoteServiceFactory
2022-02-08T11:09:43.6308005Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Invoke.descriptor'
2022-02-08T11:09:43.6308808Z 51:
2022-02-08T11:09:43.6309620Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Invoke with id 1779227116 and version
2022-02-08T11:09:43.6310749Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$result$Event.descriptor'
2022-02-08T11:09:43.6311276Z 51:
2022-02-08T11:09:43.6312297Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$result$Event with id 1456719160 and version
2022-02-08T11:09:43.6313194Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Parsing entry 'Calculator$add$Return.descriptor'
2022-02-08T11:09:43.6313942Z 51:
2022-02-08T11:09:43.6314731Z 51: [2022-02-08T11:09:43] [ debug] [celix_pubsub_serialization_provider] Adding message serialization entry for msg Calculator$add$Return with id 2120707264 and version
2022-02-08T11:09:43.6316551Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:43.6317890Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) on service dependency with type celix::rsa::IImportServiceFactory
2022-02-08T11:09:43.6319447Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:43.6321303Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) on service dependency with type celix::rsa::IExportServiceFactory
2022-02-08T11:09:43.6421299Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=e460a845-8842-443d-8571-ff2311be5d7c) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.6422828Z 51: [2022-02-08T11:09:43] [ trace] [celix::rsa::ConfiguredDiscoveryManager] Created endpoint description from .clientCache/bundle8/version0.0/META-INF/discovery/endpoint_discovery.json: Endpoint[service.exported.interfaces=ICalculator, service.imported.configs=pubsub, endpoint.topic=test, endpoint.id=id-01, objectClass=ICalculator, service.imported=true, endpoint.scope=default, endpoint.framework.uuid=85ffce68-6f70-4ea0-9ae1-d153729fa53a, ]
2022-02-08T11:09:43.6424316Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component celix::rsa::RemoteServiceAdmin (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) on service dependency with type celix::rsa::EndpointDescription
2022-02-08T11:09:43.6425288Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component celix::rsa::RemoteServiceAdmin (uuid=dfa38c27-150d-42e5-bc5d-dbe98898bf32) on service dependency with type celix::rsa::EndpointDescription
2022-02-08T11:09:43.6426029Z 51: [2022-02-08T11:09:43] [ debug] [celix::rsa::RemoteServiceAdmin] Adding endpoint id-01, created export service for ICalculator
2022-02-08T11:09:43.6426837Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) from state DM_CMP_STATE_INACTIVE to state DM_CMP_STATE_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.6427599Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Adding new request for publisher default/test_invoke
2022-02-08T11:09:43.6428141Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Created new topic sender entry default:test_invoke
2022-02-08T11:09:43.6428974Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.6429918Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) on service dependency with type celix::PromiseFactory
2022-02-08T11:09:43.6430844Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:43.6431788Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) on service dependency with type celix::PushStreamProvider
2022-02-08T11:09:43.6434084Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_zmq_v2] [PSA_ZMQ] pubsub_zmqAdmin_matchPublisher
2022-02-08T11:09:43.6444889Z 51: [Debug] PubSub: Could not load properties for publication on scope default / topic test_invoke. Searched location .clientCache/bundle7/version0.0/./META-INF/topics/pub/test_invoke.properties, bundleId=7
2022-02-08T11:09:43.6447290Z 51: [2022-02-08T11:09:43] [ debug] [celix_psa_topology_manager] Found PSA match for publisher with scope/topic default/test_invoke. PSA svc id is 7
2022-02-08T11:09:43.6448727Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) on service dependency with type pubsub.publisher
2022-02-08T11:09:43.6454056Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) on service dependency with type pubsub.publisher
2022-02-08T11:09:43.6455382Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.6456659Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'ImportedCalculator' (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.6457771Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service pubsub.subscriber for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c)
2022-02-08T11:09:43.6458977Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service ICalculator for component ImportedCalculator (uuid=303b6b00-dbf2-45f8-9f07-01f4dd80ed6c)
2022-02-08T11:09:43.6459812Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Adding subscriber default/test_return
2022-02-08T11:09:43.6460617Z 51: [2022-02-08T11:09:43] [ trace] [celix_psa_topology_manager] Created new topic receiver entry default:test_return
2022-02-08T11:09:43.6461591Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling set service for component CalculatorConsumer (uuid=e460a845-8842-443d-8571-ff2311be5d7c) on service dependency with type ICalculator
2022-02-08T11:09:43.6462626Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Calling add service for component CalculatorConsumer (uuid=e460a845-8842-443d-8571-ff2311be5d7c) on service dependency with type ICalculator
2022-02-08T11:09:43.6463890Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=e460a845-8842-443d-8571-ff2311be5d7c) from state DM_CMP_STATE_WAITING_FOR_REQUIRED to state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED
2022-02-08T11:09:43.6465150Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] performing transition for component 'CalculatorConsumer' (uuid=e460a845-8842-443d-8571-ff2311be5d7c) from state DM_CMP_STATE_INSTANTIATED_AND_WAITING_FOR_REQUIRED to state DM_CMP_STATE_TRACKING_OPTIONAL
2022-02-08T11:09:43.6466328Z 51: [2022-02-08T11:09:43] [ trace] [celix_framework] Async registering service celix::IShellCommand for component CalculatorConsumer (uuid=e460a845-8842-443d-8571-ff2311be5d7c)
2022-02-08T11:09:43.6466755Z 51:
2022-02-08T11:09:43.6467747Z 51: Topic Senders:
2022-02-08T11:09:43.6468313Z 51: |- Topic Sender default/test_invoke
2022-02-08T11:09:43.6468699Z 51: |- serializer type = json
2022-02-08T11:09:43.6469211Z 51: |- protocol type = envelope-v2
2022-02-08T11:09:43.6469673Z 51: |- url = ipc:///tmp/pubsub-test-return (static)
2022-02-08T11:09:43.6469995Z 51:
2022-02-08T11:09:43.6470723Z 51:
2022-02-08T11:09:43.6471313Z 51: Topic Receivers:
2022-02-08T11:09:43.6471896Z 51:
2022-02-08T11:09:43.6472475Z 51:
2022-02-08T11:09:43.6473113Z 51: Topic Senders:
2022-02-08T11:09:43.6473802Z 51: |- Topic Sender default/test_return
2022-02-08T11:09:43.6474178Z 51: |- serializer type = json
2022-02-08T11:09:43.6474713Z 51: |- protocol type = envelope-v2
2022-02-08T11:09:43.6475300Z 51: |- url = ipc:///tmp/pubsub-test-invoke (static)
2022-02-08T11:09:43.6475622Z 51:
2022-02-08T11:09:43.6476245Z 51:
2022-02-08T11:09:43.6476858Z 51: Topic Receivers:
2022-02-08T11:09:43.6477607Z 51: |- Topic Receiver default/test_invoke
2022-02-08T11:09:43.6478003Z 51: |- serializer type = json
2022-02-08T11:09:43.6478607Z 51: |- protocol type = envelope-v2
2022-02-08T11:09:43.6479079Z 51: |- connected url = ipc:///tmp/pubsub-test-return (static)
2022-02-08T11:09:43.6479402Z 51:
2022-02-08T11:09:43.6480294Z 51: [2022-02-08T11:09:43] [ info] [celix_psa_zmq_v2] PSA_ZMQ_TP: Delaying first send for late joiners...
2022-02-08T11:09:43.6480660Z 51:
2022-02-08T11:09:43.6571968Z 51: [2022-02-08T11:09:43] [ info] [celix_psa_zmq_v2] PSA_ZMQ_TP: Delaying first send for late joiners...
2022-02-08T11:09:43.6572374Z 51:
2022-02-08T11:09:45.6470041Z 51: [2022-02-08T11:09:45] [ info] [celix_psa_zmq_v2] PSA_ZMQ_TP: Delaying first send for late joiners...
2022-02-08T11:09:45.6470424Z 51:
2022-02-08T11:09:50.6475798Z 51: /home/runner/work/celix/celix/bundles/cxx_remote_services/integration/gtest/src/RemoteServicesIntegrationTestSuite.cc:132: Failure
2022-02-08T11:09:50.6476991Z 51: Value of: promise.isSuccessfullyResolved()
2022-02-08T11:09:50.6477436Z 51: Actual: false
2022-02-08T11:09:50.6477967Z 51: Expected: true
```
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: dev-unsubscribe@celix.apache.org
For queries about this service, please contact Infrastructure at:
users@infra.apache.org