Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.10.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 15274320 kB
MemAvailable 30512140 kB
Buffers 20 kB
Cached 16358648 kB
SwapCached 2032 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-03 04:37:22
DTS_END                         2022-10-03 04:37:22.9710
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/47894
TRN_ID                          215870
ATT_ID                          594
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: 9264.00 28666 1111680
customer order: creation 160.75 455 19290
customer order: refuse 14.22 394 1706
order to supplier: creation 43.41 1377 5209
order to supplier: removal 3.22 193747 386
invoice (draft): creation 43.18 2896 5182
invoice (draft): removal 53.62 987 6434
invoice accept: apply 40.75 26155 4890
invoice accept: cancel 48.82 1114 5858
customer reserve: creation 3827.50 249 459300
customer reserve: removal 4736.41 104 568369
realization accept: apply 78.25 241 9390
realization accept: cancel 91.86 165 11023
payment from customer: creation 54.73 165 6567
payment from customer: removal 5.58 115 669
payment to supplier: creation 46.34 173 5561
payment to supplier: removal 3.31 142 397
service: total inventory turnovers 3.75 3832 450
service: total monetary turnovers 7.23 356 868
service: aggregate perf. data 1.05 13733 126
service: refresh index statistics 0.04 326926 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-10-2022 04:38:12 to 03-10-2022 04:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9706
TEST_TIME 2 6964
TEST_TIME 3 6754
TEST_TIME 4 6021
TEST_TIME 5 6037
TEST_TIME 6 6140
TEST_TIME 7 6171
TEST_TIME 8 6014
TEST_TIME 9 6033
TEST_TIME 10 6055
TEST_TIME 11 6018
TEST_TIME 12 6202
TEST_TIME 13 6247
TEST_TIME 14 6202
TEST_TIME 15 6374
TEST_TIME 16 6503
TEST_TIME 17 6403
TEST_TIME 18 6690
TEST_TIME 19 7252
TEST_TIME 20 7458
TEST_TIME 21 7938
TEST_TIME 22 8301
TEST_TIME 23 8418
TEST_TIME 24 8386
TEST_TIME 25 8662
TEST_TIME 26 9075
TEST_TIME 27 9178
TEST_TIME 28 9936
TEST_TIME 29 10254
TEST_TIME 30 10533
TEST_TIME 31 11043
TEST_TIME 32 11398
TEST_TIME 33 11156
TEST_TIME 34 10943
TEST_TIME 35 11227
TEST_TIME 36 11071
TEST_TIME 37 10982
TEST_TIME 38 10829
TEST_TIME 39 10577
TEST_TIME 40 10372
TEST_TIME 41 10230
TEST_TIME 42 10257
TEST_TIME 43 10088
TEST_TIME 44 10038
TEST_TIME 45 9966
TEST_TIME 46 9960
TEST_TIME 47 9970
TEST_TIME 48 9972
TEST_TIME 49 10051
TEST_TIME 50 9982
TEST_TIME 51 9819
TEST_TIME 52 9821
TEST_TIME 53 9878
TEST_TIME 54 10026
TEST_TIME 55 9973
TEST_TIME 56 9970
TEST_TIME 57 9994
TEST_TIME 58 10040
TEST_TIME 59 9985
TEST_TIME 60 9991
TEST_TIME 61 9950
TEST_TIME 62 9985
TEST_TIME 63 9997
TEST_TIME 64 9905
TEST_TIME 65 9892
TEST_TIME 66 9782
TEST_TIME 67 9686
TEST_TIME 68 9725
TEST_TIME 69 9656
TEST_TIME 70 9575
TEST_TIME 71 9476
TEST_TIME 72 9417
TEST_TIME 73 9436
TEST_TIME 74 9413
TEST_TIME 75 9402
TEST_TIME 76 9382
TEST_TIME 77 9384
TEST_TIME 78 9397
TEST_TIME 79 9357
TEST_TIME 80 9368
TEST_TIME 81 9331
TEST_TIME 82 9377
TEST_TIME 83 9522
TEST_TIME 84 9583
TEST_TIME 85 9691
TEST_TIME 86 9711
TEST_TIME 87 9671
TEST_TIME 88 9666
TEST_TIME 89 9593
TEST_TIME 90 9562
TEST_TIME 91 9510
TEST_TIME 92 9482
TEST_TIME 93 9436
TEST_TIME 94 9388
TEST_TIME 95 9366
TEST_TIME 96 9346
TEST_TIME 97 9272
TEST_TIME 98 9301
TEST_TIME 99 9275
TEST_TIME 100 9260
TEST_TIME 101 9271
TEST_TIME 102 9299
TEST_TIME 103 9274
TEST_TIME 104 9243
TEST_TIME 105 9316
TEST_TIME 106 9330
TEST_TIME 107 9359
TEST_TIME 108 9382
TEST_TIME 109 9420
TEST_TIME 110 9396
TEST_TIME 111 9405
TEST_TIME 112 9360
TEST_TIME 113 9363
TEST_TIME 114 9318
TEST_TIME 115 9309
TEST_TIME 116 9337
TEST_TIME 117 9294
TEST_TIME 118 9311
TEST_TIME 119 9307
TEST_TIME 120 9289

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-10-2022 04:38:16 to 03-10-2022 04:38:19.

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 1533583 1533583 0 0.00 0 27188 13 0 0
doc_list_biud 1533572 1533572 0 0.00 0 42297 44 0 0
sp_add_invoice_to_stock 4955 4890 65 1.31 37 257465 26155 0 65
sp_cancel_adding_invoice 5858 5858 0 0.00 5 103982 1114 0 0
sp_cancel_client_order 1706 1706 0 0.00 5 13584 395 0 0
sp_cancel_customer_reserve 568369 568369 0 0.00 1 38660 105 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 8593 116 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 6514 142 0 0
sp_cancel_supplier_invoice 6444 6434 10 0.15 2 42374 987 0 10
sp_cancel_supplier_order 386 386 0 0.00 19 1580686 193747 0 0
sp_cancel_write_off 11023 11023 0 0.00 2 12345 165 0 0
sp_client_order 19290 19290 0 0.00 5 28486 455 0 0
sp_customer_reserve 459300 459300 0 0.00 1 66268 249 0 0
sp_fill_shopping_cart 29661 29661 0 0.00 1 13465 72 0 0
sp_fill_shopping_cart_clo_res 459266 459266 0 0.00 0 16176 19 0 0
sp_get_clo_for_invoice 4847 4847 0 0.00 0 45351 309 0 0
sp_kill_cost_storno 17947 17947 0 0.00 0 19000 72 0 0
sp_kill_qstorno_ret_qs2qd 1154581 1154581 0 0.00 0 42295 46 0 0
sp_kill_qty_storno 608010 608010 0 0.00 0 42297 104 0 0
sp_lock_selected_doc 19810 19810 0 0.00 0 10918 8 0 0
sp_make_cost_storno 26364 26364 0 0.00 0 10067 67 0 0
sp_multiply_rows_for_pdistr 26363 26363 0 0.00 0 9671 36 0 0
sp_multiply_rows_for_qdistr 19290 19290 0 0.00 0 15667 184 0 0
sp_pay_from_customer 6567 6567 0 0.00 5 10078 165 0 0
sp_pay_to_supplier 5561 5561 0 0.00 6 8989 173 0 0
sp_payment_common 12128 12128 0 0.00 2 9371 42 0 0
sp_qd_handle_on_cancel_clo 1706 1706 0 0.00 0 12559 318 0 0
sp_qd_handle_on_invoice_upd_sts 10704 10704 0 0.00 0 22618 478 0 0
sp_qd_handle_on_reserve_upd_sts 9388 9388 0 0.00 0 14895 51 0 0
sp_reserve_write_off 9390 9390 0 0.00 9 14909 241 0 0
sp_supplier_invoice 5182 5182 0 0.00 9 105107 2897 0 0
sp_supplier_order 5209 5209 0 0.00 7 33194 1377 0 0
srv_aggregate_perf_data 126 126 0 0.00 2399 122155 13734 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 764 119505 35393 0 0
srv_find_qd_qs_mism 1064216 1064216 0 0.00 0 27183 11 0 0
srv_make_invnt_saldo 450 450 0 0.00 105 81175 3833 0 0
srv_make_money_saldo 868 868 0 0.00 1 9011 356 0 0
srv_recalc_idx_stat 5 5 0 0.00 175615 447647 326926 0 0
t$perf-abend:sp_add_invoice_to_stock 65 65 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4894 4894 0 0.00 1 10904 723 0 0
t$perf-norm:sp_cancel_adding_invoice 397 397 0 0.00 1 6583 385 0 0
t$perf-norm:sp_cancel_client_order 1706 1706 0 0.00 0 1724 24 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 1098 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 5595 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 1150 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 391 391 0 0.00 0 2227 40 0 0
t$perf-norm:sp_cancel_supplier_order 386 386 0 0.00 0 77047 6524 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 2338 23 0 0
t$perf-norm:sp_client_order 19290 19290 0 0.00 0 7771 30 0 0
t$perf-norm:sp_customer_reserve 946 946 0 0.00 0 1329 12 0 0
t$perf-norm:sp_pay_from_customer 6568 6568 0 0.00 0 10347 17 0 0
t$perf-norm:sp_pay_to_supplier 5561 5561 0 0.00 0 1489 14 0 0
t$perf-norm:sp_reserve_write_off 9390 9390 0 0.00 0 6469 19 0 0
t$perf-norm:sp_supplier_invoice 5182 5182 0 0.00 0 1749 23 0 0
t$perf-norm:sp_supplier_order 5209 5209 0 0.00 0 6377 29 0 0
t$perf-norm:srv_aggregate_perf_data 126 126 0 0.00 0 190 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 73 7 0 0
t$perf-norm:srv_make_invnt_saldo 450 450 0 0.00 0 587 11 0 0
t$perf-norm:srv_make_money_saldo 868 868 0 0.00 0 1622 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 203 64 0 0
v_add_invoice_to_stock 9810 9680 130 1.32 0 6280 22 0 130
v_all_customers 35358 35358 0 0.00 0 72 3 0 0
v_all_suppliers 28764 28764 0 0.00 0 82 4 0 0
v_all_wares 38572 38572 0 0.00 0 86 3 0 0
v_cancel_adding_invoice 794 794 0 0.00 0 3773 21 0 0
v_cancel_client_order 3412 3412 0 0.00 0 3151 10 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 2576 11 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 1571 10 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1315 11 0 0
v_cancel_supplier_invoice 802 782 20 2.49 0 1255 20 0 20
v_cancel_supplier_order 772 772 0 0.00 0 745 10 0 0
v_cancel_write_off 1942 1942 0 0.00 0 1994 23 0 0
v_max_id_clo_ord 5201 5201 0 0.00 0 1702 5 0 0
v_max_id_clo_res 946 946 0 0.00 0 816 11 0 0
v_max_id_ord_sup 5172 5172 0 0.00 0 4900 9 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 237 7 0 0
v_max_non_paid_realizn 3292 3292 0 0.00 0 357 6 0 0
v_min_id_clo_ord 5201 5201 0 0.00 0 3210 7 0 0
v_min_id_clo_res 946 946 0 0.00 0 941 10 0 0
v_min_id_ord_sup 5172 5172 0 0.00 0 4971 9 0 0
v_min_non_paid_invoice 5561 5561 0 0.00 0 8959 18 0 0
v_min_non_paid_realizn 6567 6567 0 0.00 0 9366 20 0 0
v_our_firm 9764 9764 0 0.00 0 46 4 0 0
v_reserve_write_off 18774 18774 0 0.00 0 4000 11 0 0
x_lock_depdocs_on_canc_invoice 11901 11901 0 0.00 0 28837 120 0 0
x_lock_depdocs_on_canc_sup_ord 386 386 0 0.00 0 10818 369 0 0
x_make_qstorno_1000_1200 5209 5209 0 0.00 2 33072 1233 0 0
x_make_qstorno_1200_2000 5182 5182 0 0.00 4 105030 2697 0 0
x_make_qstorno_2100_3300 459298 459298 0 0.00 0 66264 220 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 03-10-2022 04:38:20 to 03-10-2022 04:38:25.

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:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 21497 8192000000 9718933856 10016583680 799678160 8.893 100 89 178 0 828700960 7593136 21429664
02:09:55 17383 8192000000 9759487936 10064932864 834202208 9.242 100 92 184 0 867659728 11808896 21648624
02:11:19 15466 8192000000 9791013520 10107764736 863868352 9.539 100 87 174 0 900964240 17193744 19902144
02:12:39 8813 8192000000 9849340352 10180673536 897109616 9.870 100 76 148 1 956457168 43198000 16149552
02:13:58 3982 8192000000 9900566160 10241388544 952887712 10.419 100 87 168 1 1018034848 47494320 17652816
02:15:05 2138 8192000000 9970803728 10324631552 997860448 10.858 100 88 171 0 1083882432 69226224 16795760
02:16:10 788 8192000000 10006842624 10372505600 1019198000 11.064 100 96 181 0 1123069872 84821008 19050864
02:17:13 1134 8192000000 10012713968 10379698176 1044575264 11.309 100 94 184 2 1129603936 67362096 17666576
02:18:16 986 8192000000 10014218288 10389590016 1059432656 11.451 100 93 174 0 1136817312 58693120 18691536
02:19:18 1721 8192000000 10027471600 10400423936 1066458000 11.518 100 88 170 1 1140178304 57553872 16166432
02:20:21 8475 8192000000 10036607776 10423619584 1066972304 11.523 100 70 133 2 1162720160 81618224 14129632
02:21:30 1068 8192000000 10059482736 10444148736 1077504608 11.624 100 96 184 1 1183016144 85872656 19638880
02:22:32 800 8192000000 10043779920 10436165632 1080651648 11.654 100 82 161 1 1164817136 67810448 16355040
02:23:33 994 8192000000 10050305072 10434940928 1081997008 11.666 100 89 174 2 1170471712 70896672 17578032
02:24:35 1999 8192000000 10044560032 10433142784 1087028720 11.714 100 75 133 0 1165347088 65813472 12504896
02:25:38 4794 8192000000 10029196464 10429894656 1092818480 11.769 100 63 122 2 1157925072 52871312 12235280
02:26:44 1132 8192000000 10052285936 10437619712 1091605584 11.758 100 95 186 2 1175971392 64282144 20083664
02:27:45 1178 8192000000 10043878288 10436395008 1092353088 11.765 100 96 179 3 1166559728 55962848 18243792
02:28:48 970 8192000000 10027590240 10414571520 1067214224 11.525 98 91 169 1 1153648992 69270608 17164160
02:29:50 1554 8192000000 9999913120 10386022400 1057765488 11.435 99 78 145 1 1124936224 53159872 14010864
02:30:52 2409 8192000000 10017260928 10394124288 1050595184 11.366 98 81 150 1 1140872128 75821120 14455824
02:31:55 1039 8192000000 9995378688 10375364608 1042319936 11.287 98 80 152 4 1119190272 60496096 16374240
02:32:57 1266 8192000000 9990112016 10370203648 1051152512 11.372 100 87 162 2 1110840320 44132656 15555152
02:34:02 1844 8192000000 10015623472 10404159488 1058265968 11.440 100 71 133 0 1143352400 70813072 14273360
02:35:05 1629 8192000000 9980124592 10359513088 1038320112 11.249 100 77 136 5 1099503984 48146080 13037792
02:36:07 1073 8192000000 9962448240 10336186368 1027438752 11.144 100 86 161 2 1088066384 43584736 17042896
02:37:10 1146 8192000000 9987486160 10362277888 1021261952 11.084 98 91 173 2 1109877456 72485696 16129808
02:38:15 1097 8192000000 10011779488 10391130112 1061100320 11.467 100 98 190 0 1132485696 52950640 18434736
02:39:23 6719 8192000000 10024511088 10407489536 1065685840 11.511 100 70 134 0 1145187536 65069920 14431776
02:40:35 4132 8192000000 10052383840 10438115328 1088671424 11.730 100 60 115 0 1163304192 62280896 12351872
02:41:43 6945 8192000000 10122968640 10513866752 1109247600 11.925 100 69 126 0 1243491088 120579024 13664464
02:43:09 13718 8192000000 10173200560 10569904128 1125521200 12.079 100 64 126 2 1292639456 153109888 14008368
02:44:28 2797 8192000000 10235474208 10631413760 1141869856 12.233 100 78 149 0 1356799408 198049552 16880000
02:45:36 5352 8192000000 10275835344 10679783424 1161086224 12.413 100 62 124 0 1394351440 218854912 14410304
02:46:49 1055 8192000000 10318850256 10719821824 1171653680 12.512 100 86 170 0 1432685712 241121792 19910240
02:47:56 7374 8192000000 10386229248 10790539264 1187970048 12.664 100 77 152 0 1492735952 287674096 17091808
02:49:07 764 8192000000 10407586480 10818191360 1187371424 12.659 100 95 186 0 1519228640 309751664 22105552
02:50:12 1471 8192000000 10474079360 10874867712 1202108848 12.796 100 95 183 1 1578835216 356519040 20207328
02:51:18 1905 8192000000 10515437392 10922033152 1209992416 12.869 100 87 172 0 1627185600 396809648 20383536
02:52:24 751 8192000000 10567595152 10991542272 1219781984 12.960 100 98 191 1 1677635120 434078944 23774192
02:53:27 1096 8192000000 10573789344 10977771520 1194730688 12.727 100 95 181 0 1683372848 466159808 22482352
02:54:31 771 8192000000 10625886416 11044974592 1211781472 12.886 100 97 194 0 1732348816 496815936 23751408
02:55:36 1443 8192000000 10587411712 11078762496 1206465184 12.836 100 95 184 0 1688657408 458928304 23263920
02:56:41 2401 8192000000 10602409920 11067805696 1190565632 12.689 100 80 152 0 1707919648 497455904 19898112
02:57:45 4216 8192000000 10584946304 11056283648 1184851344 12.635 100 63 126 0 1694222208 492908064 16462800
02:58:54 2778 8192000000 10576963008 11000532992 1175799808 12.551 99 68 136 0 1684653792 491914928 16939056
03:00:06 6129 8192000000 10538462208 10978353152 1176096320 12.554 100 53 103 1 1644309360 455154944 13058096
03:01:36 22727 8192000000 10557549520 11037310976 1178292352 12.574 100 45 90 0 1679439488 489561552 11585584
03:03:26 40661 8192000000 10481949936 10969108480 1173065328 12.525 100 33 65 0 1630248688 448786800 8396560
03:05:31 19451 8192000000 10322259584 10893357056 1167608992 12.474 100 29 56 0 1488895296 314160368 7125936
03:06:52 37107 8192000000 10293242864 10823606272 1164707696 12.447 100 19 38 0 1421845008 252415072 4722240
03:08:46 64190 8192000000 10083212960 10570182656 1131739584 12.138 98 10 20 0 1196680256 62998080 1942592
03:10:50 39438 8192000000 10114023568 10551160832 1124883184 12.073 98 37 65 2 1201675520 69844672 6947664
03:12:34 104435 8192000000 10065345216 10545500160 1083651680 11.682 95 15 30 0 1135782784 49330000 2801104
03:15:20 119502 8192000000 10086636192 10521931776 1122831808 12.054 99 34 66 2 1163322656 33616208 6874640
03:18:44 23623 8192000000 10119400256 10587676672 1133978640 12.159 100 55 95 2 1218109648 72581776 11549232
03:20:08 41632 8192000000 10087628496 10546999296 1135387296 12.172 100 19 34 0 1183193776 43851824 3954656
03:21:51 25918 8192000000 10139223648 10587037696 1128485696 12.107 99 37 73 0 1235480736 98672544 8322496
03:23:25 18017 8192000000 10167236512 10604453888 1145566896 12.268 100 55 103 1 1259331280 101532752 12231632
03:24:44 10341 8192000000 10191060288 10616872960 1160078288 12.404 100 69 116 4 1279244112 107590672 11575152
03:25:54 69508 8192000000 10160999280 10665766912 1152933632 12.337 99 18 36 0 1276484944 119779776 3771536
03:28:07 63953 8192000000 10153100784 10638426112 1155657568 12.363 100 10 20 0 1267740736 109877360 2205808
03:30:14 61877 8192000000 10153781424 10611597312 1142363488 12.238 99 23 46 0 1233508624 85541344 5603792
03:32:26 41558 8192000000 10135384928 10604085248 1156260272 12.368 100 37 72 0 1217662944 54163088 7239584
03:34:08 52435 8192000000 10133283424 10606137344 1096022000 11.800 95 23 46 0 1167365280 66399120 4944160
03:36:01 9542 8192000000 10094652384 10527490048 1132262464 12.143 100 47 90 1 1179633360 38332176 9038720
03:37:14 39809 8192000000 10090293696 10529366016 1075897744 11.608 95 42 84 0 1151686224 67623888 8164592
03:38:54 44530 8192000000 10099028320 10536681472 1098214496 11.821 99 57 112 0 1171804880 62272848 11317536
03:40:39 46819 8192000000 10110954688 10551689216 1120660336 12.033 100 33 61 0 1181361408 54957232 5743840
03:42:41 99922 8192000000 10095271488 10561286144 1125459952 12.079 100 25 48 0 1185107504 54607120 5040432
03:45:21 77768 8192000000 10054000768 10531700736 1112921568 11.960 98 16 32 0 1180591312 64526992 3142752
03:48:34 101983 8192000000 10128296656 10610528256 1140363680 12.219 100 15 30 0 1220587136 77047840 3175616
03:52:11 38598 8192000000 10099068992 10543841280 1122649984 12.052 98 24 48 0 1184957184 57122128 5185072
03:53:52 34814 8192000000 10141067760 10589376512 1126970688 12.093 98 31 62 1 1226493776 92577392 6945696
03:55:34 20793 8192000000 10173287760 10625286144 1145774224 12.270 100 37 74 1 1270224800 117032880 7417696
03:56:55 11939 8192000000 10218250768 10645594112 1144530736 12.258 99 52 102 0 1300260304 144913136 10816432
03:58:08 18812 8192000000 10231451328 10661294080 1134868992 12.167 98 43 82 0 1302009296 157554288 9586016
03:59:27 10848 8192000000 10224921664 10666536960 1132714736 12.147 99 45 89 0 1319087248 176604032 9768480
04:00:47 54403 8192000000 10146504528 10650787840 1103214304 11.868 96 23 46 0 1251808064 143169328 5424432
04:02:58 42184 8192000000 10111245536 10568945664 1126014800 12.084 99 21 40 1 1213904560 83484864 4404896
04:04:41 12474 8192000000 10114329920 10544009216 1145401088 12.266 100 44 88 1 1192746608 38550912 8794608
04:05:53 92379 8192000000 10088542688 10592280576 1143578784 12.249 100 31 62 0 1224829632 74960912 6289936
04:08:29 31565 8192000000 10117348528 10574839808 1146119264 12.273 100 48 95 2 1212657312 57049536 9488512
04:10:11 58045 8192000000 10104840832 10579582976 1136730640 12.185 99 10 19 0 1199549984 61029392 1789952
04:12:54 104782 8192000000 10108097520 10587664384 1130604016 12.127 98 13 24 0 1196967744 63628192 2735536
04:15:39 33556 8192000000 10110140320 10553024512 1135027120 12.169 99 32 65 0 1185777072 43189456 7560496
04:17:17 31535 8192000000 10132930512 10582052864 1151196320 12.321 100 19 38 0 1232718128 77455280 4066528
04:18:50 51971 8192000000 10147545264 10630123520 1153278768 12.340 99 22 44 0 1255168240 96780720 5108752
04:20:48 34828 8192000000 10170562880 10612813824 1117200864 12.001 97 35 70 0 1228817264 103999088 7617312
04:22:25 61257 8192000000 10155787264 10606125056 1146912976 12.281 100 38 73 1 1232633904 78335248 7385680
04:24:31 69813 8192000000 10154452624 10633850880 1112886592 11.960 97 23 47 0 1212501648 94913504 4701552
04:26:41 83946 8192000000 10109497008 10582323200 1121576336 12.042 99 26 50 0 1200504992 74150048 4778608
04:29:05 70298 8192000000 10081980848 10592677888 1112309808 11.954 98 6 12 0 1151221392 37679680 1231904
04:32:18 48782 8192000000 10129238304 10609700864 1131570048 12.136 99 28 55 0 1225171184 87823216 5777920
04:34:40 40716 8192000000 10098368656 10571194368 1135378800 12.172 100 34 66 0 1180384064 38243984 6761280
04:36:21 33861 8192000000 10100216240 10561548288 1107192480 11.906 98 43 84 1 1197205360 80774400 9238480

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-10-2022 04:38:27 to 03-10-2022 04:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 65 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-10-2022 04:38:44 to 03-10-2022 04:38:46.

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          212365
MON$OLDEST_ACTIVE               216074
MON$OLDEST_SNAPSHOT             216074
MON$NEXT_TRANSACTION            216074
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                       2054944
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             768
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2854 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2854 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2854 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Mon Oct  3 04:37:23 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Mon Oct  3 04:37:24 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Mon Oct  3 04:37:29 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
--- end of diff output ---

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

03.10.22 04:38:47. 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-10-03 04:38:50 No severe PSQL-related problems occured
03.10.22 04:38:51. End of report.