Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.02.2023 13: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 15106732 kB
MemAvailable 30423060 kB
Buffers 0 kB
Cached 16620108 kB
SwapCached 3144 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.3
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 2023-02-26 13:37:26
DTS_END                         2023-02-26 13:37:26.5740
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/38920
TRN_ID                          216227
ATT_ID                          564
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: 8975.87 20472 1077104
customer order: creation 164.77 534 19772
customer order: refuse 13.97 393 1676
order to supplier: creation 44.10 1527 5292
order to supplier: removal 3.31 184583 397
invoice (draft): creation 44.03 3177 5284
invoice (draft): removal 50.59 1041 6071
invoice accept: apply 40.37 26315 4844
invoice accept: cancel 46.68 1276 5601
customer reserve: creation 3727.47 251 447296
customer reserve: removal 4548.43 106 545812
realization accept: apply 80.76 284 9691
realization accept: cancel 86.78 188 10414
payment from customer: creation 56.56 177 6787
payment from customer: removal 5.63 113 676
payment to supplier: creation 46.74 189 5609
payment to supplier: removal 3.13 123 376
service: total inventory turnovers 4.11 3873 493
service: total monetary turnovers 7.53 380 903
service: aggregate perf. data 0.88 15456 105
service: refresh index statistics 0.04 169456 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-02-2023 13:39:40 to 26-02-2023 13:39:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8013
TEST_TIME 2 6891
TEST_TIME 3 6019
TEST_TIME 4 5916
TEST_TIME 5 6840
TEST_TIME 6 6182
TEST_TIME 7 6454
TEST_TIME 8 6486
TEST_TIME 9 6596
TEST_TIME 10 6938
TEST_TIME 11 6979
TEST_TIME 12 7271
TEST_TIME 13 7360
TEST_TIME 14 7466
TEST_TIME 15 7406
TEST_TIME 16 7590
TEST_TIME 17 7933
TEST_TIME 18 8274
TEST_TIME 19 8280
TEST_TIME 20 8458
TEST_TIME 21 8420
TEST_TIME 22 8357
TEST_TIME 23 8253
TEST_TIME 24 8248
TEST_TIME 25 8241
TEST_TIME 26 8634
TEST_TIME 27 8701
TEST_TIME 28 8791
TEST_TIME 29 9005
TEST_TIME 30 8925
TEST_TIME 31 9679
TEST_TIME 32 10009
TEST_TIME 33 10856
TEST_TIME 34 11121
TEST_TIME 35 11160
TEST_TIME 36 10906
TEST_TIME 37 10653
TEST_TIME 38 10732
TEST_TIME 39 10624
TEST_TIME 40 10450
TEST_TIME 41 10634
TEST_TIME 42 10520
TEST_TIME 43 10464
TEST_TIME 44 10292
TEST_TIME 45 10345
TEST_TIME 46 10221
TEST_TIME 47 10161
TEST_TIME 48 10029
TEST_TIME 49 9880
TEST_TIME 50 9789
TEST_TIME 51 9726
TEST_TIME 52 9601
TEST_TIME 53 9569
TEST_TIME 54 9479
TEST_TIME 55 9594
TEST_TIME 56 9519
TEST_TIME 57 9496
TEST_TIME 58 9414
TEST_TIME 59 9427
TEST_TIME 60 9422
TEST_TIME 61 9442
TEST_TIME 62 9382
TEST_TIME 63 9435
TEST_TIME 64 9375
TEST_TIME 65 9394
TEST_TIME 66 9412
TEST_TIME 67 9484
TEST_TIME 68 9522
TEST_TIME 69 9481
TEST_TIME 70 9525
TEST_TIME 71 9498
TEST_TIME 72 9489
TEST_TIME 73 9546
TEST_TIME 74 9440
TEST_TIME 75 9433
TEST_TIME 76 9352
TEST_TIME 77 9295
TEST_TIME 78 9277
TEST_TIME 79 9229
TEST_TIME 80 9194
TEST_TIME 81 9174
TEST_TIME 82 9107
TEST_TIME 83 9054
TEST_TIME 84 9121
TEST_TIME 85 9066
TEST_TIME 86 9064
TEST_TIME 87 9019
TEST_TIME 88 9029
TEST_TIME 89 9004
TEST_TIME 90 9075
TEST_TIME 91 9099
TEST_TIME 92 9173
TEST_TIME 93 9319
TEST_TIME 94 9275
TEST_TIME 95 9242
TEST_TIME 96 9248
TEST_TIME 97 9172
TEST_TIME 98 9228
TEST_TIME 99 9181
TEST_TIME 100 9155
TEST_TIME 101 9108
TEST_TIME 102 9071
TEST_TIME 103 9021
TEST_TIME 104 8972
TEST_TIME 105 8966
TEST_TIME 106 8952
TEST_TIME 107 8936
TEST_TIME 108 8992
TEST_TIME 109 8986
TEST_TIME 110 8991
TEST_TIME 111 8995
TEST_TIME 112 8965
TEST_TIME 113 9004
TEST_TIME 114 8969
TEST_TIME 115 8964
TEST_TIME 116 9050
TEST_TIME 117 9140
TEST_TIME 118 9110
TEST_TIME 119 9050
TEST_TIME 120 8999

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-02-2023 13:39:43 to 26-02-2023 13:39:47.

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 1489900 1489900 0 0.00 0 21151 13 0 0
doc_list_biud 1489871 1489871 0 0.00 0 45774 45 0 0
sp_add_invoice_to_stock 4962 4844 118 2.37 58 263446 26316 0 118
sp_cancel_adding_invoice 5601 5601 0 0.00 5 127319 1277 0 0
sp_cancel_client_order 1676 1676 0 0.00 6 27934 393 0 0
sp_cancel_customer_reserve 545812 545812 0 0.00 1 45792 107 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 4 2031 113 0 0
sp_cancel_pay_to_supplier 376 376 0 0.00 4 5748 124 0 0
sp_cancel_supplier_invoice 6085 6071 14 0.23 2 18370 1042 0 14
sp_cancel_supplier_order 397 397 0 0.00 12 1473337 184583 0 0
sp_cancel_write_off 10414 10414 0 0.00 1 16937 188 0 0
sp_client_order 19772 19772 0 0.00 5 53008 534 0 0
sp_customer_reserve 447296 447296 0 0.00 1 110635 251 0 0
sp_fill_shopping_cart 30334 30334 0 0.00 1 15365 85 0 0
sp_fill_shopping_cart_clo_res 447261 447261 0 0.00 0 19733 22 0 0
sp_get_clo_for_invoice 4798 4798 0 0.00 0 26021 332 0 0
sp_kill_cost_storno 17067 17067 0 0.00 0 18844 77 0 0
sp_kill_qstorno_ret_qs2qd 1108506 1108506 0 0.00 0 41916 46 0 0
sp_kill_qty_storno 584453 584453 0 0.00 0 45772 106 0 0
sp_lock_selected_doc 19970 19970 0 0.00 0 7541 7 0 0
sp_make_cost_storno 26878 26878 0 0.00 0 21147 76 0 0
sp_multiply_rows_for_pdistr 26878 26878 0 0.00 0 10149 40 0 0
sp_multiply_rows_for_qdistr 19772 19772 0 0.00 0 13601 209 0 0
sp_pay_from_customer 6787 6787 0 0.00 5 11391 178 0 0
sp_pay_to_supplier 5609 5609 0 0.00 7 17853 189 0 0
sp_payment_common 12396 12396 0 0.00 2 10042 43 0 0
sp_qd_handle_on_cancel_clo 1676 1676 0 0.00 0 27908 318 0 0
sp_qd_handle_on_invoice_upd_sts 10392 10392 0 0.00 0 31477 533 0 0
sp_qd_handle_on_reserve_upd_sts 9691 9691 0 0.00 0 15752 71 0 0
sp_reserve_write_off 9691 9691 0 0.00 9 21187 285 0 0
sp_supplier_invoice 5284 5284 0 0.00 11 77376 3177 0 0
sp_supplier_order 5292 5292 0 0.00 6 28071 1528 0 0
srv_aggregate_perf_data 105 105 0 0.00 2787 108140 15456 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 754 139265 34965 0 0
srv_find_qd_qs_mism 1030462 1030462 0 0.00 0 14222 10 0 0
srv_make_invnt_saldo 493 493 0 0.00 41 109255 3873 0 0
srv_make_money_saldo 903 903 0 0.00 1 14486 381 0 0
srv_recalc_idx_stat 5 5 0 0.00 53132 314212 169456 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4844 4844 0 0.00 1 16403 997 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 7653 524 0 0
t$perf-norm:sp_cancel_client_order 1676 1676 0 0.00 0 3872 31 0 0
t$perf-norm:sp_cancel_customer_reserve 607 607 0 0.00 0 1500 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 597 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 376 376 0 0.00 0 730 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 3173 42 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 78462 7085 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1251 18 0 0
t$perf-norm:sp_client_order 19772 19772 0 0.00 0 7446 40 0 0
t$perf-norm:sp_customer_reserve 934 934 0 0.00 0 5891 27 0 0
t$perf-norm:sp_pay_from_customer 6787 6787 0 0.00 0 9394 22 0 0
t$perf-norm:sp_pay_to_supplier 5609 5609 0 0.00 0 6561 23 0 0
t$perf-norm:sp_reserve_write_off 9691 9691 0 0.00 0 5043 23 0 0
t$perf-norm:sp_supplier_invoice 5284 5284 0 0.00 0 4468 30 0 0
t$perf-norm:sp_supplier_order 5292 5292 0 0.00 0 3672 30 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 331 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 7524 110 0 0
t$perf-norm:srv_make_invnt_saldo 493 493 0 0.00 0 4079 23 0 0
t$perf-norm:srv_make_money_saldo 903 903 0 0.00 0 868 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 206 99 0 0
v_add_invoice_to_stock 9818 9582 236 2.40 0 5766 25 0 236
v_all_customers 36410 36410 0 0.00 0 90 4 0 0
v_all_suppliers 29728 29728 0 0.00 0 199 4 0 0
v_all_wares 39544 39544 0 0.00 0 107 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 2034 20 0 0
v_cancel_client_order 3352 3352 0 0.00 0 1387 11 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 1299 13 0 0
v_cancel_customer_reserve 1214 1214 0 0.00 1 316 10 0 0
v_cancel_payment_to_supplier 752 752 0 0.00 0 1317 11 0 0
v_cancel_supplier_invoice 768 740 28 3.64 0 2808 30 0 28
v_cancel_supplier_order 794 794 0 0.00 0 1264 14 0 0
v_cancel_write_off 1930 1930 0 0.00 0 707 24 0 0
v_max_id_clo_ord 5289 5289 0 0.00 0 4653 6 0 0
v_max_id_clo_res 934 934 0 0.00 0 3046 14 0 0
v_max_id_ord_sup 5273 5273 0 0.00 0 7104 9 0 0
v_max_non_paid_invoice 1307 1307 0 0.00 0 3426 11 0 0
v_max_non_paid_realizn 3400 3400 0 0.00 0 2013 8 0 0
v_min_id_clo_ord 5289 5289 0 0.00 0 8134 13 0 0
v_min_id_clo_res 934 934 0 0.00 0 8623 47 0 0
v_min_id_ord_sup 5273 5273 0 0.00 0 5125 11 0 0
v_min_non_paid_invoice 5609 5609 0 0.00 0 4893 13 0 0
v_min_non_paid_realizn 6787 6787 0 0.00 0 5404 15 0 0
v_our_firm 9908 9908 0 0.00 0 123 5 0 0
v_reserve_write_off 19382 19382 0 0.00 0 3994 12 0 0
x_lock_depdocs_on_canc_invoice 11302 11302 0 0.00 0 11788 127 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 4217 397 0 0
x_make_qstorno_1000_1200 5292 5292 0 0.00 3 28054 1352 0 0
x_make_qstorno_1200_2000 5284 5284 0 0.00 5 77365 2950 0 0
x_make_qstorno_2100_3300 447296 447296 0 0.00 0 110622 216 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-02-2023 13:39:48 to 26-02-2023 13:39:52.

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
11:07:30 4 8192000000 8800661776 8812552192 6166784 0.075 2 2 3 0 6309520 81376 61360
11:08:32 30788 8192000000 9716660064 10013220864 789134032 8.786 100 90 181 0 819299184 7937776 22227376
11:10:06 21543 8192000000 9748710464 10052571136 822516864 9.124 100 89 178 0 856979472 12801536 21661072
11:11:34 14610 8192000000 9797189888 10117861376 862964304 9.530 100 79 158 0 905801488 24640320 18196864
11:12:53 9309 8192000000 9861700736 10190397440 916248448 10.059 100 88 176 1 973315648 38095984 18971216
11:14:06 4050 8192000000 9905322608 10245165056 946368064 10.355 100 86 168 0 1025028400 60959936 17700400
11:15:12 2142 8192000000 9960265312 10310832128 986005264 10.743 100 89 174 0 1081027632 77389584 17632784
11:16:17 1824 8192000000 9984208016 10341404672 1015187808 11.026 100 92 177 0 1103232640 70945984 17098848
11:17:20 1240 8192000000 10023256384 10388967424 1043938000 11.302 100 94 183 0 1142630144 80399136 18293008
11:18:23 799 8192000000 10022843056 10399211520 1054564768 11.404 100 94 177 1 1144241584 70664672 19012144
11:19:25 947 8192000000 10020168208 10391670784 1060065024 11.457 100 94 181 1 1145068736 67108912 17894800
11:20:27 1272 8192000000 10045344976 10422521856 1065124128 11.505 100 90 173 4 1164729296 82402144 17203024
11:21:30 1188 8192000000 10039186464 10419683328 1076814192 11.617 100 94 182 5 1154058048 58081440 19162416
11:22:32 1212 8192000000 10073321680 10459123712 1086427024 11.709 100 96 193 3 1194956720 89639936 18889760
11:23:34 1056 8192000000 10042221648 10427154432 1090475168 11.747 100 95 183 0 1165656464 56079408 19101888
11:24:36 1121 8192000000 10078178064 10464669696 1100100048 11.839 100 98 191 0 1201384528 80935984 20348496
11:25:39 1234 8192000000 10077762896 10463350784 1095134032 11.791 100 93 181 1 1198464592 84490400 18840160
11:26:41 1123 8192000000 10059121792 10455732224 1099308704 11.831 100 77 151 1 1184279024 69376496 15593824
11:27:43 1062 8192000000 10079540048 10467467264 1102237056 11.859 100 95 178 2 1202786352 81451952 19097344
11:28:45 1104 8192000000 10072342720 10457735168 1098471712 11.823 100 94 187 0 1194885872 76865216 19548944
11:29:48 1463 8192000000 10035037168 10420514816 1086266784 11.707 100 91 169 1 1160942704 56518640 18157280
11:30:51 1084 8192000000 10024302640 10401374208 1052301104 11.383 99 95 180 3 1145825360 75159712 18364544
11:31:53 1274 8192000000 10023727232 10400645120 1068321392 11.536 100 98 182 3 1144448064 58208144 17918528
11:32:55 1031 8192000000 10005438736 10384158720 1056293216 11.421 100 91 179 1 1130887088 56011712 18582160
11:33:57 1165 8192000000 10000485072 10370461696 1020485744 11.077 98 94 182 2 1124412240 85074384 18852112
11:35:00 1218 8192000000 9997126352 10369912832 1027700288 11.146 100 86 168 0 1118927696 74610304 16617104
11:36:03 1249 8192000000 10007395632 10386157568 1051561856 11.376 100 100 194 3 1132717376 60094528 21060992
11:37:05 1090 8192000000 10000585248 10375692288 1055502736 11.413 100 94 173 1 1128391072 53693392 19194944
11:38:11 1380 8192000000 10015603824 10390384640 1062817360 11.483 100 90 170 1 1133993024 54145568 17030096
11:39:21 14042 8192000000 10005248192 10394595328 1061449936 11.470 99 62 119 0 1124531904 51735760 11346208
11:40:42 23105 8192000000 10062535504 10452463616 1088383872 11.727 100 54 105 0 1175164608 76682688 10098048
11:42:09 1389 8192000000 10113895424 10514333696 1108172784 11.915 100 91 172 2 1234750880 108148528 18429568
11:43:15 2342 8192000000 10122590848 10524467200 1123280176 12.058 100 67 134 0 1236003184 98681296 14041712
11:44:43 14045 8192000000 10146053856 10547355648 1131773456 12.138 100 63 115 1 1259912864 116173808 11965600
11:46:00 1093 8192000000 10204280688 10598178816 1140398912 12.219 100 96 190 0 1325830560 164581216 20850432
11:47:04 5133 8192000000 10220389552 10630529024 1143147840 12.245 100 82 163 1 1337327520 176286768 17892912
11:48:13 1527 8192000000 10238885616 10646142976 1158016976 12.385 100 91 177 2 1353547152 175818608 19711568
11:49:18 1110 8192000000 10269664640 10689613824 1162758704 12.429 100 89 168 4 1371596752 190762432 18075616
11:50:22 767 8192000000 10306309616 10710986752 1177825248 12.570 100 98 190 0 1418473776 218283824 22364704
11:51:25 867 8192000000 10348803392 10752479232 1182591392 12.614 100 98 186 1 1457869024 253757968 21519664
11:52:32 2255 8192000000 10373864032 10784264192 1187129280 12.657 100 83 157 1 1486496080 281319168 18047632
11:53:38 1617 8192000000 10378850720 10822516736 1183064336 12.619 100 88 174 0 1493715872 290060640 20590896
11:54:42 1168 8192000000 10395073872 10843013120 1194735856 12.727 100 95 191 2 1503679872 286739152 22204864
11:55:50 906 8192000000 10400066896 10807603200 1202695904 12.801 100 99 190 1 1509565856 285635168 21234784
11:56:55 855 8192000000 10400705344 10828832768 1201987136 12.795 100 96 181 3 1506754128 284723824 20043168
11:57:59 2219 8192000000 10424485520 10852261888 1212505632 12.892 100 79 153 0 1542672560 312032224 18134704
11:59:09 996 8192000000 10473353040 10881744896 1206766416 12.839 100 93 184 0 1578235488 349819696 21649376
12:00:14 3059 8192000000 10506059376 10935803904 1203238432 12.806 100 64 128 1 1613040912 393897568 15904912
12:01:21 4580 8192000000 10551973008 10985693184 1201594192 12.791 100 79 156 0 1667108528 445438160 20076176
12:02:37 743 8192000000 10589533776 11013496832 1192507232 12.707 99 98 192 0 1695433952 479188272 23738448
12:03:39 2374 8192000000 10590349664 11054002176 1196645920 12.745 100 80 159 0 1703405920 487009808 19750192
12:04:48 2950 8192000000 10607233648 11061825536 1193919824 12.720 100 65 130 1 1710979648 500219088 16840736
12:05:54 5453 8192000000 10610527504 11074514944 1192919888 12.711 100 49 96 0 1706831008 501018080 12893040
12:07:05 14071 8192000000 10600956016 11034181632 1173372144 12.528 99 45 90 0 1707258768 522508672 11377952
12:08:21 14344 8192000000 10495129088 11070787584 1154566256 12.352 100 34 66 1 1603583360 439500080 9517024
12:09:51 19928 8192000000 10290375920 10825244672 1129212496 12.114 99 28 56 0 1435847328 299471648 7163184
12:11:52 30685 8192000000 10103900944 10544676864 1132458080 12.145 99 49 86 0 1222091696 81293472 8340144
12:13:25 59724 8192000000 10155662864 10587758592 1144016368 12.253 99 43 87 1 1246408896 92611632 9780896
12:15:26 43706 8192000000 10161973536 10625159168 1148630496 12.297 99 26 52 0 1282314144 127932736 5750912
12:17:59 58841 8192000000 10099351360 10631155712 1112921040 11.960 97 17 33 0 1224904208 108290080 3693088
12:19:59 49368 8192000000 10087512640 10547376128 1138417568 12.201 100 11 22 0 1194060112 53319568 2322976
12:21:52 80216 8192000000 10095255776 10584985600 1146535872 12.277 100 34 66 0 1234580528 81080048 6964608
12:24:24 139265 8192000000 10066681952 10514354176 1122791600 12.053 98 30 58 0 1171802224 43297776 5712848
12:27:49 70579 8192000000 10078459488 10551799808 1126580640 12.089 99 20 40 0 1183174448 52782320 3811488
12:30:08 30615 8192000000 10134974944 10600095744 1147796320 12.289 100 27 54 0 1239105856 85427824 5881712
12:31:50 22135 8192000000 10132557232 10589478912 1113827248 11.969 98 37 73 0 1224756656 103373264 7556144
12:33:18 67050 8192000000 10095415504 10578378752 1134570448 12.164 100 9 18 0 1212541920 75854848 2116624
12:35:27 73200 8192000000 10096702320 10603331584 1145796688 12.270 100 14 28 0 1233605344 84598960 3209696
12:37:42 6483 8192000000 10060109328 10491273216 1127521184 12.098 99 81 149 3 1151493056 8449936 15521936
12:38:49 24431 8192000000 10060589680 10501672960 1110282656 11.935 97 19 37 0 1137970032 24476544 3210832
12:40:15 39182 8192000000 10079889232 10559406080 1124175712 12.066 100 39 78 0 1216143328 83664000 8303616
12:41:55 47233 8192000000 10076332448 10547769344 1108027104 11.914 97 22 43 0 1152855296 40501024 4327168
12:43:43 54442 8192000000 10109197008 10598342656 1139823264 12.214 100 24 48 0 1211277152 66544416 4909472
12:45:43 68364 8192000000 10092999296 10533621760 1100092288 11.839 96 24 48 0 1143966496 39421088 4453120
12:48:52 26395 8192000000 10099769184 10534342656 1094894784 11.789 97 48 96 1 1157279584 53011808 9372992
12:50:21 111375 8192000000 10078843728 10518323200 1116457472 11.994 99 21 42 0 1170620624 49793360 4369792
12:53:16 64729 8192000000 10069897536 10528448512 1096499472 11.804 98 29 58 0 1160881232 58601904 5779856
12:55:24 77272 8192000000 10098019344 10562772992 1118464896 12.012 99 14 27 0 1189166368 67559872 3141600
12:57:42 55153 8192000000 10092900608 10547601408 1124236048 12.067 99 23 46 0 1196829360 66830000 5763312
13:00:54 60611 8192000000 10109537456 10570039296 1118607424 12.014 98 29 58 0 1212492032 87253120 6631488
13:03:03 51712 8192000000 10168424560 10620874752 1124676288 12.071 98 28 56 0 1243791760 112801664 6313808
13:04:55 34801 8192000000 10209054544 10665742336 1151251776 12.321 100 32 64 0 1296184480 137779680 7153024
13:06:36 33662 8192000000 10259519920 10697920512 1152901968 12.337 100 24 48 0 1343413520 184142912 6368640
13:08:19 84191 8192000000 10202185936 10666463232 1128121968 12.104 98 16 32 0 1308651856 177059920 3469968
13:10:53 30821 8192000000 10074626224 10503266304 1104286144 11.878 96 38 76 0 1144636784 32172800 8177840
13:12:24 36596 8192000000 10206547520 10646323200 1155355312 12.360 100 48 96 0 1289572864 124495824 9721728
13:14:02 92948 8192000000 10091358992 10595729408 1123959552 12.064 98 37 74 0 1228614928 96136208 8519168
13:16:57 43766 8192000000 10086035504 10539732992 1140901488 12.224 99 12 24 0 1176612528 33168176 2542864
13:18:40 60831 8192000000 10103822464 10582130688 1145106064 12.264 99 18 36 0 1191391648 41837552 4448032
13:20:45 55462 8192000000 10146738304 10598670336 1166916400 12.468 100 23 44 1 1245189472 73648176 4624896
13:22:54 92790 8192000000 10131114576 10621804544 1115727392 11.987 96 10 20 1 1185118192 67375216 2015584
13:25:27 55377 8192000000 10134264992 10600398848 1143962720 12.253 99 28 56 0 1227984192 77360944 6660528
13:27:33 37594 8192000000 10125199968 10590912512 1133313248 12.153 99 35 70 0 1204068944 63721296 7034400
13:29:30 43037 8192000000 10153199392 10597404672 1131585120 12.136 99 30 60 0 1225907312 87855920 6466272
13:31:17 44728 8192000000 10155736464 10587242496 1135322976 12.172 99 38 76 0 1237590560 94579312 7688272
13:33:06 30492 8192000000 10159556224 10679881728 1139529840 12.211 100 34 68 0 1285865136 139174800 7160496
13:34:43 63122 8192000000 10078277408 10550534144 1078329648 11.632 95 27 54 0 1147742496 63876528 5536320
13:36:47 49970 8192000000 9163591088 9447747584 301833872 3.553 28 13 26 0 344366688 39916400 2616416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 26-02-2023 13:39:54 to 26-02-2023 13:40:10.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 26-02-2023 13:40:11 to 26-02-2023 13:40:14.

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          217137
MON$OLDEST_ACTIVE               217138
MON$OLDEST_SNAPSHOT             217138
MON$NEXT_TRANSACTION            217138
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                       2055840
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:304: 
:305: mscs-test-161.budzdorov.ru    Sun Feb 26 13:37:49 2023
:306:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:307: 
--- end of diff output ---

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

26.02.23 13:40:15. 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
2023-02-26 13:40:17 No severe PSQL-related problems occured
26.02.23 13:40:19. End of report.