azure-sdk-for-cpp icon indicating copy to clipboard operation
azure-sdk-for-cpp copied to clipboard

azure-core-amqp.TestSessions.MultipleSessionBeginEnd intermittently fails with SIGABRT raised, exception: Could not begin session

Open ahsonkhan opened this issue 5 months ago • 0 comments

From unrelated PR https://github.com/Azure/azure-sdk-for-cpp/pull/6024 on ValidateLive Win2019_x86_with_unit_test_winHttp https://dev.azure.com/azure-sdk/590cfd2a-581c-4dcb-a12e-6568ce786175/_apis/build/builds/4162693/logs/2709

2024-09-23T21:35:48.9249269Z test 467
2024-09-23T21:35:48.9250192Z         Start 467: azure-core-amqp.TestSessions.MultipleSessionBeginEnd
2024-09-23T21:35:48.9250680Z 
2024-09-23T21:35:48.9251755Z 467: Test command: D:\a\_work\1\s\build\sdk\core\azure-core-amqp\test\ut\Release\azure-core-amqp-tests.exe "--gtest_filter=TestSessions.MultipleSessionBeginEnd" "--gtest_also_run_disabled_tests"
2024-09-23T21:35:48.9252673Z 467: Working Directory: D:/a/_work/1/s/build/sdk/core/azure-core-amqp/test/ut
2024-09-23T21:35:48.9253442Z 467: Test timeout computed to be: 10000000
2024-09-23T21:35:48.9356695Z 467: Note: Google Test filter = TestSessions.MultipleSessionBeginEnd
2024-09-23T21:35:48.9357720Z 467: [==========] Running 1 test from 1 test suite.
2024-09-23T21:35:48.9358760Z 467: [----------] Global test environment set-up.
2024-09-23T21:35:48.9359701Z 467: [----------] 1 test from TestSessions
2024-09-23T21:35:48.9360672Z 467: [ RUN      ] TestSessions.MultipleSessionBeginEnd
2024-09-23T21:35:48.9361581Z 467: 
2024-09-23T21:35:48.9363264Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(120):: Trying Test port: 49297
2024-09-23T21:35:48.9364809Z 467: 
2024-09-23T21:35:48.9369239Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(614):: Wait 5 seconds for listener to start.
2024-09-23T21:35:48.9370383Z 467: 
2024-09-23T21:35:48.9371361Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(594):: Start test listener on port 49297
2024-09-23T21:35:48.9372409Z 467: 
2024-09-23T21:35:48.9378958Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(596):: listener started
2024-09-23T21:35:48.9380341Z 467: 
2024-09-23T21:35:48.9381528Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(620):: Listener running.
2024-09-23T21:35:48.9382944Z 467: [2024-09-23T21:35:48.9365234Z T: 6756] INFO  : Unknown port specified, assuming socket connection transport.
2024-09-23T21:35:48.9384603Z 467: [2024-09-23T21:35:48.9365614Z T: 6756] DEBUG : Create socket transport for host localhost port: 49297
2024-09-23T21:35:48.9386816Z 467: [2024-09-23T21:35:48.9366438Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_START to CONNECTION_STATE_START
2024-09-23T21:35:48.9388352Z 467: 
2024-09-23T21:35:48.9389602Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(241):: Connection state changed. OldState: Start -> Start
2024-09-23T21:35:48.9391195Z 467: [2024-09-23T21:35:48.9367282Z T: 6756] DEBUG : ConnectionImpl::Open: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e
2024-09-23T21:35:48.9407974Z 467: [2024-09-23T21:35:48.9405592Z T: 6756] DEBUG : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:412 Func: _send_header: -> Header (AMQP 0.1.0.0)
2024-09-23T21:35:48.9412330Z 467: [2024-09-23T21:35:48.9406012Z T: 6892] WARN  : File: D:\a\_work\1\s\build\_deps\vcpkg-src\buildtrees\azure-c-shared-utility\src\909bf604e3-0d7276768c.clean\src\dns_resolver_sync.c:50 Func: _dns_resolver_create: NULL hostname
2024-09-23T21:35:48.9413623Z 467: [2024-09-23T21:35:48.9406478Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_START to CONNECTION_STATE_HDR_SENT
2024-09-23T21:35:48.9414427Z 467: 
2024-09-23T21:35:48.9415172Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(241):: Connection state changed. OldState: Start -> HeaderSent
2024-09-23T21:35:48.9416068Z 467: [2024-09-23T21:35:48.9407400Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_HDR_SENT to CONNECTION_STATE_START
2024-09-23T21:35:48.9416783Z 467: 
2024-09-23T21:35:48.9417465Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(241):: Connection state changed. OldState: 
2024-09-23T21:35:48.9418425Z 467: HeaderSent[  INFO ] ->  StartD:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(675):
2024-09-23T21:35:48.9419165Z 467: : OnSocketAccepted - Socket connection received.
2024-09-23T21:35:48.9419925Z 467: [2024-09-23T21:35:48.9408374Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 0
2024-09-23T21:35:48.9420631Z 467: 
2024-09-23T21:35:48.9421452Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(695):: Connection State changed. Connection: Mock Server for MultipleSessionBeginEnd[ Old state : 2024-09-23T21:35:48.9409169ZStart T:  New state: 6756] StartDEBUG
2024-09-23T21:35:48.9422404Z 467:  : Enabled async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e[2024-09-23T21:35:48.9409542Z
2024-09-23T21:35:48.9423182Z 467:  T: 6892] DEBUG : ConnectionImpl::Listen: 00C76050 ID: Mock Server for MultipleSessionBeginEnd
2024-09-23T21:35:48.9423821Z 467: 
2024-09-23T21:35:48.9424355Z 467: [  INFO ] 
2024-09-23T21:35:48.9425226Z 467: D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(259):[  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(695):: Connection State changed. Connection: Mock Server for MultipleSessionBeginEnd Old state : Start New state: HeaderExchanged: 
2024-09-23T21:35:48.9426094Z 467: Opening 30 sessions.
2024-09-23T21:35:48.9426821Z 467: [2024-09-23T21:35:48.9410999Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 1
2024-09-23T21:35:49.0414699Z 467: [2024-09-23T21:35:49.0411980Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 2
2024-09-23T21:35:49.0415800Z 467: 
2024-09-23T21:35:49.0416673Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(695):: Connection State changed. Connection: Mock Server for MultipleSessionBeginEnd Old state : HeaderExchanged New state: OpenSent
2024-09-23T21:35:49.1539084Z 467: [2024-09-23T21:35:49.***34927Z[ T: 67562024-09-23T21:35:49.***34968Z]  T: DEBUG8112 : ] Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 3DEBUG
2024-09-23T21:35:49.1540687Z 467:  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:927 Func: _connection_byte_received: <- Header (AMQP 0.1.0.0)
2024-09-23T21:35:49.1542009Z 467: [2024-09-23T21:35:49.***36233Z T: 8112] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_START to CONNECTION_STATE_HDR_EXCH
2024-09-23T21:35:49.1542979Z 467: 
2024-09-23T21:35:49.1543947Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(241):: Connection state changed. OldState: Start -> HeaderExchanged
2024-09-23T21:35:49.1545178Z 467: [2024-09-23T21:35:49.***38330Z T: 8112] DEBUG : 65535:-> [OPEN]* {ba5fe879-072e-4596-a37a-72331ce1ea6e,localhost,4294967295,65535,60000,NULL,NULL,NULL,NULL,{}}
2024-09-23T21:35:49.1546450Z 467: [2024-09-23T21:35:49.***38748Z T: 8112] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_HDR_EXCH to CONNECTION_STATE_OPEN_SENT
2024-09-23T21:35:49.1547410Z 467: 
2024-09-23T21:35:49.1548359Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(241):: Connection state changed. OldState: HeaderExchanged -> OpenSent
2024-09-23T21:35:49.1549595Z 467: [2024-09-23T21:35:49.***39811Z T: 8112] DEBUG : 0:<- [OPEN]* {Mock Server for MultipleSessionBeginEnd,localhost,4294967295,65535,120000,NULL,NULL,NULL,NULL,{}}
2024-09-23T21:35:49.1550832Z 467: [2024-09-23T21:35:49.***40344Z T: 8112] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_OPEN_SENT to CONNECTION_STATE_OPENED
2024-09-23T21:35:49.1551781Z 467: 
2024-09-23T21:35:49.1552720Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(241):: Connection state changed. OldState: OpenSent -> Opened
2024-09-23T21:35:49.1554068Z 467: [2024-09-23T21:35:49.***4***32Z T: 8112] DEBUG : 0:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.1555085Z 467: [2024-09-23T21:35:49.***42306Z T: 8112] DEBUG : 1:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.1556090Z 467: [2024-09-23T21:35:49.***43750Z T: 8112] DEBUG : 2:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.1556900Z 467: 
2024-09-23T21:35:49.1557957Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(695):: Connection State changed. Connection: Mock Server for MultipleSessionBeginEnd Old state : OpenSent New state: Opened
2024-09-23T21:35:49.1559039Z 467: 
2024-09-23T21:35:49.1559976Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.1560900Z 467: 
2024-09-23T21:35:49.1561812Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.1562753Z 467: 
2024-09-23T21:35:49.1563660Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.2632750Z 467: [[2024-09-23T21:35:49.2629406Z2024-09-23T21:35:49.2629379Z T:  T: 81126756] ] DEBUGDEBUG :  : 0:<- [BEGIN]* {0,0,10000,1,4294967295}
2024-09-23T21:35:49.2634033Z 467: 3:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.2635068Z 467: [2024-09-23T21:35:49.2630429Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 4[
2024-09-23T21:35:49.2636185Z 467: 2024-09-23T21:35:49.2630585Z T: 8112] DEBUG : 1:<- [BEGIN]* {1,0,10000,1,4294967295}
2024-09-23T21:35:49.2637162Z 467: [2024-09-23T21:35:49.2631209Z T: 8112] DEBUG : 2:<- [BEGIN]* {2,0,10000,1,4294967295}
2024-09-23T21:35:49.2637979Z 467: 
2024-09-23T21:35:49.2638929Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.3725603Z 467: [[2024-09-23T21:35:49.3722830Z2024-09-23T21:35:49.3722959Z T: 8112 T: ] 6756DEBUG]  : 3:<- [BEGIN]* {3,0,10000,1,4294967295}
2024-09-23T21:35:49.3726891Z 467: DEBUG : 4:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.3727676Z 467: 
2024-09-23T21:35:49.3728602Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.[
2024-09-23T21:35:49.3729588Z 467: 2024-09-23T21:35:49.3724199Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 5
2024-09-23T21:35:49.4826735Z 467: [[2024-09-23T21:35:49.4823078Z2024-09-23T21:35:49.4823298Z T:  T: 67568112] DEBUG]  : DEBUG5:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.4827987Z 467:  : 4:<- [BEGIN]* {4,0,10000,1,4294967295}
2024-09-23T21:35:49.4828976Z 467: [2024-09-23T21:35:49.4824049Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 6
2024-09-23T21:35:49.4829874Z 467: 
2024-09-23T21:35:49.4830784Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.5918860Z 467: [2024-09-23T21:35:49.5916701Z T: 6756] DEBUG : 6:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.5920674Z 467: [[2024-09-23T21:35:49.5917217Z T: 2024-09-23T21:35:49.5916868Z6756] DEBUG T:  : 8112Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 7] 
2024-09-23T21:35:49.5921783Z 467: DEBUG : 5:<- [BEGIN]* {5,0,10000,1,4294967295}
2024-09-23T21:35:49.5922645Z 467: 
2024-09-23T21:35:49.5923599Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.7012813Z 467: [[2024-09-23T21:35:49.7010508Z2024-09-23T21:35:49.7010574Z T:  T: 6756] DEBUG : 7:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.7013956Z 467: 8112] DEBUG : 6:<- [BEGIN]* {6,0,10000,1,4294967295}
2024-09-23T21:35:49.7015012Z 467: [2024-09-23T21:35:49.7011349Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 8
2024-09-23T21:35:49.7015935Z 467: 
2024-09-23T21:35:49.7016898Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.8106761Z 467: [2024-09-23T21:35:49.8104332Z T: 8112] DEBUG : 7:<- [BEGIN]* {7,0,10000,1,4294967295}
2024-09-23T21:35:49.8107758Z 467: 
2024-09-23T21:35:49.8108850Z 467: [2024-09-23T21:35:49.8105014Z[  INFO ] T:  6756D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):] : DEBUGOnNewEndpoint - Incoming endpoint created, create session. : 8:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.8109931Z 467: 
2024-09-23T21:35:49.8110864Z 467: [2024-09-23T21:35:49.8105773Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 9
2024-09-23T21:35:49.9201166Z 467: [[2024-09-23T21:35:49.9198242Z2024-09-23T21:35:49.9198400Z T:  T: 67568112] ] DEBUGDEBUG :  : 9:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:49.9202367Z 467: 8:<- [BEGIN]* {8,0,10000,1,4294967295}
2024-09-23T21:35:49.9203284Z 467: [2024-09-23T21:35:49.9199460Z T: 
2024-09-23T21:35:49.9204537Z 467: 6756[  INFO ]]  DEBUGD:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708): : : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 10OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:49.9205652Z 467: 
2024-09-23T21:35:50.0295667Z 467: [2024-09-23T21:35:50.0292227Z T: 8112] DEBUG : [2024-09-23T21:35:50.0292776Z9:<- [BEGIN]* {9,0,10000,1,4294967295}
2024-09-23T21:35:50.0297381Z 467:  T: 6756] DEBUG : 10:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.0298324Z 467: [
2024-09-23T21:35:50.0299304Z 467: 2024-09-23T21:35:50.0293197Z[  INFO ] T:  6756D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):] : DEBUGOnNewEndpoint - Incoming endpoint created, create session. : 
2024-09-23T21:35:50.0300432Z 467: Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 11
2024-09-23T21:35:50.8113562Z 467: [[2024-09-23T21:35:50.1392139Z2024-09-23T21:35:50.1392316Z T:  T: 81126756] ] DEBUGDEBUG :  : 11:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.8114984Z 467: 10:<- [BEGIN]* {10,0,10000,1,4294967295}
2024-09-23T21:35:50.8115952Z 467: [2024-09-23T21:35:50.1393095Z
2024-09-23T21:35:50.8117081Z 467:  T: 6756[  INFO ]]  DEBUGD:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708): : : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 12OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:50.8118090Z 467: 
2024-09-23T21:35:50.8118928Z 467: [[2024-09-23T21:35:50.2485642Z2024-09-23T21:35:50.2485859Z T:  T: 8112] DEBUG6756 : ] 11:<- [BEGIN]* {11,0,10000,1,4294967295}
2024-09-23T21:35:50.8119788Z 467: DEBUG : 12:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.8120509Z 467: 
2024-09-23T21:35:50.8121406Z 467: [2024-09-23T21:35:50.2486655Z[  INFO ] T:  6756D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):] : DEBUGOnNewEndpoint - Incoming endpoint created, create session. : 
2024-09-23T21:35:50.8122404Z 467: Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 13
2024-09-23T21:35:50.8123330Z 467: [2024-09-23T21:35:50.3579383Z T: 8112] [DEBUG2024-09-23T21:35:50.3579667Z : 12:<- [BEGIN]* {12,0,10000,1,4294967295}
2024-09-23T21:35:50.8124444Z 467:  T: 6756] DEBUG : 13:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.8125183Z 467: 
2024-09-23T21:35:50.8126049Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:50.8127057Z 467: [2024-09-23T21:35:50.3580380Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 14
2024-09-23T21:35:50.8127957Z 467: [2024-09-23T21:35:50.4673110Z T: 8112] DEBUG : 13:<- [BEGIN]* {13,0,10000,1,4294967295}
2024-09-23T21:35:50.8128689Z 467: 
2024-09-23T21:35:50.8129491Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:50.8130396Z 467: [2024-09-23T21:35:50.4674021Z T: 6756] DEBUG : 14:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.8131346Z 467: [2024-09-23T21:35:50.4674477Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: ***
2024-09-23T21:35:50.8132283Z 467: [[2024-09-23T21:35:50.5767139Z T: 81122024-09-23T21:35:50.5767190Z] DEBUG T:  : 14:<- [BEGIN]* {14,0,10000,1,4294967295}
2024-09-23T21:35:50.8133120Z 467: 6756] DEBUG : ***:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.8133814Z 467: 
2024-09-23T21:35:50.8134663Z 467: [  INFO ][ 2024-09-23T21:35:50.5767962ZD:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708): T: : 6756OnNewEndpoint - Incoming endpoint created, create session.] 
2024-09-23T21:35:50.8135615Z 467: DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 16
2024-09-23T21:35:50.8136486Z 467: [2024-09-23T21:35:50.6860614Z T: 8112] DEBUG : ***:<- [BEGIN]* {***,0,10000,1,4294967295}
2024-09-23T21:35:50.8137228Z 467: 
2024-09-23T21:35:50.8138067Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):[: 2024-09-23T21:35:50.6861346ZOnNewEndpoint - Incoming endpoint created, create session. T: 
2024-09-23T21:35:50.8139121Z 467: 6756] DEBUG : 16:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.8140006Z 467: [2024-09-23T21:35:50.6861964Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 17
2024-09-23T21:35:50.8140915Z 467: [[2024-09-23T21:35:50.7954804Z T: 2024-09-23T21:35:50.7954906Z8112] DEBUG T:  : 16:<- [BEGIN]* {16,0,10000,1,4294967295}
2024-09-23T21:35:50.8141720Z 467: 6756] DEBUG : 17:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.8142418Z 467: 
2024-09-23T21:35:50.8143261Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: [OnNewEndpoint - Incoming endpoint created, create session.2024-09-23T21:35:50.7955617Z
2024-09-23T21:35:50.8144222Z 467:  T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 18
2024-09-23T21:35:50.9050329Z 467: [2024-09-23T21:35:50.9048104Z T: 8112] DEBUG : 17:<- [BEGIN]* {17,0,10000,1,4294967295}
2024-09-23T21:35:50.9051687Z 467: [2024-09-23T21:35:50.9048548Z T: 6756
2024-09-23T21:35:50.9052820Z 467: ] DEBUG[  INFO ] :  18:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:50.9054025Z 467: D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:50.9055386Z 467: [2024-09-23T21:35:50.9049247Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 19
2024-09-23T21:35:51.0145012Z 467: [[2024-09-23T21:35:51.0142200Z2024-09-23T21:35:51.0142300Z T:  T: 81126756] ] DEBUGDEBUG :  : 19:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.0146409Z 467: 18:<- [BEGIN]* {18,0,10000,1,4294967295}
2024-09-23T21:35:51.0147612Z 467: [2024-09-23T21:35:51.0143539Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 20
2024-09-23T21:35:51.0149694Z 467: 
2024-09-23T21:35:51.0150814Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.1239026Z 467: [2024-09-23T21:35:51.1235942Z[ T: 81122024-09-23T21:35:51.1236126Z]  T: DEBUG6756 : ] 19:<- [BEGIN]* {19,0,10000,1,4294967295}
2024-09-23T21:35:51.1240493Z 467: DEBUG : 20:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.1241484Z 467: 
2024-09-23T21:35:51.1242444Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.1243456Z 467: [2024-09-23T21:35:51.1237281Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 21
2024-09-23T21:35:51.2332332Z 467: [[2024-09-23T21:35:51.2329663Z2024-09-23T21:35:51.2329840Z T:  T: 67568112] DEBUG]  : DEBUG21:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.2333791Z 467:  : 20:<- [BEGIN]* {20,0,10000,1,4294967295}
2024-09-23T21:35:51.2335008Z 467: [2024-09-23T21:35:51.2330910Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 22
2024-09-23T21:35:51.2336138Z 467: 
2024-09-23T21:35:51.2337239Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.3425865Z 467: [[2024-09-23T21:35:51.3423345Z2024-09-23T21:35:51.3423523Z T: 8112 T: ] 6756DEBUG]  : DEBUG21:<- [BEGIN]* {21,0,10000,1,4294967295}
2024-09-23T21:35:51.3427128Z 467: 
2024-09-23T21:35:51.3428264Z 467: [  INFO ]  : D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):22:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.3429448Z 467: : OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.3430659Z 467: [2024-09-23T21:35:51.3424745Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 23
2024-09-23T21:35:51.4519075Z 467: [[2024-09-23T21:35:51.4516876Z2024-09-23T21:35:51.4516999Z T:  T: 67568112] ] DEBUGDEBUG :  : 23:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.4520422Z 467: 22:<- [BEGIN]* {22,0,10000,1,4294967295}
2024-09-23T21:35:51.4521459Z 467: [2024-09-23T21:35:51.4517786Z T: 
2024-09-23T21:35:51.4522746Z 467: 6756[  INFO ]] DEBUG  : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 24D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):
2024-09-23T21:35:51.4524015Z 467: : OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.5619782Z 467: [[2024-09-23T21:35:51.5617086Z2024-09-23T21:35:51.5617068Z T: 6756 T: ] 8112DEBUG] DEBUG :  : 24:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.5621069Z 467: 23:<- [BEGIN]* {23,0,10000,1,4294967295}
2024-09-23T21:35:51.5622082Z 467: [2024-09-23T21:35:51.5618435Z T: 6756] DEBUG
2024-09-23T21:35:51.5623174Z 467:  : [  INFO ]Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 25 
2024-09-23T21:35:51.5624450Z 467: D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.6713206Z 467: [[2024-09-23T21:35:51.6711057Z2024-09-23T21:35:51.6711320Z T:  T: 81126756] ] DEBUGDEBUG :  : 25:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.6715007Z 467: 24:<- [BEGIN]* {24,0,10000,1,4294967295}
2024-09-23T21:35:51.6716004Z 467: [2024-09-23T21:35:51.6711976Z T: 
2024-09-23T21:35:51.6716977Z 467: 6756] [  INFO ]DEBUG  : D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 26: 
2024-09-23T21:35:51.6718075Z 467: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.7807059Z 467: [2024-09-23T21:35:51.7804800Z T: 8112] DEBUG : 25:<- [BEGIN]* {25,0,10000,1,4294967295}
2024-09-23T21:35:51.7808581Z 467: [2024-09-23T21:35:51.7805335Z T: 6756] 
2024-09-23T21:35:51.7809779Z 467: DEBUG : [  INFO ]26:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.7811117Z 467:  D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:51.7812630Z 467: [2024-09-23T21:35:51.7806227Z T: 6756] DEBUG : Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 27
2024-09-23T21:35:51.8900948Z 467: [2024-09-23T21:35:51.8898013Z[ T: 2024-09-23T21:35:51.8898191Z8112 T: ] DEBUG6756]  : 26:<- [BEGIN]* {26,0,10000,1,4294967295}
2024-09-23T21:35:51.8902241Z 467: DEBUG : 27:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.8903040Z 467: 
2024-09-23T21:35:51.8904038Z 467: [2024-09-23T21:35:51.8899075Z[  INFO ] T:  6756D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):] : DEBUGOnNewEndpoint - Incoming endpoint created, create session. : 
2024-09-23T21:35:51.8905149Z 467: Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 28
2024-09-23T21:35:51.9994728Z 467: [[2024-09-23T21:35:51.9991926Z2024-09-23T21:35:51.9992032Z T:  T: 81126756] ] DEBUGDEBUG :  : 27:<- [BEGIN]* {27,0,10000,1,4294967295}
2024-09-23T21:35:51.9995966Z 467: 28:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:51.9996820Z 467: [2024-09-23T21:35:51.9993122Z T: 
2024-09-23T21:35:51.9997948Z 467: 6756] [  INFO ]DEBUG  : D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 29: 
2024-09-23T21:35:51.9999103Z 467: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:52.1089727Z 467: [[2024-09-23T21:35:52.1086***1Z2024-09-23T21:35:52.1086276Z T: 6756]  T: 8112DEBUG]  : DEBUG29:-> [BEGIN]* {NULL,0,1,1,4294967295}
2024-09-23T21:35:52.1091530Z 467:  : 28:<- [BEGIN]* {28,0,10000,1,4294967295}
2024-09-23T21:35:52.1092399Z 467: [2024-09-23T21:35:52.1087099Z T: 6756
2024-09-23T21:35:52.1093377Z 467: ] DEBUG : [  INFO ]Try to enable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 30 
2024-09-23T21:35:52.1094548Z 467: D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(708):: OnNewEndpoint - Incoming endpoint created, create session.
2024-09-23T21:35:52.2181992Z 467: [2024-09-23T21:35:52.2179649Z T: 8112] DEBUG : 29:<- [BEGIN]* {29,0,10000,1,4294967295}
2024-09-23T21:35:52.7338968Z 467: 
2024-09-23T21:35:52.7340279Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(270):: Closing 30 sessions.
2024-09-23T21:35:52.7341107Z 467: [2024-09-23T21:35:52.7337112Z T: 6756] DEBUG : 0:-> [END]* {}
2024-09-23T21:35:52.7342142Z 467: [2024-09-23T21:35:52.7337558Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 31
2024-09-23T21:35:52.8431709Z 467: [2024-09-23T21:35:52.8429378Z T: 6756] DEBUG : 1:-> [END]* {}
2024-09-23T21:35:52.8433025Z 467: [2024-09-23T21:35:52.8429917Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 30
2024-09-23T21:35:52.8743814Z 467: [2024-09-23T21:35:52.87416***Z T: 8112] DEBUG : 0:<- [END]* {}
2024-09-23T21:35:52.9525307Z 467: [2024-09-23T21:35:52.9523178Z T: 6756] DEBUG : 2:-> [END]* {}
2024-09-23T21:35:52.9526599Z 467: [2024-09-23T21:35:52.9523569Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 29
2024-09-23T21:35:52.9837566Z 467: [2024-09-23T21:35:52.9835380Z T: 8112] DEBUG : 1:<- [END]* {}
2024-09-23T21:35:53.0619451Z 467: [2024-09-23T21:35:53.0617259Z T: 6756] DEBUG : 3:-> [END]* {}
2024-09-23T21:35:53.0620983Z 467: [2024-09-23T21:35:53.0617756Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 28
2024-09-23T21:35:53.0931710Z 467: [2024-09-23T21:35:53.0929468Z T: 8112] DEBUG : 2:<- [END]* {}
2024-09-23T21:35:53.1713374Z 467: [2024-09-23T21:35:53.1711198Z T: 6756] DEBUG : 4:-> [END]* {}
2024-09-23T21:35:53.1714595Z 467: [2024-09-23T21:35:53.1711820Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 27
2024-09-23T21:35:53.2025426Z 467: [2024-09-23T21:35:53.2023212Z T: 8112] DEBUG : 3:<- [END]* {}
2024-09-23T21:35:53.2807745Z 467: [2024-09-23T21:35:53.2805085Z T: 6756] DEBUG : 5:-> [END]* {}
2024-09-23T21:35:53.2809065Z 467: [2024-09-23T21:35:53.2805768Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 26
2024-09-23T21:35:53.3119089Z 467: [2024-09-23T21:35:53.3116939Z T: 8112] DEBUG : 4:<- [END]* {}
2024-09-23T21:35:53.3900459Z 467: [2024-09-23T21:35:53.3898357Z T: 6756] DEBUG : 6:-> [END]* {}
2024-09-23T21:35:53.3901632Z 467: [2024-09-23T21:35:53.3898994Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 25
2024-09-23T21:35:53.4213073Z 467: [2024-09-23T21:35:53.4210744Z T: 8112] DEBUG : 5:<- [END]* {}
2024-09-23T21:35:53.5001292Z 467: [2024-09-23T21:35:53.4999071Z T: 6756] DEBUG : 7:-> [END]* {}
2024-09-23T21:35:53.5002467Z 467: [2024-09-23T21:35:53.4999697Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 24
2024-09-23T21:35:53.5313739Z 467: [2024-09-23T21:35:53.531***30Z T: 8112] DEBUG : 6:<- [END]* {}
2024-09-23T21:35:53.6094946Z 467: [2024-09-23T21:35:53.6092928Z T: 6756] DEBUG : 8:-> [END]* {}
2024-09-23T21:35:53.6096076Z 467: [2024-09-23T21:35:53.6093544Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 23
2024-09-23T21:35:53.6407184Z 467: [2024-09-23T21:35:53.6405296Z T: 8112] DEBUG : 7:<- [END]* {}
2024-09-23T21:35:53.7188633Z 467: [2024-09-23T21:35:53.7186608Z T: 6756] DEBUG : 9:-> [END]* {}
2024-09-23T21:35:53.7189793Z 467: [2024-09-23T21:35:53.7187229Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 22
2024-09-23T21:35:53.7501341Z 467: [2024-09-23T21:35:53.74991***Z T: 8112] DEBUG : 8:<- [END]* {}
2024-09-23T21:35:53.8283217Z 467: [2024-09-23T21:35:53.8280937Z T: 6756] DEBUG : 10:-> [END]* {}
2024-09-23T21:35:53.8284708Z 467: [2024-09-23T21:35:53.828***40Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 21
2024-09-23T21:35:53.8594632Z 467: [2024-09-23T21:35:53.8592591Z T: 8112] DEBUG : 9:<- [END]* {}
2024-09-23T21:35:53.9376358Z 467: [2024-09-23T21:35:53.9374323Z T: 6756] DEBUG : 11:-> [END]* {}
2024-09-23T21:35:53.9377571Z 467: [2024-09-23T21:35:53.9374931Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 20
2024-09-23T21:35:53.9688763Z 467: [2024-09-23T21:35:53.9686459Z T: 8112] DEBUG : 10:<- [END]* {}
2024-09-23T21:35:54.0589091Z 467: [2024-09-23T21:35:54.0586619Z T: 6756] DEBUG : 12:-> [END]* {}
2024-09-23T21:35:54.0590244Z 467: [2024-09-23T21:35:54.0587181Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 19
2024-09-23T21:35:54.0744655Z 467: [2024-09-23T21:35:54.0742475Z T: 8112] DEBUG : 11:<- [END]* {}
2024-09-23T21:35:54.1683093Z 467: [2024-09-23T21:35:54.1680221Z T: 6756] DEBUG : 13:-> [END]* {}
2024-09-23T21:35:54.1684605Z 467: [2024-09-23T21:35:54.1680859Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 18
2024-09-23T21:35:54.1838084Z 467: [2024-09-23T21:35:54.1835951Z T: 8112] DEBUG : 12:<- [END]* {}
2024-09-23T21:35:54.2776523Z 467: [2024-09-23T21:35:54.2773880Z T: 6756] DEBUG : 14:-> [END]* {}
2024-09-23T21:35:54.2777829Z 467: [2024-09-23T21:35:54.2774553Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 17
2024-09-23T21:35:54.2932013Z 467: [2024-09-23T21:35:54.2929981Z T: 8112] DEBUG : 13:<- [END]* {}
2024-09-23T21:35:54.3869277Z 467: [2024-09-23T21:35:54.3867730Z T: 6756] DEBUG : ***:-> [END]* {}
2024-09-23T21:35:54.3870499Z 467: [2024-09-23T21:35:54.3868305Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 16
2024-09-23T21:35:54.4025690Z 467: [2024-09-23T21:35:54.4023786Z T: 8112] DEBUG : 14:<- [END]* {}
2024-09-23T21:35:54.4965422Z 467: [2024-09-23T21:35:54.4962426Z T: 6756] DEBUG : 16:-> [END]* {}
2024-09-23T21:35:54.4966873Z 467: [2024-09-23T21:35:54.4963256Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: ***
2024-09-23T21:35:54.5120116Z 467: [2024-09-23T21:35:54.5117767Z T: 8112] DEBUG : ***:<- [END]* {}
2024-09-23T21:35:54.6057362Z 467: [2024-09-23T21:35:54.6055169Z T: 6756] DEBUG : 17:-> [END]* {}
2024-09-23T21:35:54.6058539Z 467: [2024-09-23T21:35:54.6055821Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 14
2024-09-23T21:35:54.6213534Z 467: [2024-09-23T21:35:54.6211327Z T: 8112] DEBUG : 16:<- [END]* {}
2024-09-23T21:35:54.7151518Z 467: [2024-09-23T21:35:54.7149023Z T: 6756] DEBUG : 18:-> [END]* {}
2024-09-23T21:35:54.7152767Z 467: [2024-09-23T21:35:54.7149709Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 13
2024-09-23T21:35:54.7307243Z 467: [2024-09-23T21:35:54.7305018Z T: 8112] DEBUG : 17:<- [END]* {}
2024-09-23T21:35:54.8245322Z 467: [2024-09-23T21:35:54.8243045Z T: 6756] DEBUG : 19:-> [END]* {}
2024-09-23T21:35:54.8246601Z 467: [2024-09-23T21:35:54.8243669Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 12
2024-09-23T21:35:54.8400790Z 467: [2024-09-23T21:35:54.8398674Z T: 8112] DEBUG : 18:<- [END]* {}
2024-09-23T21:35:54.9338843Z 467: [2024-09-23T21:35:54.9336527Z T: 6756] DEBUG : 20:-> [END]* {}
2024-09-23T21:35:54.9340013Z 467: [2024-09-23T21:35:54.9337054Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 11
2024-09-23T21:35:54.9494580Z 467: [2024-09-23T21:35:54.9492446Z T: 8112] DEBUG : 19:<- [END]* {}
2024-09-23T21:35:55.0432964Z 467: [2024-09-23T21:35:55.0430838Z T: 6756] DEBUG : 21:-> [END]* {}
2024-09-23T21:35:55.0434136Z 467: [2024-09-23T21:35:55.0431451Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 10
2024-09-23T21:35:55.0595693Z 467: [2024-09-23T21:35:55.0586514Z T: 8112] DEBUG : 20:<- [END]* {}
2024-09-23T21:35:55.1526248Z 467: [2024-09-23T21:35:55.***24019Z T: 6756] DEBUG : 22:-> [END]* {}
2024-09-23T21:35:55.1527521Z 467: [2024-09-23T21:35:55.***24665Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 9
2024-09-23T21:35:55.1682705Z 467: [2024-09-23T21:35:55.1680513Z T: 8112] DEBUG : 21:<- [END]* {}
2024-09-23T21:35:55.2621034Z 467: [2024-09-23T21:35:55.2618495Z T: 6756] DEBUG : 23:-> [END]* {}
2024-09-23T21:35:55.2622214Z 467: [2024-09-23T21:35:55.2619136Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 8
2024-09-23T21:35:55.2775962Z 467: [2024-09-23T21:35:55.2773726Z T: 8112] DEBUG : 22:<- [END]* {}
2024-09-23T21:35:55.3713588Z 467: [2024-09-23T21:35:55.371***24Z T: 6756] DEBUG : 24:-> [END]* {}
2024-09-23T21:35:55.3714787Z 467: [2024-09-23T21:35:55.3712***4Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 7
2024-09-23T21:35:55.3869704Z 467: [2024-09-23T21:35:55.3867485Z T: 8112] DEBUG : 23:<- [END]* {}
2024-09-23T21:35:55.4807599Z 467: [2024-09-23T21:35:55.4805679Z T: 6756] DEBUG : 25:-> [END]* {}
2024-09-23T21:35:55.4808746Z 467: [2024-09-23T21:35:55.4806329Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 6
2024-09-23T21:35:56.3569719Z 467: [2024-09-23T21:35:55.4967914Z T: 8112] DEBUG : 24:<- [END]* {}
2024-09-23T21:35:57.3748356Z 467: [2024-09-23T21:35:55.5905464Z T: 6756] DEBUG : 26:-> [END]* {}
2024-09-23T21:35:57.3749794Z 467: [2024-09-23T21:35:55.5906135Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 5
2024-09-23T21:35:57.3750811Z 467: [2024-09-23T21:35:55.6061605Z T: 8112] DEBUG : 25:<- [END]* {}
2024-09-23T21:35:57.3751770Z 467: [2024-09-23T21:35:55.6999066Z T: 6756] DEBUG : 27:-> [END]* {}
2024-09-23T21:35:57.3752711Z 467: [2024-09-23T21:35:55.6999575Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 4
2024-09-23T21:35:57.3753677Z 467: [2024-09-23T21:35:55.7***5141Z T: 8112] DEBUG : 26:<- [END]* {}
2024-09-23T21:35:57.3754525Z 467: [2024-09-23T21:35:55.8093820Z T: 6756] DEBUG : 28:-> [END]* {}
2024-09-23T21:35:57.3755432Z 467: [2024-09-23T21:35:55.8094404Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 3
2024-09-23T21:35:57.3756334Z 467: [2024-09-23T21:35:55.8248973Z T: 8112] DEBUG : 27:<- [END]* {}
2024-09-23T21:35:57.3757133Z 467: [2024-09-23T21:35:55.9186729Z T: 6756] DEBUG : 29:-> [END]* {}
2024-09-23T21:35:57.3758048Z 467: [2024-09-23T21:35:55.9187205Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 2
2024-09-23T21:35:57.3758944Z 467: [2024-09-23T21:35:55.9342883Z T: 8112] DEBUG : 28:<- [END]* {}
2024-09-23T21:35:57.3759947Z 467: [2024-09-23T21:35:56.0286530Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3761380Z 467: [2024-09-23T21:35:56.0287068Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3762536Z 467: [2024-09-23T21:35:56.0287440Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3763675Z 467: [2024-09-23T21:35:56.0287794Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3764785Z 467: [2024-09-23T21:35:56.0288232Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3765907Z 467: [2024-09-23T21:35:56.0288721Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3767024Z 467: [2024-09-23T21:35:56.0289137Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3768147Z 467: [2024-09-23T21:35:56.0289539Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3769261Z 467: [2024-09-23T21:35:56.0289913Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3770541Z 467: [2024-09-23T21:35:56.0290324Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3771656Z 467: [2024-09-23T21:35:56.0290748Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3772756Z 467: [2024-09-23T21:35:56.0291119Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3773993Z 467: [2024-09-23T21:35:56.029***44Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3775175Z 467: [2024-09-23T21:35:56.0292010Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3776279Z 467: [2024-09-23T21:35:56.0292422Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3777368Z 467: [2024-09-23T21:35:56.0292772Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3778435Z 467: [2024-09-23T21:35:56.0293108Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3779612Z 467: [2024-09-23T21:35:56.0293334Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3780664Z 467: [2024-09-23T21:35:56.0293594Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3781719Z 467: [2024-09-23T21:35:56.0293809Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3782789Z 467: [2024-09-23T21:35:56.0294033Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3783863Z 467: [2024-09-23T21:35:56.0294253Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3784915Z 467: [2024-09-23T21:35:56.0294440Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3786078Z 467: [2024-09-23T21:35:56.0294625Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3787138Z 467: [2024-09-23T21:35:56.0294801Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3788322Z 467: [2024-09-23T21:35:56.0294978Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3789411Z 467: [2024-09-23T21:35:56.0295***3Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3790622Z 467: [2024-09-23T21:35:56.0295334Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3791727Z 467: [2024-09-23T21:35:56.0295507Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:289 Func: _channel_table_release_endpoint: Outgoing Channel is not allocated.
2024-09-23T21:35:57.3792693Z 467: [2024-09-23T21:35:56.0295690Z T: 6756] DEBUG : ConnectionImpl::Close: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e
2024-09-23T21:35:57.3793632Z 467: [2024-09-23T21:35:56.0295876Z T: 6756] DEBUG : Try to disable async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e count: 1
2024-09-23T21:35:57.3794558Z 467: [2024-09-23T21:35:56.0296062Z T: 6756] DEBUG : Disabled async operation on connection: 00C70BA8 ID: ba5fe879-072e-4596-a37a-72331ce1ea6e
2024-09-23T21:35:57.3795413Z 467: [2024-09-23T21:35:56.0296529Z T: 6756] DEBUG : 65535:-> [CLOSE]* {}
2024-09-23T21:35:57.3796300Z 467: [2024-09-23T21:35:56.0296712Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_OPENED to CONNECTION_STATE_END
2024-09-23T21:35:57.3797186Z 467: 
2024-09-23T21:35:57.3798427Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\session_tests.cpp(241):: Connection state changed. OldState: Opened -> End
2024-09-23T21:35:57.3800003Z 467: [2024-09-23T21:35:56.0297037Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed to CONNECTION_STATE_END
2024-09-23T21:35:57.3801417Z 467: 
2024-09-23T21:35:57.3802442Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(626):: [Stop listening2024-09-23T21:35:56.0442805Z T: 
2024-09-23T21:35:57.3803934Z 467: 8112] WARN  : File: D:\a\_work\1\s\build\_deps\vcpkg-src\buildtrees\azure-c-shared-utility\src\909bf604e3-0d7276768c.clean\adapters\socketio_win32.c:700 Func: _socketio_dowork: Socketio_Failure: Receiving data from endpoint: 10054.
2024-09-23T21:35:57.3805206Z 467: 
2024-09-23T21:35:57.3806149Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(720):: On I/O Error - connection closed.
2024-09-23T21:35:57.3807176Z 467: 
2024-09-23T21:35:57.3808469Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(695):: Connection State changed. Connection: Mock Server for MultipleSessionBeginEnd Old state : Opened New state: Error
2024-09-23T21:35:57.3809718Z 467: 
2024-09-23T21:35:57.3810678Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(165):: Stop processing for $cbs
2024-09-23T21:35:57.3811960Z 467: [2024-09-23T21:35:56.3573947Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:2421 Func: _connection_encode_frame: Connection not open
2024-09-23T21:35:57.3813538Z 467: [2024-09-23T21:35:56.3574904Z T: 6756] WARN  : File: D:\a\_work\1\s\build\_deps\vcpkg-src\buildtrees\azure-c-shared-utility\src\909bf604e3-0d7276768c.clean\adapters\socketio_win32.c:559 Func: _socketio_send: Failure: socket state is not opened.
2024-09-23T21:35:57.3815135Z 467: [2024-09-23T21:35:56.3575109Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:532 Func: _on_bytes_encoded: Cannot send encoded bytes
2024-09-23T21:35:57.3816404Z 467: [2024-09-23T21:35:56.3575281Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_END to CONNECTION_STATE_END
2024-09-23T21:35:57.3817620Z 467: [2024-09-23T21:35:56.3575462Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed to CONNECTION_STATE_END
2024-09-23T21:35:57.3818791Z 467: [2024-09-23T21:35:56.3575674Z T: 6756] DEBUG : 65535:-> [CLOSE]* {}
2024-09-23T21:35:57.3819976Z 467: [2024-09-23T21:35:56.3575826Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed from CONNECTION_STATE_END to CONNECTION_STATE_END
2024-09-23T21:35:57.3821129Z 467: [2024-09-23T21:35:56.3575986Z T: 6756] DEBUG : Connection ba5fe879-072e-4596-a37a-72331ce1ea6e state changed to CONNECTION_STATE_END
2024-09-23T21:35:57.3821918Z 467: 
2024-09-23T21:35:57.3822744Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(626):: Stop listening
2024-09-23T21:35:57.3823530Z 467: 
2024-09-23T21:35:57.3830330Z 467: [  INFO ] D:\a\_work\1\s\sdk\core\azure-core-amqp\test\ut\mock_amqp_server.hpp(165):: Stop processing for $cbs
2024-09-23T21:35:57.3831340Z 467: [2024-09-23T21:35:56.3576409Z T: 6756] WARN  : File: D:\a\_work\1\s\sdk\core\azure-core-amqp\vendor\azure-uamqp-c\src\connection.c:2421 Func: _connection_encode_frame: Connection not open
2024-09-23T21:35:57.3832185Z 467: SIGABRT raised, exception: Could not begin session
2024-09-23T21:35:57.3832946Z 467/525 Test #467: azure-core-amqp.TestSessions.MultipleSessionBeginEnd ..............................................***Failed    8.45 sec

It has failed 3x in the last 30 days: https://dev.azure.com/azure-sdk/internal/_test/analytics?definitionId=1615&contextType=build image

ahsonkhan avatar Sep 23 '24 21:09 ahsonkhan