Μία από τις μεθόδους λήψης ιστορικού κλειδώματος στο 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, η τιμή θα αποθηκευτεί στις ιστορικές τιμές.

Παραγωγή

Γενικές πληροφορίες για τις διαδικασίες γενικά.

ΑΝΑΜΟΝΗ ΚΛΕΙΔΑΡΕΙΩΝ ΚΑΤΑ LOCKTYPES

Αίτηση

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 

Παράδειγμα

| ΑΝΑΜΟΝΗ ΚΛΕΙΔΑΡΙΑΣ ΚΑΤΑ LOCKTYPES +-----------+-------------------------------- ------------------- | τύπος κλειδαριάς| λειτουργία| διάρκεια +----------------------------------------------- --+-------------------- | συναλλαγής| ShareLock| 19:39:26 | πλειάδα| AccessExclusiveLock| 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 

Παράδειγμα

| ΛΗΨΕΙΣ ΚΛΕΙΔΑΡΙΑΣ ΚΑΤΑ ΚΛΕΙΔΑΡΙΑ +--------------------+----------------------- --------+-------------------- | τύπος κλειδαριάς| λειτουργία| διάρκεια +----------------------------------------------- --+-------------------- | σχέση| RowExclusiveLock| 51:11:10 | virtualxid| ExclusiveLock| 48:10:43 | συναλλαγής| ExclusiveLock| 44:24:53 | σχέση| AccessShareLock| 20:06:13 | πλειάδα| AccessExclusiveLock| 17:58:47 | πλειάδα| ExclusiveLock| 01:40:41 | σχέση| ShareUpdateExclusiveLock| 00:26:41 | αντικείμενο| RowExclusiveLock| 00:00:01 | συναλλαγής| ShareLock| 00:00:01 | επεκτείνω| ExclusiveLock| 00:00:01 +-------------------------------------------- -------+-----------

Λεπτομερείς πληροφορίες για συγκεκριμένα αιτήματα ερωτηματολογίου

ΑΝΑΜΟΝΗ ΚΛΕΙΔΑΡΕΙΩΝ ΚΑΤΑ ΤΥΠΟΥΣ ΚΛΕΙΔΑΡΙΑΣ ΑΠΟ 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

Παράδειγμα

| ΑΝΑΜΟΝΗ ΚΛΕΙΔΑΡΙΑΣ ΚΑΤΑ ΤΥΠΟΥΣ ΚΛΕΙΔΑΡΙΑΣ ΑΝΑ ΕΡΩΤΗΜΑ +----------+-------------------------------- +-------------------------------------------------------- -+--------------------+------------------------------------------------------------ | pid| τύπος κλειδαριάς| λειτουργία| ξεκίνησε| queryid| blocking_pids| διάρκεια +----------+------------------------------------- ---------+--------------------------------------- -----------+-------------------------------------- --- | 11288| συναλλαγής| ShareLock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11626| συναλλαγής| ShareLock| 2019-09-17 10:00:21.380921| 389015618226997618| {12380}| 00:00:29 | 11626| συναλλαγής| ShareLock| 2019-09-17 10:00:21.380921| 389015618226997618| {11092}| 00:03:25 | 11626| συναλλαγής| ShareLock| 2019-09-17 10:00:21.380921| 389015618226997618| {12213}| 00:01:55 | 11626| συναλλαγής| ShareLock| 2019-09-17 10:00:21.380921| 389015618226997618| {12751}| 00:00:01 | 11629| συναλλαγής| ShareLock| 2019-09-17 10:00:24.331935| 389015618226997618| {11092}| 00:03:22 | 11629| συναλλαγής| ShareLock| 2019-09-17 10:00:24.331935| 389015618226997618| {12007}| 00:00:01 | 12007| συναλλαγής| ShareLock| 2019-09-17 10:05:03.327933| 389015618226997618| {11629}| 00:00:13 | 12007| συναλλαγής| ShareLock| 2019-09-17 10:05:03.327933| 389015618226997618| {11092}| 00:01:10 | 12007| συναλλαγής| ShareLock| 2019-09-17 10:05:03.327933| 389015618226997618| {11288}| 00:00:05 | 12213| συναλλαγής| ShareLock| 2019-09-17 10:06:07.328019| 389015618226997618| {12007}| 00:00:10

ΛΗΨΗ ΚΛΕΙΔΑΡΙΑ ΚΑΤΑ ΤΥΠΟΥΣ ΚΛΕΙΔΑΡΙΑΣ ΑΠΟ ΕΡΩΤΗΜΑ

Αίτηση

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

Παράδειγμα

| ΛΗΨΗ ΚΛΕΙΔΑΡΙΑΣ ΚΑΤΑ ΤΥΠΟΥΣ ΚΛΕΙΔΑΡΙΑΣ ΑΝΑ ΕΡΩΤΗΣΗ +----------+--------------------------------+ ----------------------------+--------------------+ ------------------------------------------------------------------------------------------- pid| τύπος κλειδαριάς| λειτουργία| ξεκίνησε| queryid| blocking_pids| διάρκεια +----------+------------------------------------- ---------+--------------------------------------- -------------------------------------------------- --- | 11288| σχέση| RowExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {11092}| 00:03:34 | 11092| συναλλαγής| ExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11288| σχέση| RowExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:00:10 | 11092| σχέση| RowExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11092| virtualxid| ExclusiveLock| 2019-09-17 10:00:00.302936| 389015618226997618| {}| 00:03:34 | 11288| virtualxid| ExclusiveLock| 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 | 11288| πλειάδα| AccessExclusiveLock| 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 λεπτά.

Σύνολο

Τώρα, ελπίζω, θα ξεκινήσει το πιο ενδιαφέρον και χρήσιμο μέρος - η συλλογή στατιστικών στοιχείων και η ανάλυση υποθέσεων σχετικά με το ιστορικό αναμονής και αποκλεισμού.

Στο μέλλον, θέλω να πιστεύω, θα λάβουμε ένα σύνολο από κάποιες νότες (παρόμοιο με το metallink της Oracle).

Σε γενικές γραμμές, γι' αυτόν τον λόγο η μεθοδολογία που χρησιμοποιείται καθίσταται διαθέσιμη σε όλους όσο το δυνατόν γρηγορότερα.

Θα προσπαθήσω να δημοσιεύσω το έργο στο github στο εγγύς μέλλον.

Πηγή: www.habr.com

Προσθέστε ένα σχόλιο