Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.04.2022 07: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 16128980 kB
MemAvailable 31517596 kB
Buffers 0 kB
Cached 15547592 kB
SwapCached 2364 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-10 07:37:10
DTS_END                         2022-04-10 07:37:10.3150
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(68:17)
IP                              ::1/57306
TRN_ID                          224691
ATT_ID                          608
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.53 24791 1089064
customer order: creation 175.76 438 21091
customer order: refuse 15.40 419 1848
order to supplier: creation 45.54 1443 5465
order to supplier: removal 3.44 149522 413
invoice (draft): creation 45.78 3325 5493
invoice (draft): removal 48.14 1079 5777
invoice accept: apply 42.40 25539 5088
invoice accept: cancel 43.58 1274 5229
customer reserve: creation 4014.46 236 481735
customer reserve: removal 4344.85 91 521382
realization accept: apply 83.67 293 10040
realization accept: cancel 78.54 142 9425
payment from customer: creation 60.46 182 7255
payment from customer: removal 5.92 122 710
payment to supplier: creation 50.88 222 6106
payment to supplier: removal 3.44 162 413
service: total inventory turnovers 4.37 3836 524
service: total monetary turnovers 7.90 357 948
service: aggregate perf. data 0.98 16048 118
service: refresh index statistics 0.03 291095 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-04-2022 07:38:28 to 10-04-2022 07:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9988
TEST_TIME 2 8177
TEST_TIME 3 7815
TEST_TIME 4 6879
TEST_TIME 5 6489
TEST_TIME 6 6553
TEST_TIME 7 6825
TEST_TIME 8 7020
TEST_TIME 9 6983
TEST_TIME 10 6863
TEST_TIME 11 7088
TEST_TIME 12 7008
TEST_TIME 13 7085
TEST_TIME 14 7105
TEST_TIME 15 7580
TEST_TIME 16 7646
TEST_TIME 17 7684
TEST_TIME 18 7953
TEST_TIME 19 7978
TEST_TIME 20 8326
TEST_TIME 21 8321
TEST_TIME 22 8735
TEST_TIME 23 8647
TEST_TIME 24 8603
TEST_TIME 25 8657
TEST_TIME 26 8850
TEST_TIME 27 9059
TEST_TIME 28 9452
TEST_TIME 29 9831
TEST_TIME 30 10058
TEST_TIME 31 10300
TEST_TIME 32 10477
TEST_TIME 33 10362
TEST_TIME 34 10683
TEST_TIME 35 10666
TEST_TIME 36 10517
TEST_TIME 37 10915
TEST_TIME 38 11000
TEST_TIME 39 10867
TEST_TIME 40 10732
TEST_TIME 41 10519
TEST_TIME 42 10493
TEST_TIME 43 10617
TEST_TIME 44 10532
TEST_TIME 45 10449
TEST_TIME 46 10388
TEST_TIME 47 10443
TEST_TIME 48 10436
TEST_TIME 49 10374
TEST_TIME 50 10220
TEST_TIME 51 10042
TEST_TIME 52 9999
TEST_TIME 53 9956
TEST_TIME 54 9892
TEST_TIME 55 9752
TEST_TIME 56 9732
TEST_TIME 57 9736
TEST_TIME 58 9693
TEST_TIME 59 9689
TEST_TIME 60 9697
TEST_TIME 61 9662
TEST_TIME 62 9619
TEST_TIME 63 9607
TEST_TIME 64 9494
TEST_TIME 65 9476
TEST_TIME 66 9502
TEST_TIME 67 9459
TEST_TIME 68 9532
TEST_TIME 69 9549
TEST_TIME 70 9557
TEST_TIME 71 9503
TEST_TIME 72 9539
TEST_TIME 73 9512
TEST_TIME 74 9624
TEST_TIME 75 9677
TEST_TIME 76 9656
TEST_TIME 77 9624
TEST_TIME 78 9783
TEST_TIME 79 9738
TEST_TIME 80 9737
TEST_TIME 81 9648
TEST_TIME 82 9623
TEST_TIME 83 9562
TEST_TIME 84 9513
TEST_TIME 85 9486
TEST_TIME 86 9454
TEST_TIME 87 9405
TEST_TIME 88 9394
TEST_TIME 89 9393
TEST_TIME 90 9354
TEST_TIME 91 9343
TEST_TIME 92 9412
TEST_TIME 93 9373
TEST_TIME 94 9369
TEST_TIME 95 9380
TEST_TIME 96 9344
TEST_TIME 97 9377
TEST_TIME 98 9349
TEST_TIME 99 9362
TEST_TIME 100 9348
TEST_TIME 101 9378
TEST_TIME 102 9437
TEST_TIME 103 9400
TEST_TIME 104 9421
TEST_TIME 105 9473
TEST_TIME 106 9389
TEST_TIME 107 9350
TEST_TIME 108 9295
TEST_TIME 109 9288
TEST_TIME 110 9269
TEST_TIME 111 9252
TEST_TIME 112 9226
TEST_TIME 113 9178
TEST_TIME 114 9191
TEST_TIME 115 9155
TEST_TIME 116 9155
TEST_TIME 117 9204
TEST_TIME 118 9186
TEST_TIME 119 9160
TEST_TIME 120 9109

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-04-2022 07:38:32 to 10-04-2022 07:38:35.

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 1531959 1531959 0 0.00 0 27716 13 0 0
doc_list_biud 1531940 1531940 0 0.00 0 32670 37 0 0
sp_add_invoice_to_stock 5174 5088 86 1.66 40 204880 25539 0 86
sp_cancel_adding_invoice 5229 5229 0 0.00 6 74058 1274 0 0
sp_cancel_client_order 1848 1848 0 0.00 5 18970 420 0 0
sp_cancel_customer_reserve 521382 521382 0 0.00 1 26658 92 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 4 5615 122 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 4868 162 0 0
sp_cancel_supplier_invoice 5788 5777 11 0.19 3 24991 1079 0 11
sp_cancel_supplier_order 413 413 0 0.00 8 1529122 149523 0 0
sp_cancel_write_off 9425 9425 0 0.00 2 9464 142 0 0
sp_client_order 21091 21091 0 0.00 6 26185 439 0 0
sp_customer_reserve 481735 481735 0 0.00 0 88865 237 0 0
sp_fill_shopping_cart 32037 32037 0 0.00 1 27512 86 0 0
sp_fill_shopping_cart_clo_res 481692 481692 0 0.00 0 16515 18 0 0
sp_get_clo_for_invoice 5041 5041 0 0.00 0 32189 351 0 0
sp_kill_cost_storno 15777 15777 0 0.00 0 27704 74 0 0
sp_kill_qstorno_ret_qs2qd 1058379 1058379 0 0.00 0 26592 40 0 0
sp_kill_qty_storno 559146 559146 0 0.00 0 32670 94 0 0
sp_lock_selected_doc 21021 21021 0 0.00 0 11647 11 0 0
sp_make_cost_storno 28440 28440 0 0.00 0 20183 94 0 0
sp_multiply_rows_for_pdistr 28437 28437 0 0.00 0 21041 40 0 0
sp_multiply_rows_for_qdistr 21091 21091 0 0.00 0 26143 167 0 0
sp_pay_from_customer 7255 7255 0 0.00 6 12934 182 0 0
sp_pay_to_supplier 6106 6106 0 0.00 7 22318 223 0 0
sp_payment_common 13360 13360 0 0.00 2 7375 47 0 0
sp_qd_handle_on_cancel_clo 1848 1848 0 0.00 0 18959 339 0 0
sp_qd_handle_on_invoice_upd_sts 10265 10265 0 0.00 0 32668 524 0 0
sp_qd_handle_on_reserve_upd_sts 10036 10036 0 0.00 0 15180 62 0 0
sp_reserve_write_off 10040 10040 0 0.00 9 20618 293 0 0
sp_supplier_invoice 5493 5493 0 0.00 8 101266 3326 0 0
sp_supplier_order 5465 5465 0 0.00 7 37503 1444 0 0
srv_aggregate_perf_data 119 118 1 0.84 2823 261815 16049 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 682 145099 37955 0 0
srv_find_qd_qs_mism 1038318 1038318 0 0.00 0 16521 10 0 0
srv_make_invnt_saldo 524 524 0 0.00 72 71393 3837 0 0
srv_make_money_saldo 948 948 0 0.00 2 12445 357 0 0
srv_recalc_idx_stat 4 4 0 0.00 95968 379384 291096 0 0
t$perf-abend:sp_add_invoice_to_stock 86 86 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 5097 5097 0 0.00 1 10367 964 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 2 5807 506 0 0
t$perf-norm:sp_cancel_client_order 1848 1848 0 0.00 0 3952 27 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 621 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 2380 18 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 492 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1148 37 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 93339 7076 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 937 16 0 0
t$perf-norm:sp_client_order 21092 21092 0 0.00 0 21473 35 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 2403 14 0 0
t$perf-norm:sp_pay_from_customer 7255 7255 0 0.00 0 22555 21 0 0
t$perf-norm:sp_pay_to_supplier 6106 6106 0 0.00 0 4572 19 0 0
t$perf-norm:sp_reserve_write_off 10040 10040 0 0.00 0 8848 25 0 0
t$perf-norm:sp_supplier_invoice 5495 5495 0 0.00 0 10625 32 0 0
t$perf-norm:sp_supplier_order 5465 5465 0 0.00 0 12362 32 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 475 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 378 11 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 443 11 0 0
t$perf-norm:srv_make_money_saldo 948 948 0 0.00 0 1374 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 159 60 0 0
v_add_invoice_to_stock 10238 10066 172 1.68 0 5562 28 0 172
v_all_customers 38664 38664 0 0.00 0 63 4 0 0
v_all_suppliers 31186 31186 0 0.00 0 102 4 0 0
v_all_wares 42172 42172 0 0.00 0 72 4 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 3162 19 0 0
v_cancel_client_order 3696 3696 0 0.00 0 4881 12 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 4731 15 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 4732 15 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 4781 25 0 0
v_cancel_supplier_invoice 852 830 22 2.58 0 3460 28 0 22
v_cancel_supplier_order 826 826 0 0.00 0 1419 12 0 0
v_cancel_write_off 2050 2050 0 0.00 0 3113 25 0 0
v_max_id_clo_ord 5465 5465 0 0.00 0 283 5 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 1860 15 0 0
v_max_id_ord_sup 5486 5486 0 0.00 0 3686 8 0 0
v_max_non_paid_invoice 1464 1464 0 0.00 0 1034 9 0 0
v_max_non_paid_realizn 3811 3811 0 0.00 0 595 7 0 0
v_min_id_clo_ord 5465 5465 0 0.00 0 2903 11 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 2563 10 0 0
v_min_id_ord_sup 5486 5486 0 0.00 0 9136 11 0 0
v_min_non_paid_invoice 6106 6106 0 0.00 0 5124 18 0 0
v_min_non_paid_realizn 7254 7254 0 0.00 0 7362 19 0 0
v_our_firm 10394 10394 0 0.00 0 52 4 0 0
v_reserve_write_off 20070 20070 0 0.00 0 4731 12 0 0
x_lock_depdocs_on_canc_invoice 10591 10591 0 0.00 0 25102 121 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 4311 310 0 0
x_make_qstorno_1000_1200 5465 5465 0 0.00 3 37492 1278 0 0
x_make_qstorno_1200_2000 5493 5493 0 0.00 4 91291 3080 0 0
x_make_qstorno_2100_3300 481732 481732 0 0.00 0 88860 206 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-04-2022 07:38:36 to 10-04-2022 07:38:40.

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
05:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:15 27702 8192000000 9712645872 10008121344 788081888 8.775 100 81 162 0 816303248 7961744 20259616
05:09:46 25276 8192000000 9779012672 10088951808 840879088 9.309 100 88 176 0 877037440 15422608 20735744
05:11:16 13849 8192000000 9808443264 10125291520 876943104 9.669 100 91 181 1 917520848 19623648 20954096
05:12:36 11543 8192000000 9885407696 10219839488 926706128 10.162 100 78 156 0 989878432 46563904 16608400
05:13:50 3690 8192000000 9944369440 10291118080 970852064 10.595 100 91 177 0 1056183424 67386384 17944976
05:14:58 2336 8192000000 9978556944 10335232000 1007581264 10.952 100 89 178 3 1096674480 71031584 18061632
05:16:01 973 8192000000 9993187968 10360655872 1030803568 11.176 100 92 177 1 1116407664 69195872 16408224
05:17:04 1189 8192000000 10039058720 10413498368 1048456016 11.346 100 97 192 1 1155325856 88376224 18493616
05:18:07 1413 8192000000 10017334848 10396348416 1062164144 11.477 100 90 176 1 1139508048 60111728 17232176
05:19:11 1169 8192000000 10024160224 10402787328 1068514176 11.538 100 93 176 1 1144382848 58814288 17054384
05:20:12 1697 8192000000 10036026640 10416115712 1076829792 11.617 100 80 154 1 1152620976 59515968 16275216
05:21:15 7929 8192000000 10035634336 10431623168 1079078416 11.639 100 66 124 1 1154821488 63747008 11996064
05:22:24 1276 8192000000 10041879104 10425802752 1081602672 11.663 100 81 155 1 1161017984 64116688 15298624
05:23:26 1162 8192000000 10059364400 10446639104 1091105008 11.753 100 97 183 0 1181501424 71430560 18965856
05:24:28 1329 8192000000 10081549632 10470883328 1093228592 11.773 100 96 182 1 1201157856 89750560 18178704
05:25:31 1006 8192000000 10082770576 10471702528 1090102480 11.744 100 86 160 3 1202075504 96110960 15862064
05:26:34 1261 8192000000 10059518192 10451193856 1091349792 11.755 100 83 161 1 1180180848 71569328 17261728
05:27:36 1110 8192000000 10050052592 10435710976 1084285936 11.688 100 95 181 1 1175117008 71448752 19382320
05:28:38 1298 8192000000 10051464560 10441064448 1079373536 11.642 100 92 180 3 1173038400 76156480 17508384
05:29:40 989 8192000000 9993667024 10369384448 1050855664 11.369 98 89 170 1 1116960160 48141616 17962880
05:30:42 924 8192000000 10009680992 10388205568 1058729168 11.444 100 89 175 0 1132732400 55960592 18042640
05:31:44 1048 8192000000 10036801040 10418155520 1066865472 11.522 100 94 183 1 1154980448 70675904 17439072
05:32:47 876 8192000000 10004671008 10386583552 1053966304 11.399 100 95 178 1 1130080048 56544720 19569024
05:33:49 1158 8192000000 10011884288 10386079744 1043344128 11.297 99 92 175 2 1133346128 72358560 17643440
05:34:51 1051 8192000000 9969899456 10339319808 1035334048 11.220 100 87 167 1 1091185488 39255152 16596288
05:35:57 1160 8192000000 9987026464 10353078272 1018878080 11.061 99 97 182 0 1108974384 70952496 19143808
05:37:00 1049 8192000000 9989755392 10361552896 1036833088 11.234 100 89 161 1 1110822400 57394784 16594528
05:38:10 23330 8192000000 10007026048 10390278144 1059711984 11.454 100 64 119 1 1131788208 60331856 11744368
05:39:40 673 8192000000 10030843920 10407469056 1085943312 11.704 100 95 174 0 1154927312 49685056 19298944
05:40:45 1916 8192000000 10069520320 10454740992 1099112128 11.829 100 80 151 1 1187638944 72960512 15566304
05:41:55 5310 8192000000 10087112320 10480005120 1104404944 11.879 100 78 142 3 1206824640 87161424 15258272
05:43:05 6715 8192000000 10118364192 10510385152 1114916928 11.979 100 72 135 1 1233675680 104657936 14100816
05:44:24 1079 8192000000 10162940976 10562605056 1120911920 12.036 100 98 189 2 1280569440 139550288 20107232
05:45:28 14669 8192000000 10137677568 10554884096 1125404288 12.078 100 54 104 1 1256825648 120434544 10986816
05:46:49 1917 8192000000 10163281840 10566111232 1142367056 12.238 100 70 133 1 1275852992 119301968 14183968
05:47:57 3751 8192000000 10197752688 10610401280 1144639504 12.259 100 76 145 0 1319616048 158233088 16743456
05:49:04 1681 8192000000 10247846704 10651942912 1163966864 12.440 100 96 190 2 1362623872 176834240 21822768
05:50:10 1166 8192000000 10295326640 10695475200 1174995664 12.543 100 92 177 1 1404454752 209883488 19575600
05:51:15 1038 8192000000 10312965264 10718949376 1175484944 12.548 100 92 178 0 1423578944 227761936 20332064
05:52:18 846 8192000000 10329890912 10744500224 1173483664 12.529 100 96 189 0 1442242640 246747232 22011744
05:53:23 1996 8192000000 10361466976 10779750400 1182297840 12.612 100 89 176 4 1469716160 267207808 20210512
05:54:27 1003 8192000000 10387419344 10804150272 1188373296 12.668 100 97 188 1 1496489152 286117456 21998400
05:55:30 865 8192000000 10402269168 10813566976 1179570336 12.586 100 95 187 1 1509833488 307508592 22754560
05:56:34 1054 8192000000 10438797824 10867822592 1185502624 12.641 100 99 191 1 1545167344 336410832 23253888
05:57:38 874 8192000000 10456231136 10917879808 1189751712 12.681 100 97 191 0 1560908992 347292080 23865200
05:58:41 1485 8192000000 10439376672 10887827456 1189450144 12.678 100 93 179 0 1548394272 337544640 21399488
05:59:45 5553 8192000000 10452030496 10885144576 1185656896 12.643 100 57 112 1 1564762208 364850224 14255088
06:01:05 7324 8192000000 10485604400 10908377088 1178372272 12.575 100 54 108 0 1575747264 383878464 13496528
06:02:15 6797 8192000000 10495212672 10933428224 1164691632 12.447 100 54 108 0 1601880720 423112144 14076944
06:03:25 8668 8192000000 10477836512 10926604288 1154798848 12.355 100 50 98 1 1590211936 422908304 12504784
06:05:06 5255 8192000000 10393379936 10873946112 1144719216 12.260 100 47 91 0 1503439520 346823264 11897040
06:06:15 1707 8192000000 10348754224 10808455168 1144384864 12.257 100 91 173 1 1447361920 283220528 19756528
06:07:18 7007 8192000000 10308336624 10768519168 1150864720 12.318 100 46 91 1 1418135456 256444128 10826608
06:08:46 17070 8192000000 10217358656 10688028672 1100033088 11.838 96 24 48 1 1299879888 193553296 6293504
06:10:37 30948 8192000000 10214064240 10731872256 1119699792 12.024 98 17 34 0 1333426576 209228512 4498272
06:12:22 47153 8192000000 10194212160 10634534912 1141429072 12.229 100 26 51 1 1302449936 155814448 5206416
06:14:15 44117 8192000000 10148433536 10716008448 1127379664 12.097 99 12 24 0 1226648496 96624976 2643856
06:16:00 106860 8192000000 10145077680 10597863424 1139889280 12.214 99 16 32 0 1212046400 68993024 3164096
06:18:47 109038 8192000000 10130857488 10602221568 1100042144 11.838 96 17 34 1 1217601136 113873952 3685040
06:21:37 37864 8192000000 10074263520 10512699392 1117726736 12.006 99 21 40 0 1159370576 37588464 4055376
06:23:16 91325 8192000000 10088530640 10571751424 1101953872 11.856 97 22 44 2 1165972000 59444128 4574000
06:25:58 67372 8192000000 10050814528 10474827776 1117837184 12.007 99 17 34 0 1161983280 40076560 4069536
06:28:06 83808 8192000000 10088907712 10535182336 1120413824 12.031 100 40 79 1 1195666048 66747616 8504608
06:30:41 10831 8192000000 10078180128 10495451136 1130606592 12.127 100 70 134 1 1166273520 22800240 12866688
06:31:53 74414 8192000000 10124067824 10560311296 1140897344 12.224 100 43 86 1 1228398144 78139360 9361440
06:34:08 43747 8192000000 10111614576 10554028032 1126747152 12.091 98 22 45 0 1186952416 55809408 4395856
06:35:52 50300 8192000000 10130805920 10590556160 1147302000 12.284 100 39 75 1 1228530800 73402864 7825936
06:37:44 46351 8192000000 10090806256 10586071040 1124311040 12.068 98 9 18 1 1183835504 57590272 1934192
06:39:31 145097 8192000000 10070704208 10514128896 1115385504 11.983 97 27 50 2 1165374880 45200832 4788544
06:42:57 62305 8192000000 10114574304 10607267840 1141766496 12.232 99 19 38 1 1217370992 71865584 3738912
06:45:03 60008 8192000000 10101042656 10586996736 1136702624 12.185 99 16 32 2 1216816048 76619344 3494080
06:47:16 54520 8192000000 10106141488 10574249984 1116222176 11.991 98 21 42 2 1187106320 66230672 4653472
06:49:11 39252 8192000000 10129985936 10568929280 1148307648 12.294 100 43 83 1 1209880672 53235536 8337488
06:51:00 57462 8192000000 10123671792 10620444672 1123020416 12.056 98 34 68 1 1256627872 126479680 7127776
06:53:12 82429 8192000000 10159921776 10621652992 1144614976 12.259 100 25 49 0 1261535248 111777392 5142880
06:55:35 52123 8192000000 10131209360 10582130688 1146844800 12.280 99 16 33 1 1198727408 48155728 3726880
06:57:50 115281 8192000000 10035141296 10496933888 1072687104 11.578 95 37 74 0 1146670576 66055440 7928032
07:00:46 52663 8192000000 10109096224 10550247424 1121535136 12.041 99 28 56 1 1210564736 83344272 5685328
07:02:39 71628 8192000000 10122431632 10589315072 1110042944 11.933 98 18 36 1 1205227328 91333952 3850432
07:05:05 63619 8192000000 10135928528 10602979328 1096244448 11.802 97 14 28 0 1189164160 89748224 3171488
07:07:09 114751 8192000000 10093496400 10585501696 1119058448 12.018 99 13 26 0 1211118608 89221504 2838656
07:10:06 66381 8192000000 10057563504 10499080192 1052433680 11.384 94 26 50 3 1085276912 28211792 4631440
07:12:16 34658 8192000000 10048762000 10481020928 1106761248 11.902 98 28 56 1 1151655136 39520144 5373744
07:13:51 17311 8192000000 10088084176 10522198016 1090248016 11.745 97 45 89 2 1162299056 62283872 9767168
07:15:09 15398 8192000000 10098554752 10516615168 1115108448 11.981 99 56 112 1 1176272864 50464160 10700256
07:16:42 32025 8192000000 10108740416 10524901376 1115792528 11.987 99 51 102 0 1188583440 62851808 9939104
07:18:16 13119 8192000000 10119948544 10554712064 1131334832 12.134 100 56 109 0 1211788992 69733728 10720432
07:20:35 55228 8192000000 10137860256 10584162304 1139434096 12.210 100 44 84 2 1201594544 53712272 8448176
07:22:32 104271 8192000000 10062413696 10495643648 1143579040 12.249 100 24 48 1 1166340240 17436800 5324400
07:25:19 19089 8192000000 10077485664 10509541376 1142023024 12.235 100 49 95 1 1179447344 28613472 8810848
07:26:42 70352 8192000000 10099290256 10576564224 1135079760 12.169 99 17 34 0 1229130464 90560832 3489872
07:28:55 43552 8192000000 10114204992 10587955200 1143910720 12.252 100 16 32 0 1197268144 49765040 3592384
07:30:39 91211 8192000000 10100234976 10587791360 1121086912 12.037 98 15 30 0 1190988656 66549264 3352480
07:33:27 42037 8192000000 10079136272 10576900096 1134735488 12.166 99 20 40 0 1214373968 75140352 4498128
07:35:10 42119 8192000000 10129329952 10587041792 1139475392 12.211 99 25 50 1 1197012384 52350016 5186976
07:36:59 25932 8192000000 9013271568 9286426624 163680544 1.958 15 4 8 0 213863392 49246528 936320

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-04-2022 07:38:42 to 10-04-2022 07:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 86 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 10-04-2022 07:38:59 to 10-04-2022 07:39:01.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          225354
MON$OLDEST_ACTIVE               225355
MON$OLDEST_SNAPSHOT             225355
MON$NEXT_TRANSACTION            225355
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-30 07:30:08.6030 GMT
MON$PAGES                       2090752
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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-oltp40 according to config parameter 'remove_isql_logs'

10.04.22 07:39:02. There are 3 logs in /var/tmp/logs-oltp40 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-10 07:39:05 No severe PSQL-related problems occured
10.04.22 07:39:06. End of report.