Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.06.2022 04:37
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 15540640 kB
MemAvailable 31150896 kB
Buffers 20 kB
Cached 16063172 kB
SwapCached 2488 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-06-24 04:37:15
DTS_END                         2022-06-24 04:37:15.0500
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/43872
TRN_ID                          222320
ATT_ID                          634
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: 9244.72 28763 1109366
customer order: creation 169.08 447 20290
customer order: refuse 14.53 415 1744
order to supplier: creation 45.57 1379 5468
order to supplier: removal 3.50 176336 420
invoice (draft): creation 46.05 3297 5526
invoice (draft): removal 52.17 1044 6260
invoice accept: apply 42.26 25717 5071
invoice accept: cancel 47.54 1320 5705
customer reserve: creation 3883.63 243 466035
customer reserve: removal 4649.99 104 557999
realization accept: apply 82.48 304 9897
realization accept: cancel 77.51 179 9301
payment from customer: creation 59.06 195 7087
payment from customer: removal 5.92 130 710
payment to supplier: creation 49.24 200 5909
payment to supplier: removal 3.38 126 405
service: total inventory turnovers 3.85 4440 462
service: total monetary turnovers 8.07 357 968
service: aggregate perf. data 0.87 19893 104
service: refresh index statistics 0.04 339141 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-06-2022 04:37:59 to 24-06-2022 04:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9333
TEST_TIME 2 6131
TEST_TIME 3 6926
TEST_TIME 4 7046
TEST_TIME 5 6876
TEST_TIME 6 6831
TEST_TIME 7 6991
TEST_TIME 8 6870
TEST_TIME 9 6888
TEST_TIME 10 6930
TEST_TIME 11 7061
TEST_TIME 12 7135
TEST_TIME 13 7457
TEST_TIME 14 7777
TEST_TIME 15 7715
TEST_TIME 16 7875
TEST_TIME 17 7847
TEST_TIME 18 8154
TEST_TIME 19 8263
TEST_TIME 20 8329
TEST_TIME 21 8717
TEST_TIME 22 8665
TEST_TIME 23 8931
TEST_TIME 24 8866
TEST_TIME 25 8788
TEST_TIME 26 8858
TEST_TIME 27 8985
TEST_TIME 28 9139
TEST_TIME 29 9377
TEST_TIME 30 9259
TEST_TIME 31 9225
TEST_TIME 32 9543
TEST_TIME 33 9415
TEST_TIME 34 9635
TEST_TIME 35 9741
TEST_TIME 36 10120
TEST_TIME 37 10972
TEST_TIME 38 11367
TEST_TIME 39 11272
TEST_TIME 40 11101
TEST_TIME 41 10956
TEST_TIME 42 10881
TEST_TIME 43 10902
TEST_TIME 44 10734
TEST_TIME 45 10728
TEST_TIME 46 10652
TEST_TIME 47 10671
TEST_TIME 48 10522
TEST_TIME 49 10400
TEST_TIME 50 10397
TEST_TIME 51 10239
TEST_TIME 52 10211
TEST_TIME 53 10161
TEST_TIME 54 10182
TEST_TIME 55 10091
TEST_TIME 56 10144
TEST_TIME 57 10070
TEST_TIME 58 10169
TEST_TIME 59 10119
TEST_TIME 60 10096
TEST_TIME 61 9989
TEST_TIME 62 9959
TEST_TIME 63 9903
TEST_TIME 64 9842
TEST_TIME 65 9742
TEST_TIME 66 9692
TEST_TIME 67 9650
TEST_TIME 68 9679
TEST_TIME 69 9602
TEST_TIME 70 9614
TEST_TIME 71 9584
TEST_TIME 72 9562
TEST_TIME 73 9553
TEST_TIME 74 9562
TEST_TIME 75 9551
TEST_TIME 76 9564
TEST_TIME 77 9563
TEST_TIME 78 9704
TEST_TIME 79 9743
TEST_TIME 80 9742
TEST_TIME 81 9747
TEST_TIME 82 9709
TEST_TIME 83 9628
TEST_TIME 84 9638
TEST_TIME 85 9585
TEST_TIME 86 9569
TEST_TIME 87 9511
TEST_TIME 88 9457
TEST_TIME 89 9450
TEST_TIME 90 9357
TEST_TIME 91 9341
TEST_TIME 92 9285
TEST_TIME 93 9290
TEST_TIME 94 9247
TEST_TIME 95 9308
TEST_TIME 96 9287
TEST_TIME 97 9255
TEST_TIME 98 9258
TEST_TIME 99 9245
TEST_TIME 100 9292
TEST_TIME 101 9346
TEST_TIME 102 9477
TEST_TIME 103 9461
TEST_TIME 104 9520
TEST_TIME 105 9525
TEST_TIME 106 9442
TEST_TIME 107 9421
TEST_TIME 108 9402
TEST_TIME 109 9387
TEST_TIME 110 9332
TEST_TIME 111 9336
TEST_TIME 112 9339
TEST_TIME 113 9322
TEST_TIME 114 9296
TEST_TIME 115 9261
TEST_TIME 116 9302
TEST_TIME 117 9279
TEST_TIME 118 9275
TEST_TIME 119 9333
TEST_TIME 120 9267

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-06-2022 04:38:03 to 24-06-2022 04:38:06.

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 1538339 1538339 0 0.00 0 38612 14 0 0
doc_list_biud 1538321 1538321 0 0.00 0 24004 44 0 0
sp_add_invoice_to_stock 5217 5071 146 2.79 35 178351 25717 0 146
sp_cancel_adding_invoice 5705 5705 0 0.00 6 86971 1321 0 0
sp_cancel_client_order 1744 1744 0 0.00 7 6651 416 0 0
sp_cancel_customer_reserve 557999 557999 0 0.00 1 24035 105 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 9491 130 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 4 1487 127 0 0
sp_cancel_supplier_invoice 6268 6260 8 0.12 3 16109 1045 0 8
sp_cancel_supplier_order 420 420 0 0.00 9 1538560 176337 0 0
sp_cancel_write_off 9301 9301 0 0.00 2 17487 180 0 0
sp_client_order 20290 20290 0 0.00 6 27224 448 0 0
sp_customer_reserve 466035 466035 0 0.00 1 101621 244 0 0
sp_fill_shopping_cart 31274 31274 0 0.00 1 35340 91 0 0
sp_fill_shopping_cart_clo_res 465995 465995 0 0.00 0 23264 18 0 0
sp_get_clo_for_invoice 5021 5021 0 0.00 0 27037 279 0 0
sp_kill_cost_storno 16121 16121 0 0.00 0 12351 77 0 0
sp_kill_qstorno_ret_qs2qd 1131979 1131979 0 0.00 0 23491 45 0 0
sp_kill_qty_storno 596344 596344 0 0.00 0 24000 104 0 0
sp_lock_selected_doc 20671 20671 0 0.00 0 8135 8 0 0
sp_make_cost_storno 27913 27913 0 0.00 0 15615 82 0 0
sp_multiply_rows_for_pdistr 27912 27912 0 0.00 0 10456 39 0 0
sp_multiply_rows_for_qdistr 20290 20290 0 0.00 0 25631 176 0 0
sp_pay_from_customer 7087 7087 0 0.00 6 19886 195 0 0
sp_pay_to_supplier 5909 5909 0 0.00 7 15691 201 0 0
sp_payment_common 12995 12995 0 0.00 2 10646 49 0 0
sp_qd_handle_on_cancel_clo 1744 1744 0 0.00 0 6641 331 0 0
sp_qd_handle_on_invoice_upd_sts 10726 10726 0 0.00 0 18462 576 0 0
sp_qd_handle_on_reserve_upd_sts 9894 9894 0 0.00 0 12072 67 0 0
sp_reserve_write_off 9897 9897 0 0.00 9 38679 305 0 0
sp_supplier_invoice 5526 5526 0 0.00 9 124859 3297 0 0
sp_supplier_order 5468 5468 0 0.00 8 34198 1380 0 0
srv_aggregate_perf_data 104 104 0 0.00 2812 146037 19893 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 846 110244 32954 0 0
srv_find_qd_qs_mism 1060301 1060301 0 0.00 0 8219 10 0 0
srv_make_invnt_saldo 462 462 0 0.00 54 56454 4441 0 0
srv_make_money_saldo 968 968 0 0.00 1 9181 358 0 0
srv_recalc_idx_stat 5 5 0 0.00 247804 404377 339141 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5075 5075 0 0.00 1 10555 1096 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 8162 523 0 0
t$perf-norm:sp_cancel_client_order 1744 1744 0 0.00 0 1786 30 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 826 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 621 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 662 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 877 35 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 109029 7217 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 1482 22 0 0
t$perf-norm:sp_client_order 20290 20290 0 0.00 0 25063 40 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 450 13 0 0
t$perf-norm:sp_pay_from_customer 7087 7087 0 0.00 0 24925 26 0 0
t$perf-norm:sp_pay_to_supplier 5909 5909 0 0.00 0 3162 18 0 0
t$perf-norm:sp_reserve_write_off 9897 9897 0 0.00 0 24919 27 0 0
t$perf-norm:sp_supplier_invoice 5526 5526 0 0.00 0 6764 35 0 0
t$perf-norm:sp_supplier_order 5468 5468 0 0.00 0 5209 34 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 248 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 326 10 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 1867 22 0 0
t$perf-norm:srv_make_money_saldo 968 968 0 0.00 0 557 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 247 78 0 0
v_add_invoice_to_stock 10326 10034 292 2.82 0 11845 29 0 292
v_all_customers 37140 37140 0 0.00 0 81 4 0 0
v_all_suppliers 30766 30766 0 0.00 0 185 5 0 0
v_all_wares 40577 40577 0 0.00 0 88 4 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 1483 17 0 0
v_cancel_client_order 3488 3488 0 0.00 0 2458 12 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 1736 14 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 2699 16 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 797 13 0 0
v_cancel_supplier_invoice 858 842 16 1.86 0 3979 33 0 16
v_cancel_supplier_order 840 840 0 0.00 0 1147 12 0 0
v_cancel_write_off 1896 1896 0 0.00 0 3275 30 0 0
v_max_id_clo_ord 5468 5468 0 0.00 0 4932 8 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 2462 16 0 0
v_max_id_ord_sup 5517 5517 0 0.00 0 1260 5 0 0
v_max_non_paid_invoice 1511 1511 0 0.00 0 73 9 0 0
v_max_non_paid_realizn 3749 3749 0 0.00 0 1634 8 0 0
v_min_id_clo_ord 5468 5468 0 0.00 0 2600 9 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 1019 9 0 0
v_min_id_ord_sup 5517 5517 0 0.00 0 10337 16 0 0
v_min_non_paid_invoice 5909 5909 0 0.00 0 7208 16 0 0
v_min_non_paid_realizn 7085 7085 0 0.00 0 10640 15 0 0
v_our_firm 10110 10110 0 0.00 0 74 5 0 0
v_reserve_write_off 19788 19788 0 0.00 0 9503 13 0 0
x_lock_depdocs_on_canc_invoice 11544 11544 0 0.00 0 19440 135 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 10982 367 0 0
x_make_qstorno_1000_1200 5468 5468 0 0.00 2 34180 1184 0 0
x_make_qstorno_1200_2000 5526 5526 0 0.00 4 114141 3064 0 0
x_make_qstorno_2100_3300 466034 466034 0 0.00 0 101614 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-06-2022 04:38:08 to 24-06-2022 04:38:12.

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
02:07:18 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:19 33004 8192000000 9722724912 10024206336 803103680 8.928 100 83 166 0 831692416 8427872 20160864
02:09:58 18697 8192000000 9778949232 10089594880 850602352 9.406 100 88 176 0 883803584 12330240 20870992
02:11:19 18921 8192000000 9811779296 10135248896 881527648 9.715 100 80 161 1 921129472 21452176 18149648
02:12:46 8201 8192000000 9893956720 10229460992 939329008 10.286 100 83 166 0 1001776816 44683888 17763920
02:13:57 3794 8192000000 9939411104 10285940736 972657856 10.613 100 88 175 1 1057267088 66745360 17863872
02:15:03 2715 8192000000 9962136352 10317070336 999014096 10.869 100 89 169 2 1083341680 67848240 16479344
02:16:07 1750 8192000000 9995148304 10362068992 1017921536 11.052 100 96 188 1 1113677760 76316352 19439872
02:17:11 711 8192000000 10014339712 10380234752 1040079120 11.265 100 98 186 1 1127447888 68704720 18664048
02:18:13 988 8192000000 10014032080 10387726336 1057413664 11.432 100 98 185 1 1133667296 57397824 18855808
02:19:15 1119 8192000000 10038132480 10417299456 1065206416 11.506 100 96 186 1 1163151056 79080448 18864192
02:20:19 7835 8192000000 10023663424 10401873920 1073476704 11.585 100 85 161 2 1142593808 55396256 13720848
02:21:28 958 8192000000 10053072064 10435502080 1084121344 11.687 100 92 178 2 1176821488 74536864 18163280
02:22:30 1001 8192000000 10052214896 10437369856 1081129472 11.658 100 91 172 0 1169338320 70385600 17823248
02:23:32 1034 8192000000 10029718720 10414845952 1093692928 11.778 100 91 180 0 1158544704 47141632 17710144
02:24:33 1361 8192000000 10044875072 10432192512 1086793392 11.712 100 83 160 0 1164373200 61150832 16428976
02:25:37 888 8192000000 10062135344 10451804160 1099472288 11.833 100 94 184 1 1186692944 69292496 17928160
02:26:38 1215 8192000000 10083658640 10479927296 1094708352 11.787 100 88 169 2 1204469536 92440624 17320560
02:27:40 1258 8192000000 10070933888 10459762688 1097034768 11.809 100 99 192 0 1188663472 72048624 19580080
02:28:42 940 8192000000 10044483056 10438893568 1089106208 11.734 100 75 140 1 1164719344 61161424 14451712
02:29:45 3030 8192000000 10061111824 10448236544 1083235072 11.678 100 86 168 0 1180524080 80653712 16635296
02:30:49 1027 8192000000 10046873232 10432847872 1073536800 11.586 100 93 174 1 1162346720 70949216 17860704
02:31:51 992 8192000000 10009249232 10382839808 1047390624 11.336 100 95 182 2 1132139760 63953616 20795520
02:32:54 1232 8192000000 10006912928 10380910592 1035036112 11.217 99 95 182 1 1126812912 73196768 18580032
02:33:56 3962 8192000000 10001349008 10379620352 1032910560 11.196 99 82 151 1 1120433488 72276848 15246080
02:35:02 1137 8192000000 9983648384 10358620160 1030001152 11.168 100 90 165 2 1101437584 55192112 16244320
02:36:04 1170 8192000000 9975275696 10346758144 1020812512 11.080 100 83 154 0 1095791728 59580192 15399024
02:37:06 1017 8192000000 9990107744 10371407872 1046630352 11.328 100 82 149 2 1111225760 50044176 14551232
02:38:13 1451 8192000000 10031485040 10410102784 1053472784 11.394 100 91 173 1 1152864768 81033600 18358384
02:39:18 10047 8192000000 10031361696 10418630656 1069243312 11.545 100 66 129 0 1158312944 76279440 12790192
02:40:33 3512 8192000000 10042354176 10429870080 1090086016 11.743 100 78 145 1 1180458256 76350528 14021712
02:41:39 924 8192000000 10081557952 10464645120 1097720784 11.816 100 85 161 1 1194412608 81369024 15322800
02:42:42 1193 8192000000 10125499456 10511228928 1106827776 11.902 100 96 179 0 1241458832 115286656 19344400
02:43:46 3737 8192000000 10139482032 10542043136 1115362448 11.983 100 75 140 3 1261933616 131332512 15238656
02:44:57 7377 8192000000 10153245008 10553499648 1129313584 12.115 100 70 138 2 1269939232 125714016 14911632
02:46:13 14244 8192000000 10212867744 10610532352 1126488528 12.088 99 73 143 3 1323910784 182282112 15140144
02:47:29 914 8192000000 10254712208 10652241920 1158395136 12.388 100 89 176 0 1369556096 190752048 20408912
02:48:36 1788 8192000000 10270698768 10676113408 1161669600 12.419 100 84 163 1 1381894928 202022880 18202448
02:49:39 5322 8192000000 10299386400 10709102592 1175395328 12.547 100 84 166 0 1412907408 218885936 18626144
02:50:54 1543 8192000000 10345739248 10775179264 1190623328 12.689 100 97 187 0 1453402704 240363408 22415968
02:51:58 1096 8192000000 10358346960 10766761984 1188937152 12.673 100 98 190 3 1467759856 257728032 21094672
02:53:04 1338 8192000000 10378265840 10799927296 1191221744 12.695 100 81 162 2 1485854720 276444816 18188160
02:54:08 879 8192000000 10403439168 10812092416 1197681280 12.755 100 96 183 0 1509645200 291023632 20940288
02:55:12 1131 8192000000 10417809408 10850021376 1189954400 12.683 100 93 178 0 1523907792 313124944 20828448
02:56:16 835 8192000000 10420097408 10833903616 1203721408 12.811 100 94 185 0 1525928352 300255872 21951072
02:57:20 1756 8192000000 10480758544 10906615808 1208937792 12.859 100 91 179 0 1585946608 356256512 20752304
02:58:26 1162 8192000000 10483638256 10934013952 1206132672 12.833 100 100 194 0 1588254704 358917856 23204176
02:59:29 1092 8192000000 10448145904 10860699648 1188209344 12.667 99 95 191 0 1552975104 341866784 22898976
03:00:33 836 8192000000 10454199968 10873704448 1194987072 12.730 100 97 182 1 1563793808 346190224 22616512
03:01:36 1798 8192000000 10522365424 10929516544 1191249120 12.695 100 95 184 0 1620041968 407503504 21289344
03:02:41 1602 8192000000 10540422224 10974547968 1197001072 12.748 100 92 179 2 1641633616 423551760 21080784
03:03:45 1095 8192000000 10534830032 11006226432 1183791984 12.626 100 96 193 0 1635633184 427422224 24418976
03:04:47 1482 8192000000 10579900800 11015258112 1194829472 12.728 100 78 155 1 1677519232 463865280 18824480
03:05:52 4693 8192000000 10568350512 11022561280 1207416352 12.845 100 64 124 1 1672319040 449685712 15216976
03:07:16 7156 8192000000 10587057664 11013390336 1191052320 12.693 100 63 126 0 1691205296 484355616 15797360
03:08:32 7540 8192000000 10595837312 11054448640 1188624752 12.671 100 47 90 1 1693065488 492581968 11858768
03:09:40 21988 8192000000 10587208480 11080077312 1193835872 12.719 100 39 76 0 1689145120 485460176 9849072
03:11:09 16003 8192000000 10619335184 11093811200 1181762416 12.607 100 34 68 0 1707547328 516627424 9157488
03:12:26 16580 8192000000 10525848832 11059806208 1166771104 12.467 100 27 52 0 1654297872 479880704 7646064
03:13:49 22449 8192000000 10368960576 10886729728 1149581680 12.306 99 24 48 1 1524410528 368459904 6368944
03:15:56 80628 8192000000 10081099168 10508054528 1078193216 11.630 95 22 44 1 1124559008 41607200 4758592
03:18:20 30959 8192000000 10115400976 10540122112 1146136448 12.273 100 56 101 1 1212530512 54951936 11442128
03:19:59 110242 8192000000 10086513520 10569392128 1127093904 12.094 99 32 65 1 1194097936 60077904 6926128
03:23:06 71022 8192000000 10095532848 10534064128 1092608912 11.767 95 40 80 0 1140005968 38840528 8556528
03:25:24 70022 8192000000 10073847296 10504409088 1120470528 12.031 98 22 44 4 1163830320 38807296 4552496
03:27:35 18856 8192000000 10134315856 10578968576 1137090000 12.188 99 29 56 0 1231692432 87893712 6708720
03:29:04 31780 8192000000 10205303056 10659368960 1151131104 12.320 100 44 85 1 1317886096 156756976 9998016
03:30:41 62797 8192000000 10154823488 10651557888 1114804848 11.978 97 18 36 0 1290963424 171759936 4398640
03:32:55 73856 8192000000 10147891824 10638090240 1126105120 12.085 99 11 22 0 1234150928 105360480 2685328
03:36:08 100173 8192000000 10060986880 10542166016 1116762192 11.996 98 20 40 1 1152216464 31900688 3553584
03:38:49 61150 8192000000 10064611520 10580602880 1123676256 12.062 99 11 22 0 1167133424 41271776 2185392
03:41:00 70732 8192000000 10073004368 10543792128 1122487488 12.050 98 19 39 2 1171794512 45586688 3720336
03:43:12 75846 8192000000 10134364240 10640883712 1146205440 12.274 100 19 38 0 1238726768 88513504 4007824
03:47:12 30218 8192000000 10094624912 10565259264 1136316080 12.181 99 28 56 1 1198087216 56386768 5384368
03:48:44 24438 8192000000 10074408160 10518310912 1118271552 12.011 98 49 98 2 1170566784 42236800 10058432
03:50:08 24550 8192000000 10134907120 10575912960 1147036176 12.282 100 46 92 1 1230907472 74194304 9676992
03:51:33 29710 8192000000 10130716000 10608607232 1144039520 12.254 99 24 48 1 1221042816 72511648 4491648
03:53:05 80986 8192000000 10144447824 10601463808 1093797744 11.779 95 21 43 0 1196551120 98058096 4695280
03:55:28 49494 8192000000 10070966880 10532073472 1100391840 11.841 97 12 24 1 1172856192 69884112 2580240
03:57:19 34533 8192000000 10136575232 10585116672 1134961408 12.168 100 39 77 1 1210001360 67129952 7910000
03:59:29 83575 8192000000 10081274432 10516774912 1116325248 11.992 98 28 56 0 1160166864 37695344 6146272
04:01:54 49743 8192000000 10059238560 10547462144 1125671232 12.081 100 25 50 1 1182423920 51885760 4866928
04:03:54 42983 8192000000 10047831936 10517479424 1122676256 12.052 100 13 25 0 1159556784 34554240 2326288
04:05:37 55754 8192000000 10046255712 10509950976 1118849744 12.016 99 10 19 1 1153514720 32239600 2425376
04:07:33 88355 8192000000 10075533248 10560835584 1133227648 12.152 100 13 25 0 1166331632 30789760 2314224
04:10:01 69628 8192000000 10113129456 10618298368 1137791344 12.195 100 18 36 1 1226317280 84578208 3947728
04:12:27 37320 8192000000 10077551184 10508619776 1119199584 12.019 98 18 36 2 1162786352 39813504 3773264
04:14:05 29451 8192000000 10116749456 10563514368 1142764000 12.242 100 30 60 2 1229630128 80625568 6240560
04:15:42 29564 8192000000 10179188256 10613542912 1149120736 12.301 100 32 64 1 1266385680 110181520 7083424
04:17:27 101466 8192000000 10137397024 10635632640 1107420080 11.908 98 21 42 0 1263687392 151269008 4998304
04:20:10 50710 8192000000 10102627056 10536443904 1107556448 11.909 98 31 61 2 1184495088 71282000 5656640
04:22:15 28861 8192000000 10055995984 10487087104 1087039680 11.715 97 34 64 1 1135689952 41719056 6931216
04:23:46 87765 8192000000 10091247728 10540707840 1103976512 11.875 98 37 74 0 1182002368 70879344 7146512
04:26:20 50575 8192000000 10056152704 10547032064 1115126640 11.981 100 9 17 1 1161000352 44155872 1717840
04:28:22 104668 8192000000 10049457200 10520489984 1099528496 11.833 98 11 22 0 1131966048 30425328 2012224
04:31:11 58435 8192000000 10094067168 10547544064 1125661680 12.080 100 22 44 0 1172316416 42026592 4628144
04:34:02 18900 8192000000 10064908384 10488832000 1104242800 11.878 98 49 96 2 1139584512 25089904 10251808
04:35:24 48047 8192000000 10113340688 10599485440 1135826304 12.176 100 22 44 1 1220232624 79776528 4629792
04:37:14 7129 8192000000 9024853216 9257148416 177316048 2.118 16 1 2 0 189295120 11943664 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 24-06-2022 04:38:14 to 24-06-2022 04:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-06-2022 04:38:31 to 24-06-2022 04:38:33.

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          222643
MON$OLDEST_ACTIVE               222644
MON$OLDEST_SNAPSHOT             222644
MON$NEXT_TRANSACTION            222644
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                       2104576
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

24.06.22 04:38:34. 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-06-24 04:38:36 No severe PSQL-related problems occured
24.06.22 04:38:37. End of report.