Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.12.2021 16:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16614188 kB
MemAvailable 31355612 kB
Buffers 0 kB
Cached 15030040 kB
SwapCached 440 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.9
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-12-24 16:38:02
DTS_END                         2021-12-24 16:38:02.0470
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/35290
TRN_ID                          239000
ATT_ID                          674
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9302.06 19290 1116247
customer order: creation 175.59 365 21071
customer order: refuse 14.71 335 1765
order to supplier: creation 45.32 1333 5438
order to supplier: removal 3.48 87740 417
invoice (draft): creation 47.36 3636 5683
invoice (draft): removal 51.76 704 6211
invoice accept: apply 42.39 18443 5087
invoice accept: cancel 46.79 824 5615
customer reserve: creation 3937.14 181 472457
customer reserve: removal 4640.31 51 556837
realization accept: apply 86.48 190 10377
realization accept: cancel 77.33 107 9280
payment from customer: creation 60.75 145 7290
payment from customer: removal 6.06 110 727
payment to supplier: creation 50.71 138 6085
payment to supplier: removal 3.63 93 435
service: total inventory turnovers 4.94 989 593
service: total monetary turnovers 6.50 22 780
service: aggregate perf. data 0.79 21393 95
service: refresh index statistics 0.03 248996 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-12-2021 16:39:18 to 24-12-2021 16:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10081
TEST_TIME 2 6690
TEST_TIME 3 5642
TEST_TIME 4 6020
TEST_TIME 5 6555
TEST_TIME 6 6112
TEST_TIME 7 6423
TEST_TIME 8 6141
TEST_TIME 9 6204
TEST_TIME 10 6600
TEST_TIME 11 6517
TEST_TIME 12 6867
TEST_TIME 13 7068
TEST_TIME 14 7213
TEST_TIME 15 7445
TEST_TIME 16 7588
TEST_TIME 17 7739
TEST_TIME 18 8258
TEST_TIME 19 8797
TEST_TIME 20 9126
TEST_TIME 21 9689
TEST_TIME 22 9703
TEST_TIME 23 9664
TEST_TIME 24 10004
TEST_TIME 25 10124
TEST_TIME 26 10025
TEST_TIME 27 10814
TEST_TIME 28 10649
TEST_TIME 29 10597
TEST_TIME 30 10717
TEST_TIME 31 10621
TEST_TIME 32 10486
TEST_TIME 33 10221
TEST_TIME 34 10427
TEST_TIME 35 10170
TEST_TIME 36 10247
TEST_TIME 37 10111
TEST_TIME 38 9982
TEST_TIME 39 9991
TEST_TIME 40 10112
TEST_TIME 41 10178
TEST_TIME 42 10094
TEST_TIME 43 9957
TEST_TIME 44 9917
TEST_TIME 45 9869
TEST_TIME 46 9756
TEST_TIME 47 9700
TEST_TIME 48 9643
TEST_TIME 49 9563
TEST_TIME 50 9659
TEST_TIME 51 9671
TEST_TIME 52 9674
TEST_TIME 53 9678
TEST_TIME 54 9655
TEST_TIME 55 9714
TEST_TIME 56 9705
TEST_TIME 57 9824
TEST_TIME 58 9763
TEST_TIME 59 9792
TEST_TIME 60 9792
TEST_TIME 61 9849
TEST_TIME 62 9876
TEST_TIME 63 9992
TEST_TIME 64 9954
TEST_TIME 65 9886
TEST_TIME 66 9801
TEST_TIME 67 9837
TEST_TIME 68 9731
TEST_TIME 69 9743
TEST_TIME 70 9714
TEST_TIME 71 9630
TEST_TIME 72 9611
TEST_TIME 73 9524
TEST_TIME 74 9488
TEST_TIME 75 9440
TEST_TIME 76 9571
TEST_TIME 77 9517
TEST_TIME 78 9536
TEST_TIME 79 9545
TEST_TIME 80 9666
TEST_TIME 81 9612
TEST_TIME 82 9627
TEST_TIME 83 9617
TEST_TIME 84 9606
TEST_TIME 85 9727
TEST_TIME 86 9667
TEST_TIME 87 9714
TEST_TIME 88 9644
TEST_TIME 89 9567
TEST_TIME 90 9517
TEST_TIME 91 9542
TEST_TIME 92 9501
TEST_TIME 93 9444
TEST_TIME 94 9466
TEST_TIME 95 9438
TEST_TIME 96 9481
TEST_TIME 97 9431
TEST_TIME 98 9501
TEST_TIME 99 9514
TEST_TIME 100 9494
TEST_TIME 101 9519
TEST_TIME 102 9552
TEST_TIME 103 9530
TEST_TIME 104 9541
TEST_TIME 105 9526
TEST_TIME 106 9538
TEST_TIME 107 9573
TEST_TIME 108 9553
TEST_TIME 109 9531
TEST_TIME 110 9510
TEST_TIME 111 9472
TEST_TIME 112 9477
TEST_TIME 113 9439
TEST_TIME 114 9440
TEST_TIME 115 9390
TEST_TIME 116 9360
TEST_TIME 117 9399
TEST_TIME 118 9370
TEST_TIME 119 9383
TEST_TIME 120 9306

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-12-2021 16:39:22 to 24-12-2021 16:39:25.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1554059 1554059 0 0.00 0 18136 6 0 0
doc_list_biud 1554059 1554059 0 0.00 0 54766 24 0 0
sp_add_invoice_to_stock 5257 5087 170 3.23 35 192015 18444 0 170
sp_cancel_adding_invoice 5615 5615 0 0.00 4 61042 824 0 0
sp_cancel_client_order 1765 1765 0 0.00 4 8482 335 0 0
sp_cancel_customer_reserve 556837 556837 0 0.00 1 54767 52 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 3 6197 111 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 2 5733 94 0 0
sp_cancel_supplier_invoice 6226 6211 15 0.24 1 11773 704 0 15
sp_cancel_supplier_order 417 417 0 0.00 8 936382 87740 0 0
sp_cancel_write_off 9280 9280 0 0.00 1 17713 108 0 0
sp_client_order 21071 21071 0 0.00 3 58908 366 0 0
sp_customer_reserve 472457 472457 0 0.00 0 46689 182 0 0
sp_fill_shopping_cart 32187 32187 0 0.00 1 14724 39 0 0
sp_fill_shopping_cart_clo_res 472454 472454 0 0.00 0 16153 14 0 0
sp_get_clo_for_invoice 5083 5083 0 0.00 0 33378 259 0 0
sp_kill_cost_storno 16057 16057 0 0.00 0 11455 53 0 0
sp_kill_qstorno_ret_qs2qd 1129582 1129582 0 0.00 0 54763 28 0 0
sp_kill_qty_storno 595585 595585 0 0.00 0 54766 62 0 0
sp_lock_selected_doc 21317 21317 0 0.00 0 8876 10 0 0
sp_make_cost_storno 28835 28835 0 0.00 0 18133 65 0 0
sp_multiply_rows_for_pdistr 28835 28835 0 0.00 0 8500 30 0 0
sp_multiply_rows_for_qdistr 21071 21071 0 0.00 0 10157 159 0 0
sp_pay_from_customer 7290 7290 0 0.00 4 17410 145 0 0
sp_pay_to_supplier 6085 6085 0 0.00 5 18149 138 0 0
sp_payment_common 13375 13375 0 0.00 1 10274 32 0 0
sp_qd_handle_on_cancel_clo 1765 1765 0 0.00 0 7039 296 0 0
sp_qd_handle_on_invoice_upd_sts 10698 10698 0 0.00 0 40620 353 0 0
sp_qd_handle_on_reserve_upd_sts 10377 10377 0 0.00 0 15306 55 0 0
sp_reserve_write_off 10377 10377 0 0.00 6 16858 191 0 0
sp_supplier_invoice 5683 5683 0 0.00 5 102831 3636 0 0
sp_supplier_order 5438 5438 0 0.00 5 24818 1334 0 0
srv_aggregate_perf_data 96 95 1 1.04 2305 257965 21393 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1879 113311 43840 0 0
srv_find_qd_qs_mism 1067557 1067557 0 0.00 0 16189 5 0 0
srv_make_invnt_saldo 593 593 0 0.00 51 38839 989 0 0
srv_make_money_saldo 780 780 0 0.00 1 699 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 166911 376217 248996 0 0
t$perf-abend:sp_add_invoice_to_stock 170 170 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5087 5087 0 0.00 0 13050 175 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 5054 85 0 0
t$perf-norm:sp_cancel_client_order 1765 1765 0 0.00 0 8433 20 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 4097 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 484 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 449 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1462 22 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 19662 1350 0 0
t$perf-norm:sp_cancel_write_off 981 981 0 0.00 0 1960 8 0 0
t$perf-norm:sp_client_order 21071 21071 0 0.00 0 8501 16 0 0
t$perf-norm:sp_customer_reserve 1039 1039 0 0.00 0 5573 14 0 0
t$perf-norm:sp_pay_from_customer 7290 7290 0 0.00 0 6738 8 0 0
t$perf-norm:sp_pay_to_supplier 6085 6085 0 0.00 0 4335 9 0 0
t$perf-norm:sp_reserve_write_off 10377 10377 0 0.00 0 7932 13 0 0
t$perf-norm:sp_supplier_invoice 5683 5683 0 0.00 0 8444 22 0 0
t$perf-norm:sp_supplier_order 5438 5438 0 0.00 0 5522 11 0 0
t$perf-norm:srv_aggregate_perf_data 95 95 0 0.00 0 390 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 3025 75 0 0
t$perf-norm:srv_make_invnt_saldo 593 593 0 0.00 0 869 15 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 1622 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 20 7 0 0
v_add_invoice_to_stock 10504 10164 340 3.23 0 6925 19 0 340
v_all_customers 38162 38162 0 0.00 0 13 0 0 0
v_all_suppliers 31048 31048 0 0.00 0 112 1 0 0
v_all_wares 42142 42142 0 0.00 0 165 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 2310 8 0 0
v_cancel_client_order 3530 3530 0 0.00 0 2445 4 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 5437 20 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 1714 7 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 5376 12 0 0
v_cancel_supplier_invoice 836 806 30 3.58 0 1902 17 0 30
v_cancel_supplier_order 834 834 0 0.00 0 7728 30 0 0
v_cancel_write_off 1962 1962 0 0.00 0 1790 13 0 0
v_max_id_clo_ord 5436 5436 0 0.00 0 912 2 0 0
v_max_id_clo_res 1039 1039 0 0.00 0 2081 21 0 0
v_max_id_ord_sup 5679 5679 0 0.00 0 3130 3 0 0
v_max_non_paid_invoice 1676 1676 0 0.00 0 2148 4 0 0
v_max_non_paid_realizn 4124 4124 0 0.00 0 3063 3 0 0
v_min_id_clo_ord 5436 5436 0 0.00 0 11859 11 0 0
v_min_id_clo_res 1039 1039 0 0.00 0 206 2 0 0
v_min_id_ord_sup 5679 5679 0 0.00 0 6327 9 0 0
v_min_non_paid_invoice 6085 6085 0 0.00 0 6344 17 0 0
v_min_non_paid_realizn 7290 7290 0 0.00 0 9471 28 0 0
v_our_firm 10312 10312 0 0.00 0 19 1 0 0
v_reserve_write_off 20754 20754 0 0.00 0 3422 4 0 0
x_lock_depdocs_on_canc_invoice 11423 11423 0 0.00 0 11972 108 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 20045 420 0 0
x_make_qstorno_1000_1200 5438 5438 0 0.00 2 24812 1255 0 0
x_make_qstorno_1200_2000 5683 5683 0 0.00 2 102805 3495 0 0
x_make_qstorno_2100_3300 472457 472457 0 0.00 0 46688 166 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 24-12-2021 16:39:26 to 24-12-2021 16:39:31.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:05 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:07 15139 8192000000 9881002992 10189910016 972202896 10.608 100 88 176 0 1001680608 5856528 23621184
14:10:28 16835 8192000000 9949846016 10272821248 1032610272 11.194 100 85 170 0 1062673232 8212656 21850304
14:11:56 15812 8192000000 9993251184 10329829376 1078513760 11.633 100 89 179 0 1110759376 10238256 22007360
14:13:17 19970 8192000000 10037942704 10385494016 1117834368 12.007 100 81 162 0 1148738992 11327728 19576896
14:14:43 14043 8192000000 10082140800 10440892416 1167274928 12.471 100 74 148 1 1195684608 11040784 17368896
14:16:02 6965 8192000000 10126434816 10497015808 1215235952 12.918 100 75 146 0 1243175648 11742800 16196896
14:17:10 4571 8192000000 10153784864 10530975744 1246730496 13.208 100 69 138 2 1273396368 11577152 15088720
14:18:18 3159 8192000000 10196686784 10575958016 1280343152 13.516 100 90 180 0 1313967664 13646160 19978352
14:19:23 1447 8192000000 10211639920 10599694336 1300538832 13.700 100 88 176 1 1333473488 13572976 19361680
14:20:26 4104 8192000000 10216267504 10606006272 1303281568 13.725 100 81 159 0 1333434256 11632048 18520640
14:21:32 6136 8192000000 10188759920 10604277760 1307333264 13.762 100 20 40 3 1317717376 6014368 4369744
14:22:41 5062 8192000000 10213950688 10621046784 1322567040 13.900 100 32 64 2 1335803264 6168096 7068128
14:23:50 8053 8192000000 10210881936 10627829760 1324895936 13.921 100 22 44 2 1336328336 6412064 5020336
14:25:05 35036 8192000000 10218118464 10644619264 1331002656 13.976 100 35 70 1 1346217776 6567808 8647312
14:26:44 7769 8192000000 10225201248 10646736896 1335199504 14.014 100 22 43 2 1345727328 5896560 4631264
14:27:54 11871 8192000000 10206833792 10620096512 1289944864 13.604 97 21 42 0 1300908272 6317120 4646288
14:29:08 16324 8192000000 10188480000 10590384128 1241240464 13.158 95 19 38 0 1250407952 4976464 4191024
14:30:27 24420 8192000000 10162021520 10567880704 1189179872 12.676 91 22 44 1 1199239664 4430464 5629328
14:31:54 10658 8192000000 10147507440 10552995840 1237297264 13.121 98 21 42 0 1247647360 5239120 5110976
14:33:05 13021 8192000000 10153690736 10550382592 1221024608 12.971 97 18 36 0 1230602400 5386112 4191680
14:34:23 74291 8192000000 10167799664 10583310336 1281800752 13.529 100 27 54 2 1293748528 5406496 6541280
14:36:37 10107 8192000000 10200494128 10608734208 1295783072 13.657 100 34 69 1 1313470144 9881840 7805232
14:37:51 6571 8192000000 10195865200 10612154368 1305749360 13.747 100 16 32 0 1315259408 5767808 3742240
14:39:03 7429 8192000000 10242560768 10652127232 1340062640 14.058 100 49 96 3 1358419264 7651872 10704752
14:40:16 74250 8192000000 10264550576 10684338176 1359257168 14.231 100 39 78 0 1376126128 8184912 8684048
14:42:41 35965 8192000000 10285166528 10711625728 1374682000 14.369 100 45 88 2 1396143824 10052400 11409424
14:44:23 16441 8192000000 10304151376 10727587840 1392771568 14.531 100 48 96 0 1418464096 14747968 10944560
14:45:45 7470 8192000000 10320604304 10749407232 1402009264 14.613 100 47 94 1 1434634720 21031136 11594320
14:46:57 15848 8192000000 10334776368 10763501568 1413421728 14.714 100 55 110 1 1446749680 20114976 13212976
14:48:16 7926 8192000000 10357913088 10780389376 1415045392 14.729 100 64 128 0 1461033680 30652800 15335488
14:49:35 9075 8192000000 10354952928 10776662016 1408463328 14.670 99 59 118 1 1451042368 27515728 15063312
14:50:48 5319 8192000000 10368079088 10786148352 1421041264 14.782 100 68 134 3 1465551888 28148080 16362544
14:51:58 3786 8192000000 10369284176 10789367808 1417146784 14.747 99 68 137 2 1463974896 29893456 16934656
14:53:07 2775 8192000000 10377721968 10792177664 1426212640 14.828 100 83 166 1 1481411856 34589056 20610160
14:54:18 1878 8192000000 10380155376 10790809600 1419835552 14.771 100 82 161 0 1478360544 37558608 20966384
14:55:23 2421 8192000000 10352029968 10758967296 1391336176 14.518 100 81 162 0 1451736880 39275568 21125136
14:56:28 19371 8192000000 10332816240 10754387968 1387889232 14.487 100 44 87 0 1436821808 37222848 11709728
14:57:53 8151 8192000000 10309486416 10735448064 1360024896 14.238 99 42 84 0 1411049232 39435520 11588816
14:59:04 14396 8192000000 10291839952 10722021376 1333055184 13.995 98 34 68 1 1379696880 37531712 9109984
15:00:22 26569 8192000000 10266580672 10691297280 1320872544 13.885 98 38 75 0 1353779504 23894816 9012144
15:01:54 22644 8192000000 10266580624 10695139328 1319647616 13.874 98 22 43 1 1354596560 29055440 5893504
15:03:19 21632 8192000000 10272493456 10707271680 1351803472 14.164 100 18 36 2 1378604960 21773888 5027600
15:04:46 36144 8192000000 10274609056 10718011392 1336234992 14.023 99 21 41 0 1374271408 32425536 5610880
15:06:24 30271 8192000000 10282193472 10702524416 1342788128 14.083 99 33 63 0 1364114480 13068928 8257424
15:07:56 41784 8192000000 10271632032 10712141824 1323235728 13.906 97 13 24 1 1339953136 14059536 2657872
15:09:38 17504 8192000000 10299510448 10721574912 1362664992 14.261 99 52 99 2 1382770544 9184672 10920880
15:10:58 43549 8192000000 10290228992 10725797888 1369186752 14.320 99 19 38 2 1392815264 19513808 4114704
15:12:46 79371 8192000000 10297993616 10731704320 1372653392 14.351 100 46 92 0 1396014096 11878432 11482272
15:15:33 14726 8192000000 10295538656 10735095808 1380396704 14.420 100 41 80 1 1404010352 14667568 8946080
15:16:56 39826 8192000000 10293831328 10748219392 1382498544 14.439 100 25 50 1 1410914080 21900688 6514848
15:18:41 46311 8192000000 10278032512 10736640000 1361561744 14.251 98 12 24 0 1381187504 16943264 2682496
15:20:29 25745 8192000000 10286458608 10742329344 1390696752 14.512 100 24 47 3 1406118688 9786304 5635632
15:21:55 39469 8192000000 10301604464 10759954432 1402309120 14.616 100 19 38 1 1414884448 8176592 4398736
15:23:36 106531 8192000000 10306849920 10768076800 1397466336 14.572 100 30 60 0 1421438752 17358592 6613824
15:27:56 42569 8192000000 10306727216 10778214400 1398549952 14.582 99 24 48 2 1424766400 20643600 5572848
15:30:19 64212 8192000000 10297214160 10760245248 1389669840 14.503 99 15 30 0 1402296192 9312672 3313680
15:32:29 40174 8192000000 10308022320 10765930496 1400125472 14.596 99 22 44 1 1415789792 10853504 4810816
15:34:15 29246 8192000000 10313794176 10771021824 1382912240 14.443 98 37 74 2 1407501136 16237888 8351008
15:35:58 60375 8192000000 10288230848 10733338624 1320321440 13.880 95 11 22 0 1334388416 11692544 2374432
15:38:54 55217 8192000000 10314750960 10774859776 1397327280 14.571 100 18 36 1 1417473184 16341984 3803920
15:40:50 61566 8192000000 10293292032 10756714496 1331745312 13.983 96 13 26 2 1356030160 21148240 3136608
15:42:52 41151 8192000000 10285124400 10717790208 1318738160 13.865 95 45 90 2 1339670864 9741952 11190752
15:44:38 113311 8192000000 10266956608 10724061184 1325433920 13.926 96 31 62 1 1342305072 9850144 7021008
15:47:38 82684 8192000000 10258002720 10720722944 1339416304 14.052 98 10 20 0 1354866352 13171200 2278848
15:50:02 32884 8192000000 10246011136 10689613824 1334795200 14.010 99 21 43 0 1355889440 16391456 4702784
15:51:39 64254 8192000000 10260389168 10709245952 1347656880 14.126 99 16 32 1 1364388384 12548208 4183296
15:54:04 78246 8192000000 10245932848 10697752576 1309804528 13.784 97 17 34 2 1329524752 15401520 4318704
15:56:25 84224 8192000000 10249022560 10697306112 1278030656 13.495 94 19 38 1 1295818528 13103776 4684096
15:58:55 45718 8192000000 10269141008 10709344256 1333169744 13.996 99 20 40 0 1350680912 13331072 4180096
16:00:44 24967 8192000000 10265796496 10706898944 1344538224 14.098 99 40 80 0 1368133280 14334528 9260528
16:02:15 67847 8192000000 10293865504 10738282496 1346788656 14.119 98 26 52 0 1379092752 26398976 5905120
16:04:23 98195 8192000000 10282602592 10735812608 1345522608 14.107 98 20 40 0 1370303376 20575712 4205056
16:07:02 65099 8192000000 10291826896 10741989376 1365186384 14.284 99 16 32 1 1379344992 10639328 3519280
16:09:08 60150 8192000000 10282065248 10757603328 1392954960 14.532 100 15 30 0 1411337248 14992272 3390016
16:11:08 54968 8192000000 10303835040 10766295040 1396327984 14.562 100 12 23 0 1412652320 14060336 2264000
16:14:23 78438 8192000000 10308422960 10770157568 1400182480 14.597 100 13 26 1 1416090624 12987600 2920544
16:16:42 35199 8192000000 10309554448 10770026496 1395070192 14.551 99 15 30 1 1408412224 9962832 3379200
16:18:18 47389 8192000000 10327311264 10780422144 1416457488 14.741 100 35 72 1 1437622560 13198352 7966720
16:20:13 73077 8192000000 10310587680 10769375232 1372324096 14.348 97 13 26 1 1384272720 9313856 2634768
16:22:29 44987 8192000000 10313106064 10773401600 1395829232 14.558 99 46 92 2 1421925488 16235344 9860912
16:24:16 44169 8192000000 10319607008 10770059264 1371453808 14.340 97 37 74 1 1388236784 8874016 7908960
16:26:02 65756 8192000000 10287642304 10730586112 1294066720 13.641 93 15 30 0 1307120176 9645584 3407872
16:28:12 92046 8192000000 10282543296 10723762176 1325508752 13.927 96 47 93 1 1351005008 14668896 10827360
16:30:53 54772 8192000000 10262235408 10720796672 1360584704 14.243 99 17 34 2 1374931776 10722480 3624592
16:33:06 37650 8192000000 10272230224 10726236160 1364947632 14.282 99 12 24 0 1376206064 8231872 3026560
16:34:44 87216 8192000000 10236870512 10706042880 1310743680 13.793 97 16 32 0 1329039840 14608160 3688000
16:37:12 53320 8192000000 9621551248 10012852224 717524752 8.053 52 10 20 0 726348496 6054624 2769120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-12-2021 16:39:32 to 24-12-2021 16:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 170 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-12-2021 16:39:48 to 24-12-2021 16:39:50.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          239799
MON$OLDEST_ACTIVE               239800
MON$OLDEST_SNAPSHOT             239800
MON$NEXT_TRANSACTION            239800
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-03 16:41:55.3470
MON$PAGES                       2023872
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33547 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33547 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33547 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp30 according to config parameter 'remove_isql_logs'

24.12.21 16:39:51. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-12-24 16:39:54 No severe PSQL-related problems occured
24.12.21 16:39:55. End of report.