Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.05.2022 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 16217184 kB
MemAvailable 31348760 kB
Buffers 0 kB
Cached 15463008 kB
SwapCached 2452 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.10
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-05-28 16:38:24
DTS_END                         2022-05-28 16:38:24.0840
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44822
TRN_ID                          248086
ATT_ID                          697
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: 9595.62 20695 1151474
customer order: creation 183.36 299 22003
customer order: refuse 15.49 334 1859
order to supplier: creation 49.10 1107 5892
order to supplier: removal 3.78 109354 453
invoice (draft): creation 48.15 3347 5778
invoice (draft): removal 55.58 892 6670
invoice accept: apply 43.93 16561 5272
invoice accept: cancel 49.63 871 5956
customer reserve: creation 3992.43 166 479092
customer reserve: removal 4836.52 67 580382
realization accept: apply 88.27 165 10592
realization accept: cancel 90.85 106 10902
payment from customer: creation 63.51 102 7621
payment from customer: removal 6.38 91 766
payment to supplier: creation 51.89 121 6227
payment to supplier: removal 3.78 99 454
service: total inventory turnovers 4.97 1202 597
service: total monetary turnovers 7.05 29 846
service: aggregate perf. data 0.90 21825 108
service: refresh index statistics 0.03 257171 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-05-2022 16:39:22 to 28-05-2022 16:39:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3032
TEST_TIME 2 2413
TEST_TIME 3 3222
TEST_TIME 4 4249
TEST_TIME 5 4132
TEST_TIME 6 4327
TEST_TIME 7 4683
TEST_TIME 8 4476
TEST_TIME 9 4629
TEST_TIME 10 5306
TEST_TIME 11 5597
TEST_TIME 12 5954
TEST_TIME 13 6065
TEST_TIME 14 6355
TEST_TIME 15 6576
TEST_TIME 16 6526
TEST_TIME 17 6980
TEST_TIME 18 7156
TEST_TIME 19 7158
TEST_TIME 20 7597
TEST_TIME 21 8292
TEST_TIME 22 8116
TEST_TIME 23 8324
TEST_TIME 24 8764
TEST_TIME 25 8822
TEST_TIME 26 8664
TEST_TIME 27 8999
TEST_TIME 28 9774
TEST_TIME 29 10616
TEST_TIME 30 11164
TEST_TIME 31 11165
TEST_TIME 32 11275
TEST_TIME 33 11106
TEST_TIME 34 11128
TEST_TIME 35 10900
TEST_TIME 36 10814
TEST_TIME 37 10704
TEST_TIME 38 10482
TEST_TIME 39 10494
TEST_TIME 40 10444
TEST_TIME 41 10432
TEST_TIME 42 10422
TEST_TIME 43 10246
TEST_TIME 44 10192
TEST_TIME 45 10289
TEST_TIME 46 10258
TEST_TIME 47 10366
TEST_TIME 48 10321
TEST_TIME 49 10193
TEST_TIME 50 10223
TEST_TIME 51 10141
TEST_TIME 52 10034
TEST_TIME 53 9995
TEST_TIME 54 9907
TEST_TIME 55 9827
TEST_TIME 56 9727
TEST_TIME 57 9851
TEST_TIME 58 9881
TEST_TIME 59 9901
TEST_TIME 60 9902
TEST_TIME 61 9916
TEST_TIME 62 10007
TEST_TIME 63 9977
TEST_TIME 64 9958
TEST_TIME 65 9963
TEST_TIME 66 10035
TEST_TIME 67 10058
TEST_TIME 68 10056
TEST_TIME 69 10075
TEST_TIME 70 10101
TEST_TIME 71 10024
TEST_TIME 72 10144
TEST_TIME 73 10078
TEST_TIME 74 9987
TEST_TIME 75 9956
TEST_TIME 76 9942
TEST_TIME 77 9900
TEST_TIME 78 9911
TEST_TIME 79 9832
TEST_TIME 80 9835
TEST_TIME 81 9777
TEST_TIME 82 9671
TEST_TIME 83 9616
TEST_TIME 84 9604
TEST_TIME 85 9644
TEST_TIME 86 9642
TEST_TIME 87 9608
TEST_TIME 88 9596
TEST_TIME 89 9661
TEST_TIME 90 9623
TEST_TIME 91 9650
TEST_TIME 92 9723
TEST_TIME 93 9676
TEST_TIME 94 9738
TEST_TIME 95 9802
TEST_TIME 96 9853
TEST_TIME 97 9856
TEST_TIME 98 9821
TEST_TIME 99 9882
TEST_TIME 100 9839
TEST_TIME 101 9786
TEST_TIME 102 9800
TEST_TIME 103 9792
TEST_TIME 104 9750
TEST_TIME 105 9725
TEST_TIME 106 9686
TEST_TIME 107 9693
TEST_TIME 108 9647
TEST_TIME 109 9627
TEST_TIME 110 9609
TEST_TIME 111 9550
TEST_TIME 112 9541
TEST_TIME 113 9531
TEST_TIME 114 9519
TEST_TIME 115 9541
TEST_TIME 116 9570
TEST_TIME 117 9569
TEST_TIME 118 9625
TEST_TIME 119 9668
TEST_TIME 120 9600

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-05-2022 16:39:26 to 28-05-2022 16:39:29.

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 1595674 1595674 0 0.00 0 19531 6 0 0
doc_list_biud 1595674 1595674 0 0.00 0 20530 31 0 0
sp_add_invoice_to_stock 5377 5272 105 1.95 17 152211 16562 0 105
sp_cancel_adding_invoice 5956 5956 0 0.00 4 93492 871 0 0
sp_cancel_client_order 1859 1859 0 0.00 4 8165 335 0 0
sp_cancel_customer_reserve 580382 580382 0 0.00 1 20483 68 0 0
sp_cancel_pay_from_customer 766 766 0 0.00 2 5862 91 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 12468 100 0 0
sp_cancel_supplier_invoice 6672 6670 2 0.02 2 20542 892 0 2
sp_cancel_supplier_order 453 453 0 0.00 6 1151988 109354 0 0
sp_cancel_write_off 10902 10902 0 0.00 1 14163 107 0 0
sp_client_order 22003 22003 0 0.00 3 18189 299 0 0
sp_customer_reserve 479092 479092 0 0.00 0 81369 166 0 0
sp_fill_shopping_cart 33666 33666 0 0.00 1 19723 37 0 0
sp_fill_shopping_cart_clo_res 479085 479085 0 0.00 0 23629 12 0 0
sp_get_clo_for_invoice 5265 5265 0 0.00 0 26593 263 0 0
sp_kill_cost_storno 18078 18078 0 0.00 0 12449 60 0 0
sp_kill_qstorno_ret_qs2qd 1178789 1178789 0 0.00 0 20520 36 0 0
sp_kill_qty_storno 622078 622078 0 0.00 0 20527 78 0 0
sp_lock_selected_doc 22005 22005 0 0.00 0 8843 7 0 0
sp_make_cost_storno 29704 29704 0 0.00 0 19524 52 0 0
sp_multiply_rows_for_pdistr 29704 29704 0 0.00 0 8172 27 0 0
sp_multiply_rows_for_qdistr 22003 22003 0 0.00 0 18177 138 0 0
sp_pay_from_customer 7621 7621 0 0.00 4 13771 103 0 0
sp_pay_to_supplier 6227 6227 0 0.00 5 14360 121 0 0
sp_payment_common 13848 13848 0 0.00 1 8636 27 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 8157 297 0 0
sp_qd_handle_on_invoice_upd_sts 11220 11220 0 0.00 0 20227 348 0 0
sp_qd_handle_on_reserve_upd_sts 10592 10592 0 0.00 0 13254 53 0 0
sp_reserve_write_off 10592 10592 0 0.00 6 13659 165 0 0
sp_supplier_invoice 5778 5778 0 0.00 6 102841 3347 0 0
sp_supplier_order 5892 5892 0 0.00 5 51551 1108 0 0
srv_aggregate_perf_data 109 108 1 0.91 2703 388089 21825 0 1
srv_fill_mon_cache_memory 67 67 0 0.00 1334 136455 42504 0 0
srv_find_qd_qs_mism 1101349 1101349 0 0.00 0 13624 5 0 0
srv_make_invnt_saldo 597 597 0 0.00 37 129763 1203 0 0
srv_make_money_saldo 846 846 0 0.00 0 2394 30 0 0
srv_recalc_idx_stat 4 4 0 0.00 186214 338755 257171 0 0
t$perf-abend:sp_add_invoice_to_stock 105 105 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 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 5272 5272 0 0.00 0 15656 165 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 3004 88 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 5401 13 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 6705 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 766 766 0 0.00 0 2478 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 159 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 2806 18 0 0
t$perf-norm:sp_cancel_supplier_order 453 453 0 0.00 0 22647 1518 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 3241 7 0 0
t$perf-norm:sp_client_order 22003 22003 0 0.00 0 5100 17 0 0
t$perf-norm:sp_customer_reserve 1094 1094 0 0.00 0 11772 21 0 0
t$perf-norm:sp_pay_from_customer 7621 7621 0 0.00 0 5228 7 0 0
t$perf-norm:sp_pay_to_supplier 6227 6227 0 0.00 0 8566 8 0 0
t$perf-norm:sp_reserve_write_off 10592 10592 0 0.00 0 7466 9 0 0
t$perf-norm:sp_supplier_invoice 5778 5778 0 0.00 0 6987 20 0 0
t$perf-norm:sp_supplier_order 5892 5892 0 0.00 0 5136 16 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 31 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 494 8 0 0
t$perf-norm:srv_make_invnt_saldo 597 597 0 0.00 0 2512 33 0 0
t$perf-norm:srv_make_money_saldo 846 846 0 0.00 0 879 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 42 12 0 0
v_add_invoice_to_stock 10738 10528 210 1.95 0 2710 15 0 210
v_all_customers 40076 40076 0 0.00 0 69 1 0 0
v_all_suppliers 32346 32346 0 0.00 0 23 1 0 0
v_all_wares 44006 44006 0 0.00 0 105 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1157 6 0 0
v_cancel_client_order 3718 3718 0 0.00 0 2525 4 0 0
v_cancel_customer_prepayment 1532 1532 0 0.00 0 3452 9 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 472 3 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 1559 5 0 0
v_cancel_supplier_invoice 856 852 4 0.46 0 3985 21 0 4
v_cancel_supplier_order 906 906 0 0.00 0 422 4 0 0
v_cancel_write_off 2094 2094 0 0.00 0 1575 11 0 0
v_max_id_clo_ord 5892 5892 0 0.00 0 11696 5 0 0
v_max_id_clo_res 1094 1094 0 0.00 0 2687 10 0 0
v_max_id_ord_sup 5771 5771 0 0.00 0 5893 7 0 0
v_max_non_paid_invoice 1717 1717 0 0.00 0 519 3 0 0
v_max_non_paid_realizn 4045 4045 0 0.00 0 6004 9 0 0
v_min_id_clo_ord 5892 5892 0 0.00 0 3071 5 0 0
v_min_id_clo_res 1094 1094 0 0.00 0 3024 11 0 0
v_min_id_ord_sup 5771 5771 0 0.00 0 6544 6 0 0
v_min_non_paid_invoice 6227 6227 0 0.00 0 6352 17 0 0
v_min_non_paid_realizn 7621 7621 0 0.00 0 6523 19 0 0
v_our_firm 11082 11082 0 0.00 0 11 1 0 0
v_reserve_write_off 21182 21182 0 0.00 0 3126 5 0 0
x_lock_depdocs_on_canc_invoice 12200 12200 0 0.00 0 10748 110 0 0
x_lock_depdocs_on_canc_sup_ord 453 453 0 0.00 0 7047 317 0 0
x_make_qstorno_1000_1200 5892 5892 0 0.00 1 51540 1026 0 0
x_make_qstorno_1200_2000 5778 5778 0 0.00 2 98780 3213 0 0
x_make_qstorno_2100_3300 479092 479092 0 0.00 0 81365 152 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-05-2022 16:39:30 to 28-05-2022 16:39:34.

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:27 3 8192000000 8803476736 8816328704 9026832 0.110 4 1 2 0 9129120 59504 42784
14:09:28 15388 8192000000 9894394096 10206248960 982568272 10.709 100 86 170 1 1011447280 6110368 22768640
14:10:53 25435 8192000000 9953472608 10275938304 1030538576 11.174 100 90 179 1 1062431360 8306560 23586224
14:12:22 13224 8192000000 9990605152 10323025920 1070472416 11.557 100 82 163 0 1100887872 9850352 20565104
14:13:54 14266 8192000000 10032905472 10384388096 1123772624 12.063 100 77 154 1 1153365264 11276816 18315824
14:15:15 10344 8192000000 10080734832 10443923456 1172743904 12.522 100 65 130 1 1198770272 11570432 14455936
14:16:29 5892 8192000000 10131646720 10499620864 1213341232 12.900 100 79 158 0 1243405712 12776608 17287872
14:17:39 3567 8192000000 10161595072 10539393024 1248269904 13.222 100 77 155 4 1279453360 13697776 17485680
14:18:45 2619 8192000000 10189913856 10567897088 1274906048 13.466 100 89 178 1 1309048224 13417024 20725152
14:19:49 5325 8192000000 10209771792 10594504704 1292302256 13.625 100 86 173 1 1324622720 13273072 19047392
14:20:55 7717 8192000000 10211794864 10602557440 1301551392 13.709 100 76 155 1 1328097616 8749728 17796496
14:22:08 3104 8192000000 10194329520 10601472000 1303073440 13.723 100 32 64 0 1318155248 8105920 6975888
14:23:12 6319 8192000000 10203077280 10614079488 1316362240 13.844 100 30 60 1 1330438064 6961312 7114512
14:24:25 6494 8192000000 10214365584 10626994176 1323079328 13.905 100 26 52 1 1334749584 5856208 5814048
14:25:33 64147 8192000000 10221971664 10638524416 1330690560 13.973 100 25 51 0 1341916096 5894144 5331392
14:27:39 12073 8192000000 10225065648 10646863872 1325990736 13.931 99 32 64 0 1340635840 6775504 7869600
14:28:55 3229 8192000000 10202455888 10610311168 1296951008 13.668 99 33 65 1 1311655904 7008576 7696320
14:29:59 10793 8192000000 10175702096 10578137088 1221262496 12.973 94 19 38 3 1230646608 5188928 4195184
14:31:15 13158 8192000000 10160509488 10546266112 1223755056 12.996 95 66 133 0 1245036976 5397856 15884064
14:32:29 6646 8192000000 10130737376 10528886784 1232056448 13.073 99 32 65 1 1244814880 4920336 7838096
14:33:37 45337 8192000000 10174156224 10567720960 1242718192 13.171 98 29 57 1 1254213056 4366800 7128064
14:35:30 5631 8192000000 10181054176 10586296320 1285326256 13.562 100 33 67 1 1303339152 10368368 7644528
14:36:37 9537 8192000000 10192940240 10604572672 1300019360 13.695 100 24 47 1 1310970432 5410624 5540448
14:37:47 6433 8192000000 10203677568 10622873600 1315331616 13.834 100 17 33 0 1324953488 5448368 4173504
14:38:55 54075 8192000000 10237022208 10649559040 1335820176 14.020 100 42 84 0 1352293344 5970848 10502320
14:40:55 27196 8192000000 10269326704 10688143360 1361004720 14.246 100 39 78 4 1388228192 19357200 7866272
14:42:26 21433 8192000000 10280028928 10705764352 1365697104 14.288 99 36 72 0 1391991648 17697440 8597104
14:43:51 6707 8192000000 10317413696 10736488448 1394122112 14.543 100 64 123 2 1433409568 24368736 14918720
14:45:07 16800 8192000000 10307606752 10738290688 1392590496 14.529 100 37 73 2 1428046672 25823728 9632448
14:46:45 15344 8192000000 10339192512 10759303168 1403115600 14.623 100 57 115 1 1447162656 30470976 13576080
14:48:08 2221 8192000000 10349721616 10767937536 1409129792 14.676 100 72 143 1 1459845392 33430160 17285440
14:49:15 3594 8192000000 10340859104 10761596928 1385533024 14.466 98 77 151 4 1437302144 33050448 18718672
14:50:22 2104 8192000000 10349836480 10762858496 1399393200 14.590 100 70 139 2 1451791072 34378320 18019552
14:51:29 1860 8192000000 10366656672 10784260096 1413121696 14.712 100 79 156 2 1470699920 38316144 19262080
14:52:36 5770 8192000000 10344103536 10763464704 1374621232 14.368 98 70 136 2 1434943104 43261856 17060016
14:53:46 2628 8192000000 10357877248 10781220864 1402426688 14.617 99 76 148 2 1465723808 43658480 19638640
14:54:55 1333 8192000000 10389205296 10802970624 1419324320 14.767 100 83 166 1 1491819632 51777312 20718000
14:56:03 2765 8192000000 10384800208 10799910912 1413140800 14.712 100 84 165 0 1485493136 51650976 20701360
14:57:11 6034 8192000000 10366457824 10778611712 1393930928 14.541 100 71 142 0 1469429888 57415664 18083296
14:58:24 2295 8192000000 10398106592 10804019200 1402603440 14.618 100 90 181 1 1495880368 69081344 24195584
14:59:29 13353 8192000000 10349411856 10763554816 1377667168 14.396 98 58 115 1 1440319008 47849088 14802752
15:00:48 4168 8192000000 10373113264 10778873856 1392173232 14.525 100 79 159 3 1470605024 58054368 20377424
15:01:58 11459 8192000000 10370925520 10791211008 1395464480 14.555 99 51 102 0 1469511328 59572368 14474480
15:03:15 11020 8192000000 10375742912 10806595584 1410994976 14.693 100 38 76 1 1477766320 56087616 10683728
15:04:29 20602 8192000000 10373896016 10809765888 1405174336 14.641 100 38 76 1 1478338464 61886048 11278080
15:05:57 20157 8192000000 10360773312 10822668288 1392617168 14.529 100 26 52 1 1465398896 64326528 8455200
15:07:26 32021 8192000000 10314658608 10788052992 1361290480 14.249 99 15 30 0 1424257936 58194528 4772928
15:09:07 69691 8192000000 10301672912 10757939200 1389696144 14.503 100 10 20 0 1404822160 13467040 1658976
15:11:21 59530 8192000000 10293204080 10747179008 1374371936 14.366 99 5 10 0 1384770944 9562880 836128
15:13:21 63140 8192000000 10301004448 10747854848 1378922976 14.407 99 39 78 2 1394419792 6955232 8541584
15:15:26 110071 8192000000 10296443968 10749816832 1365442256 14.286 98 21 43 1 1378445824 8454496 4549072
15:18:24 61810 8192000000 10316000592 10759712768 1376254656 14.383 99 25 50 1 1392975344 11250320 5470368
15:20:26 71580 8192000000 10292232976 10740334592 1357959440 14.219 97 13 26 0 1372520480 11894272 2666768
15:22:39 12717 8192000000 10319831456 10761601024 1400042432 14.595 100 41 80 1 1421790304 12701728 9046144
15:23:53 18246 8192000000 10325214320 10765090816 1405349984 14.643 100 28 56 0 1424282272 12326000 6606288
15:25:22 60961 8192000000 10295517984 10769244160 1385380224 14.465 99 23 46 1 1403023312 12075632 5567456
15:27:55 48060 8192000000 10300538544 10765058048 1401431120 14.608 100 14 27 0 1413462096 8994192 3036784
15:29:44 38683 8192000000 10286112944 10752327680 1388795712 14.495 100 19 38 0 1401006656 7851760 4359184
15:31:35 63578 8192000000 10300005168 10770243584 1404515424 14.635 100 16 32 0 1416993296 9163744 3314128
15:33:42 67254 8192000000 10317678256 10787508224 1400555936 14.600 99 17 32 1 1425416944 20897248 3963760
15:36:11 22331 8192000000 10308065280 10780614656 1393357200 14.536 100 12 23 0 1418321936 21347520 3617216
15:37:39 26274 8192000000 10286953184 10744332288 1383691760 14.450 99 21 42 1 1394946528 6765296 4489472
15:39:05 48288 8192000000 10293528080 10750889984 1337280480 14.033 95 14 28 0 1350629632 10471168 2877984
15:41:08 24261 8192000000 10298834096 10746433536 1376556016 14.386 99 44 87 1 1397033920 10208224 10269680
15:42:33 21675 8192000000 10289804880 10732482560 1355305808 14.195 98 27 54 0 1373595984 12729936 5560240
15:43:55 38722 8192000000 10284727312 10734632960 1335959520 14.021 97 33 66 1 1357164448 13913120 7291808
15:45:43 27437 8192000000 10264969696 10709676032 1335423424 14.016 98 23 46 0 1349190848 9091072 4676352
15:47:18 45734 8192000000 10284618256 10728431616 1360927392 14.246 99 17 34 0 1373813152 9254272 3631488
15:49:05 41835 8192000000 10290242448 10729136128 1365735744 14.289 99 25 50 0 1382992160 11999280 5257136
15:50:49 91249 8192000000 10266977440 10722525184 1338302672 14.042 98 19 37 0 1352502464 9818640 4381152
15:53:29 60540 8192000000 10254822560 10711371776 1332367056 13.989 97 25 48 1 1349626304 12376864 4882384
15:55:30 43739 8192000000 10258615232 10706718720 1345911344 14.111 99 34 68 1 1361640272 8336528 7392400
15:57:19 52503 8192000000 10265596768 10723966976 1367862320 14.308 100 9 19 1 1382320960 12382592 2076048
15:59:16 33776 8192000000 10266204464 10723385344 1371671584 14.342 100 15 30 1 1382311120 7355296 3284240
16:00:50 64935 8192000000 10270121632 10730762240 1359900080 14.236 99 24 48 1 1378247616 12590448 5757088
16:03:01 55687 8192000000 10260082096 10722004992 1340154496 14.059 98 5 10 0 1349236912 8299904 782512
16:04:57 99482 8192000000 10299512544 10756943872 1382474960 14.439 100 19 38 0 1397868736 11166496 4227280
16:07:37 47602 8192000000 10285937392 10732359680 1342401104 14.079 97 27 52 0 1354630272 7099136 5130032
16:09:29 97881 8192000000 10274871040 10734886912 1340433680 14.061 97 19 38 0 1354186992 9848368 3904944
16:12:07 56795 8192000000 10300640656 10766811136 1388837600 14.495 100 19 38 1 1413830144 20098064 4894480
16:14:07 64336 8192000000 10272663040 10736840704 1351029408 14.157 98 31 62 0 1371073440 13037248 7006784
16:16:44 79730 8192000000 10278337792 10724741120 1332504656 13.990 97 11 22 2 1347955280 13000064 2450560
16:19:06 136454 8192000000 10288976384 10741612544 1368886768 14.317 100 20 40 1 1391424240 17980000 4557472
16:22:24 32151 8192000000 10281090208 10749603840 1374010528 14.363 100 7 14 0 1395946848 20693392 1242928
16:23:57 96451 8192000000 10270637136 10734247936 1327400224 13.944 96 12 24 0 1339249744 9015056 2834464
16:26:33 80376 8192000000 10256511648 10709118976 1279850608 13.512 93 14 28 1 1288446736 5865008 2731120
16:28:56 76826 8192000000 10241616016 10699640832 1280166880 13.515 94 11 22 0 1293616400 11193120 2256400
16:31:13 102846 8192000000 10263854608 10722516992 1326566928 13.936 98 10 20 1 1344104896 15207376 2330592
16:33:58 48956 8192000000 10256721232 10699714560 1290089216 13.605 94 25 50 1 1314339968 18228960 6021792
16:35:54 56383 8192000000 10272515760 10722824192 1328622704 13.955 98 14 28 0 1352547888 20687088 3238096
16:37:53 42193 8192000000 9235955200 9525702656 365226624 4.268 27 2 4 0 370085056 4593840 264592

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-05-2022 16:39:36 to 28-05-2022 16:39:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 105 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-05-2022 16:39:52 to 28-05-2022 16:39:54.

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          248603
MON$OLDEST_ACTIVE               248604
MON$OLDEST_SNAPSHOT             248604
MON$NEXT_TRANSACTION            248604
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                       2049824
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33597 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33597 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33597 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33597 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'

28.05.22 16:39:55. 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-05-28 16:39:57 No severe PSQL-related problems occured
28.05.22 16:39:59. End of report.