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

azure-core-amqp.TestCbs.CbsOpenAndPutError segfaults intermittently on Linux

Open antkmsft opened this issue 1 year ago • 0 comments

I saw azure-core-amqp.TestCbs.CbsOpenAndPutError (SEGFAULT) on Ubuntu22_included_coverage

2023-12-19T19:30:27.1323916Z test 560
2023-12-19T19:30:27.1324916Z         Start 560: azure-core-amqp.TestCbs.CbsOpenAndPutError
2023-12-19T19:30:27.1325279Z 
2023-12-19T19:30:27.1326424Z 560: Test command: /mnt/vss/_work/1/s/build/sdk/core/azure-core-amqp/test/ut/azure-core-amqp-tests "--gtest_filter=TestCbs.CbsOpenAndPutError" "--gtest_also_run_disabled_tests"
2023-12-19T19:30:27.1327523Z 560: Working Directory: /mnt/vss/_work/1/s/build/sdk/core/azure-core-amqp/test/ut
2023-12-19T19:30:27.1328086Z 560: Test timeout computed to be: 10000000
2023-12-19T19:30:27.1375060Z 560: Note: Google Test filter = TestCbs.CbsOpenAndPutError
2023-12-19T19:30:27.1375838Z 560: [==========] Running 1 test from 1 test suite.
2023-12-19T19:30:27.1376591Z 560: [----------] Global test environment set-up.
2023-12-19T19:30:27.1377268Z 560: [----------] 1 test from TestCbs
2023-12-19T19:30:27.1377858Z 560: [ RUN      ] TestCbs.CbsOpenAndPutError
2023-12-19T19:30:27.1388030Z 560: 
2023-12-19T19:30:27.1389103Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/session_tests.cpp:119:: Trying Test port: 49319
2023-12-19T19:30:27.1390305Z 560: [2023-12-19T19:30:27.1387780Z T: 7f970fccf140] INFO  : Unknown port specified, assuming socket connection transport.
2023-12-19T19:30:27.1391424Z 560: [2023-12-19T19:30:27.1388104Z T: 7f970fccf140] DEBUG : Create socket transport for host localhost port: 49319
2023-12-19T19:30:27.1392766Z 560: [2023-12-19T19:30:27.1388988Z T: 7f970fccf140] DEBUG : Connection 57757c06-fa69-4dda-8198-5eec19cb50ce state changed from CONNECTION_STATE_START(0) to CONNECTION_STATE_START(0)
2023-12-19T19:30:27.1393648Z 560: 
2023-12-19T19:30:27.1394617Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:216:: Wait 10 seconds for listener to start.
2023-12-19T19:30:27.1395362Z 560: 
2023-12-19T19:30:27.1396355Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:187:: Start test listener on port 49319
2023-12-19T19:30:27.1397434Z 560: 
2023-12-19T19:30:27.1398345Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:189:: listener started
2023-12-19T19:30:27.1399049Z 560: 
2023-12-19T19:30:27.1399967Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:193:: Wait for connection on listener.
2023-12-19T19:30:27.1400928Z 560: 
2023-12-19T19:30:27.1401824Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:222:: Listener running.
2023-12-19T19:30:27.1403116Z 560: [2023-12-19T19:30:27.1395088Z T: 7f970fccf140] DEBUG : Opening message sender. Authenticate if needed with audience: Target{ Address: $cbs, Durable: None, Expiry Policy: session-end, Timeout: 0, Dynamic: false}
2023-12-19T19:30:27.1404485Z 560: [2023-12-19T19:30:27.1395700Z T: 7f970fccf140] DEBUG : Message sender state changed from Idle(1) to Opening(2).
2023-12-19T19:30:27.1405598Z 560: [2023-12-19T19:30:27.1395893Z T: 7f970fccf140] INFO  : OnMessageSenderStateChanged: Idle -> Opening
2023-12-19T19:30:27.1407031Z 560: [2023-12-19T19:30:27.1400065Z T: 7f970e5fe640] WARN  : File: /mnt/vss/_work/1/s/build/_deps/vcpkg-src/buildtrees/azure-c-shared-utility/src/d9ae13ea55-d48f7a9441.clean/src/dns_resolver_sync.c:50 Func: dns_resolver_create: NULL hostname
2023-12-19T19:30:27.1407776Z 560: 
2023-12-19T19:30:27.1408713Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:410:: OnSocketAccepted - Socket connection received.
2023-12-19T19:30:27.1409869Z 560: [2023-12-19T19:30:27.1401305Z T: 7f970e5fe640] DEBUG : Connection Mock Server for CbsOpenAndPutError state changed from CONNECTION_STATE_START(0) to CONNECTION_STATE_START(0)
2023-12-19T19:30:27.1410522Z 560: 
2023-12-19T19:30:27.1411253Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:429:: Connection State changed. Connection: Mock Server for CbsOpenAndPutError Old state : Start New state: Start
2023-12-19T19:30:27.1412098Z 560: [2023-12-19T19:30:27.1401789Z T: 7f970e5fe640] DEBUG : ConnectionImpl::Listen: 0x7f9708001ad0 ID: Mock Server for CbsOpenAndPutError
2023-12-19T19:30:27.1412953Z 560: [2023-12-19T19:30:27.1401963Z T: 7f970e5fe640] DEBUG : Connection Mock Server for CbsOpenAndPutError state changed from CONNECTION_STATE_START(0) to CONNECTION_STATE_HDR_EXCH(3)
2023-12-19T19:30:27.1413552Z 560: 
2023-12-19T19:30:27.1414290Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:429:: Connection State changed. Connection: Mock Server for CbsOpenAndPutError Old state : Start New state: HeaderExchanged
2023-12-19T19:30:27.1415169Z 560: [2023-12-19T19:30:27.1402303Z T: 7f970e5fe640] DEBUG : Try to enable async operation on connection: 0x7f9708001ad0 ID: Mock Server for CbsOpenAndPutError count: 0
2023-12-19T19:30:27.1416043Z 560: [2023-12-19T19:30:27.1402460Z T: 7f970e5fe640] DEBUG : Enabled async operation on connection: 0x7f9708001ad0 ID: Mock Server for CbsOpenAndPutError
2023-12-19T19:30:27.1416916Z 560: [2023-12-19T19:30:27.1402970Z T: 7f970fccf140] DEBUG : File: /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/vendor/azure-uamqp-c/src/connection.c:150 Func: send_header: -> Header (AMQP 0.1.0.0)
2023-12-19T19:30:27.1417806Z 560: [2023-12-19T19:30:27.1403182Z T: 7f970fccf140] DEBUG : Connection 57757c06-fa69-4dda-8198-5eec19cb50ce state changed from CONNECTION_STATE_START(0) to CONNECTION_STATE_HDR_SENT(2)
2023-12-19T19:30:27.1418698Z 560: [2023-12-19T19:30:27.1403367Z T: 7f970fccf140] DEBUG : Connection 57757c06-fa69-4dda-8198-5eec19cb50ce state changed from CONNECTION_STATE_HDR_SENT(2) to CONNECTION_STATE_START(0)
2023-12-19T19:30:27.1419467Z 560: [2023-12-19T19:30:27.1403531Z T: 7f970fccf140] DEBUG : Opening message sender. Enable async operation.
2023-12-19T19:30:27.1420264Z 560: [2023-12-19T19:30:27.1403691Z T: 7f970fccf140] DEBUG : Try to enable async operation on connection: 0x555e2cd41d80 ID: 57757c06-fa69-4dda-8198-5eec19cb50ce count: 0
2023-12-19T19:30:27.1421319Z 560: [2023-12-19T19:30:27.1403840Z T: 7f970fccf140] DEBUG : Enabled async operation on connection: 0x555e2cd41d80 ID: 57757c06-fa69-4dda-8198-5eec19cb50ce
2023-12-19T19:30:27.1422328Z 560: [2023-12-19T19:30:27.1404187Z T: 7f970fccf140] DEBUG : Message receiver state change MESSAGE_RECEIVER_STATE_IDLE -> MESSAGE_RECEIVER_STATE_OPENING
2023-12-19T19:30:27.1423377Z 560: [2023-12-19T19:30:27.1404344Z[ T: 2023-12-19T19:30:27.1404466Z T: 7f970f5ff6407f970fccf140] DEBUG : Opening message receiver. Start async
2023-12-19T19:30:27.1424183Z 560: [2023-12-19T19:30:27.1404922Z T: ] DEBUG : -> [OPEN]* {Mock Server for CbsOpenAndPutError,localhost,4294967295,65535,120000,NULL,NULL,NULL,NULL,{}}
2023-12-19T19:30:27.1424980Z 560: 7f970fccf140] DEBUG : Try to enable async operation on connection: 0x555e2cd41d80 ID: 57757c06-fa69-4dda-8198-5eec19cb50ce count: 1
2023-12-19T19:30:27.1425817Z 560: [2023-12-19T19:30:27.1405445Z T: 7f970f5ff640] DEBUG : Connection Mock Server for CbsOpenAndPutError state changed from CONNECTION_STATE_HDR_EXCH(3) to CONNECTION_STATE_OPEN_SENT(7)
2023-12-19T19:30:27.1426407Z 560: 
2023-12-19T19:30:27.1427151Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:429:: Connection State changed. Connection: Mock Server for CbsOpenAndPutError Old state : HeaderExchanged New state: OpenSent
2023-12-19T19:30:27.2416108Z 560: [2023-12-19T19:30:27.2407532Z T: 7f970f5ff640] DEBUG : File: /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/vendor/azure-uamqp-c/src/connection.c:663 Func: connection_byte_received: <- Header (AMQP 0.1.0.0)
2023-12-19T19:30:27.2417796Z 560: [2023-12-19T19:30:27.2407813Z T: 7f970f5ff640] DEBUG : Connection 57757c06-fa69-4dda-8198-5eec19cb50ce state changed from CONNECTION_STATE_START(0) to CONNECTION_STATE_HDR_EXCH(3)
2023-12-19T19:30:27.2419072Z 560: [2023-12-19T19:30:27.2408842Z T: 7f970f5ff640] DEBUG : -> [OPEN]* {57757c06-fa69-4dda-8198-5eec19cb50ce,localhost,4294967295,65535,60000,NULL,NULL,NULL,NULL,{}}
2023-12-19T19:30:27.2420373Z 560: [2023-12-19T19:30:27.2409963Z T: 7f970f5ff640] DEBUG : Connection 57757c06-fa69-4dda-8198-5eec19cb50ce state changed from CONNECTION_STATE_HDR_EXCH(3) to CONNECTION_STATE_OPEN_SENT(7)
2023-12-19T19:30:27.2421838Z 560: [2023-12-19T19:30:27.2411267Z T: 7f970f5ff640] DEBUG : <- [OPEN]* {Mock Server for CbsOpenAndPutError,localhost,4294967295,65535,120000,NULL,NULL,NULL,NULL,{}}
2023-12-19T19:30:27.2423150Z 560: [2023-12-19T19:30:27.2411799Z T: 7f970f5ff640] DEBUG : Connection 57757c06-fa69-4dda-8198-5eec19cb50ce state changed from CONNECTION_STATE_OPEN_SENT(7) to CONNECTION_STATE_OPENED(9)
2023-12-19T19:30:27.2424218Z 560: [2023-12-19T19:30:27.2412971Z T: 7f970f5ff640] DEBUG : -> [BEGIN]* {NULL,0,1,1,4294967295}
2023-12-19T19:30:27.3420682Z 560: [2023-12-19T19:30:27.3415414Z T: 7f970f5ff640] DEBUG : <- [OPEN]* {57757c06-fa69-4dda-8198-5eec19cb50ce,localhost,4294967295,65535,60000,NULL,NULL,NULL,NULL,{}}
2023-12-19T19:30:27.3422693Z 560: [2023-12-19T19:30:27.3415796Z T: 7f970f5ff640] DEBUG : Connection Mock Server for CbsOpenAndPutError state changed from CONNECTION_STATE_OPEN_SENT(7) to CONNECTION_STATE_OPENED(9)
2023-12-19T19:30:27.3423510Z 560: 
2023-12-19T19:30:27.3424541Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:429:: Connection State changed. Connection: Mock Server for CbsOpenAndPutError Old state : OpenSent New state: Opened
2023-12-19T19:30:27.3425883Z 560: [2023-12-19T19:30:27.3416530Z T: 7f970f5ff640] DEBUG : <- [BEGIN]* {NULL,0,1,1,4294967295}
2023-12-19T19:30:27.3426644Z 560: 
2023-12-19T19:30:27.3427708Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:443:: OnNewEndpoint - Incoming endpoint created, create session.
2023-12-19T19:30:27.3428873Z 560: [2023-12-19T19:30:27.3418011Z T: 7f970f5ff640] DEBUG : -> [BEGIN]* {0,0,10000,1,4294967295}
2023-12-19T19:30:27.3429918Z 560: [2023-12-19T19:30:27.3418491Z T: 7f970f5ff640] DEBUG : <- [BEGIN]* {0,0,10000,1,4294967295}
2023-12-19T19:30:27.3431043Z 560: [2023-12-19T19:30:27.3419275Z T: 7f970f5ff640] DEBUG : -> [ATTACH]* {$cbs-sender,0,false,0,0,* {$cbs},* {$cbs},NULL,NULL,0,18446744073709551615}
2023-12-19T19:30:27.3432630Z 560: [2023-12-19T19:30:27.3419922Z T: 7f970f5ff640] DEBUG : -> [ATTACH]* {$cbs-receiver,1,true,0,0,* {$cbs},* {$cbs},NULL,NULL,NULL,18446744073709551615,NULL,NULL,{}}
2023-12-19T19:30:27.4426655Z 560: [2023-12-19T19:30:27.4422028Z T: 7f970f5ff640] DEBUG : <- [ATTACH]* {$cbs-sender,0,false,0,0,* {$cbs},* {$cbs},NULL,NULL,0,18446744073709551615}
2023-12-19T19:30:27.4428816Z 560: [2023-12-19T19:30:27.4422366Z T: 7f970f5ff640] WARN  : File: /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/vendor/azure-uamqp-c/src/amqpvalue.c:2252 Func: amqpvalue_clone: NULL value
2023-12-19T19:30:27.4429718Z 560: 
2023-12-19T19:30:27.4430736Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:470:: OnLinkAttached. Source: Source{ Address: $cbs
2023-12-19T19:30:27.4432058Z 560: , Durable: None, Expiry Policy: session-end, Timeout: 0, Dynamic: false} Target: Target{ Address: $cbs, Durable: None, Expiry Policy: session-end, Timeout: 0, Dynamic: false} Role: 0
2023-12-19T19:30:27.4432952Z 560: 
2023-12-19T19:30:27.4433914Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:501:: Role is sender, create receiver.
2023-12-19T19:30:27.4434699Z 560: 
2023-12-19T19:30:27.4435634Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:506:: No receiver found, create new.
2023-12-19T19:30:27.4436747Z 560: [2023-12-19T19:30:27.4424428Z T: 7f970f5ff640] DEBUG : No credential, returning empty token.
2023-12-19T19:30:27.4437958Z 560: [2023-12-19T19:30:27.4424654Z T: 7f970f5ff640] DEBUG : Message receiver state change MESSAGE_RECEIVER_STATE_IDLE -> MESSAGE_RECEIVER_STATE_OPENING
2023-12-19T19:30:27.4439311Z 560: [2023-12-19T19:30:27.4425583Z T: 7f970f5ff640] DEBUG : -> [ATTACH]* {$cbs-sender,0,true,0,0,* {$cbs},* {$cbs},NULL,NULL,NULL,18446744073709551615,NULL,NULL,{}}
2023-12-19T19:30:27.4440467Z 560: [2023-12-19T19:30:27.4425774Z T: 7f970f5ff640] DEBUG : Opening message receiver. Start async
2023-12-19T19:30:27.4441696Z 560: [2023-12-19T19:30:27.4425959Z T: 7f970f5ff640] DEBUG : Try to enable async operation on connection: 0x7f9708001ad0 ID: Mock Server for CbsOpenAndPutError count: 1
2023-12-19T19:30:27.4442792Z 560: [2023-12-19T19:30:27.4426457Z T: 7f970f5ff640] DEBUG : -> [FLOW]* {0,10000,0,1,0,0,10000}
2023-12-19T19:30:27.4443957Z 560: [2023-12-19T19:30:27.4426619Z T: 7f970f5ff640] DEBUG : Message receiver state change MESSAGE_RECEIVER_STATE_OPENING -> MESSAGE_RECEIVER_STATE_OPEN
2023-12-19T19:30:27.4444756Z 560: 
2023-12-19T19:30:27.4445734Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:530:: Message Receiver State changed. Old state: Opening New state: Open
2023-12-19T19:30:27.4446968Z 560: [2023-12-19T19:30:27.4427228Z T: 7f970f5ff640] DEBUG : <- [ATTACH]* {$cbs-receiver,1,true,0,0,* {$cbs},* {$cbs},NULL,NULL,NULL,18446744073709551615,NULL,NULL,{}}
2023-12-19T19:30:27.4447765Z 560: 
2023-12-19T19:30:27.4448686Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:470:: OnLinkAttached. Source: Source{ Address: $cbs
2023-12-19T19:30:27.4449918Z 560: , Durable: None, Expiry Policy: session-end, Timeout: 0, Dynamic: false} Target: Target{ Address: $cbs, Durable: None, Expiry Policy: session-end, Timeout: 0, Dynamic: false} Role: 1
2023-12-19T19:30:27.4450747Z 560: 
2023-12-19T19:30:27.4451626Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:478:: Role is receiver, create sender.
2023-12-19T19:30:27.4452346Z 560: 
2023-12-19T19:30:27.4453217Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:485:: No sender found, create new.
2023-12-19T19:30:27.4454517Z 560: [2023-12-19T19:30:27.4428589Z T: 7f970f5ff640] DEBUG : Opening message sender. Authenticate if needed with audience: Target{ Address: $cbs, Durable: None, Expiry Policy: session-end, Timeout: 0, Dynamic: false}
2023-12-19T19:30:27.4455904Z 560: [2023-12-19T19:30:27.4428769Z T: 7f970f5ff640] DEBUG : No credential, returning empty token.
2023-12-19T19:30:27.4456962Z 560: [2023-12-19T19:30:27.4428953Z T: 7f970f5ff640] DEBUG : Message sender state changed from Idle(1) to Opening(2).
2023-12-19T19:30:27.4457697Z 560: 
2023-12-19T19:30:27.4458668Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:548:: Message Sender State changed. Old state: Idle New state: Opening
2023-12-19T19:30:27.4460018Z 560: [2023-12-19T19:30:27.4429487Z T: 7f970f5ff640] DEBUG : -> [ATTACH]* {$cbs-receiver,1,false,0,0,* {$cbs},* {$cbs},NULL,NULL,0,18446744073709551615}
2023-12-19T19:30:27.4461317Z 560: [2023-12-19T19:30:27.4429644Z T: 7f970f5ff640] DEBUG : Opening message sender. Enable async operation.
2023-12-19T19:30:27.4462551Z 560: [2023-12-19T19:30:27.4429811Z T: 7f970f5ff640] DEBUG : Try to enable async operation on connection: 0x7f9708001ad0 ID: Mock Server for CbsOpenAndPutError count: 2
2023-12-19T19:30:27.4463681Z 560: [2023-12-19T19:30:27.4430061Z T: 7f970f5ff640] DEBUG : Message sender state changed from Opening(2) to Open(3).
2023-12-19T19:30:27.4464395Z 560: 
2023-12-19T19:30:27.4465327Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:548:: Message Sender State changed. Old state: Opening New state: Open
2023-12-19T19:30:27.4466516Z 560: [2023-12-19T19:30:27.4430677Z T: 7f970f5ff640] DEBUG : <- [ATTACH]* {$cbs-sender,0,true,0,0,* {$cbs},* {$cbs},NULL,NULL,NULL,18446744073709551615,NULL,NULL,{}}
2023-12-19T19:30:27.4467607Z 560: [2023-12-19T19:30:27.4430852Z T: 7f970f5ff640] DEBUG : Message sender state changed from Opening(2) to Open(3).
2023-12-19T19:30:27.4468610Z 560: [2023-12-19T19:30:27.4431019Z T: 7f970f5ff640] INFO  : OnMessageSenderStateChanged: Opening -> Open
2023-12-19T19:30:27.4469305Z 560: 
2023-12-19T19:30:27.4470157Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/claim_based_security_tests.cpp:155:: Open Completed.
2023-12-19T19:30:27.4471135Z 560: [2023-12-19T19:30:27.4433635Z T: 7f970f5ff640] DEBUG : -> [FLOW]* {0,1,0,1,0,0,10000}
2023-12-19T19:30:27.5456244Z 560: [2023-12-19T19:30:27.5450527Z T: 7f970f5ff640] DEBUG : <- [FLOW]* {0,1,0,1,0,0,10000}
2023-12-19T19:30:27.5457486Z 560: [2023-12-19T19:30:27.5451295Z T: 7f970f5ff640] DEBUG : <- [FLOW]* {0,10000,0,1,0,0,10000}
2023-12-19T19:30:27.5458538Z 560: [2023-12-19T19:30:27.5452203Z T: 7f970f5ff640] DEBUG : -> [TRANSFER]* {0,0,<01 00 00 00>,0,false,false}
2023-12-19T19:30:27.5459587Z 560: [2023-12-19T19:30:27.5452603Z T: 7f970f5ff640] DEBUG : <- [ATTACH]* {$cbs-receiver,1,false,0,0,* {$cbs},* {$cbs},NULL,NULL,0,18446744073709551615}
2023-12-19T19:30:27.5460662Z 560: [2023-12-19T19:30:27.5452961Z T: 7f970f5ff640] DEBUG : -> [FLOW]* {0,1,1,0,1,0,10000}
2023-12-19T19:30:27.5462057Z 560: [2023-12-19T19:30:27.5453138Z T: 7f970f5ff640] DEBUG : Message receiver state change MESSAGE_RECEIVER_STATE_OPENING -> MESSAGE_RECEIVER_STATE_OPEN
2023-12-19T19:30:27.5463135Z 560: [2023-12-19T19:30:27.5453340Z T: 7f970f5ff640] INFO  : OnMessageReceiverStateChanged: Opening -> Open
2023-12-19T19:30:27.5464113Z 560: [2023-12-19T19:30:27.5453974Z T: 7f970f5ff640] DEBUG : -> [FLOW]* {0,10000,0,1,1,0,10000}
2023-12-19T19:30:27.6462441Z 560: [2023-12-19T19:30:27.6455836Z T: 7f970f5ff640] DEBUG : <- [TRANSFER]* {0,0,<01 00 00 00>,0,false,false}
2023-12-19T19:30:27.6463926Z 560: 
2023-12-19T19:30:27.6464963Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:537:: Received a message 0x7f9700004a90
2023-12-19T19:30:27.6465753Z 560: 
2023-12-19T19:30:27.6466612Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:157:: Received message: Message: 
2023-12-19T19:30:27.6467432Z 560:     Header Header{durable=false, priority=4, firstAcquirer=false, deliveryCount=0}
2023-12-19T19:30:27.6468418Z 560:     Properties: MessageProperties {MessageId: [2023-12-19T19:30:27.6458509Z T: 7f970f5ff640] DEBUG : -> [DISPOSITION]* {true,0,0,true,* {}}
2023-12-19T19:30:27.6469413Z 560: [2023-12-19T19:30:27.6459096Z T: 7f970f5ff640] DEBUG : <- [FLOW]* {0,1,1,0,1,0,10000}
2023-12-19T19:30:27.6470032Z 560: [2023-12-19T19:30:27.6459606Z T: 7f970f5ff640] DEBUG : <- [FLOW]* {0,10000,0,1,1,0,10000}
2023-12-19T19:30:27.6470447Z 560: 0}
2023-12-19T19:30:27.6470759Z 560: 
2023-12-19T19:30:27.6471289Z 560:     Application Properties: {name, of one}{operation, put-token}{type, servicebus.windows.net:sastoken}, deliveryTag=<01 00 00 00>
2023-12-19T19:30:27.6471870Z 560:     Body: [
2023-12-19T19:30:27.6472220Z 560:         AmqpValue: stringizedToken
2023-12-19T19:30:27.6472559Z 560:     ]
2023-12-19T19:30:27.6473068Z 560: [2023-12-19T19:30:27.6460766Z T: 7f970f5ff640] DEBUG : <- [DISPOSITION]* {true,0,0,true,* {}}
2023-12-19T19:30:27.6473903Z 560: [2023-12-19T19:30:27.6461789Z T: 7f970e5fe640] DEBUG : Header:* {}Properties:* {NULL,NULL,NULL,NULL,NULL,0}Application properties:* {[status-code:500],[status-description:Internal Server Error]}Body - amqp value:* NULL-> [TRANSFER]* {1,0,<01 00 00 00>,0,false,false}
2023-12-19T19:30:27.7475874Z 560: [2023-12-19T19:30:27.7463851Z T: 7f970f5ff640] DEBUG : <- [TRANSFER]* {1,0,<01 00 00 00>,0,false,false}
2023-12-19T19:30:27.7477229Z 560: [2023-12-19T19:30:27.7465011Z T: 7f970f5ff640] INFO  : Received message before send completed.
2023-12-19T19:30:27.7478230Z 560: [2023-12-19T19:30:27.7466275Z T: 7f970f5ff640] DEBUG : -> [DISPOSITION]* {true,0,0,true,* {}}
2023-12-19T19:30:27.7479208Z 560: [2023-12-19T19:30:27.7466862Z T: 7f970f5ff640] DEBUG : -> [FLOW]* {1,1,1,0}
2023-12-19T19:30:27.7480130Z 560: [2023-12-19T19:30:27.7467444Z T: 7f970fccf140] DEBUG : Lock for Closing message sender.
2023-12-19T19:30:27.7481023Z 560: [2023-12-19T19:30:27.7467657Z T: 7f970fccf140] DEBUG : Closing message sender.
2023-12-19T19:30:27.7481633Z 560: [2023-12-19T19:30:27.7467964Z T: 7f970fccf140] DEBUG : Unsubscribe from link detach event.
2023-12-19T19:30:27.7482333Z 560: [2023-12-19T19:30:27.7468404Z T: 7f970fccf140] DEBUG : Try to disable async operation on connection: 0x555e2cd41d80 ID: 57757c06-fa69-4dda-8198-5eec19cb50ce count: 2
2023-12-19T19:30:27.7483015Z 560: [2023-12-19T19:30:27.7468776Z T: 7f970fccf140] DEBUG : Message sender state changed from Open(3) to Closing(4).
2023-12-19T19:30:27.7483602Z 560: [2023-12-19T19:30:27.7469026Z T: 7f970fccf140] INFO  : OnMessageSenderStateChanged: Open -> Closing
2023-12-19T19:30:27.7484155Z 560: [2023-12-19T19:30:27.7469582Z T: 7f970fccf140] DEBUG : -> [DETACH]* {0,true}
2023-12-19T19:30:27.7484713Z 560: [2023-12-19T19:30:27.7469783Z T: 7f970fccf140] DEBUG : Lock for Closing message receiver.
2023-12-19T19:30:27.7485282Z 560: [2023-12-19T19:30:27.7469970Z T: 7f970fccf140] DEBUG : Receiver unsubscribe from link detach event.
2023-12-19T19:30:27.7485859Z 560: [2023-12-19T19:30:27.7470155Z T: 7f970fccf140] DEBUG : Closing message receiver. Stop async
2023-12-19T19:30:27.7486518Z 560: [2023-12-19T19:30:27.7470373Z T: 7f970fccf140] DEBUG : Try to disable async operation on connection: 0x555e2cd41d80 ID: 57757c06-fa69-4dda-8198-5eec19cb50ce count: 1
2023-12-19T19:30:27.7487235Z 560: [2023-12-19T19:30:27.7470597Z T: 7f970fccf140] DEBUG : Disabled async operation on connection: 0x555e2cd41d80 ID: 57757c06-fa69-4dda-8198-5eec19cb50ce
2023-12-19T19:30:27.7487924Z 560: [2023-12-19T19:30:27.7470789Z T: 7f970fccf140] DEBUG : Message receiver state change MESSAGE_RECEIVER_STATE_OPEN -> MESSAGE_RECEIVER_STATE_CLOSING
2023-12-19T19:30:27.7488546Z 560: [2023-12-19T19:30:27.7470942Z T: 7f970fccf140] INFO  : OnMessageReceiverStateChanged: Open -> Closing
2023-12-19T19:30:27.7489095Z 560: [2023-12-19T19:30:27.7471191Z T: 7f970fccf140] DEBUG : -> [DETACH]* {1,true}
2023-12-19T19:30:27.7489479Z 560: 
2023-12-19T19:30:27.7489955Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:227:: Stop listening
2023-12-19T19:30:27.8469095Z 560: 
2023-12-19T19:30:27.8471265Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:368:: Exception thrown sending CBS response: Error sending message
2023-12-19T19:30:27.8472758Z 560: [2023-12-19T19:30:27.8465951Z T: 7f970fccf140] DEBUG : Lock for Closing message sender.
2023-12-19T19:30:27.8473873Z 560: [2023-12-19T19:30:27.8466270Z T: 7f970fccf140] DEBUG : Closing message sender.
2023-12-19T19:30:27.8475088Z 560: [2023-12-19T19:30:27.8466477Z T: 7f970fccf140] DEBUG : Unsubscribe from link detach event.
2023-12-19T19:30:27.8476635Z 560: [2023-12-19T19:30:27.8466835Z T: 7f970fccf140] DEBUG : Try to disable async operation on connection: 0x7f9708001ad0 ID: Mock Server for CbsOpenAndPutError count: 3
2023-12-19T19:30:27.8477909Z 560: [2023-12-19T19:30:27.8467086Z T: 7f970fccf140] DEBUG : Message sender state changed from Open(3) to Closing(4).
2023-12-19T19:30:27.8478557Z 560: 
2023-12-19T19:30:27.8479240Z 560: [  INFO ] /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/test/ut/mock_amqp_server.hpp:548:: Message Sender State changed. Old state: Open New state: Closing
2023-12-19T19:30:27.8480061Z 560: [2023-12-19T19:30:27.8468203Z T: 7f970fccf140] DEBUG : -> [DETACH]* {1,true}
2023-12-19T19:30:27.8480898Z 560: [2023-12-19T19:30:27.8468475Z T: 7f970fccf140] WARN  : File: /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/vendor/azure-uamqp-c/src/amqpvalue.c:2252 Func: amqpvalue_clone: NULL value
2023-12-19T19:30:27.8481778Z 560: [2023-12-19T19:30:27.8468728Z T: 7f970fccf140] WARN  : File: /mnt/vss/_work/1/s/sdk/core/azure-core-amqp/vendor/azure-uamqp-c/src/amqpvalue.c:2252 Func: amqpvalue_clone: NULL value
2023-12-19T19:30:27.8482551Z 560: [2023-12-19T19:30:27.8469443Z T: 7f970f5ff640] DEBUG : <- [DISPOSITION]* {true,0,0,true,* {}}
2023-12-19T19:30:27.9331670Z 419/516 Test #560: azure-core-amqp.TestCbs.CbsOpenAndPutError ........................................................***Exception: SegFault  0.80 sec

antkmsft avatar Dec 19 '23 21:12 antkmsft