Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.04.2022 01:39
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 16593804 kB
MemAvailable 31626220 kB
Buffers 0 kB
Cached 15144116 kB
SwapCached 2204 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-04-01 01:38:07
DTS_END                         2022-04-01 01:38:07.1500
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/55520
TRN_ID                          241906
ATT_ID                          706
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: 9486.12 21425 1138334
customer order: creation 172.53 343 20703
customer order: refuse 14.77 342 1772
order to supplier: creation 48.02 1253 5762
order to supplier: removal 3.39 107141 407
invoice (draft): creation 45.75 4374 5490
invoice (draft): removal 54.27 793 6512
invoice accept: apply 41.32 18056 4958
invoice accept: cancel 48.98 878 5877
customer reserve: creation 3906.35 175 468762
customer reserve: removal 4838.77 59 580653
realization accept: apply 84.86 177 10183
realization accept: cancel 93.73 110 11247
payment from customer: creation 59.81 94 7177
payment from customer: removal 5.64 69 677
payment to supplier: creation 51.07 119 6128
payment to supplier: removal 3.47 58 416
service: total inventory turnovers 5.06 1092 607
service: total monetary turnovers 7.53 24 903
service: aggregate perf. data 0.80 20312 96
service: refresh index statistics 0.03 273022 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-04-2022 01:40:03 to 01-04-2022 01:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 793
TEST_TIME 2 1886
TEST_TIME 3 3295
TEST_TIME 4 3671
TEST_TIME 5 4298
TEST_TIME 6 4988
TEST_TIME 7 4827
TEST_TIME 8 5515
TEST_TIME 9 5395
TEST_TIME 10 5549
TEST_TIME 11 5795
TEST_TIME 12 6218
TEST_TIME 13 6626
TEST_TIME 14 6902
TEST_TIME 15 7184
TEST_TIME 16 7252
TEST_TIME 17 7332
TEST_TIME 18 7629
TEST_TIME 19 8080
TEST_TIME 20 8669
TEST_TIME 21 8946
TEST_TIME 22 9204
TEST_TIME 23 9156
TEST_TIME 24 9550
TEST_TIME 25 9865
TEST_TIME 26 10080
TEST_TIME 27 10928
TEST_TIME 28 11748
TEST_TIME 29 11508
TEST_TIME 30 11384
TEST_TIME 31 11392
TEST_TIME 32 11196
TEST_TIME 33 11050
TEST_TIME 34 10938
TEST_TIME 35 10840
TEST_TIME 36 10901
TEST_TIME 37 10814
TEST_TIME 38 10751
TEST_TIME 39 10611
TEST_TIME 40 10677
TEST_TIME 41 10588
TEST_TIME 42 10590
TEST_TIME 43 10529
TEST_TIME 44 10405
TEST_TIME 45 10279
TEST_TIME 46 10262
TEST_TIME 47 10282
TEST_TIME 48 10198
TEST_TIME 49 10234
TEST_TIME 50 10193
TEST_TIME 51 10131
TEST_TIME 52 10207
TEST_TIME 53 10133
TEST_TIME 54 10182
TEST_TIME 55 10110
TEST_TIME 56 10175
TEST_TIME 57 10270
TEST_TIME 58 10303
TEST_TIME 59 10258
TEST_TIME 60 10386
TEST_TIME 61 10320
TEST_TIME 62 10384
TEST_TIME 63 10480
TEST_TIME 64 10482
TEST_TIME 65 10381
TEST_TIME 66 10277
TEST_TIME 67 10239
TEST_TIME 68 10136
TEST_TIME 69 10090
TEST_TIME 70 10016
TEST_TIME 71 10039
TEST_TIME 72 9970
TEST_TIME 73 9889
TEST_TIME 74 9805
TEST_TIME 75 9771
TEST_TIME 76 9778
TEST_TIME 77 9715
TEST_TIME 78 9662
TEST_TIME 79 9675
TEST_TIME 80 9682
TEST_TIME 81 9651
TEST_TIME 82 9651
TEST_TIME 83 9760
TEST_TIME 84 9810
TEST_TIME 85 9811
TEST_TIME 86 9796
TEST_TIME 87 9826
TEST_TIME 88 9807
TEST_TIME 89 9875
TEST_TIME 90 9921
TEST_TIME 91 9888
TEST_TIME 92 9797
TEST_TIME 93 9797
TEST_TIME 94 9770
TEST_TIME 95 9731
TEST_TIME 96 9708
TEST_TIME 97 9679
TEST_TIME 98 9654
TEST_TIME 99 9586
TEST_TIME 100 9607
TEST_TIME 101 9645
TEST_TIME 102 9621
TEST_TIME 103 9642
TEST_TIME 104 9625
TEST_TIME 105 9730
TEST_TIME 106 9684
TEST_TIME 107 9727
TEST_TIME 108 9764
TEST_TIME 109 9752
TEST_TIME 110 9741
TEST_TIME 111 9752
TEST_TIME 112 9824
TEST_TIME 113 9742
TEST_TIME 114 9687
TEST_TIME 115 9727
TEST_TIME 116 9692
TEST_TIME 117 9652
TEST_TIME 118 9617
TEST_TIME 119 9559
TEST_TIME 120 9487

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-04-2022 01:40:07 to 01-04-2022 01:40:11.

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 1572282 1572282 0 0.00 0 12767 6 0 0
doc_list_biud 1572282 1572282 0 0.00 0 47487 28 0 0
sp_add_invoice_to_stock 5098 4958 140 2.74 16 172175 18056 0 140
sp_cancel_adding_invoice 5877 5877 0 0.00 4 49802 879 0 0
sp_cancel_client_order 1772 1772 0 0.00 4 11844 343 0 0
sp_cancel_customer_reserve 580653 580653 0 0.00 1 47488 60 0 0
sp_cancel_pay_from_customer 677 677 0 0.00 2 7340 70 0 0
sp_cancel_pay_to_supplier 416 416 0 0.00 3 1725 59 0 0
sp_cancel_supplier_invoice 6523 6512 11 0.16 1 25508 793 0 11
sp_cancel_supplier_order 407 407 0 0.00 7 1173131 107142 0 0
sp_cancel_write_off 11247 11247 0 0.00 1 12932 111 0 0
sp_client_order 20703 20703 0 0.00 3 41625 344 0 0
sp_customer_reserve 468762 468762 0 0.00 0 118260 175 0 0
sp_fill_shopping_cart 31921 31921 0 0.00 1 18589 41 0 0
sp_fill_shopping_cart_clo_res 468759 468759 0 0.00 0 31631 14 0 0
sp_get_clo_for_invoice 4955 4955 0 0.00 0 23497 337 0 0
sp_kill_cost_storno 18217 18217 0 0.00 0 7333 56 0 0
sp_kill_qstorno_ret_qs2qd 1179472 1179472 0 0.00 0 47483 32 0 0
sp_kill_qty_storno 621606 621606 0 0.00 0 47486 69 0 0
sp_lock_selected_doc 21056 21056 0 0.00 0 12040 5 0 0
sp_make_cost_storno 28443 28443 0 0.00 0 12159 48 0 0
sp_multiply_rows_for_pdistr 28443 28443 0 0.00 0 8989 25 0 0
sp_multiply_rows_for_qdistr 20703 20703 0 0.00 0 41615 149 0 0
sp_pay_from_customer 7177 7177 0 0.00 4 7341 95 0 0
sp_pay_to_supplier 6128 6128 0 0.00 5 12772 119 0 0
sp_payment_common 13305 13305 0 0.00 1 6581 21 0 0
sp_qd_handle_on_cancel_clo 1772 1772 0 0.00 0 11835 313 0 0
sp_qd_handle_on_invoice_upd_sts 10832 10832 0 0.00 0 23948 337 0 0
sp_qd_handle_on_reserve_upd_sts 10183 10183 0 0.00 0 9891 63 0 0
sp_reserve_write_off 10183 10183 0 0.00 6 18158 178 0 0
sp_supplier_invoice 5490 5490 0 0.00 7 139624 4375 0 0
sp_supplier_order 5762 5762 0 0.00 5 47653 1254 0 0
srv_aggregate_perf_data 97 96 1 1.03 2450 252075 20312 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 1763 163874 49570 0 0
srv_find_qd_qs_mism 1089759 1089759 0 0.00 0 9753 5 0 0
srv_make_invnt_saldo 607 607 0 0.00 31 43714 1092 0 0
srv_make_money_saldo 903 903 0 0.00 1 464 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 116793 444781 273022 0 0
t$perf-abend:sp_add_invoice_to_stock 140 140 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 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 4958 4958 0 0.00 0 12221 162 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 3135 94 0 0
t$perf-norm:sp_cancel_client_order 1772 1772 0 0.00 0 1726 9 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 3034 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 677 677 0 0.00 0 1001 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 416 416 0 0.00 0 618 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 1627 12 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 20365 1454 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 1778 5 0 0
t$perf-norm:sp_client_order 20703 20703 0 0.00 0 11774 17 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 4134 13 0 0
t$perf-norm:sp_pay_from_customer 7177 7177 0 0.00 0 7652 7 0 0
t$perf-norm:sp_pay_to_supplier 6128 6128 0 0.00 0 4932 8 0 0
t$perf-norm:sp_reserve_write_off 10183 10183 0 0.00 0 7846 10 0 0
t$perf-norm:sp_supplier_invoice 5490 5490 0 0.00 0 7449 20 0 0
t$perf-norm:sp_supplier_order 5762 5762 0 0.00 0 3096 15 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 277 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 1431 24 0 0
t$perf-norm:srv_make_invnt_saldo 607 607 0 0.00 0 2577 24 0 0
t$perf-norm:srv_make_money_saldo 903 903 0 0.00 0 7418 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10190 9910 280 2.74 0 4325 19 0 280
v_all_customers 37948 37948 0 0.00 0 26 1 0 0
v_all_suppliers 31706 31706 0 0.00 0 21 1 0 0
v_all_wares 41406 41406 0 0.00 0 111 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 1727 8 0 0
v_cancel_client_order 3544 3544 0 0.00 0 2768 6 0 0
v_cancel_customer_prepayment 1354 1354 0 0.00 0 1368 3 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 4739 7 0 0
v_cancel_payment_to_supplier 832 832 0 0.00 0 526 4 0 0
v_cancel_supplier_invoice 834 812 22 2.63 0 1890 16 0 22
v_cancel_supplier_order 814 814 0 0.00 0 4744 17 0 0
v_cancel_write_off 2106 2106 0 0.00 0 1678 14 0 0
v_max_id_clo_ord 5760 5760 0 0.00 0 619 2 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 4636 22 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 3680 3 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 2188 5 0 0
v_max_non_paid_realizn 3634 3634 0 0.00 0 4005 5 0 0
v_min_id_clo_ord 5760 5760 0 0.00 0 3160 7 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 1375 5 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 7378 6 0 0
v_min_non_paid_invoice 6128 6128 0 0.00 0 5114 13 0 0
v_min_non_paid_realizn 7177 7177 0 0.00 0 4375 14 0 0
v_our_firm 10544 10544 0 0.00 0 21 1 0 0
v_reserve_write_off 20366 20366 0 0.00 0 4746 7 0 0
x_lock_depdocs_on_canc_invoice 11983 11983 0 0.00 0 12959 123 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 16099 436 0 0
x_make_qstorno_1000_1200 5762 5762 0 0.00 1 47635 1162 0 0
x_make_qstorno_1200_2000 5490 5490 0 0.00 4 139609 4218 0 0
x_make_qstorno_2100_3300 468762 468762 0 0.00 0 118259 158 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-04-2022 01:40:11 to 01-04-2022 01:40:16.

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
23:08:10 1 8192000000 8797664400 8808464384 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:11 34475 8192000000 9912955280 10225840128 999203488 10.871 100 91 182 0 1028644272 6343408 23097376
23:10:48 26231 8192000000 9948482736 10272112640 1035433968 11.221 100 86 172 1 1066877776 9086768 22357040
23:12:20 14541 8192000000 9993364512 10328248320 1075307552 11.603 100 86 172 0 1107133616 10568880 21257184
23:13:37 10602 8192000000 10043258016 10394243072 1129735648 12.119 100 76 149 2 1158198848 10483472 17979728
23:14:55 8657 8192000000 10094723968 10454958080 1182986624 12.618 100 76 149 1 1211998240 11928848 17082768
23:16:08 5417 8192000000 10148270640 10517319680 1226825808 13.025 100 81 162 1 1258714416 13840432 18048176
23:17:17 2276 8192000000 10173406336 10550038528 1261496112 13.344 100 83 166 1 1292664464 12631392 18536960
23:18:21 1804 8192000000 10195828256 10575118336 1280904432 13.521 100 84 168 0 1312727008 12781744 19040832
23:19:23 9309 8192000000 10213132816 10601611264 1300584112 13.701 100 85 167 3 1330470016 11622896 18263008
23:20:33 7895 8192000000 10198156576 10608513024 1309719184 13.784 100 25 50 0 1322658032 7110336 5828512
23:21:43 4415 8192000000 10205042144 10617331712 1319652304 13.874 100 30 60 0 1332886832 6476144 6758384
23:22:48 15651 8192000000 10212361344 10631557120 1324766080 13.920 100 19 38 0 1334904144 6132208 4005856
23:24:06 49225 8192000000 10242667280 10645434368 1329791680 13.965 100 56 113 2 1349475888 5554928 14129280
23:25:55 3506 8192000000 10232032624 10645491712 1331382576 13.980 100 32 62 1 1345947264 6974880 7589808
23:27:02 4637 8192000000 10230102016 10650091520 1335652736 14.018 100 23 46 1 1346605552 5298352 5654464
23:28:13 69079 8192000000 10175611600 10593230848 1243270416 13.176 94 37 74 1 1258151584 5458096 9423072
23:30:22 5334 8192000000 10144595104 10549039104 1226501808 13.022 97 22 44 1 1236831360 5450000 4879552
23:31:30 5827 8192000000 10130947200 10523889664 1216558752 12.930 98 31 62 0 1230344112 7128000 6657360
23:32:36 68692 8192000000 10145257040 10548228096 1220185776 12.963 97 42 82 1 1234651504 4988848 9476880
23:34:45 6134 8192000000 10181287744 10584203264 1283212640 13.542 100 39 78 2 1297838368 5451824 9173904
23:35:52 7387 8192000000 10185267856 10599653376 1295458944 13.654 100 18 36 0 1304501568 5234112 3808512
23:37:00 9577 8192000000 10198303136 10610884608 1303859744 13.730 100 20 41 1 1313973488 5192640 4921104
23:38:20 98676 8192000000 10231153408 10646425600 1323577280 13.909 100 41 82 2 1339020672 5746400 9696992
23:41:05 17450 8192000000 10266270560 10680827904 1354604672 14.189 100 44 88 2 1377504128 13002848 9896608
23:42:25 38491 8192000000 10277782560 10698854400 1367954016 14.309 100 34 66 4 1393484944 17999280 7531648
23:44:06 9642 8192000000 10306596832 10719674368 1382110848 14.435 100 65 129 0 1416843152 19055296 15677008
23:45:18 19190 8192000000 10312463712 10734718976 1386570992 14.475 100 48 95 0 1428946384 31323360 11052032
23:46:46 2186 8192000000 10324596640 10742484992 1391965056 14.523 100 64 128 4 1435074544 27415392 15694096
23:47:50 6592 8192000000 10342578912 10761973760 1403836640 14.629 100 64 128 1 1450699648 31497968 15365040
23:49:05 9961 8192000000 10351704256 10769653760 1404042960 14.631 100 67 134 3 1460525328 40209728 16272640
23:50:20 4825 8192000000 10367899904 10785693696 1413421904 14.714 100 70 141 0 1478012544 46814192 17776448
23:51:36 9406 8192000000 10367294992 10785542144 1412673664 14.708 100 66 132 0 1469453920 40612144 16168112
23:52:48 1760 8192000000 10365018720 10773913600 1396678800 14.565 100 82 162 2 1465366016 48091968 20595248
23:53:53 3879 8192000000 10361507056 10774474752 1399452592 14.590 100 82 165 2 1470761744 51167552 20141600
23:55:02 3084 8192000000 10362760672 10768248832 1382323760 14.437 100 81 162 1 1464895392 62059488 20512144
23:56:09 3274 8192000000 10364199344 10766127104 1381969680 14.434 100 85 170 0 1469474576 65585728 21919168
23:57:21 4706 8192000000 10373327424 10786369536 1384057888 14.453 100 56 109 1 1472077744 73780192 14239664
23:58:28 6723 8192000000 10379334736 10796417024 1393390896 14.536 99 53 106 1 1474682848 67177920 14114032
23:59:38 13579 8192000000 10362635648 10787446784 1401276544 14.606 100 48 97 1 1469460912 54885120 13299248
00:01:03 12471 8192000000 10354303824 10785853440 1382676080 14.440 99 35 70 2 1447064400 54256240 10132080
00:02:23 23737 8192000000 10352507504 10789814272 1380472144 14.421 99 28 56 1 1445196032 56889520 7834368
00:04:04 19078 8192000000 10329716960 10770268160 1369441696 14.322 100 20 40 0 1432985696 57057664 6486336
00:05:26 30859 8192000000 10295809952 10748174336 1332352144 13.988 98 16 32 0 1381870656 44750624 4767888
00:06:59 70128 8192000000 10256347264 10694090752 1307525456 13.764 96 13 26 0 1320840432 10372144 2942832
00:09:15 23279 8192000000 10275938512 10717675520 1359272544 14.231 99 12 24 0 1374341296 12303648 2765104
00:10:53 149286 8192000000 10297233664 10743734272 1383066064 14.444 100 35 69 3 1399410416 7601568 8742784
00:14:28 56176 8192000000 10316405744 10756780032 1383042976 14.444 99 24 48 0 1399196368 10556160 5597232
00:16:30 84706 8192000000 10281154128 10722603008 1360415008 14.241 97 18 36 0 1372816528 8241552 4159968
00:18:56 20580 8192000000 10319409776 10755252224 1393410000 14.536 100 29 56 1 1407261104 7569808 6281296
00:20:17 160794 8192000000 10290658096 10748190720 1369346176 14.321 98 22 44 0 1385592576 11473008 4773392
00:24:01 53226 8192000000 10328544672 10783133696 1415462912 14.732 100 31 63 1 1438185232 15121600 7600720
00:25:57 74256 8192000000 10300570768 10772930560 1390638192 14.512 99 13 26 0 1415465136 21693760 3133184
00:28:23 41029 8192000000 10305375904 10768674816 1402049376 14.613 100 27 54 1 1418204336 9953312 6201648
00:30:12 79695 8192000000 10295053120 10756632576 1360837840 14.245 97 16 32 1 1376803232 12351200 3614192
00:32:33 42327 8192000000 10304491104 10760302592 1376560592 14.386 98 17 34 0 1389133360 8814000 3758768
00:34:17 37440 8192000000 10300326320 10757087232 1398492704 14.582 100 17 34 0 1412937888 9902368 4542816
00:36:04 49098 8192000000 10289790496 10747817984 1345862096 14.110 96 13 26 0 1359045248 10025328 3157824
00:37:53 22925 8192000000 10305797040 10753540096 1386616800 14.476 99 42 84 1 1410477728 14204544 9656384
00:39:19 52147 8192000000 10297834112 10754584576 1358510832 14.224 97 17 34 0 1375235312 12913600 3810880
00:41:12 52812 8192000000 10285138992 10746589184 1355811808 14.200 98 11 22 0 1372262096 13906576 2543712
00:43:05 95224 8192000000 10277739424 10735853568 1379759200 14.414 100 23 44 1 1394670960 9771232 5140528
00:45:43 77780 8192000000 10259447360 10717216768 1313664064 13.819 95 7 14 0 1324946592 9643936 1638592
00:48:27 72788 8192000000 10274216672 10739593216 1365654992 14.288 99 16 32 0 1381632624 12408736 3568896
00:50:41 98980 8192000000 10261399712 10714263552 1320363136 13.880 96 21 42 1 1332560992 7648464 4549392
00:53:32 39994 8192000000 10270680928 10720870400 1360573696 14.243 99 27 55 1 1378804976 12149168 6082112
00:55:12 80564 8192000000 10282273168 10742681600 1383925888 14.452 100 22 43 0 1398570432 9819520 4825024
00:58:02 86977 8192000000 10273031952 10728284160 1308845456 13.776 94 23 46 2 1328677456 13819024 6012976
01:00:40 76750 8192000000 10289595632 10753716224 1357243744 14.213 98 22 44 0 1381102208 18562464 5296000
01:03:01 87480 8192000000 10299481696 10744492032 1341567216 14.072 97 26 52 1 1358827728 11572624 5687888
01:06:01 22000 8192000000 10295391200 10747924480 1384933360 14.461 100 42 84 0 1404475888 9657536 9884992
01:07:26 163874 8192000000 10276813072 10738978816 1290099584 13.605 93 7 14 1 1303945584 12206224 1639776
01:11:39 42727 8192000000 10288309936 10752016384 1382040976 14.435 100 22 44 0 1400040992 12617120 5382896
01:13:22 78504 8192000000 10246580400 10708463616 1328213504 13.951 95 11 21 0 1339837568 9219056 2405008
01:15:41 74646 8192000000 10284511008 10740527104 1358029744 14.220 98 17 35 1 1371538064 10157456 3350864
01:18:05 91891 8192000000 10281586960 10765955072 1356896560 14.209 98 5 10 0 1379644784 21533280 1214944
01:20:38 35988 8192000000 10291888752 10744840192 1347576080 14.126 97 17 34 0 1363034960 11636816 3822064
01:22:14 69760 8192000000 10290047376 10745720832 1356007248 14.201 98 25 50 0 1378932832 16532608 6392976
01:24:41 45573 8192000000 10295884816 10748043264 1359198448 14.230 98 29 57 1 1379882736 13928608 6755680
01:26:32 61819 8192000000 10256347104 10715017216 1324053312 13.913 97 16 32 0 1340994496 13593104 3348080
01:28:34 21077 8192000000 10269066000 10705330176 1306626928 13.755 96 38 76 0 1326447264 11556160 8264176
01:29:55 63648 8192000000 10253272288 10719481856 1317366992 13.853 97 26 52 1 1337235280 13896640 5971648
01:32:04 82578 8192000000 10247877664 10694434816 1305107808 13.742 97 35 68 1 1327575328 14216320 8251200
01:35:31 63860 8192000000 10229586512 10688614400 1305864528 13.749 97 9 18 1 1315028192 6776400 2387264
01:37:46 29309 8192000000 9237836032 9567756288 367916528 4.298 28 5 11 0 373283952 4159968 1207456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-04-2022 01:40:17 to 01-04-2022 01:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 140 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-04-2022 01:40:33 to 01-04-2022 01:40:35.

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          242764
MON$OLDEST_ACTIVE               242765
MON$OLDEST_SNAPSHOT             242765
MON$NEXT_TRANSACTION            242765
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                       2044576
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:793: 
:794: mscs-test-161.budzdorov.ru    Fri Apr  1 01:38:16 2022
:795:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:796: 
:797: 
:798: mscs-test-161.budzdorov.ru    Fri Apr  1 01:38:18 2022
:799:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:800: 
--- end of diff output ---

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

01.04.22 01:40:36. 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-04-01 01:40:38 No severe PSQL-related problems occured
01.04.22 01:40:40. End of report.