Skip to content
New issue

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

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

Already on GitHub? Sign in to your account

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

Open
ahsonkhan opened this issue Sep 23, 2024 · 0 comments
Assignees
Labels
AMQP Issues related to the AMQP protocol Support in Azure Core test-reliability Issue that causes tests to be unreliable

Comments

@ahsonkhan
Copy link
Member

ahsonkhan commented Sep 23, 2024

From unrelated PR #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 ahsonkhan added test-reliability Issue that causes tests to be unreliable AMQP Issues related to the AMQP protocol Support in Azure Core labels Sep 23, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
AMQP Issues related to the AMQP protocol Support in Azure Core test-reliability Issue that causes tests to be unreliable
Projects
None yet
Development

No branches or pull requests

2 participants