Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.05.2022 07: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 16333196 kB
MemAvailable 31565072 kB
Buffers 0 kB
Cached 15404060 kB
SwapCached 3244 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-05-02 07:37:16
DTS_END                         2022-05-02 07:37:16.8190
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/54198
TRN_ID                          220561
ATT_ID                          650
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: 9204.98 27140 1104598
customer order: creation 166.46 436 19975
customer order: refuse 14.78 360 1774
order to supplier: creation 44.83 1356 5379
order to supplier: removal 3.38 154466 406
invoice (draft): creation 44.83 2836 5379
invoice (draft): removal 53.25 956 6390
invoice accept: apply 41.25 24651 4950
invoice accept: cancel 48.23 1076 5788
customer reserve: creation 3819.58 236 458349
customer reserve: removal 4670.27 86 560433
realization accept: apply 81.91 261 9829
realization accept: cancel 88.20 137 10584
payment from customer: creation 58.09 197 6971
payment from customer: removal 5.83 123 699
payment to supplier: creation 48.74 199 5849
payment to supplier: removal 3.48 104 418
service: total inventory turnovers 3.96 4042 475
service: total monetary turnovers 6.88 405 826
service: aggregate perf. data 0.99 14821 119
service: refresh index statistics 0.04 336050 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-05-2022 07:40:05 to 02-05-2022 07:40:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6774
TEST_TIME 2 6432
TEST_TIME 3 6449
TEST_TIME 4 6013
TEST_TIME 5 5748
TEST_TIME 6 5991
TEST_TIME 7 6221
TEST_TIME 8 6222
TEST_TIME 9 6241
TEST_TIME 10 6590
TEST_TIME 11 6682
TEST_TIME 12 6750
TEST_TIME 13 6881
TEST_TIME 14 7026
TEST_TIME 15 7200
TEST_TIME 16 7294
TEST_TIME 17 7404
TEST_TIME 18 7611
TEST_TIME 19 7573
TEST_TIME 20 7948
TEST_TIME 21 8172
TEST_TIME 22 8269
TEST_TIME 23 8385
TEST_TIME 24 8865
TEST_TIME 25 8586
TEST_TIME 26 8556
TEST_TIME 27 9326
TEST_TIME 28 9836
TEST_TIME 29 10377
TEST_TIME 30 10454
TEST_TIME 31 10725
TEST_TIME 32 10890
TEST_TIME 33 10660
TEST_TIME 34 10740
TEST_TIME 35 10484
TEST_TIME 36 10701
TEST_TIME 37 10680
TEST_TIME 38 10600
TEST_TIME 39 10571
TEST_TIME 40 10423
TEST_TIME 41 10523
TEST_TIME 42 10444
TEST_TIME 43 10395
TEST_TIME 44 10292
TEST_TIME 45 10396
TEST_TIME 46 10301
TEST_TIME 47 10308
TEST_TIME 48 10247
TEST_TIME 49 10056
TEST_TIME 50 9920
TEST_TIME 51 9889
TEST_TIME 52 9813
TEST_TIME 53 9719
TEST_TIME 54 9699
TEST_TIME 55 9626
TEST_TIME 56 9640
TEST_TIME 57 9547
TEST_TIME 58 9646
TEST_TIME 59 9590
TEST_TIME 60 9464
TEST_TIME 61 9398
TEST_TIME 62 9579
TEST_TIME 63 9573
TEST_TIME 64 9517
TEST_TIME 65 9634
TEST_TIME 66 9621
TEST_TIME 67 9607
TEST_TIME 68 9551
TEST_TIME 69 9691
TEST_TIME 70 9716
TEST_TIME 71 9686
TEST_TIME 72 9747
TEST_TIME 73 9668
TEST_TIME 74 9582
TEST_TIME 75 9502
TEST_TIME 76 9548
TEST_TIME 77 9471
TEST_TIME 78 9419
TEST_TIME 79 9361
TEST_TIME 80 9342
TEST_TIME 81 9343
TEST_TIME 82 9313
TEST_TIME 83 9270
TEST_TIME 84 9285
TEST_TIME 85 9291
TEST_TIME 86 9314
TEST_TIME 87 9297
TEST_TIME 88 9352
TEST_TIME 89 9346
TEST_TIME 90 9305
TEST_TIME 91 9332
TEST_TIME 92 9370
TEST_TIME 93 9545
TEST_TIME 94 9575
TEST_TIME 95 9492
TEST_TIME 96 9432
TEST_TIME 97 9431
TEST_TIME 98 9430
TEST_TIME 99 9385
TEST_TIME 100 9338
TEST_TIME 101 9281
TEST_TIME 102 9224
TEST_TIME 103 9158
TEST_TIME 104 9187
TEST_TIME 105 9202
TEST_TIME 106 9205
TEST_TIME 107 9192
TEST_TIME 108 9239
TEST_TIME 109 9208
TEST_TIME 110 9183
TEST_TIME 111 9165
TEST_TIME 112 9211
TEST_TIME 113 9208
TEST_TIME 114 9266
TEST_TIME 115 9294
TEST_TIME 116 9290
TEST_TIME 117 9313
TEST_TIME 118 9301
TEST_TIME 119 9302
TEST_TIME 120 9225

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 02-05-2022 07:40:08 to 02-05-2022 07:40:12.

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 1526227 1526227 0 0.00 0 20619 13 0 0
doc_list_biud 1526226 1526226 0 0.00 0 32253 38 0 0
sp_add_invoice_to_stock 5043 4950 93 1.84 46 237044 24651 0 93
sp_cancel_adding_invoice 5788 5788 0 0.00 6 67405 1076 0 0
sp_cancel_client_order 1774 1774 0 0.00 5 7901 360 0 0
sp_cancel_customer_reserve 560433 560433 0 0.00 1 32257 87 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 6442 124 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 4 1547 105 0 0
sp_cancel_supplier_invoice 6397 6390 7 0.10 3 24980 956 0 7
sp_cancel_supplier_order 406 406 0 0.00 13 1294515 154466 0 0
sp_cancel_write_off 10584 10584 0 0.00 2 17452 138 0 0
sp_client_order 19975 19975 0 0.00 6 13947 437 0 0
sp_customer_reserve 458349 458349 0 0.00 1 99405 236 0 0
sp_fill_shopping_cart 30728 30728 0 0.00 1 14521 74 0 0
sp_fill_shopping_cart_clo_res 458323 458323 0 0.00 0 16408 17 0 0
sp_get_clo_for_invoice 4921 4921 0 0.00 0 20215 299 0 0
sp_kill_cost_storno 17489 17489 0 0.00 0 7317 62 0 0
sp_kill_qstorno_ret_qs2qd 1138246 1138246 0 0.00 0 32251 38 0 0
sp_kill_qty_storno 600125 600125 0 0.00 0 32253 89 0 0
sp_lock_selected_doc 20543 20543 0 0.00 0 9315 7 0 0
sp_make_cost_storno 27570 27570 0 0.00 0 17117 82 0 0
sp_multiply_rows_for_pdistr 27570 27570 0 0.00 0 9554 42 0 0
sp_multiply_rows_for_qdistr 19975 19975 0 0.00 0 12904 175 0 0
sp_pay_from_customer 6971 6971 0 0.00 5 16123 197 0 0
sp_pay_to_supplier 5849 5849 0 0.00 6 15157 200 0 0
sp_payment_common 12820 12820 0 0.00 2 9542 52 0 0
sp_qd_handle_on_cancel_clo 1774 1774 0 0.00 0 7892 284 0 0
sp_qd_handle_on_invoice_upd_sts 10709 10709 0 0.00 0 17919 493 0 0
sp_qd_handle_on_reserve_upd_sts 9829 9829 0 0.00 0 17596 59 0 0
sp_reserve_write_off 9829 9829 0 0.00 8 17641 262 0 0
sp_supplier_invoice 5379 5379 0 0.00 11 105752 2836 0 0
sp_supplier_order 5379 5379 0 0.00 7 45670 1357 0 0
srv_aggregate_perf_data 119 119 0 0.00 2439 157214 14821 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 839 158150 39553 0 0
srv_find_qd_qs_mism 1056215 1056215 0 0.00 0 20619 10 0 0
srv_make_invnt_saldo 475 475 0 0.00 58 116190 4043 0 0
srv_make_money_saldo 826 826 0 0.00 1 12031 406 0 0
srv_recalc_idx_stat 5 5 0 0.00 277229 432286 336051 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4950 4950 0 0.00 1 12882 795 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 5105 326 0 0
t$perf-norm:sp_cancel_client_order 1774 1774 0 0.00 0 2664 27 0 0
t$perf-norm:sp_cancel_customer_reserve 699 699 0 0.00 0 2008 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 1491 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 367 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 1730 33 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 93650 6136 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 846 16 0 0
t$perf-norm:sp_client_order 19975 19975 0 0.00 0 4678 30 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 1037 13 0 0
t$perf-norm:sp_pay_from_customer 6971 6971 0 0.00 0 15505 19 0 0
t$perf-norm:sp_pay_to_supplier 5849 5849 0 0.00 0 2019 15 0 0
t$perf-norm:sp_reserve_write_off 9829 9829 0 0.00 0 5886 21 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 3349 27 0 0
t$perf-norm:sp_supplier_order 5379 5379 0 0.00 0 2184 29 0 0
t$perf-norm:srv_aggregate_perf_data 119 119 0 0.00 0 404 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 1130 21 0 0
t$perf-norm:srv_make_invnt_saldo 475 475 0 0.00 0 1419 15 0 0
t$perf-norm:srv_make_money_saldo 826 826 0 0.00 0 530 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 229 132 0 0
v_add_invoice_to_stock 10026 9840 186 1.85 0 4334 22 0 186
v_all_customers 36672 36672 0 0.00 0 79 3 0 0
v_all_suppliers 30380 30380 0 0.00 0 76 4 0 0
v_all_wares 39950 39950 0 0.00 0 89 3 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 940 14 0 0
v_cancel_client_order 3548 3548 0 0.00 0 2390 10 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 5143 14 0 0
v_cancel_customer_reserve 1398 1398 0 0.00 0 2391 12 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 862 11 0 0
v_cancel_supplier_invoice 844 830 14 1.65 0 4110 27 0 14
v_cancel_supplier_order 812 812 0 0.00 0 2145 14 0 0
v_cancel_write_off 1892 1892 0 0.00 0 2301 22 0 0
v_max_id_clo_ord 5379 5379 0 0.00 0 220 4 0 0
v_max_id_clo_res 989 989 0 0.00 0 1946 13 0 0
v_max_id_ord_sup 5374 5374 0 0.00 0 2116 6 0 0
v_max_non_paid_invoice 1412 1412 0 0.00 0 2949 10 0 0
v_max_non_paid_realizn 3635 3635 0 0.00 0 2144 8 0 0
v_min_id_clo_ord 5379 5379 0 0.00 0 3559 8 0 0
v_min_id_clo_res 989 989 0 0.00 0 3297 17 0 0
v_min_id_ord_sup 5374 5374 0 0.00 0 5599 11 0 0
v_min_non_paid_invoice 5849 5849 0 0.00 0 6504 20 0 0
v_min_non_paid_realizn 6971 6971 0 0.00 0 9497 30 0 0
v_our_firm 9950 9950 0 0.00 0 282 4 0 0
v_reserve_write_off 19658 19658 0 0.00 0 4140 11 0 0
x_lock_depdocs_on_canc_invoice 11763 11763 0 0.00 0 16248 121 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 18069 472 0 0
x_make_qstorno_1000_1200 5379 5379 0 0.00 2 45660 1196 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 4 105652 2644 0 0
x_make_qstorno_2100_3300 458348 458348 0 0.00 0 99404 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-05-2022 07:40:13 to 02-05-2022 07:40:17.

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:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:19 28080 8192000000 9721650816 10020700160 799461472 8.891 100 89 176 1 828696032 7694880 21539680
05:10:02 22301 8192000000 9775960800 10084442112 848086960 9.381 100 94 188 0 883489856 13305360 22097536
05:11:32 22107 8192000000 9808839200 10132369408 873209568 9.632 100 83 164 1 915799312 22965680 19624064
05:12:58 8575 8192000000 9897986560 10236485632 928568304 10.181 100 84 168 0 1008226624 62140960 17517360
05:14:10 3614 8192000000 9910864720 10258280448 961237296 10.501 100 87 165 2 1039292032 61207120 16847616
05:15:16 1816 8192000000 9980134432 10336624640 998428192 10.863 100 92 180 1 1097331472 80062176 18841104
05:16:20 1149 8192000000 9996131264 10361917440 1026312752 11.133 100 87 164 0 1114452448 71901344 16238352
05:17:23 1613 8192000000 10029967040 10403835904 1049015440 11.351 100 93 182 0 1147530784 80016192 18499152
05:18:27 6813 8192000000 10039194304 10412576768 1060150832 11.458 100 89 174 0 1153864032 76785584 16927616
05:19:37 979 8192000000 10025822144 10405003264 1071185776 11.563 100 90 167 3 1147076096 58920480 16969840
05:20:39 1246 8192000000 10062533024 10445082624 1077093488 11.620 100 97 188 0 1181272160 85390976 18787696
05:21:42 973 8192000000 10062539760 10442412032 1082848496 11.675 100 95 182 1 1184702848 82826784 19027568
05:22:45 1192 8192000000 10054633936 10440654848 1088057840 11.724 100 93 178 2 1173217024 65768128 19391056
05:23:47 11053 8192000000 10028506080 10421760000 1091599152 11.758 100 68 132 1 1173550848 68450768 13500928
05:24:59 1076 8192000000 10048714624 10439405568 1103251648 11.868 100 92 176 2 1175889888 54360736 18277504
05:26:01 2230 8192000000 10061922352 10456551424 1099489024 11.833 100 80 149 0 1181556912 67186128 14881760
05:27:04 1477 8192000000 10056505120 10450161664 1099118016 11.829 100 90 168 1 1179184896 61264384 18802496
05:28:06 3912 8192000000 10055026272 10448019456 1103119136 11.867 100 68 121 0 1178079184 62142112 12817936
05:29:11 2056 8192000000 10046780272 10435932160 1082387392 11.670 99 74 145 2 1161651088 64513360 14750336
05:30:14 2011 8192000000 10042562240 10431725568 1077749024 11.626 100 77 148 1 1160029808 66880240 15400544
05:31:18 1122 8192000000 10033091984 10415722496 1065054016 11.505 100 95 181 3 1151132464 67176640 18901808
05:32:20 1180 8192000000 10021841392 10396688384 1049350928 11.354 100 94 182 1 1148140176 79564208 19225040
05:33:22 1046 8192000000 10055735744 10440269824 1059217968 11.449 100 98 191 0 1178498720 99257248 20023504
05:34:25 988 8192000000 10010421280 10386911232 1046408224 11.326 100 93 179 0 1131358000 66998896 17950880
05:35:27 4654 8192000000 9982701616 10362830848 1022368752 11.095 98 60 120 0 1085040640 50364304 12307584
05:36:34 2218 8192000000 9972863904 10353016832 1036192272 11.228 100 65 126 1 1094191744 44771664 13227808
05:37:42 11380 8192000000 9981425216 10368524288 1052044624 11.380 100 60 120 2 1106456800 41298288 13113888
05:39:02 3860 8192000000 10036785888 10419109888 1068766528 11.540 100 78 141 1 1160770112 77729312 14274272
05:40:12 6522 8192000000 10043076000 10431954944 1072275472 11.574 99 68 132 2 1157963888 71848448 13839968
05:41:25 3390 8192000000 10097612432 10490486784 1093892000 11.780 100 80 148 1 1211548688 101837440 15819248
05:42:33 1122 8192000000 10133705152 10519777280 1100251520 11.840 100 84 161 1 1248992864 132356032 16385312
05:43:37 8116 8192000000 10151521296 10553090048 1116032160 11.989 100 74 134 2 1266912592 135866496 15013936
05:44:49 3374 8192000000 10149462480 10549501952 1123859488 12.063 100 81 145 0 1267030320 127925664 15245168
05:45:57 2947 8192000000 10199991040 10595057664 1135676896 12.175 100 81 160 1 1310898848 157802592 17419360
05:47:05 2593 8192000000 10242704816 10644979712 1158491680 12.389 100 65 123 0 1356604928 183052304 15060944
05:48:11 832 8192000000 10288774640 10684235776 1170708368 12.503 100 94 183 0 1396067072 205285328 20073376
05:49:16 1113 8192000000 10321784544 10723237888 1181088240 12.600 100 91 177 0 1433721344 232738784 19894320
05:50:20 915 8192000000 10327191696 10740695040 1180326976 12.593 100 92 177 0 1437948496 236980304 20641216
05:51:23 1024 8192000000 10366190528 10780647424 1195760752 12.737 100 96 186 4 1481589344 264098224 21730368
05:52:28 1639 8192000000 10378242720 10777210880 1181164032 12.601 100 91 175 2 1486890320 285261696 20464592
05:53:32 1214 8192000000 10404325312 10815844352 1180654608 12.596 100 94 182 1 1512982544 310579312 21748624
05:54:36 844 8192000000 10462621728 10864631808 1193396240 12.715 100 96 190 0 1565456048 349857920 22201888
05:55:40 1060 8192000000 10490177264 10908286976 1187369264 12.659 100 95 183 2 1596268016 387392128 21506624
05:56:43 1350 8192000000 10499957296 10939490304 1191005968 12.693 100 88 176 2 1608923440 396373088 21544384
05:57:47 1014 8192000000 10520531680 10953564160 1196825776 12.747 100 96 190 2 1623730448 404783616 22121056
05:58:51 900 8192000000 10537502944 10957844480 1198962112 12.767 100 87 172 0 1645319680 425886080 20471488
05:59:57 5576 8192000000 10504024672 10962640896 1189322304 12.677 100 52 104 0 1614781024 411639824 13818896
06:01:08 7450 8192000000 10532902880 10988589056 1186824016 12.654 100 57 113 0 1639967248 439018752 14124480
06:02:23 21976 8192000000 10509023264 10950336512 1150583872 12.315 98 37 73 1 1587748544 428097520 9067152
06:04:01 21756 8192000000 10436084752 10966642688 1125658720 12.080 98 35 70 0 1541554208 406532128 9363360
06:05:30 28273 8192000000 10289818400 10756104192 1120783392 12.034 99 27 52 0 1389950640 262403408 6763840
06:06:59 39641 8192000000 10209033216 10644824064 1134577456 12.164 100 32 64 1 1312084656 170063136 7444064
06:08:41 37389 8192000000 10089687360 10540421120 1125482448 12.079 99 28 56 2 1196132960 64773264 5877248
06:10:50 18016 8192000000 10166902000 10603790336 1140400784 12.219 100 33 66 0 1252446272 104468192 7577296
06:12:47 70394 8192000000 10100430352 10567548928 1127583744 12.099 99 37 67 2 1231958576 96809984 7564848
06:14:58 117611 8192000000 10083668080 10522222592 1093636976 11.777 96 13 25 0 1167170480 71020512 2512992
06:17:56 101667 8192000000 10091238032 10556108800 1117576688 12.004 98 9 18 1 1168694656 49463792 1654176
06:21:20 29423 8192000000 10119732864 10556997632 1127984448 12.102 99 12 23 1 1186511072 56146384 2380240
06:22:50 59147 8192000000 10078769392 10508640256 1103935040 11.875 96 31 61 0 1132921280 23076624 5909616
06:24:51 155830 8192000000 10129707808 10569400320 1150559376 12.315 100 16 32 0 1182942560 28951184 3432000
06:28:29 49167 8192000000 10089051408 10532577280 1121728720 12.043 97 26 51 1 1180697072 52914992 6053360
06:30:19 50320 8192000000 10151327392 10594930688 1132477328 12.145 98 39 75 0 1221277328 80390032 8409968
06:32:11 17751 8192000000 10176968320 10602463232 1151606960 12.325 100 56 103 2 1274125280 111769760 10748560
06:33:32 21488 8192000000 10172045360 10649149440 1154581776 12.352 100 13 26 2 1272806064 114972032 3252256
06:34:55 25372 8192000000 10167916368 10662010880 1156526672 12.371 100 15 30 0 1262234880 102498864 3209344
06:36:21 55814 8192000000 10137093552 10596401152 1137172464 12.189 98 17 34 0 1211900608 70524976 4203168
06:38:27 94035 8192000000 10111289392 10567553024 1118064480 12.009 97 13 24 1 1221880704 100512224 3304000
06:41:10 50235 8192000000 10142340688 10587410432 1111579504 11.947 97 16 32 1 1203452512 88315056 3557952
06:43:02 46280 8192000000 10130805584 10565226496 1129635440 12.118 99 38 73 0 1197166512 60385712 7145360
06:44:53 56674 8192000000 10150083968 10598191104 1071766736 11.569 94 33 65 1 1176302032 96668544 7866752
06:46:54 55255 8192000000 10088598752 10548977664 1111199824 11.944 98 20 40 1 1151349408 36164304 3985280
06:48:54 56997 8192000000 10086794640 10533998592 1071329664 11.565 95 31 62 4 1148948752 70973056 6646032
06:50:57 92983 8192000000 10080832560 10510635008 1117725792 12.006 100 43 86 0 1167549888 40053248 9770848
06:53:52 55481 8192000000 10082516320 10574635008 1124562320 12.070 99 24 48 3 1175114480 45978736 4573424
06:55:48 55266 8192000000 10087708480 10533961728 1119921680 12.026 99 21 42 1 1178463488 54406480 4135328
06:57:43 48373 8192000000 10045596032 10526924800 1102536784 11.862 98 7 14 0 1140649696 36950544 1162368
06:59:32 32333 8192000000 10068918544 10525401088 1111285568 11.945 98 24 47 0 1170039968 53880240 4874160
07:01:10 52907 8192000000 10116862048 10575675392 1129740224 12.119 99 27 55 0 1205431536 69809968 5881344
07:03:08 28912 8192000000 10087845536 10542137344 1134308304 12.162 100 26 52 1 1184400496 44687008 5405184
07:04:37 22440 8192000000 10140760528 10581364736 1148302432 12.294 100 48 96 0 1218727840 60867952 9557456
07:06:08 69816 8192000000 10138748096 10583719936 1132075456 12.141 98 27 54 2 1221622704 84151888 5395360
07:08:21 38783 8192000000 10202412944 10659045376 1150384160 12.313 100 32 62 2 1293477680 136182288 6911232
07:10:10 47537 8192000000 10126443776 10697719808 1133095200 12.151 100 9 18 0 1283977840 148416704 2465936
07:12:03 158150 8192000000 10088512864 10552287232 1119504976 12.022 98 19 38 0 1178291936 54537536 4249424
07:15:43 71879 8192000000 10048276784 10518077440 1116872208 11.997 98 8 16 0 1151414064 33441408 1100448
07:17:55 74851 8192000000 10067242432 10515607552 1074598672 11.596 94 22 44 0 1115326656 36362656 4365328
07:20:17 83455 8192000000 10084395696 10577371136 1124510272 12.070 99 16 32 1 1194462032 66809136 3142624
07:22:43 74078 8192000000 10083176608 10561376256 1118896400 12.017 98 24 49 1 1183738832 59728112 5114320
07:24:58 72654 8192000000 10079115232 10528350208 1128518752 12.107 98 11 22 0 1162061936 31053888 2489296
07:27:15 14367 8192000000 10122030880 10556821504 1125503888 12.079 98 46 92 2 1210848448 75589280 9755280
07:28:39 30095 8192000000 10204765744 10664574976 1134259424 12.161 99 47 94 2 1282808304 138079568 10469312
07:30:21 82382 8192000000 10140467920 10613059584 1121948880 12.045 98 30 60 0 1225079552 96787616 6343056
07:32:49 55800 8192000000 10107564448 10561081344 1120413360 12.031 98 48 89 2 1172801456 43552064 8836032
07:34:49 52369 8192000000 10154221440 10595282944 1147113392 12.282 100 33 66 0 1230928160 77219280 6595488
07:36:43 65183 8192000000 9233449872 9536192512 360526976 4.215 32 6 12 0 391207184 29570368 1109840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-05-2022 07:40:19 to 02-05-2022 07:40:35.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-05-2022 07:40:36 to 02-05-2022 07:40:38.

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          220560
MON$OLDEST_ACTIVE               221085
MON$OLDEST_SNAPSHOT             221085
MON$NEXT_TRANSACTION            221085
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                       2067040
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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 ---
:38: 
:39: mscs-test-161.budzdorov.ru    Mon May  2 07:37:55 2022
:40:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:41: 
--- end of diff output ---

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

02.05.22 07:40:39. 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-05-02 07:40:41 No severe PSQL-related problems occured
02.05.22 07:40:42. End of report.