Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.07.2022 13: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 15313536 kB
MemAvailable 31079252 kB
Buffers 0 kB
Cached 16342456 kB
SwapCached 2516 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-07-12 13:37:14
DTS_END                         2022-07-12 13:37:14.8760
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/37716
TRN_ID                          218608
ATT_ID                          568
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: 8762.53 24161 1051503
customer order: creation 166.82 490 20018
customer order: refuse 13.83 489 1659
order to supplier: creation 44.85 1418 5382
order to supplier: removal 3.26 193236 391
invoice (draft): creation 45.92 3733 5510
invoice (draft): removal 47.39 1091 5687
invoice accept: apply 42.03 27297 5044
invoice accept: cancel 42.59 1516 5111
customer reserve: creation 3897.83 253 467739
customer reserve: removal 4170.00 118 500400
realization accept: apply 80.47 328 9656
realization accept: cancel 79.18 206 9502
payment from customer: creation 58.68 208 7041
payment from customer: removal 5.40 172 648
payment to supplier: creation 48.23 244 5787
payment to supplier: removal 3.43 145 411
service: total inventory turnovers 3.97 4837 476
service: total monetary turnovers 7.76 446 931
service: aggregate perf. data 0.87 19208 104
service: refresh index statistics 0.05 227790 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-07-2022 13:38:30 to 12-07-2022 13:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9501
TEST_TIME 2 7341
TEST_TIME 3 7314
TEST_TIME 4 7232
TEST_TIME 5 7128
TEST_TIME 6 7153
TEST_TIME 7 7209
TEST_TIME 8 7155
TEST_TIME 9 7154
TEST_TIME 10 7308
TEST_TIME 11 7597
TEST_TIME 12 7473
TEST_TIME 13 7472
TEST_TIME 14 7405
TEST_TIME 15 7560
TEST_TIME 16 7638
TEST_TIME 17 7643
TEST_TIME 18 7594
TEST_TIME 19 7468
TEST_TIME 20 7539
TEST_TIME 21 7642
TEST_TIME 22 7690
TEST_TIME 23 7664
TEST_TIME 24 7667
TEST_TIME 25 7697
TEST_TIME 26 7599
TEST_TIME 27 8177
TEST_TIME 28 8327
TEST_TIME 29 8643
TEST_TIME 30 9177
TEST_TIME 31 9055
TEST_TIME 32 9179
TEST_TIME 33 9464
TEST_TIME 34 9641
TEST_TIME 35 9884
TEST_TIME 36 10311
TEST_TIME 37 10701
TEST_TIME 38 10658
TEST_TIME 39 10649
TEST_TIME 40 10784
TEST_TIME 41 10695
TEST_TIME 42 10461
TEST_TIME 43 10300
TEST_TIME 44 10194
TEST_TIME 45 10259
TEST_TIME 46 10145
TEST_TIME 47 10140
TEST_TIME 48 10282
TEST_TIME 49 10238
TEST_TIME 50 10188
TEST_TIME 51 10072
TEST_TIME 52 10041
TEST_TIME 53 9939
TEST_TIME 54 9848
TEST_TIME 55 9845
TEST_TIME 56 9800
TEST_TIME 57 9648
TEST_TIME 58 9616
TEST_TIME 59 9573
TEST_TIME 60 9506
TEST_TIME 61 9434
TEST_TIME 62 9334
TEST_TIME 63 9299
TEST_TIME 64 9263
TEST_TIME 65 9180
TEST_TIME 66 9255
TEST_TIME 67 9199
TEST_TIME 68 9185
TEST_TIME 69 9142
TEST_TIME 70 9147
TEST_TIME 71 9149
TEST_TIME 72 9119
TEST_TIME 73 9100
TEST_TIME 74 9082
TEST_TIME 75 9139
TEST_TIME 76 9174
TEST_TIME 77 9125
TEST_TIME 78 9112
TEST_TIME 79 9102
TEST_TIME 80 9136
TEST_TIME 81 9217
TEST_TIME 82 9254
TEST_TIME 83 9273
TEST_TIME 84 9295
TEST_TIME 85 9214
TEST_TIME 86 9162
TEST_TIME 87 9085
TEST_TIME 88 9025
TEST_TIME 89 8999
TEST_TIME 90 8935
TEST_TIME 91 8913
TEST_TIME 92 8942
TEST_TIME 93 8897
TEST_TIME 94 8881
TEST_TIME 95 8942
TEST_TIME 96 8925
TEST_TIME 97 8910
TEST_TIME 98 8885
TEST_TIME 99 8920
TEST_TIME 100 8912
TEST_TIME 101 8891
TEST_TIME 102 8935
TEST_TIME 103 8951
TEST_TIME 104 8990
TEST_TIME 105 9037
TEST_TIME 106 9022
TEST_TIME 107 9033
TEST_TIME 108 9087
TEST_TIME 109 9127
TEST_TIME 110 9083
TEST_TIME 111 9015
TEST_TIME 112 8962
TEST_TIME 113 8966
TEST_TIME 114 8945
TEST_TIME 115 8925
TEST_TIME 116 8881
TEST_TIME 117 8879
TEST_TIME 118 8863
TEST_TIME 119 8845
TEST_TIME 120 8790

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 13:38:33 to 12-07-2022 13:38:36.

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 1479148 1479148 0 0.00 0 30050 15 0 0
doc_list_biud 1479145 1479145 0 0.00 0 43764 47 0 0
sp_add_invoice_to_stock 5168 5044 124 2.39 44 178740 27297 0 124
sp_cancel_adding_invoice 5111 5111 0 0.00 6 86193 1517 0 0
sp_cancel_client_order 1659 1659 0 0.00 6 8820 490 0 0
sp_cancel_customer_reserve 500400 500400 0 0.00 1 43615 118 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 4 11823 173 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 3 3325 145 0 0
sp_cancel_supplier_invoice 5696 5687 9 0.15 3 20994 1092 0 9
sp_cancel_supplier_order 391 391 0 0.00 13 1731705 193237 0 0
sp_cancel_write_off 9502 9502 0 0.00 2 13337 207 0 0
sp_client_order 20018 20018 0 0.00 5 32210 490 0 0
sp_customer_reserve 467739 467739 0 0.00 1 46839 254 0 0
sp_fill_shopping_cart 30909 30909 0 0.00 1 29149 102 0 0
sp_fill_shopping_cart_clo_res 467699 467699 0 0.00 0 12263 20 0 0
sp_get_clo_for_invoice 4998 4998 0 0.00 0 26120 337 0 0
sp_kill_cost_storno 15672 15672 0 0.00 0 30027 94 0 0
sp_kill_qstorno_ret_qs2qd 1016380 1016380 0 0.00 0 43546 51 0 0
sp_kill_qty_storno 537404 537404 0 0.00 0 43764 118 0 0
sp_lock_selected_doc 20157 20157 0 0.00 0 6873 7 0 0
sp_make_cost_storno 27482 27482 0 0.00 0 18115 94 0 0
sp_multiply_rows_for_pdistr 27482 27482 0 0.00 0 7858 44 0 0
sp_multiply_rows_for_qdistr 20018 20018 0 0.00 0 32185 167 0 0
sp_pay_from_customer 7041 7041 0 0.00 5 20232 209 0 0
sp_pay_to_supplier 5787 5787 0 0.00 6 21235 245 0 0
sp_payment_common 12828 12828 0 0.00 2 7605 54 0 0
sp_qd_handle_on_cancel_clo 1659 1659 0 0.00 1 8807 402 0 0
sp_qd_handle_on_invoice_upd_sts 10109 10109 0 0.00 0 43763 667 0 0
sp_qd_handle_on_reserve_upd_sts 9656 9656 0 0.00 0 12309 78 0 0
sp_reserve_write_off 9656 9656 0 0.00 9 18904 328 0 0
sp_supplier_invoice 5510 5510 0 0.00 7 91903 3734 0 0
sp_supplier_order 5382 5382 0 0.00 7 90143 1418 0 0
srv_aggregate_perf_data 104 104 0 0.00 2541 200227 19209 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 594 130335 32723 0 0
srv_find_qd_qs_mism 1001341 1001341 0 0.00 0 16759 11 0 0
srv_make_invnt_saldo 476 476 0 0.00 70 94227 4838 0 0
srv_make_money_saldo 931 931 0 0.00 1 11741 447 0 0
srv_recalc_idx_stat 6 6 0 0.00 79106 408739 227790 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5044 5044 0 0.00 1 10806 1134 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 12806 628 0 0
t$perf-norm:sp_cancel_client_order 1659 1659 0 0.00 0 2606 33 0 0
t$perf-norm:sp_cancel_customer_reserve 664 664 0 0.00 0 893 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 12293 46 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 326 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 1477 46 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 1 115365 8368 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 4421 30 0 0
t$perf-norm:sp_client_order 20018 20018 0 0.00 0 31806 45 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1181 17 0 0
t$perf-norm:sp_pay_from_customer 7041 7041 0 0.00 0 2104 20 0 0
t$perf-norm:sp_pay_to_supplier 5787 5787 0 0.00 0 12481 25 0 0
t$perf-norm:sp_reserve_write_off 9656 9656 0 0.00 0 4216 25 0 0
t$perf-norm:sp_supplier_invoice 5510 5510 0 0.00 0 4415 34 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 4136 39 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1311 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 251 7 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 1115 21 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 727 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 2 201 96 0 0
v_add_invoice_to_stock 10244 9996 248 2.42 0 7824 28 0 248
v_all_customers 37150 37150 0 0.00 0 128 4 0 0
v_all_suppliers 30220 30220 0 0.00 0 83 5 0 0
v_all_wares 40036 40036 0 0.00 0 171 5 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 2599 24 0 0
v_cancel_client_order 3318 3318 0 0.00 0 5552 15 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 6188 20 0 0
v_cancel_customer_reserve 1328 1328 0 0.00 0 2332 14 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 3307 17 0 0
v_cancel_supplier_invoice 792 774 18 2.27 0 989 29 0 18
v_cancel_supplier_order 782 782 0 0.00 0 581 13 0 0
v_cancel_write_off 1910 1910 0 0.00 0 4342 33 0 0
v_max_id_clo_ord 5381 5381 0 0.00 0 464 6 0 0
v_max_id_clo_res 984 984 0 0.00 0 2271 15 0 0
v_max_id_ord_sup 5510 5510 0 0.00 0 3753 8 0 0
v_max_non_paid_invoice 1568 1568 0 0.00 0 2710 11 0 0
v_max_non_paid_realizn 3500 3500 0 0.00 0 4726 11 0 0
v_min_id_clo_ord 5381 5381 0 0.00 0 4065 10 0 0
v_min_id_clo_res 984 984 0 0.00 0 5546 20 0 0
v_min_id_ord_sup 5510 5510 0 0.00 0 6850 16 0 0
v_min_non_paid_invoice 5787 5787 0 0.00 0 7581 21 0 0
v_min_non_paid_realizn 7041 7041 0 0.00 0 5379 20 0 0
v_our_firm 9968 9968 0 0.00 0 164 5 0 0
v_reserve_write_off 19312 19312 0 0.00 0 18746 15 0 0
x_lock_depdocs_on_canc_invoice 10411 10411 0 0.00 0 20087 153 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 3623 386 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 2 90028 1223 0 0
x_make_qstorno_1200_2000 5510 5510 0 0.00 3 91863 3442 0 0
x_make_qstorno_2100_3300 467738 467738 0 0.00 0 46825 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 13:38:37 to 12-07-2022 13:38:41.

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:18 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:19 23306 8192000000 9722144368 10022162432 802337808 8.920 100 85 170 0 830516192 7444800 20733584
11:09:48 16454 8192000000 9764096880 10070949888 836022864 9.260 100 85 170 0 867645200 11157376 20464960
11:11:12 17123 8192000000 9798875648 10114699264 866538240 9.565 100 88 174 0 904196992 17510848 20147904
11:12:43 11753 8192000000 9854473296 10186346496 908737552 9.985 100 83 162 2 962160496 35150368 18272576
11:14:00 4303 8192000000 9915805296 10258296832 958671360 10.476 100 93 182 0 1038535808 60593504 19270944
11:15:07 2781 8192000000 9972753936 10326941696 986915920 10.751 100 89 175 0 1087963216 83439744 17607552
11:16:11 945 8192000000 9993780304 10355806208 1019035952 11.063 100 96 188 1 1113585600 75683136 18866512
11:17:14 1627 8192000000 10029595056 10399686656 1042728960 11.291 100 92 177 1 1161830000 100810720 18290320
11:18:17 1108 8192000000 10013626416 10385321984 1057360992 11.431 100 96 171 0 1133476848 59241808 16874048
11:19:20 1243 8192000000 10028410160 10407903232 1060604752 11.462 100 96 186 0 1154563792 75135952 18823088
11:20:23 1087 8192000000 10054822384 10438598656 1070894496 11.561 100 97 192 1 1181310864 91241328 19175040
11:21:26 907 8192000000 10020280416 10400829440 1078790864 11.636 100 98 185 1 1146770848 49547600 18432384
11:22:28 1026 8192000000 10029900080 10416640000 1078892688 11.637 100 89 170 0 1150420832 53726288 17801856
11:23:31 1003 8192000000 10059024096 10452480000 1086629200 11.711 100 88 171 2 1183223680 79120256 17474224
11:24:34 1123 8192000000 10061816864 10450489344 1085182528 11.697 100 91 174 0 1185727344 81907056 18637760
11:25:37 1226 8192000000 10064479696 10457034752 1087538288 11.719 100 95 182 1 1187775488 80948496 19288704
11:26:38 1330 8192000000 10083645936 10473029632 1098851264 11.827 100 96 185 2 1207403696 88838944 19713488
11:27:41 1566 8192000000 10036742576 10425290752 1096896912 11.808 100 81 154 0 1154196176 41770416 15528848
11:28:43 1042 8192000000 10028077376 10414444544 1082502992 11.671 100 94 176 2 1152185072 50962256 18719824
11:29:45 1122 8192000000 10041877472 10427711488 1081990704 11.666 100 88 160 3 1166399872 66342384 18066784
11:30:47 698 8192000000 10014525856 10397495296 1067152384 11.525 99 83 158 1 1136650560 51902816 17595360
11:31:48 909 8192000000 9998761776 10375880704 1064350720 11.498 100 99 182 3 1121608672 38613104 18644848
11:32:50 1010 8192000000 9970883184 10356809728 1054973328 11.408 100 83 158 2 1099548576 27425312 17149936
11:33:52 843 8192000000 10001929552 10376335360 1046826032 11.330 100 94 186 1 1120054144 55119136 18108976
11:34:54 1213 8192000000 9996541120 10369581056 1020157728 11.074 98 96 174 5 1119405600 81222896 18024976
11:35:56 1222 8192000000 9991728528 10364813312 1038851984 11.254 100 82 150 3 1105669408 52114784 14702640
11:36:59 1055 8192000000 9986363744 10368598016 1045491328 11.317 100 77 150 3 1109203792 48229472 15482992
11:38:08 11097 8192000000 9997560656 10382622720 1064449216 11.499 100 66 125 0 1111966416 35695408 11821792
11:39:26 10432 8192000000 10065063568 10457292800 1079831520 11.646 100 63 124 0 1187757280 95756416 12169344
11:40:39 3087 8192000000 10082995088 10475102208 1100619296 11.844 100 78 155 2 1209956512 94126976 15210240
11:41:47 1095 8192000000 10112626512 10501640192 1108125856 11.915 100 98 183 0 1233144592 105475904 19542832
11:42:52 2853 8192000000 10149789456 10544136192 1120102096 12.028 100 85 162 0 1256175952 119143456 16930400
11:44:00 26260 8192000000 10141684032 10565378048 1118215312 12.010 100 60 120 0 1262797296 131530288 13051696
11:45:32 1074 8192000000 10184380976 10585980928 1139491872 12.211 100 94 176 4 1300896416 140219552 21184992
11:46:40 6594 8192000000 10203364256 10613211136 1152019888 12.328 100 69 135 3 1321867392 154800320 15047184
11:47:50 1489 8192000000 10262822784 10662264832 1162420768 12.426 100 88 171 0 1377063728 196058624 18584336
11:48:54 2211 8192000000 10249766144 10656579584 1167865312 12.477 100 80 159 0 1362316416 176627824 17823280
11:50:00 1204 8192000000 10251124128 10668498944 1168027904 12.478 100 90 177 3 1361344560 173065632 20251024
11:51:05 1554 8192000000 10304130960 10707783680 1173142816 12.526 100 92 174 0 1408718640 216523104 19052720
11:52:10 1436 8192000000 10353411136 10772111360 1183652048 12.624 100 99 195 0 1467479168 260419616 23407504
11:53:16 875 8192000000 10349782336 10760986624 1189992608 12.683 100 87 172 1 1462374416 253077392 19304416
11:54:24 4080 8192000000 10357384080 10782093312 1196134816 12.740 100 77 153 1 1466941776 252869152 17937808
11:55:34 2118 8192000000 10409004576 10821443584 1194963776 12.730 100 88 176 1 1515765776 299808448 20993552
11:56:39 1342 8192000000 10433414128 10856128512 1201690432 12.792 100 94 181 0 1551376896 327572320 22114144
11:57:47 775 8192000000 10465676192 10876092416 1199153984 12.768 100 93 178 2 1572042896 351597648 21291264
11:58:51 1244 8192000000 10501622080 10916245504 1213800944 12.904 100 94 184 0 1606321712 370921840 21598928
11:59:55 1546 8192000000 10548399104 10979835904 1209331088 12.863 100 98 191 1 1648788896 415754784 23703024
12:01:03 1133 8192000000 10554631792 10972807168 1195981856 12.739 100 97 185 3 1658011264 439938608 22090800
12:02:08 1672 8192000000 10590109408 11016220672 1189795088 12.681 100 97 194 2 1689381296 476895280 22690928
12:03:15 1258 8192000000 10594811968 11046383616 1190394800 12.687 100 98 185 2 1695931808 482859120 22677888
12:04:18 1744 8192000000 10595442832 11070582784 1184306320 12.630 100 93 175 0 1696452368 490434576 21711472
12:05:22 587 8192000000 10578196768 11020869632 1177142944 12.564 100 70 133 2 1678552304 484786672 16622688
12:06:43 6164 8192000000 10513031360 10995171328 1188651408 12.671 100 53 106 0 1625529120 423361680 13516032
12:07:51 10905 8192000000 10488793008 10983632896 1178147264 12.573 100 38 76 1 1592118256 404565552 9405440
12:09:17 20286 8192000000 10497989504 10982486016 1181191712 12.601 100 42 78 1 1620503040 429682272 9629056
12:10:53 33285 8192000000 10393100944 10890498048 1176180416 12.555 100 30 60 0 1545485056 361693600 7611040
12:12:27 25273 8192000000 10289609744 10831699968 1133822368 12.157 99 18 36 0 1434799296 296081552 4895376
12:13:54 31452 8192000000 10088868400 10580815872 1077105184 11.620 95 21 42 3 1171425328 89526656 4793488
12:15:26 1996 8192000000 10144107136 10558111744 1123399648 12.059 99 71 136 0 1236236672 98748096 14088928
12:16:30 642 8192000000 10143324752 10578927616 1128219488 12.105 100 92 180 1 1236993648 88687088 20087072
12:17:33 1500 8192000000 10162987488 10567573504 1147229328 12.283 100 81 151 5 1262623264 98817504 16576432
12:18:35 130332 8192000000 10189293600 10635001856 1139271392 12.209 99 47 90 0 1300322288 150175488 10875408
12:21:47 34154 8192000000 10207944752 10697334784 1149968544 12.309 100 58 116 0 1327267792 164250304 13048944
12:23:43 65211 8192000000 10229942544 10714898432 1146980528 12.281 100 17 34 1 1338870928 187771616 4118784
12:25:51 90981 8192000000 10138488768 10626019328 1147079472 12.282 100 46 86 0 1239769488 83821440 8868576
12:28:30 68766 8192000000 10101478272 10590388224 1149766096 12.307 100 27 53 0 1227607040 70954144 6886800
12:30:40 45187 8192000000 10103675040 10571542528 1138566640 12.202 100 29 58 2 1195805968 52223728 5015600
12:33:13 90467 8192000000 10051279744 10480680960 1103712288 11.873 98 31 57 0 1145818832 35473904 6632640
12:35:49 80270 8192000000 10073537584 10530697216 1122078560 12.047 99 33 65 1 1156351728 27457664 6815504
12:38:10 52686 8192000000 10071468000 10520907776 1121423408 12.040 99 15 30 0 1160104544 35385632 3295504
12:40:06 7956 8192000000 10121919600 10558337024 1141342144 12.228 100 46 83 0 1215936112 65744848 8849120
12:41:16 41057 8192000000 10126389280 10583040000 1141765616 12.232 100 31 63 0 1231371680 82651344 6954720
12:43:16 29103 8192000000 10134548960 10601168896 1146791280 12.279 100 38 75 1 1257963792 102206272 8966240
12:45:08 66653 8192000000 10063272608 10574585856 1125247824 12.077 99 12 24 0 1195292352 67487200 2557328
12:47:27 47650 8192000000 10047544880 10504708096 1110081504 11.933 98 20 40 0 1142032448 28172432 3778512
12:49:20 30808 8192000000 10070014528 10513354752 1112122560 11.953 98 39 77 0 1162735968 43018416 7594992
12:50:52 28763 8192000000 10109055568 10542145536 1111775664 11.949 98 50 100 0 1187400720 65604928 10020128
12:52:22 75445 8192000000 10057223488 10532605952 1131486400 12.135 100 17 34 0 1205743152 71051600 3205152
12:54:41 23303 8192000000 10103736592 10521378816 1122072016 12.047 99 51 101 0 1168299424 36388480 9838928
12:56:06 48545 8192000000 10156533968 10615672832 1144238784 12.255 100 28 56 0 1231566752 81588128 5739840
12:58:06 47656 8192000000 10103828048 10597974016 1126598112 12.089 99 10 20 0 1199551440 70554992 2398336
13:00:03 91147 8192000000 10047705744 10516242432 1040082128 11.265 93 14 28 0 1092823200 50289088 2451984
13:02:57 121299 8192000000 10040093008 10506403840 1115759264 11.987 100 22 45 0 1142847264 22861472 4226528
13:06:01 51268 8192000000 10105343904 10560581632 1110164656 11.934 98 31 59 0 1192547728 76136624 6246448
13:07:53 50816 8192000000 10115907104 10568146944 1100682976 11.844 98 22 44 0 1234057936 128600256 4774704
13:10:40 53777 8192000000 10130194416 10584895488 1102441024 11.861 98 12 24 0 1212729936 107340880 2948032
13:12:38 56000 8192000000 10030510528 10490658816 1100034976 11.838 99 12 23 2 1130965072 28803776 2126320
13:14:35 67917 8192000000 10097984992 10541322240 1108531120 11.919 99 25 49 2 1152787552 39590560 4665872
13:16:44 17073 8192000000 10062371296 10504994816 1108554912 11.919 99 33 62 1 1151792816 35967136 7270768
13:18:04 26136 8192000000 10118398912 10560339968 1113605296 11.967 98 35 69 1 1195223408 73803936 7814176
13:19:31 36479 8192000000 10133715856 10583986176 1121066464 12.037 99 31 60 0 1222621360 95288800 6266096
13:21:09 35225 8192000000 10124335152 10619576320 1123952656 12.064 100 27 52 0 1220372912 90904592 5515664
13:22:47 40950 8192000000 10083131952 10530086912 1135194816 12.170 100 50 94 2 1188479040 43121984 10162240
13:24:33 62531 8192000000 10101903392 10588540928 1124759488 12.072 98 42 84 0 1231756032 98691600 8304944
13:26:37 76529 8192000000 10045576192 10487705600 1117125984 12.000 98 30 59 0 1165302832 41155136 7021712
13:29:39 37362 8192000000 10082274064 10527481856 1118475248 12.013 98 39 77 0 1170403840 44343296 7585296
13:31:17 62100 8192000000 10076493488 10557931520 1115619696 11.986 98 9 17 0 1171795216 54494960 1680560
13:33:20 76794 8192000000 10097945184 10574462976 1137199008 12.189 99 13 26 0 1196593600 56409296 2985296
13:35:40 67080 8192000000 10059015616 10519146496 1118413728 12.012 98 16 32 0 1155666800 34171760 3081312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 12-07-2022 13:38:43 to 12-07-2022 13:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-07-2022 13:39:01 to 12-07-2022 13:39:03.

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          217883
MON$OLDEST_ACTIVE               219032
MON$OLDEST_SNAPSHOT             219032
MON$NEXT_TRANSACTION            219032
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                       2131712
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:104: 
:105: mscs-test-161.budzdorov.ru    Tue Jul 12 13:37:17 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
--- end of diff output ---

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

12.07.22 13:39:04. 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-07-12 13:39:06 No severe PSQL-related problems occured
12.07.22 13:39:07. End of report.