หนึ่งในวิธีการรับประวัติการล็อกใน PostgreSQL

ความต่อเนื่องของบทความ “ความพยายามที่จะสร้างอะนาล็อก ASH สำหรับ PostgreSQL "

บทความนี้จะตรวจสอบและแสดงโดยใช้ข้อความค้นหาและตัวอย่างเฉพาะ ข้อมูลที่เป็นประโยชน์ใดบ้างที่สามารถรับได้โดยใช้ประวัติของมุมมอง pg_locks

การเตือน
เนื่องจากความแปลกใหม่ของหัวข้อและระยะเวลาการทดสอบที่ยังไม่เสร็จ บทความอาจมีข้อผิดพลาด คำติชมและความคิดเห็นได้รับการต้อนรับและคาดหวังอย่างยิ่ง

ป้อนข้อมูล

ประวัติความเป็นมาของการเป็นตัวแทน pg_locks

archive_locking

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 
);

โดยพื้นฐานแล้วโต๊ะจะคล้ายกับโต๊ะ archive_pg_stat_activityอธิบายรายละเอียดเพิ่มเติมที่นี่ - pg_stat_statements + pg_stat_activity + loq_query = pg_ash? และที่นี่ - ความพยายามที่จะสร้างอะนาล็อกของ ASH สำหรับ PostgreSQL

เพื่อเติมคอลัมน์ รหัสแบบสอบถาม มีการใช้ฟังก์ชัน

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;

คำอธิบาย: ค่าของคอลัมน์ queryid ได้รับการอัพเดตในตาราง history_locking และเมื่อมีการสร้างพาร์ติชันใหม่สำหรับตาราง archive_locking ค่าจะถูกเก็บไว้ในค่าประวัติ

เอาท์พุต

ข้อมูลทั่วไปเกี่ยวกับกระบวนการโดยทั่วไป

กำลังรอล็อคตามประเภทล็อค

การสอบสวน

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 

ตัวอย่าง

| กำลังรอการล็อคตามประเภทการล็อค +------+----------------------- --------+ ------------------- | ประเภทล็อค| โหมด| ระยะเวลา +--------------------+-------------------------- - ---+------------------- | รหัสธุรกรรม| แชร์Lock| 19:39:26 | สิ่งอันดับ| การเข้าถึง ExclusiveLock| 00:03:35 +-------------------+-------------------------------------- - --------+---------------

การล็อคตามประเภทล็อค

การสอบสวน

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 

ตัวอย่าง

| การล็อคตามประเภทล็อค +--------------------+----------------------- ---------+------------------- | ประเภทล็อค| โหมด| ระยะเวลา +--------------------+-------------------------- - ---+------------------- | ความสัมพันธ์| แถว ExclusiveLock| 51:11:10 | virtualxid| เอกสิทธิ์ล็อค| 48:10:43 | รหัสธุรกรรม| เอกสิทธิ์ล็อค| 44:24:53 | ความสัมพันธ์| การเข้าถึงShareLock| 20:06:13 | สิ่งอันดับ| การเข้าถึง ExclusiveLock| 17:58:47 | สิ่งอันดับ| เอกสิทธิ์ล็อค| 01:40:41 | ความสัมพันธ์| แชร์อัปเดตExclusiveLock| 00:26:41 | วัตถุ| แถว ExclusiveLock| 00:00:01 | รหัสธุรกรรม| แชร์Lock| 00:00:01 | ขยาย| เอกสิทธิ์ล็อค| 00:00:01 +-------------------+----------------------- - --------+---------------

ข้อมูลโดยละเอียดเกี่ยวกับคำขอ queryid เฉพาะ

กำลังรอการล็อคตาม LOCKTYPES โดย QUERYID

การสอบสวน

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

ตัวอย่าง

| กำลังรอการล็อคโดย LOCKTYPES โดย QUERYID + -----------+----------+----- --------------- +--------------------------------+--- ----------------- -+--------------------+---------------- --------- | พีด| ประเภทล็อค| โหมด| เริ่มต้น| รหัสสอบถาม| blocking_pids| ระยะเวลา +----------+-------------------------+---------------- ---------+--------------------+-------- -----------+---------------------+----------------- --- | 11288| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11626| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:00:21.380921| 389015618226997618| {12380| 00:00:29 | 11626| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:00:21.380921| 389015618226997618| {11092}| 00:03:25 | 11626| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:00:21.380921| 389015618226997618| {12213}| 00:01:55 | 11626| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:00:21.380921| 389015618226997618| {12751}| 00:00:01 | 11629| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:00:24.331935| 389015618226997618| {11092}| 00:03:22 | 11629| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:00:24.331935| 389015618226997618| {12007}| 00:00:01 | 12007| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:05:03.327933| 389015618226997618| {11629}| 00:00:13 | 12007| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:05:03.327933| 389015618226997618| {11092}| 00:01:10 | 12007| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:05:03.327933| 389015618226997618| {11288}| 00:00:05 | 12213| รหัสธุรกรรม| แชร์Lock| 2019-09-17 10:06:07.328019| 389015618226997618| {12007}| 00:00:10

การล็อคโดย LOCKTYPES โดย QUERYID

การสอบสวน

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

ตัวอย่าง

| การล็อคตามประเภทล็อคโดย QUERYID +-----+-----+------ --------------+ ----------------------------------+---- ----------------+ --------------------+---------------- -------- | พีด| ประเภทล็อค| โหมด| เริ่มต้น| รหัสสอบถาม| blocking_pids| ระยะเวลา +----------+-------------------------+---------------- ---------+--------------------+-------- -----------+---------------------+----------------- --- | 11288| ความสัมพันธ์| แถว ExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11092| รหัสธุรกรรม| เอกสิทธิ์ล็อค| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11288| ความสัมพันธ์| แถว ExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:00:10 | 11092| ความสัมพันธ์| แถว ExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11092| virtualxid| เอกสิทธิ์ล็อค| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11288| virtualxid| เอกสิทธิ์ล็อค| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11288| รหัสธุรกรรม| เอกสิทธิ์ล็อค| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11288| สิ่งอันดับ| การเข้าถึง ExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34

การใช้ประวัติการล็อกเพื่อวิเคราะห์เหตุการณ์ด้านประสิทธิภาพ

  1. คำขอที่มี queryid=389015618226997618 ดำเนินการโดยกระบวนการที่มี pid=11288 กำลังรอการบล็อกเริ่มตั้งแต่ 2019-09-17 10:00:00 เป็นเวลา 3 นาที
  2. การล็อคถูกยึดไว้โดยกระบวนการที่มี pid=11092
  3. กระบวนการที่มี pid=11092 ดำเนินการตามคำขอด้วย queryid=389015618226997618 เริ่มต้นจาก 2019-09-17 10:00:00 ล็อคไว้เป็นเวลา 3 นาที

ทั้งหมด

ตอนนี้ฉันหวังว่าส่วนที่น่าสนใจและมีประโยชน์ที่สุดจะเริ่มขึ้น - การรวบรวมสถิติและการวิเคราะห์กรณีต่างๆ เกี่ยวกับประวัติของการรอและการบล็อก

ในอนาคต ฉันอยากจะเชื่อว่าเราจะได้รับชุดบันทึกย่อบางส่วน (คล้ายกับ Metalink ของ Oracle)

โดยทั่วไป ด้วยเหตุนี้เองที่ทำให้ทุกคนสามารถใช้วิธีการที่ใช้ได้โดยเร็วที่สุด

ฉันจะพยายามโพสต์โครงการบน GitHub ในอนาคตอันใกล้นี้

ที่มา: will.com

เพิ่มความคิดเห็น