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

[YSQL][SQLsmith] ../../src/yb/client/meta_cache.cc:2159] Destructing LookupDataGroup(0x00000001236314f8), running_request_number: 7 with non empty lookups: [0x0000000123631520 -> { deadline: partition_start: }] #11308

Open
def- opened this issue Feb 1, 2022 · 0 comments
Assignees
Labels
area/ysql Yugabyte SQL (YSQL) kind/bug This issue is a bug kind/failing-test Tests and testing infra priority/medium Medium priority issue qa_automation Bugs identified via itest-system, LST, Stress automation or causing automation failures

Comments

@def-
Copy link
Contributor

def- commented Feb 1, 2022

Jira Link: DB-1173

Description

SQLsmith found this, another one that happens during TRAP handling, can't reproduce:

TRAP: FailedAssertion("!(bms_is_subset(appendrel->lateral_relids, required_outer))", File: "../../../../../../../src/postgres/src/backend/optimizer/util/relnode.c", Line: 1543)
2022-02-01 17:41:16.560 CET [52434] LOG:  server process (PID 4993) was terminated by signal 6: Abort trap
2022-02-01 17:41:16.560 CET [52434] DETAIL:  Failed process was running: select  
	  (select k from public.single_row_decimal limit 1 offset 1)
	     as c0, 
	  subq_1.c0 as c1
	from 
	  (select distinct 
	          ref_0.h1 as c0, 
	          ref_0.r1 as c1, 
	          ref_0.v2 as c2, 
	          ref_0.r1 as c3
	        from 
	          public.test_split as ref_0
	        where (select k from public.single_row_array limit 1 offset 1)
	             is not NULL
	        limit 166) as subq_0
	    left join (select  
	          ref_1.v1 as c0
	        from 
	          public.single_row_not_null_constraints as ref_1
	        where cast(null as timestamptz) <> cast(null as date)) as subq_1
	    on (((select pg_catalog.every(b) from public.composite_table)
	             = true) 
	        and ((select col_bigint from public.table_create_org limit 1 offset 1)
	             = cast(null as int2))),
	  lateral (select  
	        case when cast(null as int2) <= cast(null as int2) then subq_1.c0 else subq_1.c0 end
	           as c0
	      from 
	        (select  
	              subq_0.c2 as c0, 
	              subq_1.c0 
2022-02-01 17:41:16.560 CET [52434] LOG:  terminating any other active server processes
2022-02-01 17:41:16.560 CET [4987] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.560 CET [4987] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.560 CET [4987] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
I0201 17:41:16.561162 1844883456 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
2022-02-01 17:41:16.561 CET [4992] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.561 CET [4992] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.561 CET [4992] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2022-02-01 17:41:16.561 CET [4995] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.561 CET [4995] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.561 CET [4995] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2022-02-01 17:41:16.561 CET [4995] CONTEXT:  SQL function "cnt_i" statement 1
W0201 17:41:16.561554 1846030336 outbound_call.cc:131] Failed to schedule invoking callback on response for request yb.tserver.TabletServerService.Read to 127.0.0.1: Aborted (yb/rpc/thread_pool.cc:260): Service is shutting down
2022-02-01 17:41:16.561 CET [4988] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.561 CET [4988] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.561 CET [4988] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2022-02-01 17:41:16.561 CET [4994] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.561 CET [4994] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.561 CET [4994] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
I0201 17:41:16.561941 1844883456 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
2022-02-01 17:41:16.562 CET [4990] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.562 CET [4990] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.562 CET [4990] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
2022-02-01 17:41:16.561 CET [4991] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.561 CET [4991] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.561 CET [4991] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
I0201 17:41:16.562521 1844310016 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
I0201 17:41:16.563005 1843736576 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
I0201 17:41:16.563364 1844883456 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
I0201 17:41:16.563947 1843736576 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
I0201 17:41:16.564175 1844883456 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
2022-02-01 17:41:16.564 CET [4989] WARNING:  terminating connection because of crash of another server process
2022-02-01 17:41:16.564 CET [4989] DETAIL:  The postmaster has commanded this server process to roll back the current transaction and exit, because another server process exited abnormally and possibly corrupted shared memory.
2022-02-01 17:41:16.564 CET [4989] HINT:  In a moment you should be able to reconnect to the database and repeat your command.
F0201 17:41:16.564494 48530816 meta_cache.cc:2159] Destructing LookupDataGroup(0x00000001236314f8), running_request_number: 7 with non empty lookups: [0x0000000123631520 -> { deadline:  partition_start:  }]
I0201 17:41:16.569105 1844883456 poller.cc:66] Poll stopped: Service unavailable (yb/rpc/scheduler.cc:80): Scheduler is shutting down (system error 58)
Fatal failure details written to /Users/deen/var/data/yb-data/tserver/logs/yb-tserver.FATAL.details.2022-02-01T17_41_16.pid4995.txt
F20220201 17:41:16 ../../src/yb/client/meta_cache.cc:2159] Destructing LookupDataGroup(0x00000001236314f8), running_request_number: 7 with non empty lookups: [0x0000000123631520 -> { deadline:  partition_start:  }]
    @        0x10687d894  google::LogDestination::LogToSinks()
    @        0x10687c9cc  google::LogMessage::SendToLog()
    @        0x10687d388  google::LogMessage::Flush()
    @        0x10687d19c  google::LogMessage::~LogMessage()
    @        0x10b4ec5a8  yb::client::internal::LookupDataGroup::~LookupDataGroup()
    @        0x10b4ec660  yb::client::internal::LookupDataGroup::~LookupDataGroup()
    @        0x10b506bc4  std::__1::pair<>::~pair()
    @        0x10b506b94  std::__1::pair<>::~pair()
    @        0x10b506aa0  std::__1::allocator_traits<>::destroy<>()
    @        0x10b5069e8  std::__1::__hash_table<>::__deallocate_node()
    @        0x10b506968  std::__1::__hash_table<>::~__hash_table()
    @        0x10b506928  std::__1::__hash_table<>::~__hash_table()
    @        0x10b5068fc  std::__1::unordered_map<>::~unordered_map()
    @        0x10b4dd17c  std::__1::unordered_map<>::~unordered_map()
    @        0x10b5064c8  yb::client::internal::TableData::~TableData()
    @        0x10b506480  yb::client::internal::TableData::~TableData()
    @        0x10b50644c  std::__1::pair<>::~pair()
    @        0x10b50641c  std::__1::pair<>::~pair()
    @        0x10b506328  std::__1::allocator_traits<>::destroy<>()
    @        0x10b506270  std::__1::__hash_table<>::__deallocate_node()
    @        0x10b5061f0  std::__1::__hash_table<>::~__hash_table()
    @        0x10b5061b0  std::__1::__hash_table<>::~__hash_table()
    @        0x10b506184  std::__1::unordered_map<>::~unordered_map()
    @        0x10b4dd1d4  std::__1::unordered_map<>::~unordered_map()
    @        0x10b4dd2c0  yb::client::internal::MetaCache::~MetaCache()
    @        0x10b4dd30c  yb::client::internal::MetaCache::~MetaCache()
    @        0x10b2cbda0  yb::RefCountedThreadSafe<>::DeleteInternal()
    @        0x10b2cbd70  yb::DefaultRefCountedThreadSafeTraits<>::Destruct()
    @        0x10b2cbd4c  yb::RefCountedThreadSafe<>::Release()
    @        0x10b460948  scoped_refptr<>::~scoped_refptr()
    @        0x10b30a964  scoped_refptr<>::~scoped_refptr()
    @        0x10b30ab20  yb::client::YBClient::Data::~Data()

*** Check failure stack trace: ***
    @        0x10687cb8c  google::LogMessage::SendToLog()
    @        0x10687d388  google::LogMessage::Flush()
    @        0x10687d19c  google::LogMessage::~LogMessage()
    @        0x10b4ec5a8  yb::client::internal::LookupDataGroup::~LookupDataGroup()
    @        0x10b4ec660  yb::client::internal::LookupDataGroup::~LookupDataGroup()
    @        0x10b506bc4  std::__1::pair<>::~pair()
    @        0x10b506b94  std::__1::pair<>::~pair()
    @        0x10b506aa0  std::__1::allocator_traits<>::destroy<>()
    @        0x10b5069e8  std::__1::__hash_table<>::__deallocate_node()
    @        0x10b506968  std::__1::__hash_table<>::~__hash_table()
    @        0x10b506928  std::__1::__hash_table<>::~__hash_table()
    @        0x10b5068fc  std::__1::unordered_map<>::~unordered_map()
    @        0x10b4dd17c  std::__1::unordered_map<>::~unordered_map()
    @        0x10b5064c8  yb::client::internal::TableData::~TableData()
    @        0x10b506480  yb::client::internal::TableData::~TableData()
    @        0x10b50644c  std::__1::pair<>::~pair()
    @        0x10b50641c  std::__1::pair<>::~pair()
    @        0x10b506328  std::__1::allocator_traits<>::destroy<>()
    @        0x10b506270  std::__1::__hash_table<>::__deallocate_node()
    @        0x10b5061f0  std::__1::__hash_table<>::~__hash_table()
    @        0x10b5061b0  std::__1::__hash_table<>::~__hash_table()
    @        0x10b506184  std::__1::unordered_map<>::~unordered_map()
    @        0x10b4dd1d4  std::__1::unordered_map<>::~unordered_map()
    @        0x10b4dd2c0  yb::client::internal::MetaCache::~MetaCache()
    @        0x10b4dd30c  yb::client::internal::MetaCache::~MetaCache()
    @        0x10b2cbda0  yb::RefCountedThreadSafe<>::DeleteInternal()
    @        0x10b2cbd70  yb::DefaultRefCountedThreadSafeTraits<>::Destruct()
    @        0x10b2cbd4c  yb::RefCountedThreadSafe<>::Release()
    @        0x10b460948  scoped_refptr<>::~scoped_refptr()
    @        0x10b30a964  scoped_refptr<>::~scoped_refptr()
    @        0x10b30ab20  yb::client::YBClient::Data::~Data()
    @        0x10b30ac98  yb::client::YBClient::Data::~Data()
@def- def- added kind/bug This issue is a bug area/ysql Yugabyte SQL (YSQL) labels Feb 1, 2022
@def- def- changed the title [YSQL] ../../src/yb/client/meta_cache.cc:2159] Destructing LookupDataGroup(0x00000001236314f8), running_request_number: 7 with non empty lookups: [0x0000000123631520 -> { deadline: partition_start: }] [YSQL][SQLsmith] ../../src/yb/client/meta_cache.cc:2159] Destructing LookupDataGroup(0x00000001236314f8), running_request_number: 7 with non empty lookups: [0x0000000123631520 -> { deadline: partition_start: }] Feb 3, 2022
@yugabyte-ci yugabyte-ci added the priority/medium Medium priority issue label Jun 8, 2022
@kripasreenivasan kripasreenivasan added the qa_automation Bugs identified via itest-system, LST, Stress automation or causing automation failures label Sep 13, 2022
@yugabyte-ci yugabyte-ci added the kind/failing-test Tests and testing infra label Oct 12, 2022
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
area/ysql Yugabyte SQL (YSQL) kind/bug This issue is a bug kind/failing-test Tests and testing infra priority/medium Medium priority issue qa_automation Bugs identified via itest-system, LST, Stress automation or causing automation failures
Projects
None yet
Development

No branches or pull requests

4 participants