Uploaded image for project: 'Couchbase C client library libcouchbase'
  1. Couchbase C client library libcouchbase
  2. CCBC-1531

Segfault in threshold logger when calling lcb_destroy

    XMLWordPrintable

Details

    • Bug
    • Resolution: Unresolved
    • Major
    • None
    • 3.2.4
    • library
    • None
    • 1

    Description

      Running the MockUnitTest.testAppendE2BIG test

      [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
      

      Attachments

        Activity

          People

            avsej Sergey Avseyev
            jake.rawsthorne Jake Rawsthorne
            Votes:
            0 Vote for this issue
            Watchers:
            0 Start watching this issue

            Dates

              Created:
              Updated:

              PagerDuty