Segfault in threshold logger when calling lcb_destroy

[nix-shell:~/Code/cbsdk/libcouchbase/build]$ lldb ./bin/unit-tests -- --gtest_filter="MockUnitTest.testAppendE2BIG"

(lldb) target create "./bin/unit-tests"

Current executable set to '/Users/jakerawsthorne/Code/cbsdk/libcouchbase/build/bin/unit-tests' (x86_64).

(lldb) settings set -- target.run-args  "--gtest_filter=MockUnitTest.testAppendE2BIG"

(lldb) r

warning: (x86_64) /Users/jakerawsthorne/Code/cbsdk/libcouchbase/build/bin/unit-tests(0x0000000100000000) address 0x0000000100000000 maps to more than one section: unit-tests.__TEXT and unit-tests.__TEXT

warning: (x86_64) /Users/jakerawsthorne/Code/cbsdk/libcouchbase/build/bin/unit-tests(0x0000000100000000) address 0x000000010029c000 maps to more than one section: unit-tests.__DATA and unit-tests.__DATA

Process 36971 launched: '/Users/jakerawsthorne/Code/cbsdk/libcouchbase/build/bin/unit-tests' (x86_64)

warning: (x86_64) /Users/jakerawsthorne/Code/cbsdk/libcouchbase/build/bin/unit-tests(0x0000000100000000) address 0x0000000100000000 maps to more than one section: unit-tests.__TEXT and unit-tests.__TEXT

warning: (x86_64) /Users/jakerawsthorne/Code/cbsdk/libcouchbase/build/bin/unit-tests(0x0000000100000000) address 0x000000010029c000 maps to more than one section: unit-tests.__DATA and unit-tests.__DATA

Note: Google Test filter = MockUnitTest.testAppendE2BIG

[==========] Running 1 test from 1 test case.

[----------] Global test environment set-up.

0ms [Ic0cc491fe017245e] {36971/307} [INFO] (instance - L:521) Version=3.2.4_5_gccbaadf579, Changeset=ccbaadf579d3b4db6b3c63a931f9575443d66359

0ms [Ic0cc491fe017245e] {36971/307} [INFO] (instance - L:522) Effective connection string: couchbases://10.112.220.101?ssl=no_verify&dnssrv=off&bootstrap_on=cccp&compression=off. Bucket=default

6ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (instance - L:389) Applying initial cntl compression=off

6ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (instance - L:155) Adding host 10.112.220.101:18091 to initial HTTP bootstrap list

6ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (instance - L:155) Adding host 10.112.220.101:11207 to initial CCCP bootstrap list

6ms [Ic0cc491fe017245e] {36971/307} [TRACE] (instance - L:193) Bootstrap hosts loaded (cccp:1, http:1)

6ms [Ic0cc491fe017245e] {36971/307} [INFO] (bootstrap - L:277) Requested network configuration: heuristic

6ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (confmon - L:94) Preparing providers (this may be called multiple times)

6ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (confmon - L:100) Provider CCCP is ENABLED

6ms [Ic0cc491fe017245e] {36971/307} [TRACE] (confmon - L:319) Refreshing current cluster map (bucket: default)

6ms [Ic0cc491fe017245e] {36971/307} [TRACE] (confmon - L:305) Attempting to retrieve cluster map via CCCP

6ms [Ic0cc491fe017245e] {36971/307} [INFO] (cccp - L:176) Requesting connection to node 10.112.220.101:11207 for CCCP configuration

6ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (lcbio_mgr - L:501) <10.112.220.101:11207> (HE=0x102a04080) Creating new connection because none are available in the pool

6ms [Ic0cc491fe017245e] {36971/307} [TRACE] (lcbio_mgr - L:413) <10.112.220.101:11207> (HE=0x102a04080) New pool entry: I=0x102a042f0

6ms [Ic0cc491fe017245e] {36971/307} [INFO] (connection - L:497) <10.112.220.101:11207> (SOCK=d3f81db236308e75) Starting. Timeout=2000000us

7ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (connection - L:262) <10.112.220.101:11207> (SOCK=d3f81db236308e75) Created new socket with FD=4

7ms [Ic0cc491fe017245e] {36971/307} [TRACE] (connection - L:362) <10.112.220.101:11207> (SOCK=d3f81db236308e75) Scheduling I/O watcher for asynchronous connection completion.

7ms [Ic0cc491fe017245e] {36971/307} [INFO] (connection - L:159) <10.112.220.101:11207> (SOCK=d3f81db236308e75) Connected established

7ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (connection - L:106) <10.112.220.101:11207> (SOCK=d3f81db236308e75) Successfully set TCP_NODELAY

7ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (connection - L:106) <10.112.220.101:11207> (SOCK=d3f81db236308e75) Successfully set TCP_KEEPALIVE

7ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (lcbio_mgr - L:369) <10.112.220.101:11207> (HE=0x102a04080) Received result for I=0x102a042f0,C=0x0; E=0x0

7ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (lcbio_mgr - L:329) <10.112.220.101:11207> (HE=0x102a04080) Assigning R=0x102a041f0 SOCKET=0x102a04530

7ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x100e06df0,unknown) Pairing with SOCK=d3f81db236308e75

8ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (negotiation - L:471) <10.112.220.101:11207> (CTX=0x100e06df0,sasl,SASLREQ=0x104009400) HELO identificator: {"a":"libcouchbase/3.2.4_5_gccbaadf579 (Darwin-19.6.0; x86_64; Clang 7.1.0)","i":"c0cc491fe017245e/d3f81db236308e75"}, features: 0x02 (TLS), 0x06 (XATTR), 0x0b (JSON), 0x08 (Select bucket), 0x07 (XERROR), 0x03 (TCP nodelay), 0x04 (Mutation seqno), 0x0f (Tracing), 0x12 (Collections), 0x0e (Unordered execution), 0x17 (Create as Deleted), 0x14 (Preserve Expiry)

8ms [Ic0cc491fe017245e] {36971/307} [TRACE] (SSL - L:240) <10.112.220.101:11207> sock=0x102a04530: ST(0x10). before SSL initialization. R(0x1) unknown (unknown)

11ms [Ic0cc491fe017245e] {36971/307} [TRACE] (SSL - L:240) <10.112.220.101:11207> sock=0x102a04530: ST(0x20). SSL negotiation finished successfully. R(0x1) unknown (unknown)

11ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (SSL - L:245) sock=0x102a04530. Using SSL version TLSv1.3. Cipher=TLS_AES_256_GCM_SHA384

13ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (negotiation - L:502) <10.112.220.101:11207> (CTX=0x100e06df0,sasl,SASLREQ=0x104009400) Server supports features: 0x03 (TCP nodelay), 0x04 (Mutation seqno), 0x06 (XATTR), 0x07 (XERROR), 0x08 (Select bucket), 0x0b (JSON), 0x0e (Unordered execution), 0x0f (Tracing), 0x12 (Collections), 0x14 (Preserve Expiry), 0x17 (Create as Deleted)

13ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (negotiation - L:326) <10.112.220.101:11207> (CTX=0x100e06df0,sasl,SASLREQ=0x104009400) Using SCRAM-SHA512 SASL mechanism

18ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (negotiation - L:554) <10.112.220.101:11207> (CTX=0x100e06df0,sasl,SASLREQ=0x104009400) Sending SELECT_BUCKET "default"

18ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x100e06df0,sasl) Destroying context for SOCK=d3f81db236308e75. Pending Writes=0, Entered=true, Socket Refcount=1

18ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x100e07b20,unknown) Pairing with SOCK=d3f81db236308e75

19ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x100e07b20,bc_cccp) Destroying context for SOCK=d3f81db236308e75. Pending Writes=0, Entered=true, Socket Refcount=1

19ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (lcbio_mgr - L:554) <10.112.220.101:11207> (HE=0x102a04080) Placing socket back into the pool. I=0x102a042f0,C=0x102a04530

19ms [Ic0cc491fe017245e] {36971/307} [INFO] (confmon - L:183) Setting initial configuration. Received via CCCP (bucket="default", rev=1:65, address="10.112.220.101")

20ms [Ic0cc491fe017245e] {36971/307} [INFO] (bootstrap - L:95) Selected network configuration: "default"

20ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (lcbio_mgr - L:492) <10.112.220.101:11207> (HE=0x102a04080) Found ready connection in pool. Reusing socket and not creating new connection

20ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (lcbio_mgr - L:329) <10.112.220.101:11207> (HE=0x102a04080) Assigning R=0x100e07850 SOCKET=0x102a04530

20ms [Ic0cc491fe017245e] {36971/307} [TRACE] (server - L:1005) <10.112.220.101:11207> (SRV=0x100e06df0) Got new KV connection (json=yes, snappy=no, mt=yes, durability=no, bucket=yes "default")

20ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x102a04450,unknown) Pairing with SOCK=d3f81db236308e75

22ms [Ic0cc491fe017245e] {36971/307} [INFO] (tests-ENV - L:447) Using real cluster version 7.1.0-1985 (id=14)

22ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (server - L:1214) <10.112.220.101:11207> (CTX=0x102a04450,memcached,SRV=0x100e06df0,IX=0) Finalizing context

22ms [Ic0cc491fe017245e] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x102a04450,memcached) Destroying context for SOCK=d3f81db236308e75. Pending Writes=0, Entered=false, Socket Refcount=1

[----------] 1 test from MockUnitTest

[ RUN      ] MockUnitTest.testAppendE2BIG

23ms [Id1360f2a034f8568] {36971/307} [INFO] (instance - L:521) Version=3.2.4_5_gccbaadf579, Changeset=ccbaadf579d3b4db6b3c63a931f9575443d66359

23ms [Id1360f2a034f8568] {36971/307} [INFO] (instance - L:522) Effective connection string: couchbases://10.112.220.101?ssl=no_verify&dnssrv=off&bootstrap_on=cccp&compression=off. Bucket=default

23ms [Id1360f2a034f8568] {36971/307} [DEBUG] (instance - L:389) Applying initial cntl compression=off

23ms [Id1360f2a034f8568] {36971/307} [DEBUG] (instance - L:155) Adding host 10.112.220.101:18091 to initial HTTP bootstrap list

23ms [Id1360f2a034f8568] {36971/307} [DEBUG] (instance - L:155) Adding host 10.112.220.101:11207 to initial CCCP bootstrap list

23ms [Id1360f2a034f8568] {36971/307} [TRACE] (instance - L:193) Bootstrap hosts loaded (cccp:1, http:1)

23ms [Id1360f2a034f8568] {36971/307} [INFO] (bootstrap - L:277) Requested network configuration: heuristic

23ms [Id1360f2a034f8568] {36971/307} [DEBUG] (confmon - L:94) Preparing providers (this may be called multiple times)

23ms [Id1360f2a034f8568] {36971/307} [DEBUG] (confmon - L:100) Provider CCCP is ENABLED

23ms [Id1360f2a034f8568] {36971/307} [TRACE] (confmon - L:319) Refreshing current cluster map (bucket: default)

23ms [Id1360f2a034f8568] {36971/307} [TRACE] (confmon - L:305) Attempting to retrieve cluster map via CCCP

23ms [Id1360f2a034f8568] {36971/307} [INFO] (cccp - L:176) Requesting connection to node 10.112.220.101:11207 for CCCP configuration

23ms [Id1360f2a034f8568] {36971/307} [DEBUG] (lcbio_mgr - L:501) <10.112.220.101:11207> (HE=0x102806030) Creating new connection because none are available in the pool

23ms [Id1360f2a034f8568] {36971/307} [TRACE] (lcbio_mgr - L:413) <10.112.220.101:11207> (HE=0x102806030) New pool entry: I=0x102d04920

23ms [Id1360f2a034f8568] {36971/307} [INFO] (connection - L:497) <10.112.220.101:11207> (SOCK=a78b415628364f8d) Starting. Timeout=2000000us

23ms [Id1360f2a034f8568] {36971/307} [DEBUG] (connection - L:262) <10.112.220.101:11207> (SOCK=a78b415628364f8d) Created new socket with FD=4

23ms [Id1360f2a034f8568] {36971/307} [TRACE] (connection - L:362) <10.112.220.101:11207> (SOCK=a78b415628364f8d) Scheduling I/O watcher for asynchronous connection completion.

24ms [Id1360f2a034f8568] {36971/307} [INFO] (connection - L:159) <10.112.220.101:11207> (SOCK=a78b415628364f8d) Connected established

24ms [Id1360f2a034f8568] {36971/307} [DEBUG] (connection - L:106) <10.112.220.101:11207> (SOCK=a78b415628364f8d) Successfully set TCP_NODELAY

24ms [Id1360f2a034f8568] {36971/307} [DEBUG] (connection - L:106) <10.112.220.101:11207> (SOCK=a78b415628364f8d) Successfully set TCP_KEEPALIVE

24ms [Id1360f2a034f8568] {36971/307} [DEBUG] (lcbio_mgr - L:369) <10.112.220.101:11207> (HE=0x102806030) Received result for I=0x102d04920,C=0x0; E=0x0

24ms [Id1360f2a034f8568] {36971/307} [DEBUG] (lcbio_mgr - L:329) <10.112.220.101:11207> (HE=0x102806030) Assigning R=0x102804080 SOCKET=0x1028062f0

24ms [Id1360f2a034f8568] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x102806f50,unknown) Pairing with SOCK=a78b415628364f8d

24ms [Id1360f2a034f8568] {36971/307} [DEBUG] (negotiation - L:471) <10.112.220.101:11207> (CTX=0x102806f50,sasl,SASLREQ=0x105809000) HELO identificator: {"a":"libcouchbase/3.2.4_5_gccbaadf579 (Darwin-19.6.0; x86_64; Clang 7.1.0)","i":"d1360f2a034f8568/a78b415628364f8d"}, features: 0x02 (TLS), 0x06 (XATTR), 0x0b (JSON), 0x08 (Select bucket), 0x07 (XERROR), 0x03 (TCP nodelay), 0x04 (Mutation seqno), 0x0f (Tracing), 0x12 (Collections), 0x0e (Unordered execution), 0x17 (Create as Deleted), 0x14 (Preserve Expiry)

24ms [Id1360f2a034f8568] {36971/307} [TRACE] (SSL - L:240) <10.112.220.101:11207> sock=0x1028062f0: ST(0x10). before SSL initialization. R(0x1) unknown (unknown)

28ms [Id1360f2a034f8568] {36971/307} [TRACE] (SSL - L:240) <10.112.220.101:11207> sock=0x1028062f0: ST(0x20). SSL negotiation finished successfully. R(0x1) unknown (unknown)

28ms [Id1360f2a034f8568] {36971/307} [DEBUG] (SSL - L:245) sock=0x1028062f0. Using SSL version TLSv1.3. Cipher=TLS_AES_256_GCM_SHA384

29ms [Id1360f2a034f8568] {36971/307} [DEBUG] (negotiation - L:502) <10.112.220.101:11207> (CTX=0x102806f50,sasl,SASLREQ=0x105809000) Server supports features: 0x03 (TCP nodelay), 0x04 (Mutation seqno), 0x06 (XATTR), 0x07 (XERROR), 0x08 (Select bucket), 0x0b (JSON), 0x0e (Unordered execution), 0x0f (Tracing), 0x12 (Collections), 0x14 (Preserve Expiry), 0x17 (Create as Deleted)

29ms [Id1360f2a034f8568] {36971/307} [DEBUG] (negotiation - L:326) <10.112.220.101:11207> (CTX=0x102806f50,sasl,SASLREQ=0x105809000) Using SCRAM-SHA512 SASL mechanism

34ms [Id1360f2a034f8568] {36971/307} [DEBUG] (negotiation - L:554) <10.112.220.101:11207> (CTX=0x102806f50,sasl,SASLREQ=0x105809000) Sending SELECT_BUCKET "default"

35ms [Id1360f2a034f8568] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x102806f50,sasl) Destroying context for SOCK=a78b415628364f8d. Pending Writes=0, Entered=true, Socket Refcount=1

35ms [Id1360f2a034f8568] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x102c07650,unknown) Pairing with SOCK=a78b415628364f8d

36ms [Id1360f2a034f8568] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x102c07650,bc_cccp) Destroying context for SOCK=a78b415628364f8d. Pending Writes=0, Entered=true, Socket Refcount=1

36ms [Id1360f2a034f8568] {36971/307} [DEBUG] (lcbio_mgr - L:554) <10.112.220.101:11207> (HE=0x102806030) Placing socket back into the pool. I=0x102d04920,C=0x1028062f0

36ms [Id1360f2a034f8568] {36971/307} [INFO] (confmon - L:183) Setting initial configuration. Received via CCCP (bucket="default", rev=1:65, address="10.112.220.101")

36ms [Id1360f2a034f8568] {36971/307} [INFO] (bootstrap - L:95) Selected network configuration: "default"

36ms [Id1360f2a034f8568] {36971/307} [DEBUG] (lcbio_mgr - L:492) <10.112.220.101:11207> (HE=0x102806030) Found ready connection in pool. Reusing socket and not creating new connection

36ms [Id1360f2a034f8568] {36971/307} [DEBUG] (lcbio_mgr - L:329) <10.112.220.101:11207> (HE=0x102806030) Assigning R=0x100c05330 SOCKET=0x1028062f0

36ms [Id1360f2a034f8568] {36971/307} [TRACE] (server - L:1005) <10.112.220.101:11207> (SRV=0x100c04440) Got new KV connection (json=yes, snappy=no, mt=yes, durability=no, bucket=yes "default")

36ms [Id1360f2a034f8568] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x102d04a80,unknown) Pairing with SOCK=a78b415628364f8d

42ms [Id1360f2a034f8568] {36971/307} [DEBUG] (server - L:1214) <10.112.220.101:11207> (CTX=0x102d04a80,memcached,SRV=0x100c04440,IX=0) Finalizing context

42ms [Id1360f2a034f8568] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x102d04a80,memcached) Destroying context for SOCK=a78b415628364f8d. Pending Writes=0, Entered=false, Socket Refcount=1

42ms [I94ad0b9609688e3a] {36971/307} [INFO] (instance - L:521) Version=3.2.4_5_gccbaadf579, Changeset=ccbaadf579d3b4db6b3c63a931f9575443d66359

42ms [I94ad0b9609688e3a] {36971/307} [INFO] (instance - L:522) Effective connection string: couchbases://10.112.220.101?ssl=no_verify&dnssrv=off&bootstrap_on=cccp&compression=off. Bucket=default

43ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (instance - L:389) Applying initial cntl compression=off

43ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (instance - L:155) Adding host 10.112.220.101:18091 to initial HTTP bootstrap list

43ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (instance - L:155) Adding host 10.112.220.101:11207 to initial CCCP bootstrap list

43ms [I94ad0b9609688e3a] {36971/307} [TRACE] (instance - L:193) Bootstrap hosts loaded (cccp:1, http:1)

43ms [I94ad0b9609688e3a] {36971/307} [INFO] (bootstrap - L:277) Requested network configuration: heuristic

43ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (confmon - L:94) Preparing providers (this may be called multiple times)

43ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (confmon - L:100) Provider CCCP is ENABLED

43ms [I94ad0b9609688e3a] {36971/307} [TRACE] (confmon - L:319) Refreshing current cluster map (bucket: default)

43ms [I94ad0b9609688e3a] {36971/307} [TRACE] (confmon - L:305) Attempting to retrieve cluster map via CCCP

43ms [I94ad0b9609688e3a] {36971/307} [INFO] (cccp - L:176) Requesting connection to node 10.112.220.101:11207 for CCCP configuration

43ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (lcbio_mgr - L:501) <10.112.220.101:11207> (HE=0x100f04130) Creating new connection because none are available in the pool

43ms [I94ad0b9609688e3a] {36971/307} [TRACE] (lcbio_mgr - L:413) <10.112.220.101:11207> (HE=0x100f04130) New pool entry: I=0x100f07f70

43ms [I94ad0b9609688e3a] {36971/307} [INFO] (connection - L:497) <10.112.220.101:11207> (SOCK=41cdc1fe5e198b8e) Starting. Timeout=2000000us

43ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (connection - L:262) <10.112.220.101:11207> (SOCK=41cdc1fe5e198b8e) Created new socket with FD=4

43ms [I94ad0b9609688e3a] {36971/307} [TRACE] (connection - L:362) <10.112.220.101:11207> (SOCK=41cdc1fe5e198b8e) Scheduling I/O watcher for asynchronous connection completion.

44ms [I94ad0b9609688e3a] {36971/307} [INFO] (connection - L:159) <10.112.220.101:11207> (SOCK=41cdc1fe5e198b8e) Connected established

44ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (connection - L:106) <10.112.220.101:11207> (SOCK=41cdc1fe5e198b8e) Successfully set TCP_NODELAY

44ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (connection - L:106) <10.112.220.101:11207> (SOCK=41cdc1fe5e198b8e) Successfully set TCP_KEEPALIVE

44ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (lcbio_mgr - L:369) <10.112.220.101:11207> (HE=0x100f04130) Received result for I=0x100f07f70,C=0x0; E=0x0

44ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (lcbio_mgr - L:329) <10.112.220.101:11207> (HE=0x100f04130) Assigning R=0x100f07e70 SOCKET=0x100f08190

44ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x100f08e70,unknown) Pairing with SOCK=41cdc1fe5e198b8e

44ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (negotiation - L:471) <10.112.220.101:11207> (CTX=0x100f08e70,sasl,SASLREQ=0x107009000) HELO identificator: {"a":"libcouchbase/3.2.4_5_gccbaadf579 (Darwin-19.6.0; x86_64; Clang 7.1.0)","i":"94ad0b9609688e3a/41cdc1fe5e198b8e"}, features: 0x02 (TLS), 0x06 (XATTR), 0x0b (JSON), 0x08 (Select bucket), 0x07 (XERROR), 0x03 (TCP nodelay), 0x04 (Mutation seqno), 0x0f (Tracing), 0x12 (Collections), 0x0e (Unordered execution), 0x17 (Create as Deleted), 0x14 (Preserve Expiry)

44ms [I94ad0b9609688e3a] {36971/307} [TRACE] (SSL - L:240) <10.112.220.101:11207> sock=0x100f08190: ST(0x10). before SSL initialization. R(0x1) unknown (unknown)

47ms [I94ad0b9609688e3a] {36971/307} [TRACE] (SSL - L:240) <10.112.220.101:11207> sock=0x100f08190: ST(0x20). SSL negotiation finished successfully. R(0x1) unknown (unknown)

47ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (SSL - L:245) sock=0x100f08190. Using SSL version TLSv1.3. Cipher=TLS_AES_256_GCM_SHA384

47ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (negotiation - L:502) <10.112.220.101:11207> (CTX=0x100f08e70,sasl,SASLREQ=0x107009000) Server supports features: 0x03 (TCP nodelay), 0x04 (Mutation seqno), 0x06 (XATTR), 0x07 (XERROR), 0x08 (Select bucket), 0x0b (JSON), 0x0e (Unordered execution), 0x0f (Tracing), 0x12 (Collections), 0x14 (Preserve Expiry), 0x17 (Create as Deleted)

48ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (negotiation - L:326) <10.112.220.101:11207> (CTX=0x100f08e70,sasl,SASLREQ=0x107009000) Using SCRAM-SHA512 SASL mechanism

52ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (negotiation - L:554) <10.112.220.101:11207> (CTX=0x100f08e70,sasl,SASLREQ=0x107009000) Sending SELECT_BUCKET "default"

53ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x100f08e70,sasl) Destroying context for SOCK=41cdc1fe5e198b8e. Pending Writes=0, Entered=true, Socket Refcount=1

53ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x100c04410,unknown) Pairing with SOCK=41cdc1fe5e198b8e

53ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x100c04410,bc_cccp) Destroying context for SOCK=41cdc1fe5e198b8e. Pending Writes=0, Entered=true, Socket Refcount=1

53ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (lcbio_mgr - L:554) <10.112.220.101:11207> (HE=0x100f04130) Placing socket back into the pool. I=0x100f07f70,C=0x100f08190

54ms [I94ad0b9609688e3a] {36971/307} [INFO] (confmon - L:183) Setting initial configuration. Received via CCCP (bucket="default", rev=1:65, address="10.112.220.101")

54ms [I94ad0b9609688e3a] {36971/307} [INFO] (bootstrap - L:95) Selected network configuration: "default"

102ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (lcbio_mgr - L:492) <10.112.220.101:11207> (HE=0x100f04130) Found ready connection in pool. Reusing socket and not creating new connection

105ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (lcbio_mgr - L:329) <10.112.220.101:11207> (HE=0x100f04130) Assigning R=0x100e064e0 SOCKET=0x100f08190

105ms [I94ad0b9609688e3a] {36971/307} [TRACE] (server - L:1005) <10.112.220.101:11207> (SRV=0x100b0af70) Got new KV connection (json=yes, snappy=no, mt=yes, durability=no, bucket=yes "default")

105ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (ioctx - L:94) <10.112.220.101:11207> (CTX=0x100c04410,unknown) Pairing with SOCK=41cdc1fe5e198b8e

2679ms [I94ad0b9609688e3a] {36971/307} [TRACE] (bootstrap - L:176) Background-polling for new configuration

2679ms [I94ad0b9609688e3a] {36971/307} [TRACE] (confmon - L:319) Refreshing current cluster map (bucket: default)

2751ms [I94ad0b9609688e3a] {36971/307} [TRACE] (confmon - L:305) Attempting to retrieve cluster map via CCCP

2752ms [I94ad0b9609688e3a] {36971/307} [TRACE] (cccp - L:164) Re-Issuing CCCP Command on server struct 0x100b0af70 (10.112.220.101:11207)

3568ms [I94ad0b9609688e3a] {36971/307} [TRACE] (confmon - L:157) Not applying configuration received via CCCP (bucket="default", source=CCCP, address="10.112.220.101"). No changes detected. A.rev=1:65, B.rev=1:65. Changes: servers=no, map=no, replicas=no

3579ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (server - L:1214) <10.112.220.101:11207> (CTX=0x100c04410,memcached,SRV=0x100b0af70,IX=0) Finalizing context

3579ms [I94ad0b9609688e3a] {36971/307} [DEBUG] (ioctx - L:140) <10.112.220.101:11207> (CTX=0x100c04410,memcached) Destroying context for SOCK=41cdc1fe5e198b8e. Pending Writes=0, Entered=false, Socket Refcount=1

3588ms [I94ad0b9609688e3a] {36971/307} [INFO] (tracer - L:175) Operations over threshold: {"count":1,"service":"kv","top":[{"last_dispatch_duration_us":3465227,"last_local_id":"94ad0b9609688e3a/41cdc1fe5e198b8e","last_local_socket":"10.112.220.1:51766","last_operation_id":"0","last_remote_socket":"10.112.220.101:11207","last_server_duration_us":5491,"operation_name":"upsert","total_dispatch_duration_us":3465227,"total_duration_us":3465227,"total_server_duration_us":5491}]}

Process 36971 stopped

* thread #1, queue = 'com.apple.main-thread', stop reason = EXC_BAD_ACCESS (code=1, address=0x989680)

    frame #0: 0x000000010020e5c4 unit-tests`tlt_destructor(lcbtrace_TRACER*) [inlined] bool std::__1::__tree_is_left_child<std::__1::__tree_node_base<void*>*>(__x=0x0000000100b0a760) at __tree:79:16

   76   bool

   77   __tree_is_left_child(_NodePtr __x) _NOEXCEPT

   78   {

-> 79       return __x == __x->__parent_->__left_;

   80   }

   81  

   82   // Determines if the subtree rooted at __x is a proper red black subtree.  If

Target 0: (unit-tests) stopped.

(lldb) bt

* thread #1, queue = 'com.apple.main-thread', stop reason = EXC_BAD_ACCESS (code=1, address=0x989680)

  * frame #0: 0x000000010020e5c4 unit-tests`tlt_destructor(lcbtrace_TRACER*) [inlined] bool std::__1::__tree_is_left_child<std::__1::__tree_node_base<void*>*>(__x=0x0000000100b0a760) at __tree:79:16

    frame #1: 0x000000010020e5bd unit-tests`tlt_destructor(lcbtrace_TRACER*) [inlined] std::__1::__tree_end_node<std::__1::__tree_node_base<void*>*>* std::__1::__tree_next_iter<std::__1::__tree_end_node<std::__1::__tree_node_base<void*>*>*, std::__1::__tree_node_base<void*>*>(__x=0x0000000100b0a760) at __tree:182

    frame #2: 0x000000010020e5a2 unit-tests`tlt_destructor(lcbtrace_TRACER*) [inlined] std::__1::__tree_iterator<std::__1::__value_type<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, lcb::trace::FixedQueue<lcb::trace::ReportedSpan> >, std::__1::__tree_node<std::__1::__value_type<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, lcb::trace::FixedQueue<lcb::trace::ReportedSpan> >, void*>*, long>::operator++(this=<unavailable>) at __tree:843

    frame #3: 0x000000010020e5a2 unit-tests`tlt_destructor(lcbtrace_TRACER*) [inlined] std::__1::__map_iterator<std::__1::__tree_iterator<std::__1::__value_type<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, lcb::trace::FixedQueue<lcb::trace::ReportedSpan> >, std::__1::__tree_node<std::__1::__value_type<std::__1::basic_string<char, std::__1::char_traits<char>, std::__1::allocator<char> >, lcb::trace::FixedQueue<lcb::trace::ReportedSpan> >, void*>*, long> >::operator++(this=<unavailable>) at map:776

    frame #4: 0x000000010020e5a2 unit-tests`tlt_destructor(lcbtrace_TRACER*) at threshold_logging_tracer.cc:189

    frame #5: 0x000000010020e54f unit-tests`tlt_destructor(wrapper=0x0000000102d06ae0) at threshold_logging_tracer.cc:36

    frame #6: 0x00000001001b6909 unit-tests`::lcb_destroy(instance=0x0000000102d044d0) at instance.cc:704:9

    frame #7: 0x0000000100006301 unit-tests`HandleWrap::~HandleWrap() [inlined] HandleWrap::destroy(this=0x00007ffeefbf8960) at mock-environment.cc:606:9

    frame #8: 0x00000001000062f3 unit-tests`HandleWrap::~HandleWrap() [inlined] HandleWrap::~HandleWrap(this=0x00007ffeefbf8960) at mock-environment.cc:618

    frame #9: 0x00000001000062e9 unit-tests`HandleWrap::~HandleWrap(this=0x00007ffeefbf8960) at mock-environment.cc:617

    frame #10: 0x0000000100092cc4 unit-tests`MockUnitTest_testAppendE2BIG_Test::TestBody(this=<unavailable>) at t_misc.cc:960:1

    frame #11: 0x0000000100520a24 libgtest.dylib`void testing::internal::HandleExceptionsInMethodIfSupported<testing::Test, void>(testing::Test*, void (testing::Test::*)(), char const*) [inlined] void testing::internal::HandleSehExceptionsInMethodIfSupported<testing::Test, void>(method=<unavailable>, location=<unavailable>)(), char const*) at gtest.cc:2443:10

    frame #12: 0x0000000100520a14 libgtest.dylib`void testing::internal::HandleExceptionsInMethodIfSupported<testing::Test, void>(object=<unavailable>, method=<unavailable>, location="the test body")(), char const*) at gtest.cc:2479

    frame #13: 0x000000010052096e libgtest.dylib`testing::Test::Run(this=0x0000000102c050f0) at gtest.cc:2517:5

    frame #14: 0x0000000100521d01 libgtest.dylib`testing::TestInfo::Run(this=<unavailable>) at gtest.cc:2693:11

    frame #15: 0x0000000100522687 libgtest.dylib`testing::TestCase::Run(this=<unavailable>) at gtest.cc:2811:28

    frame #16: 0x000000010052f9f7 libgtest.dylib`testing::internal::UnitTestImpl::RunAllTests(this=<unavailable>) at gtest.cc:5178:43

    frame #17: 0x000000010052f3e4 libgtest.dylib`bool testing::internal::HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool>(testing::internal::UnitTestImpl*, bool (testing::internal::UnitTestImpl::*)(), char const*) [inlined] bool testing::internal::HandleSehExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool>(method=<unavailable>, location=<unavailable>)(), char const*) at gtest.cc:2443:10

    frame #18: 0x000000010052f3d4 libgtest.dylib`bool testing::internal::HandleExceptionsInMethodIfSupported<testing::internal::UnitTestImpl, bool>(object=<unavailable>, method=<unavailable>, location="auxiliary test code (environments or event listeners)")(), char const*) at gtest.cc:2479

    frame #19: 0x000000010052f33e libgtest.dylib`testing::UnitTest::Run(this=0x000000010054e3c8) at gtest.cc:4787:10

    frame #20: 0x000000010016a82b unit-tests`main [inlined] RUN_ALL_TESTS() at gtest.h:2341:46

    frame #21: 0x000000010016a81e unit-tests`main(argc=1, argv=<unavailable>) at unit_tests.cc:45

    frame #22: 0x00007fff717a8cc9 libdyld.dylib`start + 1