Uploaded image for project: 'Percona XtraDB Cluster'
  1. Percona XtraDB Cluster
  2. PXC-1600

LP #1274199: Attempt to match against an empty key | Crash in UNIV_DEBUG

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Done
    • Priority: Low
    • Resolution: Done
    • Affects Version/s: None
    • Fix Version/s: None
    • Component/s: None
    • Labels:

      Description

      **Reported in Launchpad by Jay Janssen last update 06-05-2014 10:22:04

      [root@ip-10-29-254-10 mysql]# rpm -qa | grep -i percona
      Percona-XtraDB-Cluster-shared-55-5.5.34-25.9.607.rhel6.x86_64
      Percona-XtraDB-Cluster-client-55-5.5.34-25.9.607.rhel6.x86_64
      Percona-XtraDB-Cluster-galera-3-3.2-1.196.rhel6.x86_64
      percona-toolkit-2.2.6-1.noarch
      Percona-Server-shared-51-5.1.73-rel14.11.603.rhel6.x86_64
      percona-xtrabackup-2.1.7-721.rhel6.x86_64
      Percona-XtraDB-Cluster-server-55-5.5.34-25.9.607.rhel6.x86_64

      Scenario is 2 node cluster.

      node2 is wsrep_desync=ON and FLUSH TABLES WITH READ LOCK;

      node1 runs: sysbench --db-driver=mysql --test=sysbench_tests/db/update_non_index.lua --rand-type=uniform --rand-init --mysql-user=test --mysql-password=test --mysql-db=test --oltp-table-size=250000 --max-requests=1000000 --num-threads=8 --max-time=0 --report-interval=1 run

      Once sysbench finishes on node1, execute UNLOCK TABLES on node2. Node2 gets this error:

      Version: '5.5.34-55' socket: '/var/lib/mysql/mysql.sock' port: 3306 Percona XtraDB Cluster (GPL), wsrep_25.9.r3928
      140129 15:25:09 [Note] WSREP: Service thread queue flushed.
      140129 15:25:09 [Note] WSREP: Synchronized with group, ready for connections
      140129 15:25:09 [Note] WSREP: wsrep_notify_cmd is not defined, skipping notification.
      140129 15:25:22 [Note] WSREP: Node 1 (ip-10-29-254-10) desyncs itself from group
      140129 15:25:22 [Note] WSREP: Shifting SYNCED -> DONOR/DESYNCED (TO: 2424911)
      140129 15:26:36 [Note] WSREP: Provider paused at 3f186ea4-88ee-11e3-b496-2271cf23d665:2524911
      140129 15:27:16 [Note] WSREP: Provider resumed.
      140129 16:52:06 [Note] WSREP: Provider paused at 3f186ea4-88ee-11e3-b496-2271cf23d665:2624911
      140129 16:53:18 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000000 of size 134217728 bytes
      140129 16:54:26 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000001 of size 134217728 bytes
      140129 16:55:35 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000002 of size 134217728 bytes
      140129 16:56:42 [Note] WSREP: Created page /var/lib/mysql/gcache.page.000003 of size 134217728 bytes
      140129 17:07:50 [Note] WSREP: Provider resumed.
      140129 17:07:51 [ERROR] WSREP: Attempt to match against an empty key (1,0): 22 (Invalid argument)
      at galera/src/key_set.cpp:throw_match_empty_key():135
      140129 17:07:51 [Note] WSREP: applier thread exiting (code:7)
      140129 17:07:51 [ERROR] WSREP: node consistency compromised, aborting
      140129 17:07:51 [Note] WSREP: starting shutdown
      140129 17:07:51 [Note] /usr/sbin/mysqld: Normal shutdown

      140129 17:07:51 [Note] WSREP: Stop replication
      140129 17:07:51 [Note] WSREP: Closing send monitor...
      140129 17:07:51 [Note] WSREP: Closed send monitor.
      140129 17:07:51 [Note] WSREP: gcomm: terminating thread
      140129 17:07:51 [Note] WSREP: gcomm: joining thread
      140129 17:07:51 [Note] WSREP: gcomm: closing backend
      140129 17:07:52 [Note] WSREP: view(view_id(NON_PRIM,825f9682-88f9-11e3-9ed8-b732f283d4db,2) memb {
      887a4798-88f9-11e3-9c26-2ecbbc9449b7,0
      } joined {
      } left {
      } partitioned {
      825f9682-88f9-11e3-9ed8-b732f283d4db,0
      })
      140129 17:07:52 [Note] WSREP: New COMPONENT: primary = no, bootstrap = no, my_idx = 0, memb_num = 1
      140129 17:07:52 [Note] WSREP: view((empty))
      140129 17:07:52 [Note] WSREP: gcomm: closed
      140129 17:07:52 [Note] WSREP: Flow-control interval: [1000000, 1000000]
      140129 17:07:52 [Note] WSREP: Received NON-PRIMARY.
      140129 17:07:52 [Note] WSREP: Shifting DONOR/DESYNCED -> OPEN (TO: 3624911)
      140129 17:07:52 [Note] WSREP: Received self-leave message.
      140129 17:07:52 [Note] WSREP: Flow-control interval: [0, 0]
      140129 17:07:52 [Note] WSREP: Received SELF-LEAVE. Closing connection.
      140129 17:07:52 [Note] WSREP: Shifting OPEN -> CLOSED (TO: 3624911)
      140129 17:07:52 [Note] WSREP: RECV thread exiting 0: Success
      140129 17:07:52 [Note] WSREP: recv_thread() joined.
      140129 17:07:52 [Note] WSREP: Closing replication queue.
      140129 17:07:52 [Note] WSREP: Closing slave action queue.
      140129 17:07:54 [Note] WSREP: killing local connection: 18

      Note that node2 is also hung at this point: mysqld is there, but it does not accept connections. I'm unsure if this will happen consistently or not.

        Smart Checklist

          Attachments

            Activity

              People

              • Assignee:
                krunal.bauskar Krunal Bauskar
                Reporter:
                lpjirasync lpjirasync (Inactive)
              • Votes:
                0 Vote for this issue
                Watchers:
                1 Start watching this issue

                Dates

                • Created:
                  Updated: