Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.08.2022 22:40
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 16069324 kB
MemAvailable 30952864 kB
Buffers 0 kB
Cached 15680344 kB
SwapCached 2508 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.11
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-08-11 22:38:26
DTS_END                         2022-08-11 22:38:26.7300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51100
TRN_ID                          234838
ATT_ID                          630
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: 9075.88 21379 1089106
customer order: creation 164.88 362 19785
customer order: refuse 14.04 352 1685
order to supplier: creation 43.78 1294 5253
order to supplier: removal 3.63 89941 435
invoice (draft): creation 44.39 3670 5327
invoice (draft): removal 52.52 752 6302
invoice accept: apply 39.78 17871 4773
invoice accept: cancel 47.40 704 5688
customer reserve: creation 3764.49 172 451739
customer reserve: removal 4622.19 55 554663
realization accept: apply 78.50 207 9420
realization accept: cancel 79.22 95 9507
payment from customer: creation 55.79 119 6695
payment from customer: removal 5.28 115 634
payment to supplier: creation 46.08 123 5529
payment to supplier: removal 3.08 84 369
service: total inventory turnovers 4.39 1008 527
service: total monetary turnovers 5.75 27 690
service: aggregate perf. data 0.68 24152 81
service: refresh index statistics 0.03 286486 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-08-2022 22:40:50 to 11-08-2022 22:40:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6163
TEST_TIME 2 3958
TEST_TIME 3 3534
TEST_TIME 4 4309
TEST_TIME 5 5042
TEST_TIME 6 4953
TEST_TIME 7 5022
TEST_TIME 8 5193
TEST_TIME 9 5129
TEST_TIME 10 4980
TEST_TIME 11 5369
TEST_TIME 12 5743
TEST_TIME 13 5963
TEST_TIME 14 6185
TEST_TIME 15 6428
TEST_TIME 16 6987
TEST_TIME 17 7269
TEST_TIME 18 7308
TEST_TIME 19 7593
TEST_TIME 20 7827
TEST_TIME 21 7922
TEST_TIME 22 8328
TEST_TIME 23 8513
TEST_TIME 24 8691
TEST_TIME 25 9173
TEST_TIME 26 9371
TEST_TIME 27 9490
TEST_TIME 28 10245
TEST_TIME 29 10373
TEST_TIME 30 10184
TEST_TIME 31 9940
TEST_TIME 32 9931
TEST_TIME 33 9831
TEST_TIME 34 9889
TEST_TIME 35 9807
TEST_TIME 36 9703
TEST_TIME 37 9607
TEST_TIME 38 9532
TEST_TIME 39 9549
TEST_TIME 40 9487
TEST_TIME 41 9577
TEST_TIME 42 9527
TEST_TIME 43 9374
TEST_TIME 44 9313
TEST_TIME 45 9190
TEST_TIME 46 9247
TEST_TIME 47 9180
TEST_TIME 48 9129
TEST_TIME 49 9049
TEST_TIME 50 8970
TEST_TIME 51 8896
TEST_TIME 52 9004
TEST_TIME 53 9039
TEST_TIME 54 8962
TEST_TIME 55 9115
TEST_TIME 56 9127
TEST_TIME 57 9138
TEST_TIME 58 9118
TEST_TIME 59 9313
TEST_TIME 60 9302
TEST_TIME 61 9398
TEST_TIME 62 9503
TEST_TIME 63 9500
TEST_TIME 64 9535
TEST_TIME 65 9487
TEST_TIME 66 9386
TEST_TIME 67 9310
TEST_TIME 68 9246
TEST_TIME 69 9170
TEST_TIME 70 9239
TEST_TIME 71 9207
TEST_TIME 72 9183
TEST_TIME 73 9108
TEST_TIME 74 9126
TEST_TIME 75 9129
TEST_TIME 76 9111
TEST_TIME 77 9056
TEST_TIME 78 9005
TEST_TIME 79 9051
TEST_TIME 80 9033
TEST_TIME 81 9041
TEST_TIME 82 9065
TEST_TIME 83 9170
TEST_TIME 84 9208
TEST_TIME 85 9168
TEST_TIME 86 9169
TEST_TIME 87 9201
TEST_TIME 88 9231
TEST_TIME 89 9258
TEST_TIME 90 9217
TEST_TIME 91 9269
TEST_TIME 92 9196
TEST_TIME 93 9203
TEST_TIME 94 9165
TEST_TIME 95 9138
TEST_TIME 96 9102
TEST_TIME 97 9070
TEST_TIME 98 9029
TEST_TIME 99 9012
TEST_TIME 100 9008
TEST_TIME 101 8986
TEST_TIME 102 9030
TEST_TIME 103 9035
TEST_TIME 104 9052
TEST_TIME 105 9124
TEST_TIME 106 9101
TEST_TIME 107 9167
TEST_TIME 108 9158
TEST_TIME 109 9190
TEST_TIME 110 9271
TEST_TIME 111 9268
TEST_TIME 112 9276
TEST_TIME 113 9235
TEST_TIME 114 9181
TEST_TIME 115 9175
TEST_TIME 116 9185
TEST_TIME 117 9167
TEST_TIME 118 9123
TEST_TIME 119 9133
TEST_TIME 120 9080

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-08-2022 22:40:53 to 11-08-2022 22:40:56.

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 1505486 1505486 0 0.00 0 33763 6 0 0
doc_list_biud 1505483 1505483 0 0.00 0 30601 26 0 0
sp_add_invoice_to_stock 4919 4773 146 2.96 54 170095 17872 0 146
sp_cancel_adding_invoice 5688 5688 0 0.00 5 58553 705 0 0
sp_cancel_client_order 1685 1685 0 0.00 4 21787 353 0 0
sp_cancel_customer_reserve 554663 554663 0 0.00 0 26112 55 0 0
sp_cancel_pay_from_customer 634 634 0 0.00 3 7217 116 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 2 4599 84 0 0
sp_cancel_supplier_invoice 6318 6302 16 0.25 1 26264 753 0 16
sp_cancel_supplier_order 435 435 0 0.00 7 1006221 89941 0 0
sp_cancel_write_off 9507 9507 0 0.00 1 10841 95 0 0
sp_client_order 19785 19785 0 0.00 3 25539 363 0 0
sp_customer_reserve 451739 451739 0 0.00 0 113684 172 0 0
sp_fill_shopping_cart 30360 30360 0 0.00 1 18891 38 0 0
sp_fill_shopping_cart_clo_res 451731 451731 0 0.00 0 27244 15 0 0
sp_get_clo_for_invoice 4765 4765 0 0.00 0 28301 317 0 0
sp_kill_cost_storno 16198 16198 0 0.00 0 8918 49 0 0
sp_kill_qstorno_ret_qs2qd 1125570 1125570 0 0.00 0 26251 30 0 0
sp_kill_qty_storno 592464 592464 0 0.00 0 30598 65 0 0
sp_lock_selected_doc 19672 19672 0 0.00 0 8496 8 0 0
sp_make_cost_storno 26409 26409 0 0.00 0 32347 69 0 0
sp_multiply_rows_for_pdistr 26409 26409 0 0.00 0 9085 28 0 0
sp_multiply_rows_for_qdistr 19785 19785 0 0.00 0 16814 154 0 0
sp_pay_from_customer 6695 6695 0 0.00 4 14528 120 0 0
sp_pay_to_supplier 5529 5529 0 0.00 4 12149 124 0 0
sp_payment_common 12222 12222 0 0.00 1 14514 36 0 0
sp_qd_handle_on_cancel_clo 1685 1685 0 0.00 0 21780 325 0 0
sp_qd_handle_on_invoice_upd_sts 10452 10452 0 0.00 0 30597 301 0 0
sp_qd_handle_on_reserve_upd_sts 9420 9420 0 0.00 0 13512 66 0 0
sp_reserve_write_off 9420 9420 0 0.00 6 16288 207 0 0
sp_supplier_invoice 5327 5327 0 0.00 4 115367 3670 0 0
sp_supplier_order 5253 5253 0 0.00 4 52829 1294 0 0
srv_aggregate_perf_data 82 81 1 1.21 3137 300029 24152 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 931 160106 50739 0 0
srv_find_qd_qs_mism 1042368 1042368 0 0.00 0 23958 5 0 0
srv_make_invnt_saldo 527 527 0 0.00 39 31908 1008 0 0
srv_make_money_saldo 690 690 0 0.00 0 4832 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 124095 469477 286487 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 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 4773 4773 0 0.00 0 16286 220 0 0
t$perf-norm:sp_cancel_adding_invoice 364 364 0 0.00 1 5845 110 0 0
t$perf-norm:sp_cancel_client_order 1685 1685 0 0.00 0 4773 15 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 3714 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 634 634 0 0.00 0 1305 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 103 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 395 395 0 0.00 0 618 15 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 18101 1410 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 7580 19 0 0
t$perf-norm:sp_client_order 19785 19785 0 0.00 0 12745 19 0 0
t$perf-norm:sp_customer_reserve 952 952 0 0.00 0 5877 26 0 0
t$perf-norm:sp_pay_from_customer 6695 6695 0 0.00 0 9853 12 0 0
t$perf-norm:sp_pay_to_supplier 5529 5529 0 0.00 0 3743 11 0 0
t$perf-norm:sp_reserve_write_off 9420 9420 0 0.00 0 9259 14 0 0
t$perf-norm:sp_supplier_invoice 5327 5327 0 0.00 0 11255 30 0 0
t$perf-norm:sp_supplier_order 5253 5253 0 0.00 0 7617 17 0 0
t$perf-norm:srv_aggregate_perf_data 81 81 0 0.00 0 98 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 5819 99 0 0
t$perf-norm:srv_make_invnt_saldo 527 527 0 0.00 0 963 21 0 0
t$perf-norm:srv_make_money_saldo 690 690 0 0.00 0 1833 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 9818 9526 292 2.97 0 5248 21 0 292
v_all_customers 35586 35586 0 0.00 0 14 0 0 0
v_all_suppliers 29310 29310 0 0.00 0 18 1 0 0
v_all_wares 39562 39562 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 728 728 0 0.00 0 50 5 0 0
v_cancel_client_order 3370 3370 0 0.00 0 2714 5 0 0
v_cancel_customer_prepayment 1268 1268 0 0.00 0 3764 14 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 5636 9 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 4570 15 0 0
v_cancel_supplier_invoice 822 790 32 3.89 0 498 12 0 32
v_cancel_supplier_order 870 870 0 0.00 0 1419 8 0 0
v_cancel_write_off 1858 1858 0 0.00 0 8330 19 0 0
v_max_id_clo_ord 5253 5253 0 0.00 0 1162 2 0 0
v_max_id_clo_res 952 952 0 0.00 0 3144 15 0 0
v_max_id_ord_sup 5326 5326 0 0.00 0 6447 5 0 0
v_max_non_paid_invoice 1451 1451 0 0.00 0 916 2 0 0
v_max_non_paid_realizn 3667 3667 0 0.00 0 14509 8 0 0
v_min_id_clo_ord 5253 5253 0 0.00 0 6620 12 0 0
v_min_id_clo_res 952 952 0 0.00 0 5507 11 0 0
v_min_id_ord_sup 5326 5326 0 0.00 0 4703 6 0 0
v_min_non_paid_invoice 5527 5527 0 0.00 0 7667 22 0 0
v_min_non_paid_realizn 6695 6695 0 0.00 0 8729 32 0 0
v_our_firm 10032 10032 0 0.00 0 19 1 0 0
v_reserve_write_off 18840 18840 0 0.00 0 8289 6 0 0
x_lock_depdocs_on_canc_invoice 11595 11595 0 0.00 0 25533 129 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 17936 347 0 0
x_make_qstorno_1000_1200 5253 5253 0 0.00 1 52815 1227 0 0
x_make_qstorno_1200_2000 5327 5327 0 0.00 2 115356 3527 0 0
x_make_qstorno_2100_3300 451739 451739 0 0.00 0 113683 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-08-2022 22:40:57 to 11-08-2022 22:41:01.

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
20:08:30 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:31 38566 8192000000 9910226496 10223833088 989366736 10.775 100 93 186 0 1019776000 6011856 24397408
20:11:13 24103 8192000000 9954023216 10278346752 1033239040 11.200 100 91 182 0 1065040832 8234064 23567728
20:12:41 15041 8192000000 9979115312 10310991872 1062322256 11.479 100 87 174 0 1094308224 10068656 21917312
20:14:00 7755 8192000000 10028553728 10371600384 1109937232 11.932 100 87 170 1 1141492224 10985456 20569536
20:15:14 3606 8192000000 10077277584 10433294336 1161626464 12.418 100 79 158 0 1192157088 12699808 17830816
20:16:25 2740 8192000000 10121595104 10488709120 1212008608 12.888 100 75 150 0 1241101296 12512288 16580400
20:17:30 2880 8192000000 10161947840 10538770432 1249821696 13.237 100 80 160 1 1281141584 13516864 17803024
20:18:35 871 8192000000 10200644176 10581655552 1286276880 13.570 100 88 175 0 1318861552 12269104 20315568
20:19:38 2525 8192000000 10204305664 10601091072 1306625488 13.755 100 63 126 0 1331210976 10884496 13700992
20:20:43 1698 8192000000 10212700480 10610876416 1307849728 13.767 100 68 137 0 1332513984 9003328 15660928
20:21:53 3102 8192000000 10212322576 10616737792 1311159808 13.797 100 44 86 0 1329631568 8654992 9816768
20:22:57 9178 8192000000 10203903424 10621108224 1318655808 13.865 100 23 44 0 1329870912 6366048 4849056
20:24:08 8638 8192000000 10210945232 10630840320 1327502608 13.945 100 27 54 0 1340151040 6198896 6449536
20:25:17 29426 8192000000 10237504336 10644385792 1332937088 13.994 100 58 115 1 1353723040 7713376 13072576
20:26:47 4687 8192000000 10232721360 10644934656 1332005920 13.985 100 28 57 1 1343944528 4922816 7015792
20:27:52 8491 8192000000 10205153616 10623873024 1307271760 13.761 99 19 38 0 1317790208 5750320 4768128
20:29:04 59585 8192000000 10161231760 10573389824 1239321648 13.140 96 41 79 0 1256190656 6407248 10461760
20:31:04 5729 8192000000 10143924640 10543869952 1198169200 12.759 94 17 34 1 1206950448 4914512 3866736
20:32:12 9278 8192000000 10162738944 10561765376 1254720000 13.282 99 27 54 0 1267277936 6579712 5978224
20:33:22 15023 8192000000 10154324352 10555310080 1254661568 13.281 99 30 60 1 1265670352 4420848 6587936
20:34:38 24941 8192000000 10173032640 10581929984 1280737408 13.520 100 19 39 1 1290769584 5556432 4475744
20:36:03 6838 8192000000 10193623840 10602184704 1300384048 13.699 100 24 48 1 1312220816 5793664 6043104
20:37:14 7297 8192000000 10194454832 10610745344 1307099024 13.760 100 19 38 1 1316921392 5639024 4183344
20:38:22 21839 8192000000 10231949600 10644508672 1331605680 13.982 100 17 34 0 1340434160 5222016 3606464
20:39:51 122651 8192000000 10260529216 10685284352 1353500208 14.179 100 35 71 1 1369567744 8055216 8012320
20:42:58 32992 8192000000 10279861904 10707038208 1371519296 14.341 100 33 66 1 1390568656 10947904 8101456
20:44:35 17967 8192000000 10287501216 10708520960 1379537392 14.412 100 51 101 0 1402715808 11708144 11470272
20:46:08 13582 8192000000 10317788096 10735575040 1397741456 14.575 100 57 113 0 1426777616 15260544 13775616
20:47:27 5298 8192000000 10335488848 10758098944 1410274096 14.686 100 48 95 0 1443224768 21132192 11818480
20:48:37 8569 8192000000 10346609936 10766782464 1417503424 14.751 100 60 117 1 1457988528 26610640 13874464
20:49:51 3239 8192000000 10359392704 10770874368 1416789616 14.744 100 77 152 0 1464454032 29452480 18211936
20:51:01 930 8192000000 10356935792 10765844480 1411331600 14.696 100 85 171 1 1463608656 31378624 20898432
20:52:09 3731 8192000000 10349836608 10767388672 1384308112 14.455 97 70 138 1 1439766368 37236640 18221616
20:53:18 5294 8192000000 10349640480 10769149952 1388771520 14.495 98 62 124 0 1447672816 42694384 16206912
20:54:26 3723 8192000000 10346598528 10762862592 1392295008 14.526 100 67 130 0 1451546672 42795088 16456576
20:55:37 7723 8192000000 10326358864 10754248704 1387314288 14.482 100 40 79 0 1436742864 38715392 10713184
20:56:51 25733 8192000000 10322016416 10747129856 1366856992 14.299 98 49 95 1 1417197296 37946480 12393824
20:58:22 2403 8192000000 10324964096 10745597952 1376503968 14.385 100 59 117 0 1433812304 42326672 14981664
20:59:28 29655 8192000000 10321463856 10745856000 1372357760 14.348 100 39 78 0 1429390080 46095152 10937168
21:01:06 11351 8192000000 10298562128 10726547456 1344895200 14.102 99 28 55 0 1394279120 41670560 7713360
21:02:21 20589 8192000000 10298122336 10730188800 1351373584 14.160 100 22 44 0 1401394000 43758240 6262176
21:03:43 25184 8192000000 10291946384 10725871616 1352394816 14.169 99 21 42 0 1388287984 30540880 5352288
21:05:14 30916 8192000000 10291397328 10730176512 1352578864 14.171 99 20 40 0 1392219104 34268448 5371792
21:06:50 45837 8192000000 10281939264 10729533440 1364259552 14.276 100 15 31 0 1390312736 22587504 3465680
21:08:39 77211 8192000000 10251711104 10688544768 1308240256 13.770 96 40 80 0 1327645168 9011472 10393440
21:11:01 44127 8192000000 10267823040 10704068608 1333483936 13.999 98 40 78 1 1358242608 16231248 8527424
21:12:47 57000 8192000000 10262846896 10725457920 1363373184 14.268 100 10 20 0 1380090720 14710144 2007392
21:14:44 65128 8192000000 10271552144 10726666240 1353203120 14.176 98 12 24 0 1371317168 15525040 2589008
21:16:50 160083 8192000000 10280123040 10737676288 1348107472 14.130 98 13 26 1 1364844320 14147184 2589664
21:20:34 112302 8192000000 10275691504 10723319808 1368602672 14.315 99 21 42 0 1380636064 6988064 5045328
21:23:30 30422 8192000000 10302001760 10745655296 1391570912 14.520 100 37 73 0 1407990144 7620896 8798336
21:25:12 46521 8192000000 10259228208 10711515136 1346754304 14.118 97 13 26 0 1356332816 6848160 2730352
21:27:02 68830 8192000000 10284321776 10742259712 1373636704 14.360 99 16 32 1 1384393920 6947760 3809456
21:29:11 80312 8192000000 10288970304 10760822784 1391119792 14.516 100 24 49 0 1409604592 12603664 5881136
21:31:40 65306 8192000000 10309254624 10758893568 1378087904 14.399 99 14 28 0 1395355408 13865408 3402096
21:33:53 116563 8192000000 10303802624 10749485056 1334844816 14.011 95 7 14 0 1350165824 13967952 1353056
21:36:51 77740 8192000000 10316507440 10793644032 1394216000 14.543 100 16 32 0 1418154304 20683616 3254688
21:39:32 75075 8192000000 10298783392 10768965632 1375647424 14.378 98 7 14 0 1393595136 16405296 1542416
21:41:47 37676 8192000000 10302470448 10747416576 1346106784 14.112 96 16 32 0 1360996416 11326992 3562640
21:43:26 89213 8192000000 10294814208 10753998848 1362458480 14.259 98 24 48 0 1379040992 11082208 5500304
21:46:14 82726 8192000000 10292065744 10754506752 1385340368 14.464 99 13 26 0 1399527664 11171952 3015344
21:48:37 62914 8192000000 10281346864 10738659328 1345665408 14.108 96 10 20 0 1355071408 7238944 2167056
21:50:41 83676 8192000000 10294706720 10748387328 1347729888 14.127 97 22 44 0 1365237472 12646928 4860656
21:53:11 24885 8192000000 10254323920 10695315456 1316738000 13.847 96 33 65 1 1332984464 8840784 7405680
21:54:37 41170 8192000000 10228528512 10670182400 1287522352 13.582 95 23 46 0 1300403792 7616816 5264624
21:56:23 79061 8192000000 10231113728 10693484544 1303560512 13.728 97 6 12 0 1314302416 9671648 1070256
21:58:46 44014 8192000000 10250196384 10692726784 1314983312 13.831 97 29 58 0 1331576800 9679920 6913568
22:00:30 87712 8192000000 10243355920 10713673728 1312239264 13.806 97 18 36 0 1331476624 15075040 4162320
22:04:08 8252 8192000000 10259266208 10687827968 1340312432 14.060 100 47 94 0 1362158576 11805456 10040688
22:05:19 24524 8192000000 10279820736 10715656192 1358682176 14.226 100 43 86 0 1380489888 12600736 9206976
22:06:47 59560 8192000000 10268662304 10718842880 1336787536 14.028 98 13 26 0 1349159248 9691488 2680224
22:08:53 39024 8192000000 10279657888 10735480832 1377073088 14.390 100 29 58 0 1397748768 14449632 6226048
22:10:32 127502 8192000000 10277149920 10742018048 1370673216 14.333 99 16 32 0 1383252544 8247152 4332176
22:13:40 51710 8192000000 10267456176 10732154880 1360376080 14.241 98 14 28 1 1369228352 5969280 2882992
22:15:40 68397 8192000000 10275680928 10741714944 1375455824 14.376 99 12 24 0 1385706400 7661120 2589456
22:17:49 47432 8192000000 10282127472 10739605504 1362000176 14.255 98 13 26 0 1371586048 6883520 2702352
22:19:41 60143 8192000000 10301213264 10763489280 1393034512 14.533 100 27 54 0 1410734064 11476192 6223360
22:21:54 51523 8192000000 10291599520 10752663552 1380528336 14.421 99 19 38 0 1396048240 10763664 4756240
22:23:54 102682 8192000000 10306030160 10770231296 1400288016 14.598 100 16 32 0 1417755408 13904272 3563120
22:26:39 64202 8192000000 10281011696 10738319360 1347962192 14.129 96 28 56 0 1365344480 10737504 6644784
22:28:46 78929 8192000000 10294715136 10745184256 1342400608 14.079 97 12 24 0 1358696688 14022240 2273840
22:31:07 68993 8192000000 10274088320 10724921344 1337192000 14.032 97 41 81 1 1355668464 10086688 8389776
22:33:52 12071 8192000000 10285130320 10735812608 1377522256 14.394 100 42 84 1 1395966944 8872608 9572080
22:35:08 75228 8192000000 10254023024 10717876224 1343423680 14.088 98 8 16 0 1355343600 10460176 1459744
22:37:24 56721 8192000000 10274324864 10730913792 1351654400 14.162 98 17 34 0 1368551200 12845648 4051152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-08-2022 22:41:03 to 11-08-2022 22:41:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 146 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-08-2022 22:41:19 to 11-08-2022 22:41:21.

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          234837
MON$OLDEST_ACTIVE               236218
MON$OLDEST_SNAPSHOT             236218
MON$NEXT_TRANSACTION            236218
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                       2016448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33616 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33616 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33616 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33616 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 ---
:104: 
:105: mscs-test-161.budzdorov.ru    Thu Aug 11 22:38:47 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Thu Aug 11 22:38:50 2022
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:111: 
--- end of diff output ---

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

11.08.22 22:41:22. 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-08-11 22:41:24 No severe PSQL-related problems occured
11.08.22 22:41:26. End of report.