Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.01.2022 19: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 16280956 kB
MemAvailable 31262620 kB
Buffers 0 kB
Cached 15370388 kB
SwapCached 1204 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 2022-01-10 19:38:00
DTS_END                         2022-01-10 19:38:00.8000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47140
TRN_ID                          247223
ATT_ID                          718
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: 9643.92 21534 1157271
customer order: creation 179.82 320 21578
customer order: refuse 15.33 338 1839
order to supplier: creation 47.90 1216 5748
order to supplier: removal 3.61 99621 433
invoice (draft): creation 49.02 3521 5882
invoice (draft): removal 55.58 782 6670
invoice accept: apply 43.48 16699 5218
invoice accept: cancel 49.35 765 5922
customer reserve: creation 4062.72 162 487526
customer reserve: removal 4824.93 58 578991
realization accept: apply 87.83 182 10539
realization accept: cancel 87.78 101 10534
payment from customer: creation 62.52 113 7502
payment from customer: removal 6.07 81 728
payment to supplier: creation 51.06 141 6127
payment to supplier: removal 3.83 77 460
service: total inventory turnovers 5.27 934 632
service: total monetary turnovers 6.87 36 824
service: aggregate perf. data 0.95 19238 114
service: refresh index statistics 0.03 286295 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-01-2022 19:38:56 to 10-01-2022 19:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4959
TEST_TIME 2 4083
TEST_TIME 3 2929
TEST_TIME 4 4306
TEST_TIME 5 3931
TEST_TIME 6 4278
TEST_TIME 7 4894
TEST_TIME 8 5187
TEST_TIME 9 5646
TEST_TIME 10 6190
TEST_TIME 11 6514
TEST_TIME 12 6512
TEST_TIME 13 6517
TEST_TIME 14 6797
TEST_TIME 15 7032
TEST_TIME 16 7043
TEST_TIME 17 6997
TEST_TIME 18 7712
TEST_TIME 19 8237
TEST_TIME 20 8220
TEST_TIME 21 8507
TEST_TIME 22 8514
TEST_TIME 23 9024
TEST_TIME 24 9446
TEST_TIME 25 9207
TEST_TIME 26 9519
TEST_TIME 27 9833
TEST_TIME 28 10031
TEST_TIME 29 10053
TEST_TIME 30 10899
TEST_TIME 31 10851
TEST_TIME 32 10612
TEST_TIME 33 10625
TEST_TIME 34 10935
TEST_TIME 35 10979
TEST_TIME 36 10928
TEST_TIME 37 10799
TEST_TIME 38 10655
TEST_TIME 39 10574
TEST_TIME 40 10370
TEST_TIME 41 10345
TEST_TIME 42 10310
TEST_TIME 43 10411
TEST_TIME 44 10277
TEST_TIME 45 10213
TEST_TIME 46 10184
TEST_TIME 47 10146
TEST_TIME 48 10157
TEST_TIME 49 10258
TEST_TIME 50 10160
TEST_TIME 51 10186
TEST_TIME 52 10050
TEST_TIME 53 9921
TEST_TIME 54 9916
TEST_TIME 55 9904
TEST_TIME 56 9928
TEST_TIME 57 9899
TEST_TIME 58 9850
TEST_TIME 59 9752
TEST_TIME 60 9849
TEST_TIME 61 9869
TEST_TIME 62 9852
TEST_TIME 63 9903
TEST_TIME 64 9885
TEST_TIME 65 9990
TEST_TIME 66 9999
TEST_TIME 67 9946
TEST_TIME 68 10004
TEST_TIME 69 9979
TEST_TIME 70 9930
TEST_TIME 71 9958
TEST_TIME 72 10028
TEST_TIME 73 10051
TEST_TIME 74 10010
TEST_TIME 75 9948
TEST_TIME 76 9928
TEST_TIME 77 9869
TEST_TIME 78 9875
TEST_TIME 79 9863
TEST_TIME 80 9848
TEST_TIME 81 9830
TEST_TIME 82 9746
TEST_TIME 83 9706
TEST_TIME 84 9669
TEST_TIME 85 9684
TEST_TIME 86 9698
TEST_TIME 87 9668
TEST_TIME 88 9731
TEST_TIME 89 9801
TEST_TIME 90 9812
TEST_TIME 91 9875
TEST_TIME 92 9880
TEST_TIME 93 9864
TEST_TIME 94 9855
TEST_TIME 95 9890
TEST_TIME 96 9936
TEST_TIME 97 9955
TEST_TIME 98 9992
TEST_TIME 99 9991
TEST_TIME 100 9898
TEST_TIME 101 9868
TEST_TIME 102 9865
TEST_TIME 103 9851
TEST_TIME 104 9812
TEST_TIME 105 9792
TEST_TIME 106 9738
TEST_TIME 107 9718
TEST_TIME 108 9699
TEST_TIME 109 9658
TEST_TIME 110 9718
TEST_TIME 111 9674
TEST_TIME 112 9640
TEST_TIME 113 9712
TEST_TIME 114 9727
TEST_TIME 115 9727
TEST_TIME 116 9694
TEST_TIME 117 9685
TEST_TIME 118 9704
TEST_TIME 119 9728
TEST_TIME 120 9647

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-01-2022 19:38:59 to 10-01-2022 19:39:02.

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 1608488 1608488 0 0.00 0 28158 6 0 0
doc_list_biud 1608488 1608488 0 0.00 0 77989 27 0 0
sp_add_invoice_to_stock 5350 5218 132 2.46 52 156571 16700 0 132
sp_cancel_adding_invoice 5922 5922 0 0.00 5 60382 766 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 11153 339 0 0
sp_cancel_customer_reserve 578991 578991 0 0.00 1 28513 58 0 0
sp_cancel_pay_from_customer 728 728 0 0.00 2 8728 82 0 0
sp_cancel_pay_to_supplier 460 460 0 0.00 3 6862 78 0 0
sp_cancel_supplier_invoice 6683 6670 13 0.19 1 77995 782 0 13
sp_cancel_supplier_order 433 433 0 0.00 4 1056605 99622 0 0
sp_cancel_write_off 10534 10534 0 0.00 1 13719 102 0 0
sp_client_order 21578 21578 0 0.00 3 19599 320 0 0
sp_customer_reserve 487526 487526 0 0.00 0 29008 162 0 0
sp_fill_shopping_cart 33176 33176 0 0.00 1 16890 37 0 0
sp_fill_shopping_cart_clo_res 487516 487516 0 0.00 0 16657 13 0 0
sp_get_clo_for_invoice 5208 5208 0 0.00 0 19761 276 0 0
sp_kill_cost_storno 17644 17644 0 0.00 0 13336 54 0 0
sp_kill_qstorno_ret_qs2qd 1175619 1175619 0 0.00 0 77987 32 0 0
sp_kill_qty_storno 620136 620136 0 0.00 0 77989 68 0 0
sp_lock_selected_doc 21742 21742 0 0.00 0 5939 5 0 0
sp_make_cost_storno 29376 29376 0 0.00 0 26467 60 0 0
sp_multiply_rows_for_pdistr 29376 29376 0 0.00 0 8625 29 0 0
sp_multiply_rows_for_qdistr 21578 21578 0 0.00 0 19581 142 0 0
sp_pay_from_customer 7502 7502 0 0.00 4 27463 114 0 0
sp_pay_to_supplier 6127 6127 0 0.00 4 25768 142 0 0
sp_payment_common 13629 13629 0 0.00 1 15443 38 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 11147 309 0 0
sp_qd_handle_on_invoice_upd_sts 11130 11130 0 0.00 0 33549 337 0 0
sp_qd_handle_on_reserve_upd_sts 10539 10539 0 0.00 0 12168 53 0 0
sp_reserve_write_off 10539 10539 0 0.00 6 26606 182 0 0
sp_supplier_invoice 5882 5882 0 0.00 6 84340 3522 0 0
sp_supplier_order 5748 5748 0 0.00 5 24356 1217 0 0
srv_aggregate_perf_data 115 114 1 0.86 2978 292307 19238 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1497 102534 41727 0 0
srv_find_qd_qs_mism 1106923 1106923 0 0.00 0 28145 4 0 0
srv_make_invnt_saldo 632 632 0 0.00 26 32636 934 0 0
srv_make_money_saldo 824 824 0 0.00 1 9583 36 0 0
srv_recalc_idx_stat 4 4 0 0.00 172883 410742 286295 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 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 5218 5218 0 0.00 0 9992 161 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 3878 109 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 1741 9 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 1584 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 728 728 0 0.00 0 3557 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 460 460 0 0.00 0 1592 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 5089 36 0 0
t$perf-norm:sp_cancel_supplier_order 433 433 0 0.00 0 18393 1749 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 5906 14 0 0
t$perf-norm:sp_client_order 21578 21578 0 0.00 0 10679 19 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 10095 18 0 0
t$perf-norm:sp_pay_from_customer 7502 7502 0 0.00 0 8922 10 0 0
t$perf-norm:sp_pay_to_supplier 6127 6127 0 0.00 0 3212 9 0 0
t$perf-norm:sp_reserve_write_off 10539 10539 0 0.00 0 5059 9 0 0
t$perf-norm:sp_supplier_invoice 5882 5882 0 0.00 0 17600 27 0 0
t$perf-norm:sp_supplier_order 5748 5748 0 0.00 0 18192 24 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 523 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 632 632 0 0.00 0 7853 52 0 0
t$perf-norm:srv_make_money_saldo 824 824 0 0.00 0 496 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10678 10414 264 2.47 0 6834 18 0 264
v_all_customers 39584 39584 0 0.00 0 20 1 0 0
v_all_suppliers 32094 32094 0 0.00 0 141 1 0 0
v_all_wares 43152 43152 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 3045 13 0 0
v_cancel_client_order 3678 3678 0 0.00 0 2332 5 0 0
v_cancel_customer_prepayment 1456 1456 0 0.00 0 5452 11 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 2973 7 0 0
v_cancel_payment_to_supplier 920 920 0 0.00 0 2798 11 0 0
v_cancel_supplier_invoice 872 846 26 2.98 0 5011 26 0 26
v_cancel_supplier_order 866 866 0 0.00 0 617 4 0 0
v_cancel_write_off 2026 2026 0 0.00 0 2817 16 0 0
v_max_id_clo_ord 5743 5743 0 0.00 0 1792 2 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 1546 11 0 0
v_max_id_ord_sup 5857 5857 0 0.00 0 4567 5 0 0
v_max_non_paid_invoice 1680 1680 0 0.00 0 1945 6 0 0
v_max_non_paid_realizn 3848 3848 0 0.00 0 1290 3 0 0
v_min_id_clo_ord 5743 5743 0 0.00 0 4375 6 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 5743 21 0 0
v_min_id_ord_sup 5857 5857 0 0.00 0 5468 5 0 0
v_min_non_paid_invoice 6127 6127 0 0.00 0 14452 31 0 0
v_min_non_paid_realizn 7502 7502 0 0.00 0 15433 31 0 0
v_our_firm 10876 10876 0 0.00 0 140 1 0 0
v_reserve_write_off 21078 21078 0 0.00 0 8995 7 0 0
x_lock_depdocs_on_canc_invoice 12169 12169 0 0.00 0 22903 118 0 0
x_lock_depdocs_on_canc_sup_ord 433 433 0 0.00 0 18588 381 0 0
x_make_qstorno_1000_1200 5748 5748 0 0.00 1 24320 1144 0 0
x_make_qstorno_1200_2000 5882 5882 0 0.00 2 84298 3386 0 0
x_make_qstorno_2100_3300 487526 487526 0 0.00 0 29007 147 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 10-01-2022 19:39:03 to 10-01-2022 19:39:08.

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
17:08:04 9 8192000000 8809847440 8824852480 14328368 0.174 4 4 5 0 14556560 88896 139296
17:09:05 32407 8192000000 9918618368 10235506688 998664464 10.866 100 87 174 0 1027576688 6459424 22452800
17:10:51 18362 8192000000 9950831984 10276179968 1034907024 11.216 100 86 170 1 1065524960 8739152 21878784
17:12:15 17495 8192000000 9992011696 10333102080 1083918976 11.685 100 85 170 2 1115318944 10136640 21263328
17:13:50 9792 8192000000 10055471136 10408251392 1136061808 12.178 100 80 160 0 1166607552 11941024 18604720
17:15:02 6749 8192000000 10092926016 10455654400 1181302896 12.602 100 77 154 1 1212445792 13953648 17189248
17:16:11 5076 8192000000 10120719952 10491781120 1213010208 12.897 100 69 138 1 1239549168 11645552 14893408
17:17:18 3100 8192000000 10164514784 10542305280 1252293664 13.259 100 75 150 0 1282081136 12386880 17400592
17:18:24 2921 8192000000 10190617536 10571071488 1273342336 13.452 100 89 176 1 1306752560 13959616 19450608
17:19:31 12012 8192000000 10204333616 10590883840 1291641360 13.619 100 77 155 2 1319242800 10739168 16862272
17:20:44 3166 8192000000 10186619360 10592673792 1297694720 13.674 100 35 70 1 1313253680 7930928 7628032
17:21:48 5941 8192000000 10195999392 10608078848 1309038608 13.777 100 31 62 0 1322892224 6010352 7843264
17:22:54 9953 8192000000 10193234800 10613116928 1315221328 13.833 100 17 34 1 1324374352 5827824 3325200
17:24:05 82674 8192000000 10212767936 10637103104 1326188656 13.933 100 32 64 0 1339156416 5071408 7896352
17:26:30 8798 8192000000 10232445712 10646945792 1330169120 13.969 100 30 60 1 1342739904 5316544 7254240
17:27:40 10705 8192000000 10179471872 10594979840 1259323504 13.324 96 20 40 0 1269754368 4900400 5530464
17:28:55 60684 8192000000 10167043184 10559234048 1211186192 12.880 95 34 68 0 1224486448 5214160 8086096
17:31:00 8254 8192000000 10136888032 10540695552 1226164240 13.019 98 23 46 0 1237261840 5746688 5350912
17:32:08 5700 8192000000 10137537952 10536136704 1244782224 13.190 100 26 52 3 1257020336 5718000 6520112
17:33:17 14620 8192000000 10155707136 10550755328 1235590240 13.106 97 15 30 1 1244976624 5458496 3927888
17:34:32 50049 8192000000 10173334224 10582306816 1270950496 13.430 99 30 60 1 1283857456 5916256 6990704
17:36:24 6425 8192000000 10181278000 10597707776 1299232288 13.688 100 23 46 2 1310643856 5558768 5852800
17:37:31 12475 8192000000 10204342656 10614116352 1304450592 13.736 100 23 46 1 1315536704 5397712 5688400
17:38:51 8162 8192000000 10229174976 10635390976 1324622496 13.919 100 42 85 1 1340069344 4954640 10492208
17:40:04 53181 8192000000 10264698432 10679967744 1358670656 14.225 100 31 62 0 1376712832 9871440 8170736
17:42:17 31954 8192000000 10264693616 10695589888 1362480720 14.260 100 27 54 0 1385081200 15519712 7080768
17:43:58 3968 8192000000 10280774240 10704044032 1373780352 14.361 100 44 87 0 1397838176 14347664 9710160
17:45:06 24897 8192000000 10304352256 10733064192 1389830544 14.504 100 49 96 0 1420592832 19121296 11640992
17:46:37 13949 8192000000 10314438976 10741374976 1393769552 14.539 100 38 77 2 1425180160 22178224 9232384
17:47:58 9091 8192000000 10321349552 10743623680 1382319824 14.437 99 51 99 4 1419897648 24230368 13347456
17:49:15 5036 8192000000 10330051808 10754748416 1403353024 14.625 100 57 113 0 1437741632 20226736 14161872
17:50:24 5118 8192000000 10340600848 10766438400 1414429968 14.723 100 58 116 0 1450627536 21995472 14202096
17:51:33 3472 8192000000 10337054784 10753015808 1395919632 14.559 100 70 139 0 1440809904 28426048 16464224
17:52:45 2013 8192000000 10361909008 10772701184 1415245328 14.731 100 89 178 1 1468516416 31273984 21997104
17:53:51 10731 8192000000 10347333312 10768150528 1405738288 14.646 100 59 118 1 1452883120 32579296 14565536
17:55:11 2618 8192000000 10356475744 10770505728 1393572240 14.538 99 78 157 1 1453477472 40837280 19067952
17:56:19 3035 8192000000 10365432208 10773659648 1393564976 14.538 100 80 160 3 1465151472 50853072 20733424
17:57:29 1495 8192000000 10356707136 10772201472 1395295888 14.553 100 81 163 3 1460523904 45144208 20083808
17:58:34 14359 8192000000 10334850832 10768011264 1375175104 14.373 98 43 86 0 1432494800 45785824 11533872
17:59:54 7672 8192000000 10322087456 10743549952 1356269648 14.204 98 42 84 1 1414010768 46385008 11356112
18:01:09 1913 8192000000 10343460288 10752245760 1368171120 14.311 100 79 158 1 1445246768 56655760 20419888
18:02:18 5717 8192000000 10335889568 10755235840 1369110560 14.319 100 48 94 2 1438924224 56373984 13439680
18:03:26 34663 8192000000 10316090640 10741248000 1364335200 14.276 100 36 72 2 1421874464 47213312 10325952
18:05:20 18933 8192000000 10323832464 10756472832 1367351584 14.303 100 28 56 1 1425165760 49998464 7815712
18:06:40 41105 8192000000 10305982528 10746703872 1361374576 14.250 100 15 30 1 1409103408 43107280 4621552
18:08:29 25613 8192000000 10291168480 10709680128 1349578752 14.144 99 37 68 2 1364840480 8141056 7120672
18:09:58 48576 8192000000 10289585536 10724687872 1363449936 14.268 99 23 45 1 1383687360 15372928 4864496
18:11:49 70903 8192000000 10295571152 10743787520 1352414352 14.169 98 6 12 0 1367507584 14077168 1016064
18:14:34 68978 8192000000 10286168512 10731851776 1344935168 14.102 97 4 8 0 1356575424 10836352 803904
18:17:09 102534 8192000000 10293948160 10733027328 1368620960 14.315 99 35 70 2 1389123760 12676656 7826144
18:20:18 74448 8192000000 10318642912 10767675392 1385690528 14.467 99 26 52 0 1406112032 14431856 5989648
18:22:36 31517 8192000000 10306106176 10763874304 1387831184 14.487 99 14 29 0 1409267840 18171872 3264784
18:24:09 42378 8192000000 10321976352 10777165824 1411515808 14.697 100 24 48 1 1430121008 12943040 5662160
18:25:54 51538 8192000000 10323956400 10795110400 1410763024 14.691 100 20 40 0 1431194432 15474640 4956768
18:27:54 74572 8192000000 10306692688 10780647424 1400709936 14.601 99 10 20 0 1416590992 13623872 2257184
18:30:10 57657 8192000000 10317614480 10784354304 1416164256 14.739 100 12 24 0 1428832576 9972176 2696144
18:34:28 76887 8192000000 10299402416 10774003712 1392575792 14.529 99 24 48 0 1406066208 7982256 5508160
18:36:54 45836 8192000000 10314743728 10781097984 1415745296 14.735 100 26 52 1 1432462272 10815840 5901136
18:38:41 32288 8192000000 10295552304 10749095936 1369150848 14.319 98 12 23 0 1378929392 7391728 2386816
18:40:21 23828 8192000000 10286175472 10734419968 1354563744 14.189 98 28 57 0 1367459456 6827072 6068640
18:41:47 42808 8192000000 10302755936 10748121088 1362901120 14.263 98 25 50 0 1377905792 9370384 5634288
18:43:38 81680 8192000000 10251752224 10706481152 1239412992 13.141 90 7 14 0 1253935248 13149824 1372432
18:46:06 13141 8192000000 10265695968 10706923520 1355539920 14.197 100 34 68 1 1372469600 8637232 8292448
18:47:20 76857 8192000000 10257145904 10700976128 1277008560 13.486 93 13 26 0 1290133616 10635392 2489664
18:49:37 20072 8192000000 10274229648 10720731136 1347402272 14.124 99 33 66 1 1370282080 15669120 7210688
18:51:00 59001 8192000000 10277393888 10728882176 1332516816 13.990 97 15 30 0 1349105792 13225616 3363360
18:53:01 94686 8192000000 10289398496 10743701504 1377065056 14.390 100 24 48 1 1390854336 7965360 5823920
18:55:41 18508 8192000000 10290985984 10739363840 1386243728 14.472 100 31 59 1 1400589168 6846944 7498496
18:57:00 52373 8192000000 10285243056 10741039104 1372048048 14.345 99 25 50 0 1390377152 12757120 5571984
18:59:00 64584 8192000000 10264128000 10729639936 1355494288 14.197 98 13 26 1 1369017568 10616720 2906560
19:01:08 83841 8192000000 10275012368 10744610816 1353725136 14.181 98 14 28 1 1366196144 9189456 3281552
19:03:35 34363 8192000000 10276907408 10731970560 1365955392 14.291 99 22 44 3 1380255392 8460800 5839200
19:05:20 101866 8192000000 10297830704 10760728576 1365242080 14.284 98 21 42 0 1385464800 14834832 5387888
19:08:12 78561 8192000000 10303721616 10775199744 1393953312 14.541 100 13 26 0 1410057968 13155808 2948848
19:11:00 32715 8192000000 10311898816 10761777152 1384349744 14.455 99 15 30 0 1396333472 8319056 3664672
19:12:33 59409 8192000000 10311366304 10765217792 1377717920 14.396 98 30 59 1 1402431440 17160960 7552560
19:14:35 70573 8192000000 10291592608 10756325376 1340409104 14.061 96 13 26 0 1354321104 11000272 2911728
19:16:46 70949 8192000000 10285764144 10754682880 1320657424 13.883 95 5 10 0 1334866928 13287760 921744
19:19:38 84666 8192000000 10285664512 10746654720 1349126384 14.140 97 27 54 1 1370347760 15377328 5844048
19:22:05 46409 8192000000 10278411856 10734919680 1369421632 14.322 99 16 32 0 1379075664 6407968 3246064
19:23:58 58214 8192000000 10265658080 10718375936 1307411664 13.763 94 15 30 2 1317712368 6637088 3663616
19:25:57 75616 8192000000 10258606096 10721906688 1350959520 14.156 99 15 30 0 1363634256 9043408 3631328
19:28:13 44054 8192000000 10256117568 10723520512 1329556416 13.963 97 19 38 3 1348600672 14971360 4072896
19:30:20 48667 8192000000 10276574144 10724024320 1354958816 14.192 99 37 72 2 1377806128 14281616 8565696
19:32:17 46819 8192000000 10280522960 10740690944 1353535120 14.179 99 31 62 3 1375591264 14891760 7164384
19:34:04 17763 8192000000 10265051088 10713702400 1339395024 14.052 98 20 37 1 1349790032 6490320 3904688
19:35:27 77042 8192000000 10285626288 10740146176 1361876320 14.254 99 26 52 0 1384270688 16396560 5997808
19:37:44 26275 8192000000 9217268864 9485828096 367168720 4.289 27 5 9 0 379091104 10802432 1119952

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-01-2022 19:39:09 to 10-01-2022 19:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 132 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-01-2022 19:39:25 to 10-01-2022 19:39:27.

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          247737
MON$OLDEST_ACTIVE               247738
MON$OLDEST_SNAPSHOT             247738
MON$NEXT_TRANSACTION            247738
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                       2046688
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.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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 ---
:751: 
:752: mscs-test-161.budzdorov.ru    Mon Jan 10 19:38:14 2022
:753:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:754: 
:755: 
:756: mscs-test-161.budzdorov.ru    Mon Jan 10 19:38:24 2022
:757:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:758: 
:759: 
:760: mscs-test-161.budzdorov.ru    Mon Jan 10 19:38:26 2022
:761:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:762: 
--- end of diff output ---

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

10.01.22 19:39:28. 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
2022-01-10 19:39:30 No severe PSQL-related problems occured
10.01.22 19:39:32. End of report.