Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.04.2022 10: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 16261744 kB
MemAvailable 31535152 kB
Buffers 0 kB
Cached 15417964 kB
SwapCached 2288 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-07 10:37:11
DTS_END                         2022-04-07 10:37:11.2510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/41838
TRN_ID                          224697
ATT_ID                          671
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: 9236.34 23518 1108361
customer order: creation 176.13 445 21135
customer order: refuse 14.19 352 1703
order to supplier: creation 45.30 1369 5436
order to supplier: removal 3.43 161212 412
invoice (draft): creation 46.63 3065 5595
invoice (draft): removal 52.90 977 6348
invoice accept: apply 41.94 22735 5033
invoice accept: cancel 47.62 1197 5714
customer reserve: creation 3899.82 216 467978
customer reserve: removal 4608.83 94 553060
realization accept: apply 85.34 244 10241
realization accept: cancel 85.72 159 10286
payment from customer: creation 58.79 162 7055
payment from customer: removal 5.48 117 658
payment to supplier: creation 48.41 171 5809
payment to supplier: removal 3.40 109 408
service: total inventory turnovers 4.10 3788 492
service: total monetary turnovers 7.37 290 884
service: aggregate perf. data 0.92 18021 110
service: refresh index statistics 0.03 255644 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 07-04-2022 10:38:19 to 07-04-2022 10:38:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5961
TEST_TIME 2 5015
TEST_TIME 3 5166
TEST_TIME 4 5174
TEST_TIME 5 5346
TEST_TIME 6 5405
TEST_TIME 7 5535
TEST_TIME 8 5759
TEST_TIME 9 5716
TEST_TIME 10 5728
TEST_TIME 11 5800
TEST_TIME 12 5932
TEST_TIME 13 6082
TEST_TIME 14 6179
TEST_TIME 15 6274
TEST_TIME 16 6549
TEST_TIME 17 6716
TEST_TIME 18 6838
TEST_TIME 19 6804
TEST_TIME 20 7275
TEST_TIME 21 7577
TEST_TIME 22 8050
TEST_TIME 23 8141
TEST_TIME 24 8730
TEST_TIME 25 8918
TEST_TIME 26 9008
TEST_TIME 27 9083
TEST_TIME 28 9578
TEST_TIME 29 9906
TEST_TIME 30 10116
TEST_TIME 31 10531
TEST_TIME 32 10619
TEST_TIME 33 10917
TEST_TIME 34 10891
TEST_TIME 35 10868
TEST_TIME 36 10762
TEST_TIME 37 10665
TEST_TIME 38 10617
TEST_TIME 39 10755
TEST_TIME 40 10673
TEST_TIME 41 10579
TEST_TIME 42 10493
TEST_TIME 43 10616
TEST_TIME 44 10562
TEST_TIME 45 10618
TEST_TIME 46 10413
TEST_TIME 47 10277
TEST_TIME 48 10164
TEST_TIME 49 10120
TEST_TIME 50 9983
TEST_TIME 51 10085
TEST_TIME 52 9977
TEST_TIME 53 10017
TEST_TIME 54 9929
TEST_TIME 55 9912
TEST_TIME 56 9931
TEST_TIME 57 10042
TEST_TIME 58 10007
TEST_TIME 59 9915
TEST_TIME 60 9877
TEST_TIME 61 9799
TEST_TIME 62 9771
TEST_TIME 63 9726
TEST_TIME 64 9784
TEST_TIME 65 9836
TEST_TIME 66 9793
TEST_TIME 67 9808
TEST_TIME 68 9731
TEST_TIME 69 9789
TEST_TIME 70 9816
TEST_TIME 71 9754
TEST_TIME 72 9802
TEST_TIME 73 9856
TEST_TIME 74 9985
TEST_TIME 75 9982
TEST_TIME 76 9947
TEST_TIME 77 9822
TEST_TIME 78 9855
TEST_TIME 79 9809
TEST_TIME 80 9773
TEST_TIME 81 9730
TEST_TIME 82 9685
TEST_TIME 83 9628
TEST_TIME 84 9583
TEST_TIME 85 9610
TEST_TIME 86 9532
TEST_TIME 87 9605
TEST_TIME 88 9597
TEST_TIME 89 9576
TEST_TIME 90 9575
TEST_TIME 91 9563
TEST_TIME 92 9560
TEST_TIME 93 9544
TEST_TIME 94 9631
TEST_TIME 95 9679
TEST_TIME 96 9644
TEST_TIME 97 9637
TEST_TIME 98 9709
TEST_TIME 99 9735
TEST_TIME 100 9651
TEST_TIME 101 9614
TEST_TIME 102 9576
TEST_TIME 103 9565
TEST_TIME 104 9514
TEST_TIME 105 9516
TEST_TIME 106 9473
TEST_TIME 107 9454
TEST_TIME 108 9396
TEST_TIME 109 9340
TEST_TIME 110 9364
TEST_TIME 111 9347
TEST_TIME 112 9335
TEST_TIME 113 9343
TEST_TIME 114 9337
TEST_TIME 115 9376
TEST_TIME 116 9354
TEST_TIME 117 9330
TEST_TIME 118 9306
TEST_TIME 119 9305
TEST_TIME 120 9249

Performance per minute, chart


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

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 1539494 1539494 0 0.00 0 56195 12 0 0
doc_list_biud 1539490 1539490 0 0.00 0 43812 39 0 0
sp_add_invoice_to_stock 5102 5033 69 1.35 47 204204 22736 0 69
sp_cancel_adding_invoice 5714 5714 0 0.00 7 91449 1197 0 0
sp_cancel_client_order 1703 1703 0 0.00 7 7235 353 0 0
sp_cancel_customer_reserve 553060 553060 0 0.00 1 24988 94 0 0
sp_cancel_pay_from_customer 658 658 0 0.00 4 7053 117 0 0
sp_cancel_pay_to_supplier 408 408 0 0.00 3 2732 110 0 0
sp_cancel_supplier_invoice 6359 6348 11 0.17 3 27408 978 0 11
sp_cancel_supplier_order 412 412 0 0.00 22 1272571 161213 0 0
sp_cancel_write_off 10286 10286 0 0.00 2 12907 160 0 0
sp_client_order 21135 21135 0 0.00 6 15401 446 0 0
sp_customer_reserve 467978 467978 0 0.00 1 155801 217 0 0
sp_fill_shopping_cart 32157 32157 0 0.00 1 9835 74 0 0
sp_fill_shopping_cart_clo_res 467961 467961 0 0.00 0 25098 18 0 0
sp_get_clo_for_invoice 5004 5004 0 0.00 0 20149 279 0 0
sp_kill_cost_storno 17066 17066 0 0.00 0 8463 67 0 0
sp_kill_qstorno_ret_qs2qd 1123166 1123166 0 0.00 0 27391 41 0 0
sp_kill_qty_storno 592768 592768 0 0.00 0 43812 94 0 0
sp_lock_selected_doc 21010 21010 0 0.00 0 12415 7 0 0
sp_make_cost_storno 28109 28109 0 0.00 0 43405 76 0 0
sp_multiply_rows_for_pdistr 28109 28109 0 0.00 0 12781 34 0 0
sp_multiply_rows_for_qdistr 21135 21135 0 0.00 0 14950 179 0 0
sp_pay_from_customer 7055 7055 0 0.00 6 15054 163 0 0
sp_pay_to_supplier 5809 5809 0 0.00 7 12115 171 0 0
sp_payment_common 12864 12864 0 0.00 2 12102 43 0 0
sp_qd_handle_on_cancel_clo 1703 1703 0 0.00 0 6225 286 0 0
sp_qd_handle_on_invoice_upd_sts 10718 10718 0 0.00 0 43811 484 0 0
sp_qd_handle_on_reserve_upd_sts 10241 10241 0 0.00 0 14844 56 0 0
sp_reserve_write_off 10241 10241 0 0.00 9 23532 245 0 0
sp_supplier_invoice 5595 5595 0 0.00 9 70292 3065 0 0
sp_supplier_order 5436 5436 0 0.00 8 25721 1369 0 0
srv_aggregate_perf_data 110 110 0 0.00 2853 154697 18021 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 894 211290 37773 0 0
srv_find_qd_qs_mism 1059174 1059174 0 0.00 0 12276 9 0 0
srv_make_invnt_saldo 492 492 0 0.00 46 63408 3789 0 0
srv_make_money_saldo 884 884 0 0.00 2 13050 291 0 0
srv_recalc_idx_stat 4 4 0 0.00 81454 339796 255645 0 0
t$perf-abend:sp_add_invoice_to_stock 69 69 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-norm:sp_add_invoice_to_stock 5034 5034 0 0.00 1 10287 824 0 0
t$perf-norm:sp_cancel_adding_invoice 450 450 0 0.00 1 10358 529 0 0
t$perf-norm:sp_cancel_client_order 1703 1703 0 0.00 0 4724 38 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 1995 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 658 658 0 0.00 0 1676 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 408 408 0 0.00 0 975 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 948 28 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 114576 6780 0 0
t$perf-norm:sp_cancel_write_off 1036 1036 0 0.00 0 2604 19 0 0
t$perf-norm:sp_client_order 21135 21135 0 0.00 0 10712 34 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 1793 14 0 0
t$perf-norm:sp_pay_from_customer 7055 7055 0 0.00 0 4412 16 0 0
t$perf-norm:sp_pay_to_supplier 5809 5809 0 0.00 0 6317 19 0 0
t$perf-norm:sp_reserve_write_off 10241 10241 0 0.00 0 6698 20 0 0
t$perf-norm:sp_supplier_invoice 5595 5595 0 0.00 0 3933 28 0 0
t$perf-norm:sp_supplier_order 5437 5437 0 0.00 0 7217 31 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 198 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 312 8 0 0
t$perf-norm:srv_make_invnt_saldo 492 492 0 0.00 0 890 16 0 0
t$perf-norm:srv_make_money_saldo 884 884 0 0.00 0 902 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 199 62 0 0
v_add_invoice_to_stock 10146 10008 138 1.36 0 5610 19 0 138
v_all_customers 38864 38864 0 0.00 0 73 3 0 0
v_all_suppliers 30792 30792 0 0.00 0 100 4 0 0
v_all_wares 42270 42270 0 0.00 0 75 3 0 0
v_cancel_adding_invoice 900 900 0 0.00 0 1655 15 0 0
v_cancel_client_order 3406 3406 0 0.00 0 1682 8 0 0
v_cancel_customer_prepayment 1316 1316 0 0.00 0 825 10 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 78 7 0 0
v_cancel_payment_to_supplier 816 816 0 0.00 0 274 10 0 0
v_cancel_supplier_invoice 844 822 22 2.60 0 3973 22 0 22
v_cancel_supplier_order 824 824 0 0.00 0 568 10 0 0
v_cancel_write_off 2072 2072 0 0.00 0 3131 19 0 0
v_max_id_clo_ord 5436 5436 0 0.00 0 120 4 0 0
v_max_id_clo_res 967 967 0 0.00 0 6897 21 0 0
v_max_id_ord_sup 5586 5586 0 0.00 0 6949 9 0 0
v_max_non_paid_invoice 1435 1435 0 0.00 0 106 7 0 0
v_max_non_paid_realizn 3562 3562 0 0.00 0 287 6 0 0
v_min_id_clo_ord 5436 5436 0 0.00 0 1617 6 0 0
v_min_id_clo_res 967 967 0 0.00 0 6467 21 0 0
v_min_id_ord_sup 5586 5586 0 0.00 0 9266 7 0 0
v_min_non_paid_invoice 5809 5809 0 0.00 0 12091 18 0 0
v_min_non_paid_realizn 7055 7055 0 0.00 0 6452 17 0 0
v_our_firm 10392 10392 0 0.00 0 62 4 0 0
v_reserve_write_off 20482 20482 0 0.00 0 2509 8 0 0
x_lock_depdocs_on_canc_invoice 11651 11651 0 0.00 0 12711 105 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 5559 318 0 0
x_make_qstorno_1000_1200 5436 5436 0 0.00 3 25694 1226 0 0
x_make_qstorno_1200_2000 5595 5595 0 0.00 5 70279 2855 0 0
x_make_qstorno_2100_3300 467978 467978 0 0.00 0 155798 189 0 0

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

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
08:07:15 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:16 18164 8192000000 9721523920 10018234368 795335984 8.849 100 87 174 0 824665280 7556864 21772432
08:09:44 14889 8192000000 9745174032 10049601536 824061616 9.139 100 92 184 1 858309968 12108112 22140240
08:11:05 14834 8192000000 9792259504 10110058496 857065120 9.471 100 81 162 0 893792896 17674352 19053424
08:12:26 11787 8192000000 9847840064 10180190208 910244464 10.000 100 76 150 0 958484528 32084016 16156048
08:13:43 5664 8192000000 9928153616 10270699520 957174528 10.461 100 86 170 1 1037909392 62577488 18157376
08:14:52 3329 8192000000 9977128672 10325958656 986478048 10.747 100 94 178 0 1091470640 86142944 18849648
08:15:58 1614 8192000000 9997103888 10360934400 1014397568 11.018 100 92 173 0 1117854176 86161488 17295120
08:17:01 4756 8192000000 9990796896 10357047296 1033269696 11.200 100 91 172 2 1111475968 61065904 17140368
08:18:07 832 8192000000 10023460464 10397798400 1052995408 11.389 100 96 189 1 1142810032 70166752 19647872
08:19:08 1430 8192000000 10021241264 10400681984 1065893696 11.513 100 92 179 1 1144622016 59819872 18908448
08:20:11 1233 8192000000 10044837808 10424487936 1073462128 11.585 100 98 188 1 1167315824 74405952 19447744
08:21:14 877 8192000000 10047462320 10435825664 1081157824 11.659 100 91 174 1 1167569888 67908976 18503088
08:22:15 1567 8192000000 10060987872 10449035264 1086282528 11.707 100 84 149 0 1180702672 78615808 15804336
08:23:18 1328 8192000000 10054297312 10443120640 1091110272 11.753 100 80 151 3 1178049504 70945488 15993744
08:24:22 1261 8192000000 10082814048 10470039552 1097006720 11.809 100 95 184 3 1207218496 91511424 18700352
08:25:25 1293 8192000000 10054023104 10440761344 1100116912 11.839 100 95 181 0 1174536048 55909712 18509424
08:26:28 975 8192000000 10045086080 10437152768 1105174112 11.887 100 85 157 2 1161425920 40917616 15334192
08:27:30 1015 8192000000 10054680176 10443608064 1091180912 11.754 99 77 143 0 1177127296 71497056 14449328
08:28:32 1510 8192000000 10046083728 10438524928 1092802240 11.769 100 71 138 3 1168460208 61933712 13724256
08:29:36 1301 8192000000 10053353424 10437914624 1092608464 11.767 100 91 175 1 1179340624 68880256 17851904
08:30:38 1177 8192000000 10031021840 10412335104 1069646464 11.549 100 97 178 2 1152170688 63201488 19322736
08:31:40 1271 8192000000 10008513440 10386911232 1049580592 11.357 100 96 178 2 1127204112 60448608 17174912
08:32:42 1145 8192000000 10026384752 10404786176 1064281232 11.497 100 96 186 1 1146336432 62738464 19316736
08:33:44 1051 8192000000 9993062560 10370301952 1058469152 11.442 100 92 182 1 1115255088 35066800 21719136
08:34:46 1550 8192000000 9999252912 10380603392 1052779456 11.387 100 89 166 2 1121530192 50867536 17883200
08:35:48 1631 8192000000 9976479616 10353860608 1031994336 11.188 100 77 149 1 1100775952 53540688 15240928
08:36:50 3395 8192000000 9962644576 10344513536 1039278416 11.258 99 58 108 1 1083507904 33540720 10688768
08:37:58 929 8192000000 9987633952 10363281408 1061085280 11.467 99 90 167 4 1107616992 30627312 15904400
08:39:05 3591 8192000000 10030591200 10421235712 1086083504 11.705 100 68 124 2 1150612576 53184736 11344336
08:40:14 2388 8192000000 10057761424 10445938688 1085619360 11.701 99 79 149 1 1180128544 80434240 14074944
08:41:20 7828 8192000000 10096801168 10488668160 1109536256 11.928 100 76 148 4 1215522096 90238160 15747680
08:42:32 6278 8192000000 10139896240 10538614784 1129396768 12.116 100 78 155 1 1253507312 107550016 16560528
08:43:42 6214 8192000000 10158561168 10555142144 1137222464 12.189 100 72 138 0 1273500656 121613248 14664944
08:44:58 1473 8192000000 10217721888 10611245056 1150354384 12.313 100 82 155 2 1326627392 159253792 17019216
08:46:04 2868 8192000000 10238291616 10636951552 1169398528 12.491 100 86 166 1 1350195072 162144448 18652096
08:47:10 3031 8192000000 10290969776 10692079616 1174814832 12.542 100 91 179 0 1399787632 205886864 19085936
08:48:20 1558 8192000000 10337825648 10740195328 1188294768 12.667 100 90 175 0 1454718512 245850736 20573008
08:49:24 1363 8192000000 10376856192 10783039488 1185947296 12.646 100 94 186 0 1487793968 279503136 22343536
08:50:30 1148 8192000000 10437728544 10849406976 1201385488 12.789 100 89 178 0 1545612336 323856784 20370064
08:51:34 1295 8192000000 10470451120 10885529600 1207411888 12.845 100 95 183 1 1580469504 351193408 21864208
08:52:38 1329 8192000000 10532324656 10946572288 1210604112 12.875 100 98 196 0 1636282976 402669744 23009120
08:53:42 1149 8192000000 10522219872 10964963328 1202896384 12.803 100 99 193 0 1627970304 402521824 22552096
08:54:45 1124 8192000000 10588409680 11005947904 1220094496 12.963 100 99 189 1 1697206432 454357648 22754288
08:55:50 1516 8192000000 10602144432 11019616256 1199025824 12.767 100 95 184 0 1706420000 485643328 21750848
08:56:54 1150 8192000000 10607308608 11059142656 1203229552 12.806 100 100 198 1 1713066000 485846368 23990080
08:57:56 884 8192000000 10615661312 11078459392 1200316720 12.779 100 93 186 1 1720566256 497412608 22836928
08:58:59 8295 8192000000 10573183488 11045060608 1185281520 12.639 100 59 118 0 1681816368 480988224 15546624
09:00:22 8020 8192000000 10554567584 11004063744 1176237616 12.555 100 56 112 0 1656990176 466557008 14195552
09:01:33 9241 8192000000 10427038816 10892525568 1160788976 12.411 99 48 92 1 1542339872 369788448 11762448
09:02:45 13762 8192000000 10445566544 10892378112 1166631600 12.465 100 39 77 0 1549593424 373051808 9910016
09:04:02 18750 8192000000 10433027392 10919698432 1172958064 12.524 100 33 67 0 1542520848 361163536 8399248
09:05:32 31850 8192000000 10422008960 10889080832 1172421184 12.519 100 35 70 0 1500915632 319899424 8595024
09:07:07 47458 8192000000 10319639152 10787098624 1153589360 12.343 99 27 54 1 1411920576 252014048 6317168
09:08:55 28004 8192000000 10229664688 10684329984 1159244896 12.396 100 22 44 0 1328818160 164863232 4710032
09:11:08 28933 8192000000 10066593152 10495545344 1123430384 12.059 98 37 64 3 1146239856 15485552 7323920
09:12:43 83546 8192000000 10046221328 10502668288 1086135888 11.706 95 11 19 3 1110242432 22536576 1569968
09:15:20 130368 8192000000 10055871920 10528706560 1119513040 12.022 98 11 22 1 1171434416 49841456 2079920
09:18:39 50978 8192000000 10077794208 10529275904 1117900304 12.007 100 18 30 2 1163297056 42145472 3251280
09:20:31 72864 8192000000 10082288960 10533322752 1098598528 11.824 98 22 43 0 1160191904 57603568 3989808
09:22:48 108420 8192000000 10048091856 10474024960 1074680080 11.597 97 18 34 1 1117762352 39602224 3480048
09:25:44 211289 8192000000 10145204592 10632372224 1102458384 11.861 98 36 69 2 1202339072 92883824 6996864
09:30:18 26515 8192000000 10165855232 10607566848 1147103072 12.282 100 46 87 1 1267310384 109838848 10368464
09:31:45 47029 8192000000 10184404048 10662301696 1119542928 12.023 97 31 64 1 1274497040 147916320 7037792
09:33:44 81993 8192000000 10096253808 10577731584 1139184848 12.208 100 21 42 1 1197391648 53764368 4442432
09:36:06 32766 8192000000 10092258192 10533920768 1135362320 12.172 99 40 79 1 1171653728 28330608 7960800
09:37:39 34164 8192000000 10071901424 10549735424 1114769536 11.978 99 7 13 1 1172609712 56555312 1284864
09:39:13 69127 8192000000 10115590736 10567749632 1137339440 12.190 99 11 22 0 1200220128 60971328 1909360
09:41:23 8750 8192000000 10081155696 10528407552 1145583632 12.268 100 33 58 2 1172817760 21441440 5792688
09:42:39 34067 8192000000 10092151760 10529906688 1109702720 11.930 96 26 51 0 1161766384 46578752 5484912
09:44:15 25857 8192000000 10103035472 10565984256 1125269056 12.077 98 30 58 2 1205673056 73177968 7226032
09:45:43 32035 8192000000 10134634032 10570637312 1144250752 12.255 100 30 57 1 1227647520 77585488 5811280
09:47:15 26836 8192000000 10168149792 10612797440 1154806832 12.355 100 42 83 2 1244986592 81234640 8945120
09:48:45 47595 8192000000 10189614048 10680467456 1161270176 12.415 100 30 60 1 1309108512 141435648 6402688
09:50:39 72819 8192000000 10137647184 10633043968 1126820528 12.091 98 13 26 0 1229220864 99406560 2993776
09:52:56 15338 8192000000 10123725056 10549489664 1134557296 12.164 99 56 101 0 1197346992 52303440 10486256
09:54:30 82858 8192000000 10099029360 10578706432 1148603808 12.296 100 43 81 0 1229742816 72072240 9066768
09:56:58 33866 8192000000 10110999792 10587242496 1144733344 12.260 99 24 48 1 1224361712 73951856 5676512
09:58:48 94664 8192000000 10132380400 10595291136 1122390336 12.050 97 21 42 1 1225569808 98743888 4435584
10:01:30 77752 8192000000 10147604640 10620362752 1134460896 12.163 99 22 43 0 1272488352 132899856 5127600
10:04:39 46798 8192000000 10107939728 10562240512 1098976704 11.828 96 22 44 0 1177185408 73196000 5012704
10:06:37 53098 8192000000 10146288160 10588647424 1100987472 11.847 96 24 48 0 1187122320 80526304 5608544
10:08:40 41359 8192000000 10155760240 10576625664 1100935696 11.847 96 43 84 1 1207037296 97393776 8707824
10:10:23 74323 8192000000 10083532816 10593562624 1069257872 11.545 95 24 48 1 1185360656 111162976 4939808
10:12:50 40495 8192000000 10064433152 10487414784 1111605312 11.948 98 37 73 1 1160769808 41384080 7780416
10:14:32 3904 8192000000 10135220112 10573193216 1130599696 12.127 100 59 116 0 1232588000 89379440 12608864
10:15:40 38050 8192000000 10104632192 10574938112 1134569136 12.164 100 23 46 0 1188760496 49645856 4545504
10:17:26 90351 8192000000 10085735888 10533068800 1130665424 12.128 99 22 44 1 1186197808 50956880 4575504
10:20:08 71537 8192000000 10077357280 10540208128 1139832304 12.214 99 11 22 1 1168055696 25900496 2322896
10:22:20 82010 8192000000 10065925472 10514358272 1101882400 11.855 97 16 31 1 1151349504 45740496 3726608
10:24:49 69377 8192000000 10087019184 10552676352 1128417456 12.106 100 11 22 0 1180366096 49552912 2395728
10:26:58 50956 8192000000 10041779856 10513162240 1105779008 11.892 98 4 8 0 1149126672 42437328 910336
10:28:53 67058 8192000000 10107868320 10556477440 1122706720 12.053 98 28 56 1 1182125664 52924960 6493984
10:31:07 14988 8192000000 10138045472 10569744384 1133862672 12.158 99 44 85 0 1222897792 79610048 9425072
10:32:26 47352 8192000000 10068892304 10523938816 1103593296 11.872 97 14 26 2 1137966368 31496624 2876448
10:34:35 34503 8192000000 10122504592 10552750080 1128481184 12.107 99 47 93 0 1202250832 63896096 9873552
10:36:10 37002 8192000000 10147915792 10589974528 1127753168 12.100 99 29 57 0 1231311984 97378240 6180576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 69 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 07-04-2022 10:38:51 to 07-04-2022 10:38:53.

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          219276
MON$OLDEST_ACTIVE               224811
MON$OLDEST_SNAPSHOT             224811
MON$NEXT_TRANSACTION            224811
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                       2079520
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2750 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2750 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2750 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2750 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 ---
:55: 
:56: mscs-test-161.budzdorov.ru    Thu Apr  7 10:37:11 2022
:57:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:58: 
:59: 
:60: mscs-test-161.budzdorov.ru    Thu Apr  7 10:37:17 2022
:61:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:62: 
:63: 
:64: mscs-test-161.budzdorov.ru    Thu Apr  7 10:37:20 2022
:65:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:66: 
:67: 
:68: mscs-test-161.budzdorov.ru    Thu Apr  7 10:37:23 2022
:69:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:70: 
--- end of diff output ---

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

07.04.22 10:38:54. 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-07 10:38:56 No severe PSQL-related problems occured
07.04.22 10:38:58. End of report.