Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.04.2022 04: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 16270352 kB
MemAvailable 31412988 kB
Buffers 20 kB
Cached 15343744 kB
SwapCached 2712 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-20 04:37:13
DTS_END                         2022-04-20 04:37:13.9840
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/39104
TRN_ID                          216495
ATT_ID                          589
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: 8742.47 23728 1049096
customer order: creation 168.75 490 20250
customer order: refuse 14.73 385 1768
order to supplier: creation 45.16 1496 5419
order to supplier: removal 3.32 160028 398
invoice (draft): creation 44.45 3414 5334
invoice (draft): removal 46.65 972 5598
invoice accept: apply 42.84 26633 5141
invoice accept: cancel 42.70 1220 5124
customer reserve: creation 3918.71 256 470245
customer reserve: removal 4121.55 102 494586
realization accept: apply 82.43 282 9891
realization accept: cancel 83.63 178 10035
payment from customer: creation 57.97 209 6956
payment from customer: removal 5.68 115 681
payment to supplier: creation 47.54 209 5705
payment to supplier: removal 3.66 125 439
service: total inventory turnovers 4.08 3828 490
service: total monetary turnovers 7.70 371 924
service: aggregate perf. data 0.90 17144 108
service: refresh index statistics 0.03 257106 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-04-2022 04:38:22 to 20-04-2022 04:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12551
TEST_TIME 2 10782
TEST_TIME 3 9843
TEST_TIME 4 9218
TEST_TIME 5 8314
TEST_TIME 6 7941
TEST_TIME 7 7699
TEST_TIME 8 7753
TEST_TIME 9 7247
TEST_TIME 10 7378
TEST_TIME 11 7397
TEST_TIME 12 7437
TEST_TIME 13 7652
TEST_TIME 14 7635
TEST_TIME 15 7690
TEST_TIME 16 7780
TEST_TIME 17 7995
TEST_TIME 18 8183
TEST_TIME 19 8372
TEST_TIME 20 8739
TEST_TIME 21 8649
TEST_TIME 22 8682
TEST_TIME 23 8794
TEST_TIME 24 8859
TEST_TIME 25 8841
TEST_TIME 26 8907
TEST_TIME 27 9434
TEST_TIME 28 9750
TEST_TIME 29 9850
TEST_TIME 30 10103
TEST_TIME 31 10247
TEST_TIME 32 10900
TEST_TIME 33 11454
TEST_TIME 34 11232
TEST_TIME 35 11061
TEST_TIME 36 10936
TEST_TIME 37 10651
TEST_TIME 38 10450
TEST_TIME 39 10318
TEST_TIME 40 10374
TEST_TIME 41 10422
TEST_TIME 42 10252
TEST_TIME 43 10167
TEST_TIME 44 10321
TEST_TIME 45 10271
TEST_TIME 46 10123
TEST_TIME 47 10041
TEST_TIME 48 9892
TEST_TIME 49 9831
TEST_TIME 50 9774
TEST_TIME 51 9869
TEST_TIME 52 9912
TEST_TIME 53 9847
TEST_TIME 54 9827
TEST_TIME 55 9695
TEST_TIME 56 9603
TEST_TIME 57 9539
TEST_TIME 58 9651
TEST_TIME 59 9698
TEST_TIME 60 9604
TEST_TIME 61 9573
TEST_TIME 62 9649
TEST_TIME 63 9715
TEST_TIME 64 9587
TEST_TIME 65 9488
TEST_TIME 66 9412
TEST_TIME 67 9370
TEST_TIME 68 9301
TEST_TIME 69 9238
TEST_TIME 70 9167
TEST_TIME 71 9134
TEST_TIME 72 9041
TEST_TIME 73 8988
TEST_TIME 74 8985
TEST_TIME 75 9051
TEST_TIME 76 9097
TEST_TIME 77 9044
TEST_TIME 78 9043
TEST_TIME 79 9046
TEST_TIME 80 9026
TEST_TIME 81 9084
TEST_TIME 82 9041
TEST_TIME 83 9052
TEST_TIME 84 9134
TEST_TIME 85 9126
TEST_TIME 86 9039
TEST_TIME 87 9012
TEST_TIME 88 8973
TEST_TIME 89 8937
TEST_TIME 90 8887
TEST_TIME 91 8848
TEST_TIME 92 8908
TEST_TIME 93 8915
TEST_TIME 94 8878
TEST_TIME 95 8860
TEST_TIME 96 8855
TEST_TIME 97 8853
TEST_TIME 98 8865
TEST_TIME 99 8907
TEST_TIME 100 8946
TEST_TIME 101 8923
TEST_TIME 102 8962
TEST_TIME 103 9045
TEST_TIME 104 9036
TEST_TIME 105 9038
TEST_TIME 106 8971
TEST_TIME 107 8982
TEST_TIME 108 8931
TEST_TIME 109 8926
TEST_TIME 110 8915
TEST_TIME 111 8881
TEST_TIME 112 8833
TEST_TIME 113 8797
TEST_TIME 114 8771
TEST_TIME 115 8806
TEST_TIME 116 8776
TEST_TIME 117 8816
TEST_TIME 118 8800
TEST_TIME 119 8821
TEST_TIME 120 8770

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-04-2022 04:38:25 to 20-04-2022 04:38:29.

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 1479965 1479965 0 0.00 0 19442 15 0 0
doc_list_biud 1479948 1479948 0 0.00 0 58494 40 0 0
sp_add_invoice_to_stock 5260 5141 119 2.26 59 243744 26633 0 119
sp_cancel_adding_invoice 5124 5124 0 0.00 6 76181 1220 0 0
sp_cancel_client_order 1768 1768 0 0.00 6 9812 386 0 0
sp_cancel_customer_reserve 494586 494586 0 0.00 1 61663 102 0 0
sp_cancel_pay_from_customer 681 681 0 0.00 3 3531 116 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 4 5947 126 0 0
sp_cancel_supplier_invoice 5610 5598 12 0.21 3 15225 972 0 12
sp_cancel_supplier_order 398 398 0 0.00 16 1389827 160029 0 0
sp_cancel_write_off 10035 10035 0 0.00 2 9816 178 0 0
sp_client_order 20250 20250 0 0.00 6 47945 491 0 0
sp_customer_reserve 470245 470245 0 0.00 1 51511 257 0 0
sp_fill_shopping_cart 30988 30988 0 0.00 1 25053 94 0 0
sp_fill_shopping_cart_clo_res 470215 470215 0 0.00 0 17536 21 0 0
sp_get_clo_for_invoice 5100 5100 0 0.00 0 22395 303 0 0
sp_kill_cost_storno 16279 16279 0 0.00 0 8336 70 0 0
sp_kill_qstorno_ret_qs2qd 1005203 1005203 0 0.00 0 58451 44 0 0
sp_kill_qty_storno 532491 532491 0 0.00 0 58492 102 0 0
sp_lock_selected_doc 20734 20734 0 0.00 0 6998 7 0 0
sp_make_cost_storno 27650 27650 0 0.00 0 16912 86 0 0
sp_multiply_rows_for_pdistr 27645 27645 0 0.00 0 8893 39 0 0
sp_multiply_rows_for_qdistr 20248 20248 0 0.00 0 8013 182 0 0
sp_pay_from_customer 6956 6956 0 0.00 6 26799 209 0 0
sp_pay_to_supplier 5705 5705 0 0.00 7 26820 210 0 0
sp_payment_common 12660 12660 0 0.00 2 15440 46 0 0
sp_qd_handle_on_cancel_clo 1768 1768 0 0.00 0 9768 317 0 0
sp_qd_handle_on_invoice_upd_sts 10215 10215 0 0.00 0 15819 507 0 0
sp_qd_handle_on_reserve_upd_sts 9891 9891 0 0.00 0 47133 62 0 0
sp_reserve_write_off 9891 9891 0 0.00 9 47189 282 0 0
sp_supplier_invoice 5334 5334 0 0.00 7 133976 3414 0 0
sp_supplier_order 5419 5419 0 0.00 7 64586 1496 0 0
srv_aggregate_perf_data 108 108 0 0.00 2376 289715 17145 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 798 145799 36220 0 0
srv_find_qd_qs_mism 999348 999348 0 0.00 0 19440 12 0 0
srv_make_invnt_saldo 490 490 0 0.00 55 59513 3828 0 0
srv_make_money_saldo 924 924 0 0.00 1 22870 372 0 0
srv_recalc_idx_stat 4 4 0 0.00 46798 397096 257107 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5147 5147 0 0.00 1 13256 1004 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 6473 463 0 0
t$perf-norm:sp_cancel_client_order 1768 1768 0 0.00 0 2314 29 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 1187 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 681 681 0 0.00 0 576 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 312 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 759 35 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 82896 6683 0 0
t$perf-norm:sp_cancel_write_off 1019 1019 0 0.00 0 3765 21 0 0
t$perf-norm:sp_client_order 20251 20251 0 0.00 0 6582 36 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 2118 17 0 0
t$perf-norm:sp_pay_from_customer 6959 6959 0 0.00 0 5706 19 0 0
t$perf-norm:sp_pay_to_supplier 5705 5705 0 0.00 0 4297 20 0 0
t$perf-norm:sp_reserve_write_off 9891 9891 0 0.00 0 6329 25 0 0
t$perf-norm:sp_supplier_invoice 5334 5334 0 0.00 0 5924 30 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 5851 31 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 883 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 38 4 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 2737 24 0 0
t$perf-norm:srv_make_money_saldo 924 924 0 0.00 0 886 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 167 63 0 0
v_add_invoice_to_stock 10414 10176 238 2.28 0 25175 26 0 238
v_all_customers 36996 36996 0 0.00 0 90 4 0 0
v_all_suppliers 29516 29516 0 0.00 0 71 4 0 0
v_all_wares 40490 40490 0 0.00 0 54 4 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 22617 44 0 0
v_cancel_client_order 3536 3536 0 0.00 0 3650 10 0 0
v_cancel_customer_prepayment 1362 1362 0 0.00 0 3392 13 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 1 1270 11 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 728 12 0 0
v_cancel_supplier_invoice 786 762 24 3.05 0 2830 30 0 24
v_cancel_supplier_order 796 796 0 0.00 0 700 11 0 0
v_cancel_write_off 2038 2038 0 0.00 0 7055 28 0 0
v_max_id_clo_ord 5416 5416 0 0.00 0 4355 6 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 3948 16 0 0
v_max_id_ord_sup 5326 5326 0 0.00 0 5313 8 0 0
v_max_non_paid_invoice 1686 1686 0 0.00 0 436 8 0 0
v_max_non_paid_realizn 3626 3626 0 0.00 0 936 7 0 0
v_min_id_clo_ord 5416 5416 0 0.00 0 3552 9 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 4240 21 0 0
v_min_id_ord_sup 5326 5326 0 0.00 0 7338 12 0 0
v_min_non_paid_invoice 5703 5703 0 0.00 0 8757 15 0 0
v_min_non_paid_realizn 6956 6956 0 0.00 0 13210 17 0 0
v_our_firm 10154 10154 0 0.00 0 94 5 0 0
v_reserve_write_off 19778 19778 0 0.00 0 26737 14 0 0
x_lock_depdocs_on_canc_invoice 10341 10341 0 0.00 0 12359 138 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 19048 373 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 2 64548 1317 0 0
x_make_qstorno_1200_2000 5334 5334 0 0.00 3 126441 3148 0 0
x_make_qstorno_2100_3300 470243 470243 0 0.00 0 51510 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-04-2022 04:38:30 to 20-04-2022 04:38:34.

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:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:18 21717 8192000000 9712896544 10010546176 791703984 8.812 100 83 164 1 819637488 7769504 20164000
02:09:43 27397 8192000000 9760364624 10070044672 834916688 9.249 100 83 166 0 866476880 11020656 20539536
02:11:20 12644 8192000000 9797386080 10115731456 868908368 9.589 100 82 164 0 904494128 16333872 19251888
02:12:35 12894 8192000000 9844946384 10176933888 908027296 9.978 100 80 156 1 955035808 29752016 17256496
02:13:50 6799 8192000000 9905583904 10248380416 952592944 10.417 100 87 173 0 1022620624 51501024 18526656
02:15:01 4303 8192000000 9961645120 10314981376 989821168 10.780 100 85 166 1 1082453008 76039184 16592656
02:16:07 3277 8192000000 9989168048 10354003968 1015477136 11.028 100 77 149 0 1104341936 73721952 15142848
02:17:14 1472 8192000000 10021553248 10388852736 1041816672 11.282 100 93 182 0 1145845648 86108864 17920112
02:18:18 884 8192000000 10015310112 10385760256 1057376240 11.431 100 96 180 1 1135163872 59889264 17898368
02:19:21 1331 8192000000 10030322288 10405253120 1063687072 11.492 100 97 185 0 1147436432 65735328 18014032
02:20:24 1309 8192000000 10050500464 10429140992 1069629792 11.549 100 93 178 1 1169236624 80378912 19227920
02:21:26 949 8192000000 10059155152 10439356416 1080081520 11.648 100 97 191 2 1178238416 78812752 19344144
02:22:29 1088 8192000000 10044171328 10431062016 1082582000 11.672 100 86 163 1 1165010016 64710304 17717712
02:23:31 1521 8192000000 10031398720 10413916160 1087626496 11.720 100 89 163 2 1150133760 45096096 17411168
02:24:33 1179 8192000000 10064597552 10452631552 1090447536 11.747 100 84 161 2 1182940944 77947728 14545680
02:25:36 1236 8192000000 10082954880 10473357312 1094885120 11.789 100 98 195 3 1204951776 90410448 19656208
02:26:38 1174 8192000000 10056594384 10441248768 1096802128 11.807 100 89 169 3 1174159168 61002160 16354880
02:27:41 1273 8192000000 10071264608 10465837056 1097136880 11.810 100 85 171 0 1192271376 77829920 17304576
02:28:43 1543 8192000000 10046993696 10434977792 1098264320 11.821 100 83 158 0 1170080240 55448368 16367552
02:29:46 3609 8192000000 10037340432 10427183104 1090987520 11.752 100 72 137 4 1150810528 45407072 14415936
02:30:51 1581 8192000000 10041213328 10427969536 1075825872 11.608 100 84 153 3 1159384272 68376336 15182064
02:31:54 1354 8192000000 10031846480 10416566272 1078899904 11.637 100 89 164 3 1153901952 57220336 17781712
02:32:57 1267 8192000000 10043734944 10425757696 1063781072 11.493 100 92 177 0 1166451552 85308288 17362192
02:33:59 1033 8192000000 9996868816 10372755456 1043301344 11.296 99 91 170 2 1117721136 57296928 17122864
02:35:01 1186 8192000000 9990897808 10367111168 1047211872 11.334 100 93 175 0 1112161120 47408208 17541040
02:36:03 1432 8192000000 9995091216 10367848448 1042334320 11.287 100 82 156 0 1107062992 49464352 15264320
02:37:08 1187 8192000000 10008558064 10384912384 1051314528 11.373 100 94 176 0 1129357344 60791168 17251648
02:38:14 3805 8192000000 10025545392 10402508800 1068335072 11.536 100 82 147 0 1146561968 64791104 13435792
02:39:24 3206 8192000000 10039466368 10428276736 1082867632 11.675 100 77 154 0 1169327840 70441712 16018496
02:40:32 1317 8192000000 10066709520 10449879040 1100040448 11.838 100 80 149 1 1181603856 66958944 14604464
02:41:36 1700 8192000000 10085576032 10474119168 1111238880 11.944 100 84 146 1 1203733168 78570048 13924240
02:42:41 10848 8192000000 10122041552 10522152960 1127107408 12.094 100 56 110 0 1242351392 103648288 11595696
02:43:59 15951 8192000000 10160398480 10575773696 1140157680 12.217 100 60 119 1 1272650592 119506544 12986368
02:45:24 5530 8192000000 10162267264 10577510400 1149145968 12.301 100 56 111 0 1280535040 118583168 12805904
02:46:33 1635 8192000000 10214714704 10612461568 1158565200 12.390 100 91 166 2 1324823616 147404128 18854288
02:47:38 5199 8192000000 10233693376 10642874368 1161144016 12.414 100 70 130 4 1349543776 174088464 14311296
02:48:48 1160 8192000000 10289116672 10696785920 1176359856 12.556 100 85 166 0 1401296672 206993152 17943664
02:49:52 1334 8192000000 10325011568 10728488960 1176655424 12.559 100 94 182 2 1438794368 240645408 21493536
02:50:56 1359 8192000000 10340002096 10749018112 1176634192 12.559 100 97 186 0 1453220096 255725856 20860048
02:52:00 876 8192000000 10385485264 10790748160 1186643600 12.652 100 95 183 0 1495300640 287852048 20804992
02:53:04 1045 8192000000 10409613856 10824433664 1194935008 12.729 100 93 184 0 1525674512 307732816 23006688
02:54:09 1129 8192000000 10440746864 10861441024 1190485216 12.688 100 99 195 2 1550433376 336871312 23076848
02:55:13 1774 8192000000 10430584400 10873319424 1189445056 12.678 99 93 179 0 1543950496 334901680 19603760
02:56:18 949 8192000000 10457083216 10887626752 1201774912 12.793 100 91 177 1 1566734384 343952336 21007136
02:57:21 3889 8192000000 10420903680 10873057280 1197323104 12.751 100 69 134 4 1530430080 317507728 15599248
02:58:41 888 8192000000 10456877040 10871033856 1199852944 12.775 100 96 193 0 1560027296 337817024 22357328
02:59:45 790 8192000000 10484643424 10932285440 1200974080 12.785 100 97 193 0 1588422640 365292512 22156048
03:00:50 890 8192000000 10526145200 10940190720 1194975280 12.730 100 96 187 0 1633637552 415384096 23278176
03:01:54 7613 8192000000 10520606592 10962657280 1186054240 12.647 99 60 116 0 1623276384 423243856 13978288
03:03:08 910 8192000000 10506586256 10954383360 1177761104 12.569 100 94 185 0 1609373856 409356528 22256224
03:04:10 4261 8192000000 10472808976 10977206272 1166453056 12.464 100 65 128 2 1576997024 394337280 16206688
03:05:19 7879 8192000000 10435390048 10894442496 1159002144 12.394 100 64 127 0 1552297168 376212528 17082496
03:06:29 11893 8192000000 10371058736 10810707968 1146772480 12.279 100 34 67 2 1475764656 319658864 9333312
03:07:55 36386 8192000000 10363164560 10825895936 1156982928 12.375 100 27 54 1 1474893440 310576784 7333728
03:09:43 20990 8192000000 10154359632 10684350464 1141264848 12.227 99 14 28 0 1255241680 110947840 3028992
03:11:39 3109 8192000000 10111944608 10533097472 1154824096 12.355 100 69 134 3 1204431056 34069392 15537568
03:12:44 139906 8192000000 10170018752 10626981888 1150370208 12.313 99 34 68 0 1264832368 106475792 7986368
03:16:13 34950 8192000000 10196059552 10637836288 1123986240 12.065 97 43 84 1 1254358896 121306480 9066176
03:17:56 65265 8192000000 10114230928 10565718016 1113493232 11.965 98 13 24 2 1226280432 110213072 2574128
03:20:01 57150 8192000000 10130019200 10622337024 1117443568 12.003 99 27 54 2 1250229184 126107312 6678304
03:21:59 95595 8192000000 10101528432 10541649920 1092094240 11.763 96 21 41 1 1160069056 63710112 4264704
03:24:35 65592 8192000000 10139219920 10596167680 1119246448 12.020 98 23 46 1 1187025328 62252448 5526432
03:26:52 9634 8192000000 10124185008 10616242176 1133048304 12.150 100 16 32 1 1228931088 91737328 4145456
03:28:03 41992 8192000000 10159766416 10668630016 1126354048 12.087 100 15 30 1 1261983536 132246704 3382784
03:29:54 38026 8192000000 10084288432 10528395264 1126749504 12.091 99 46 87 1 1176484016 40293824 9440688
03:31:38 79503 8192000000 10139388800 10592116736 1119712672 12.024 99 33 64 2 1205790128 79278144 6799312
03:34:02 48886 8192000000 10161238848 10603409408 1134027696 12.159 100 30 60 2 1243857584 103573296 6256592
03:35:54 96832 8192000000 10087792768 10531160064 1084562672 11.691 96 14 28 1 1142698128 55165168 2970288
03:38:31 18926 8192000000 10141449216 10566742016 1131968208 12.140 99 59 114 1 1208779584 65597936 11213440
03:39:51 63332 8192000000 10067603984 10552680448 1096812192 11.807 97 23 46 2 1169033760 68027264 4194304
03:42:01 51224 8192000000 10033721520 10459828224 1082722928 11.673 96 30 59 2 1104574848 16262000 5589920
03:43:54 30022 8192000000 10058322736 10522517504 1115098784 11.981 100 32 64 1 1175698672 54324928 6274960
03:45:28 67951 8192000000 10042173248 10505756672 1109367792 11.926 99 7 14 0 1129938320 19362624 1207904
03:47:38 98528 8192000000 10071777136 10534838272 1141219360 12.227 100 18 33 1 1201272224 56430992 3621872
03:50:16 44074 8192000000 10129653472 10579083264 1126556416 12.089 98 19 38 0 1200131808 69395744 4179648
03:52:05 87633 8192000000 10120930560 10614079488 1144920720 12.262 100 14 28 0 1229345360 81221584 3203056
03:54:44 69755 8192000000 10084192960 10521051136 1068448576 11.537 93 16 32 1 1123846768 52116272 3281920
03:57:07 59839 8192000000 10129720608 10580910080 1136937952 12.187 100 28 54 1 1249634960 107198192 5498816
03:59:12 16898 8192000000 10092930976 10514112512 1129173504 12.114 100 51 100 0 1166975968 28042432 9760032
04:00:29 11016 8192000000 10136315440 10568548352 1131916000 12.139 100 42 84 1 1227336336 87180096 8240240
04:01:41 28118 8192000000 10083335920 10529767424 1137925152 12.196 100 36 72 1 1172462048 26904112 7632784
04:03:10 121821 8192000000 10072991776 10528346112 1109130128 11.924 98 43 86 0 1173904080 56215184 8558768
04:06:12 65558 8192000000 10086373344 10552512512 1128633984 12.108 99 9 16 1 1185260976 54896832 1730160
04:08:36 56985 8192000000 10072266384 10570465280 1119111136 12.019 98 14 28 1 1189320928 67312848 2896944
04:10:35 84740 8192000000 10086209568 10529890304 1094688544 11.787 96 20 40 1 1133085264 33404544 4992176
04:13:04 21975 8192000000 10150662448 10585485312 1152303872 12.331 100 62 120 0 1230705488 64569248 13832368
04:14:37 34229 8192000000 10155397312 10615918592 1138842128 12.205 99 39 79 2 1247252672 99896032 8514512
04:16:11 19439 8192000000 10143164736 10596499456 1117344592 12.002 98 34 68 0 1223189520 98433008 7411920
04:17:33 35267 8192000000 10155324672 10632478720 1147291088 12.284 100 27 54 0 1238377376 85541136 5545152
04:19:11 145797 8192000000 10075655536 10525220864 1047646464 11.338 91 13 24 2 1158595296 108016976 2931856
04:22:38 90953 8192000000 10129387456 10577293312 1120801104 12.035 99 50 96 1 1195483056 65026944 9655008
04:26:10 53049 8192000000 10071788288 10540503040 1118414064 12.012 100 33 60 2 1193439008 68079280 6945664
04:28:09 58286 8192000000 10068722336 10518831104 1122473088 12.050 99 18 30 0 1163489504 38050896 2965520
04:30:20 66701 8192000000 10107487648 10552778752 1115852768 11.988 98 18 36 0 1197167952 77390224 3924960
04:32:27 63254 8192000000 10096826384 10567561216 1124812944 12.072 100 13 25 1 1211004976 82973040 3218992
04:34:33 14314 8192000000 10065741856 10503045120 1116740160 11.996 99 27 54 1 1157426032 34692656 5993216
04:35:50 23757 8192000000 10130340800 10565271552 1138792400 12.204 100 53 106 2 1212649968 62794848 11062720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-04-2022 04:38:36 to 20-04-2022 04:38:52.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-04-2022 04:38:53 to 20-04-2022 04:38:55.

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          214707
MON$OLDEST_ACTIVE               217238
MON$OLDEST_SNAPSHOT             217238
MON$NEXT_TRANSACTION            217238
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                       2060064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:377: 
:378: mscs-test-161.budzdorov.ru    Wed Apr 20 04:37:26 2022
:379:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:380: 
--- end of diff output ---

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

20.04.22 04:38:56. 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-20 04:38:58 No severe PSQL-related problems occured
20.04.22 04:39:00. End of report.