🥇PostgreSQL'de kilit geçmişini elde etme yöntemlerinden biri

Yazının devamı “PostgreSQL için bir ASH analogu oluşturma girişimi ".

Makale, belirli sorgular ve örnekler kullanarak, pg_locks görünümünün geçmişini kullanarak hangi yararlı bilgilerin elde edilebileceğini inceleyecek ve gösterecektir.

Uyarı.
Konunun yeniliği ve henüz tamamlanmamış test süresi nedeniyle makale hatalar içerebilir. Eleştiri ve yorumlar şiddetle memnuniyetle karşılanır ve beklenir.

Giriş verileri

pg_locks gösteriminin geçmişi

arşiv_kilitleme

CREATE TABLE archive_locking 
(       timepoint timestamp without time zone ,
	locktype text ,
	relation oid ,
	mode text ,
	tid xid ,
	vtid text ,
	pid integer ,
	blocking_pids integer[] ,
	granted boolean ,
        queryid bigint 
);

Aslında tablo tabloya benzer archive_pg_stat_activity, burada daha ayrıntılı olarak açıklanmıştır - pg_stat_statements + pg_stat_activity + loq_query = pg_ash? ve burada - PostgreSQL için ASH'nin bir analogunu oluşturma girişimi.

Bir sütunu doldurmak için sorgu kimliği fonksiyon kullanıldı

update_history_locking_by_queryid

--update_history_locking_by_queryid.sql
CREATE OR REPLACE FUNCTION update_history_locking_by_queryid() RETURNS boolean AS $$
DECLARE
  result boolean ;
  current_minute double precision ; 
  
  start_minute integer ;
  finish_minute integer ;
  
  start_period timestamp without time zone ;
  finish_period timestamp without time zone ;
  
  lock_rec record ; 
  endpoint_rec record ; 
  
  current_hour_diff double precision ;
BEGIN
  RAISE NOTICE '***update_history_locking_by_queryid';
  
  result = TRUE ;
  
  current_minute = extract ( minute from now() );

  SELECT * FROM endpoint WHERE is_need_monitoring
  INTO endpoint_rec ;
  
  current_hour_diff = endpoint_rec.hour_diff ;
  
  IF current_minute < 5 
  THEN
	RAISE NOTICE 'Current time is less than 5 minute.';
	
	start_period = date_trunc('hour',now()) + (current_hour_diff * interval '1 hour');
    finish_period = start_period - interval '5 minute' ;
  ELSE 
    finish_minute =  extract ( minute from now() ) / 5 ;
    start_minute =  finish_minute - 1 ;
  
    start_period = date_trunc('hour',now()) + interval '1 minute'*start_minute*5+(current_hour_diff * interval '1 hour');
    finish_period = date_trunc('hour',now()) + interval '1 minute'*finish_minute*5+(current_hour_diff * interval '1 hour') ;
    
  END IF ;  
  
  RAISE NOTICE 'start_period = %', start_period;
  RAISE NOTICE 'finish_period = %', finish_period;

	FOR lock_rec IN   
	WITH act_queryid AS
	 (
		SELECT 
				pid , 
				timepoint ,
				query_start AS started ,			
				MAX(timepoint) OVER (PARTITION BY pid ,	query_start   ) AS finished ,			
				queryid 
		FROM 
				activity_hist.history_pg_stat_activity 			
		WHERE 			
				timepoint BETWEEN start_period and 
								  finish_period
		GROUP BY 
				pid , 
				timepoint ,  
				query_start ,
				queryid 
	 ),
	 lock_pids AS
		(
			SELECT
				hl.pid , 
				hl.locktype  ,
				hl.mode ,
				hl.timepoint , 
				MIN ( timepoint ) OVER (PARTITION BY pid , locktype  ,mode ) as started 
			FROM 
				activity_hist.history_locking hl
			WHERE 
				hl.timepoint between start_period and 
								     finish_period
			GROUP BY 
				hl.pid , 
				hl.locktype  ,
				hl.mode ,
				hl.timepoint 
		)
	SELECT 
		lp.pid , 
		lp.locktype  ,
		lp.mode ,
		lp.timepoint ,     
		aq.queryid 
	FROM lock_pids 	lp LEFT OUTER JOIN act_queryid aq ON ( lp.pid = aq.pid AND lp.started BETWEEN aq.started AND aq.finished )
	WHERE aq.queryid IS NOT NULL 
	GROUP BY  
		lp.pid , 
		lp.locktype  ,
		lp.mode ,
		lp.timepoint , 
		aq.queryid
	LOOP
		UPDATE activity_hist.history_locking SET queryid = lock_rec.queryid 
		WHERE pid = lock_rec.pid AND locktype = lock_rec.locktype AND mode = lock_rec.mode AND timepoint = lock_rec.timepoint ;	
	END LOOP;    
  
  RETURN result ;
END
$$ LANGUAGE plpgsql;

Açıklama: queryid sütununun değeri, History_locking tablosunda güncellenir ve daha sonra archive_locking tablosu için yeni bir bölüm oluşturulduğunda değer, geçmiş değerlerde saklanır.

Çıktı

Genel olarak süreçler hakkında genel bilgiler.

KİLİT TİPLERİNE GÖRE KİLİTLER BEKLENİYOR

Istek

WITH
t AS
(
	SELECT 
		locktype  ,
		mode ,
		count(*) as total 
	FROM 
		activity_hist.archive_locking
	WHERE 
		timepoint between pg_stat_history_begin+(current_hour_diff * interval '1 hour') AND pg_stat_history_end+(current_hour_diff * interval '1 hour') AND 
		NOT granted
	GROUP BY 
		locktype  ,
		mode  
)
SELECT 
	locktype  ,
	mode ,
	total * interval '1 second' as duration			
FROM t 		
ORDER BY 3 DESC

Örnek

| KİLİT TİPLERİNE GÖRE KİLİTLER BEKLENİYOR +-----------+--------------- --------+ ------------------- | kilit tipi| modu| süre +---------------------+--------------- - ------------------------- | işlem kimliği| PaylaşLock| 19:39:26 | demet| AccessExclusiveLock| 00:03:35 +---------------------+------------ - -------+----------

KİLİT TİPLERİNE GÖRE KİLİTLERİN ALINMASI

Istek

WITH
t AS
(
	SELECT 
		locktype  ,
		mode ,
		count(*) as total 
	FROM 
		activity_hist.archive_locking
	WHERE 
		timepoint between pg_stat_history_begin+(current_hour_diff * interval '1 hour') AND pg_stat_history_end+(current_hour_diff * interval '1 hour') AND 
		granted
	GROUP BY 
		locktype  ,
		mode  
)
SELECT 
	locktype  ,
	mode ,
	total * interval '1 second' as duration			
FROM t 		
ORDER BY 3 DESC

Örnek

| KİLİT TİPLERİNE GÖRE KİLİTLERİN ÇIKARILMASI +------+------------ --------+----------- | kilit tipi| modu| süre +---------------------+--------------- - ------------------------- | ilişki| RowExclusiveLock| 51:11:10 | sanalxid| Özel Kilit| 48:10:43 | işlem kimliği| Özel Kilit| 44:24:53 | ilişki| Erişim Paylaşım Kilidi| 20:06:13 | demet| AccessExclusiveLock| 17:58:47 | demet| Özel Kilit| 01:40:41 | ilişki| PaylaşGüncellemeExclusiveLock| 00:26:41 | nesne| RowExclusiveLock| 00:00:01 | işlem kimliği| PaylaşLock| 00:00:01 | uzat| Özel Kilit| 00:00:01 +---------------------+------------ - -------+----------

Belirli sorgu kimliği istekleri hakkında ayrıntılı bilgi

QUERYID TARAFINDAN KİLİT TÜRLERİNE GÖRE KİLİTLER BEKLENİYOR

Istek

WITH
lt AS
(
	SELECT
		pid , 
		locktype  ,
		mode ,
		timepoint , 
		queryid , 
		blocking_pids ,
                MIN ( timepoint ) OVER (PARTITION BY pid , locktype  ,mode ) as started  
	FROM 
		activity_hist.archive_locking
	WHERE 
		timepoint between pg_stat_history_begin+(current_hour_diff * interval '1 hour') AND 
			                  pg_stat_history_end+(current_hour_diff * interval '1 hour') AND 
		NOT granted AND
	       queryid IS NOT NULL 
	GROUP BY 
	        pid , 
		locktype  ,
		mode ,
		timepoint ,
		queryid ,
		blocking_pids 
)
SELECT 
        lt.pid , 
	lt.locktype  ,
	lt.mode ,			
        lt.started ,
	lt.queryid  ,
	lt.blocking_pids ,
	COUNT(*)  * interval '1 second'	 as duration		
FROM lt 	
GROUP BY 
	lt.pid , 
        lt.locktype  ,
	lt.mode ,			
        lt.started ,
        lt.queryid ,
	lt.blocking_pids 
ORDER BY 4

Örnek

| QUERYID'E GÖRE KİLİT TÜRLERİNE GÖRE KİLİTLER BEKLENİYOR +----------+----------+----- --------------- ++---------------------------+--- ------- --------------+--------------- --------- | pid| kilit tipi| modu| başladı| sorgu kimliği| block_pids| süre +----------+----------------------------+----------- ---------+----------------------------+----------- -- ---------+---------+---- - | 11288| işlem kimliği| PaylaşLock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11626| işlem kimliği| PaylaşLock| 2019-09-17 10:00:21.380921| 389015618226997618| {12380}| 00:00:29 | 11626| işlem kimliği| PaylaşLock| 2019-09-17 10:00:21.380921| 389015618226997618| {11092}| 00:03:25 | 11626| işlem kimliği| PaylaşLock| 2019-09-17 10:00:21.380921| 389015618226997618| {12213}| 00:01:55 | 11626| işlem kimliği| PaylaşLock| 2019-09-17 10:00:21.380921| 389015618226997618| {12751}| 00:00:01 | 11629| işlem kimliği| PaylaşLock| 2019-09-17 10:00:24.331935| 389015618226997618| {11092}| 00:03:22 | 11629| işlem kimliği| PaylaşLock| 2019-09-17 10:00:24.331935| 389015618226997618| {12007}| 00:00:01 | 12007| işlem kimliği| PaylaşLock| 2019-09-17 10:05:03.327933| 389015618226997618| {11629}| 00:00:13 | 12007| işlem kimliği| PaylaşLock| 2019-09-17 10:05:03.327933| 389015618226997618| {11092}| 00:01:10 | 12007| işlem kimliği| PaylaşLock| 2019-09-17 10:05:03.327933| 389015618226997618| {11288}| 00:00:05 | 12213| işlem kimliği| PaylaşLock| 2019-09-17 10:06:07.328019| 389015618226997618| {12007}| 00:00:10

QUERYID TARAFINDAN KİLİT TİPLERİNE GÖRE KİLİT ALMA

Istek

WITH
lt AS
(
	SELECT
		pid , 
		locktype  ,
		mode ,
		timepoint , 
		queryid , 
		blocking_pids ,
                MIN ( timepoint ) OVER (PARTITION BY pid , locktype  ,mode ) as started  
	FROM 
		activity_hist.archive_locking
	WHERE 
		timepoint between pg_stat_history_begin+(current_hour_diff * interval '1 hour') AND 
			                  pg_stat_history_end+(current_hour_diff * interval '1 hour') AND 
		granted AND
		queryid IS NOT NULL 
	GROUP BY 
	        pid , 
		locktype  ,
		mode ,
		timepoint ,
		queryid ,
		blocking_pids 
)
SELECT 
        lt.pid , 
	lt.locktype  ,
	lt.mode ,			
        lt.started ,
	lt.queryid  ,
	lt.blocking_pids ,
	COUNT(*)  * interval '1 second'	 as duration			
FROM lt 	
GROUP BY 
	lt.pid , 
	lt.locktype  ,
	lt.mode ,			
        lt.started ,
	lt.queryid ,
	lt.blocking_pids 
ORDER BY 4

Örnek

| QUERYID'E GÖRE KİLİT TÜRLERİNE GÖRE KİLİTLERİ ALMA +----------+----------+---------- -------------+ -----------------------------+---- ------+ ----------+--------------- -------- | pid| kilit tipi| modu| başladı| sorgu kimliği| block_pids| süre +----------+----------------------------+----------- ---------+----------------------------+----------- -- ---------+---------+---- - | 11288| ilişki| RowExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11092| işlem kimliği| Özel Kilit| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11288| ilişki| RowExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:00:10 | 11092| ilişki| RowExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11092| sanalxid| Özel Kilit| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11288| sanalxid| Özel Kilit| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11288| işlem kimliği| Özel Kilit| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11288| demet| AccessExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34

Performans olaylarını analiz etmek için kilit geçmişini kullanma.

pid=389015618226997618 değerine sahip bir işlem tarafından yürütülen queryid=11288 değerine sahip bir istek, 2019 09:17:10'dan başlayarak 00 dakika boyunca engellemeyi bekliyordu.
Kilit, pid=11092 olan bir işlem tarafından tutuldu
11092-389015618226997618-2019 09:17:10 tarihinden başlayarak queryid=00 ile bir isteği yürüten pid=00'li bir işlem, kilidi 3 dakika boyunca tuttu.

sonuç

Şimdi, umarım en ilginç ve faydalı kısım başlayacaktır: istatistiklerin toplanması ve beklemelerin ve engellemelerin geçmişine ilişkin vakaların analiz edilmesi.

Gelecekte bir takım notlar alacağımıza inanmak istiyorum (Oracle'ın metalinkine benzer).

Genel olarak kullanılan metodolojinin mümkün olan en kısa sürede herkesin kullanımına sunulmasının nedeni budur.

Yakın gelecekte projeyi github'da yayınlamaya çalışacağım.

Kaynak: habr.com

PostgreSQL'de kilit geçmişini alma yöntemlerinden biri

Giriş verileri

pg_locks gösteriminin geçmişi

Çıktı

KİLİT TİPLERİNE GÖRE KİLİTLER BEKLENİYOR

KİLİT TİPLERİNE GÖRE KİLİTLERİN ALINMASI

QUERYID TARAFINDAN KİLİT TÜRLERİNE GÖRE KİLİTLER BEKLENİYOR

QUERYID TARAFINDAN KİLİT TİPLERİNE GÖRE KİLİT ALMA

sonuç

Yorum ekle Cevabı iptal