Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.09.2022 13: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 15424032 kB
MemAvailable 30576480 kB
Buffers 0 kB
Cached 16266824 kB
SwapCached 2028 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-09-25 13:37:20
DTS_END                         2022-09-25 13:37:20.7830
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/43812
TRN_ID                          214789
ATT_ID                          640
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.22 29209 1051466
customer order: creation 161.44 508 19373
customer order: refuse 14.74 405 1769
order to supplier: creation 44.23 1258 5308
order to supplier: removal 3.44 163464 413
invoice (draft): creation 43.32 2886 5198
invoice (draft): removal 49.13 913 5895
invoice accept: apply 40.31 27373 4837
invoice accept: cancel 45.03 1202 5403
customer reserve: creation 3743.33 262 449199
customer reserve: removal 4333.52 103 520022
realization accept: apply 78.95 276 9474
realization accept: cancel 81.58 167 9789
payment from customer: creation 56.14 184 6737
payment from customer: removal 5.54 121 665
payment to supplier: creation 46.55 189 5586
payment to supplier: removal 3.23 119 388
service: total inventory turnovers 3.97 4044 476
service: total monetary turnovers 6.98 422 837
service: aggregate perf. data 0.78 19786 93
service: refresh index statistics 0.03 360488 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-09-2022 13:38:17 to 25-09-2022 13:38:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6413
TEST_TIME 2 6453
TEST_TIME 3 6736
TEST_TIME 4 6364
TEST_TIME 5 5949
TEST_TIME 6 5925
TEST_TIME 7 6182
TEST_TIME 8 6269
TEST_TIME 9 6351
TEST_TIME 10 6683
TEST_TIME 11 6933
TEST_TIME 12 7005
TEST_TIME 13 6905
TEST_TIME 14 6905
TEST_TIME 15 7371
TEST_TIME 16 7485
TEST_TIME 17 7562
TEST_TIME 18 7767
TEST_TIME 19 8078
TEST_TIME 20 8138
TEST_TIME 21 8025
TEST_TIME 22 8138
TEST_TIME 23 8328
TEST_TIME 24 8598
TEST_TIME 25 9114
TEST_TIME 26 9107
TEST_TIME 27 9322
TEST_TIME 28 9605
TEST_TIME 29 9871
TEST_TIME 30 10232
TEST_TIME 31 10423
TEST_TIME 32 10697
TEST_TIME 33 11195
TEST_TIME 34 11080
TEST_TIME 35 11071
TEST_TIME 36 10904
TEST_TIME 37 10641
TEST_TIME 38 10594
TEST_TIME 39 10472
TEST_TIME 40 10368
TEST_TIME 41 10223
TEST_TIME 42 10115
TEST_TIME 43 10051
TEST_TIME 44 9895
TEST_TIME 45 9773
TEST_TIME 46 9810
TEST_TIME 47 9789
TEST_TIME 48 9629
TEST_TIME 49 9678
TEST_TIME 50 9702
TEST_TIME 51 9621
TEST_TIME 52 9574
TEST_TIME 53 9671
TEST_TIME 54 9597
TEST_TIME 55 9659
TEST_TIME 56 9622
TEST_TIME 57 9677
TEST_TIME 58 9656
TEST_TIME 59 9651
TEST_TIME 60 9600
TEST_TIME 61 9638
TEST_TIME 62 9515
TEST_TIME 63 9455
TEST_TIME 64 9344
TEST_TIME 65 9324
TEST_TIME 66 9310
TEST_TIME 67 9229
TEST_TIME 68 9221
TEST_TIME 69 9200
TEST_TIME 70 9120
TEST_TIME 71 9027
TEST_TIME 72 9000
TEST_TIME 73 8984
TEST_TIME 74 9048
TEST_TIME 75 9019
TEST_TIME 76 9011
TEST_TIME 77 8955
TEST_TIME 78 8961
TEST_TIME 79 8928
TEST_TIME 80 8996
TEST_TIME 81 9110
TEST_TIME 82 9190
TEST_TIME 83 9168
TEST_TIME 84 9119
TEST_TIME 85 9176
TEST_TIME 86 9147
TEST_TIME 87 9080
TEST_TIME 88 9108
TEST_TIME 89 9071
TEST_TIME 90 9040
TEST_TIME 91 8994
TEST_TIME 92 8940
TEST_TIME 93 8904
TEST_TIME 94 8874
TEST_TIME 95 8828
TEST_TIME 96 8814
TEST_TIME 97 8816
TEST_TIME 98 8797
TEST_TIME 99 8801
TEST_TIME 100 8820
TEST_TIME 101 8911
TEST_TIME 102 8969
TEST_TIME 103 8959
TEST_TIME 104 9009
TEST_TIME 105 8977
TEST_TIME 106 9041
TEST_TIME 107 9039
TEST_TIME 108 9022
TEST_TIME 109 8983
TEST_TIME 110 8967
TEST_TIME 111 8934
TEST_TIME 112 8884
TEST_TIME 113 8865
TEST_TIME 114 8848
TEST_TIME 115 8821
TEST_TIME 116 8823
TEST_TIME 117 8819
TEST_TIME 118 8854
TEST_TIME 119 8847
TEST_TIME 120 8786

Performance per minute, chart


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

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 1465267 1465267 0 0.00 0 31757 13 0 0
doc_list_biud 1465253 1465253 0 0.00 0 32991 42 0 0
sp_add_invoice_to_stock 4959 4837 122 2.46 25 241167 27374 0 122
sp_cancel_adding_invoice 5403 5403 0 0.00 5 83744 1202 0 0
sp_cancel_client_order 1769 1769 0 0.00 6 11082 405 0 0
sp_cancel_customer_reserve 520022 520022 0 0.00 1 17901 104 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 2599 122 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 4 3841 120 0 0
sp_cancel_supplier_invoice 5907 5895 12 0.20 2 24444 914 0 12
sp_cancel_supplier_order 413 413 0 0.00 6 1357275 163465 0 0
sp_cancel_write_off 9789 9789 0 0.00 2 13359 168 0 0
sp_client_order 19373 19373 0 0.00 5 55780 509 0 0
sp_customer_reserve 449199 449199 0 0.00 0 108323 263 0 0
sp_fill_shopping_cart 29869 29869 0 0.00 1 23467 80 0 0
sp_fill_shopping_cart_clo_res 449165 449165 0 0.00 0 20042 21 0 0
sp_get_clo_for_invoice 4792 4792 0 0.00 0 14121 301 0 0
sp_kill_cost_storno 16245 16245 0 0.00 0 5568 68 0 0
sp_kill_qstorno_ret_qs2qd 1056141 1056141 0 0.00 0 16726 45 0 0
sp_kill_qty_storno 557555 557555 0 0.00 0 32991 102 0 0
sp_lock_selected_doc 19928 19928 0 0.00 0 6407 7 0 0
sp_make_cost_storno 26589 26589 0 0.00 0 31753 77 0 0
sp_multiply_rows_for_pdistr 26586 26586 0 0.00 0 9171 37 0 0
sp_multiply_rows_for_qdistr 19373 19373 0 0.00 0 17150 212 0 0
sp_pay_from_customer 6737 6737 0 0.00 5 21127 185 0 0
sp_pay_to_supplier 5586 5586 0 0.00 6 13378 189 0 0
sp_payment_common 12322 12322 0 0.00 2 20844 44 0 0
sp_qd_handle_on_cancel_clo 1769 1769 0 0.00 0 11075 326 0 0
sp_qd_handle_on_invoice_upd_sts 10195 10195 0 0.00 0 32990 501 0 0
sp_qd_handle_on_reserve_upd_sts 9472 9472 0 0.00 0 8480 62 0 0
sp_reserve_write_off 9474 9474 0 0.00 8 31766 277 0 0
sp_supplier_invoice 5198 5198 0 0.00 8 86224 2887 0 0
sp_supplier_order 5308 5308 0 0.00 8 30255 1259 0 0
srv_aggregate_perf_data 93 93 0 0.00 2612 147922 19786 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 739 123428 34153 0 0
srv_find_qd_qs_mism 1004731 1004731 0 0.00 0 23884 10 0 0
srv_make_invnt_saldo 476 476 0 0.00 34 106618 4045 0 0
srv_make_money_saldo 837 837 0 0.00 1 13918 422 0 0
srv_recalc_idx_stat 4 4 0 0.00 286237 457799 360489 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 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 4840 4840 0 0.00 0 20108 969 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 1 6478 376 0 0
t$perf-norm:sp_cancel_client_order 1769 1769 0 0.00 0 3749 28 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 1479 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 521 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 1290 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 387 387 0 0.00 0 758 34 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 118828 7010 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 4135 28 0 0
t$perf-norm:sp_client_order 19375 19375 0 0.00 0 11698 35 0 0
t$perf-norm:sp_customer_reserve 993 993 0 0.00 0 1134 12 0 0
t$perf-norm:sp_pay_from_customer 6737 6737 0 0.00 0 4630 20 0 0
t$perf-norm:sp_pay_to_supplier 5586 5586 0 0.00 0 6356 23 0 0
t$perf-norm:sp_reserve_write_off 9475 9475 0 0.00 0 7625 26 0 0
t$perf-norm:sp_supplier_invoice 5198 5198 0 0.00 0 3494 30 0 0
t$perf-norm:sp_supplier_order 5308 5308 0 0.00 0 3800 30 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 198 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 52 4 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 6291 24 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 560 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 165 57 0 0
v_add_invoice_to_stock 9816 9572 244 2.48 0 25726 35 0 244
v_all_customers 35590 35590 0 0.00 0 224 4 0 0
v_all_suppliers 29348 29348 0 0.00 0 312 4 0 0
v_all_wares 38738 38738 0 0.00 0 226 4 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 1570 18 0 0
v_cancel_client_order 3538 3538 0 0.00 0 6456 15 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 875 11 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 12739 35 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 1268 16 0 0
v_cancel_supplier_invoice 798 774 24 3.00 0 5821 42 0 24
v_cancel_supplier_order 826 826 0 0.00 0 5252 24 0 0
v_cancel_write_off 1984 1984 0 0.00 0 6528 29 0 0
v_max_id_clo_ord 5306 5306 0 0.00 0 446 5 0 0
v_max_id_clo_res 993 993 0 0.00 0 4590 15 0 0
v_max_id_ord_sup 5191 5191 0 0.00 0 9620 7 0 0
v_max_non_paid_invoice 1409 1409 0 0.00 0 381 8 0 0
v_max_non_paid_realizn 3418 3418 0 0.00 0 5537 10 0 0
v_min_id_clo_ord 5306 5306 0 0.00 0 1826 7 0 0
v_min_id_clo_res 993 993 0 0.00 0 6254 17 0 0
v_min_id_ord_sup 5191 5191 0 0.00 0 4263 9 0 0
v_min_non_paid_invoice 5586 5586 0 0.00 0 4031 14 0 0
v_min_non_paid_realizn 6736 6736 0 0.00 0 20834 19 0 0
v_our_firm 9784 9784 0 0.00 0 63 5 0 0
v_reserve_write_off 18944 18944 0 0.00 0 27662 16 0 0
x_lock_depdocs_on_canc_invoice 10911 10911 0 0.00 0 20854 139 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 3000 342 0 0
x_make_qstorno_1000_1200 5308 5308 0 0.00 2 30244 1099 0 0
x_make_qstorno_1200_2000 5198 5198 0 0.00 4 83060 2672 0 0
x_make_qstorno_2100_3300 449199 449199 0 0.00 0 108223 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-09-2022 13:38:25 to 25-09-2022 13:38:29.

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:24 2 8192000000 8798504736 8809730048 4525216 0.055 2 1 2 0 4624640 63648 35776
11:08:25 23114 8192000000 9705212832 9998262272 780448208 8.698 100 92 183 0 810199088 7363696 22387184
11:09:53 14813 8192000000 9744697424 10045095936 817102096 9.069 100 92 184 0 850388112 10993952 22292064
11:11:11 16411 8192000000 9770564016 10084802560 844058496 9.341 100 84 166 0 882141680 18664112 19419072
11:12:31 9768 8192000000 9834802528 10162872320 896682128 9.865 100 75 150 1 945369248 32212560 16474560
11:13:46 8839 8192000000 9886725200 10231087104 949211520 10.383 100 79 153 1 1005688544 40082352 16394672
11:14:57 2289 8192000000 9955570352 10303942656 990223296 10.784 100 93 180 1 1075101776 65835840 19042640
11:16:03 2384 8192000000 9986642720 10345361408 1009502816 10.971 100 88 175 0 1109677792 82866752 17308224
11:17:07 959 8192000000 10006611408 10374361088 1033001392 11.197 100 90 170 0 1127465840 77502288 16962160
11:18:10 847 8192000000 10037438544 10410442752 1049029504 11.351 100 95 180 0 1162155856 95434144 17692208
11:19:13 1076 8192000000 10031317712 10406993920 1057767152 11.435 100 97 189 1 1156441008 79614416 19059440
11:20:16 2886 8192000000 10037841632 10420502528 1063152784 11.487 100 71 136 1 1157744624 81216528 13375312
11:21:20 1279 8192000000 10054062400 10437111808 1071703792 11.568 100 99 194 0 1178930704 87788880 19438032
11:22:22 1271 8192000000 10061081488 10449809408 1078390992 11.632 100 94 180 1 1188894224 91860080 18643152
11:23:24 3519 8192000000 10036580400 10429009920 1082227792 11.669 100 76 146 2 1159851216 61513392 16110032
11:24:28 1123 8192000000 10053058272 10442973184 1086971872 11.714 100 96 188 3 1174980752 69344432 18664448
11:25:37 1255 8192000000 10069685520 10455068672 1089049104 11.734 100 93 179 1 1189520144 82438512 18032528
11:26:40 1133 8192000000 10065270000 10451435520 1094283504 11.783 100 95 177 0 1188425456 76052048 18089904
11:27:42 1163 8192000000 10080355552 10469142528 1094321088 11.784 100 97 185 1 1202473504 88619584 19532832
11:28:45 1074 8192000000 10036237872 10421710848 1075096272 11.601 99 96 179 0 1157674224 63781472 18796480
11:29:47 940 8192000000 10039765664 10425630720 1078833104 11.636 100 97 176 2 1162574448 65290224 18451120
11:30:48 1121 8192000000 10027571424 10416250880 1075787904 11.607 99 81 154 0 1157093936 64959280 16346752
11:31:51 5840 8192000000 10006268944 10390233088 1067989984 11.533 100 56 108 0 1137864896 59601728 10273184
11:32:57 1848 8192000000 10026580128 10411995136 1071794896 11.569 100 78 148 2 1143950272 57856272 14299104
11:34:02 1362 8192000000 9965646000 10337357824 1031735088 11.185 100 92 172 3 1090965216 41934288 17295840
11:35:04 1096 8192000000 9985845232 10351636480 1015384752 11.027 100 96 180 5 1108484352 74704096 18395504
11:36:06 963 8192000000 9935678848 10298470400 995702064 10.837 99 88 161 1 1057001664 44225840 17073760
11:37:08 2972 8192000000 9975778064 10355957760 1029578160 11.164 99 60 113 0 1095824480 55269408 10976912
11:38:18 11072 8192000000 9999174208 10374455296 1051531024 11.375 100 69 132 0 1112645344 48329952 12784368
11:39:41 16828 8192000000 10039516272 10423177216 1064248624 11.497 100 72 128 1 1169306928 92248384 12809920
11:41:04 3474 8192000000 10056427632 10440634368 1068226224 11.535 100 70 135 1 1172579792 90211600 14141968
11:42:11 4206 8192000000 10118931232 10507628544 1090634112 11.749 100 72 139 0 1240338176 134966000 14738064
11:43:19 2151 8192000000 10141529312 10528854016 1095254528 11.793 100 76 143 0 1268447200 157919936 15272736
11:44:25 1887 8192000000 10179214128 10572001280 1109864928 11.931 100 76 148 0 1296420336 170433856 16121552
11:45:30 894 8192000000 10197899264 10587213824 1121729872 12.043 100 94 187 0 1314577888 170619056 22228960
11:46:36 5944 8192000000 10211787744 10612031488 1135863824 12.177 100 80 154 1 1328897664 175966832 17067008
11:47:45 1783 8192000000 10233661920 10640498688 1142038480 12.235 100 96 183 1 1349140608 187576768 19525360
11:48:50 1361 8192000000 10265258640 10672168960 1156863504 12.374 100 80 154 1 1376748016 203216656 16667856
11:49:56 1301 8192000000 10308368272 10710712320 1166667104 12.466 100 85 158 0 1421524640 238602048 16255488
11:51:02 1917 8192000000 10298235872 10706415616 1154721184 12.354 99 84 157 0 1416292992 242980784 18591024
11:52:08 825 8192000000 10353183040 10782646272 1170757440 12.504 100 96 184 1 1459858048 268510912 20589696
11:53:10 1397 8192000000 10356783120 10765860864 1162369232 12.425 100 93 181 1 1463550368 280718608 20462528
11:54:17 2259 8192000000 10367544288 10780631040 1180926432 12.599 100 83 159 1 1478820096 279937856 17955808
11:55:22 727 8192000000 10379731792 10817970176 1179644464 12.587 100 97 189 0 1488035680 286966176 21425040
11:56:26 828 8192000000 10414179968 10825302016 1185813040 12.644 100 97 183 0 1519499504 313205520 20480944
11:57:28 890 8192000000 10405562352 10808336384 1168244848 12.480 99 95 189 2 1508859872 318395024 22220000
11:58:32 1383 8192000000 10488340352 10912980992 1193752368 12.718 100 80 158 0 1592522224 379733152 19036704
11:59:37 972 8192000000 10497931520 10932203520 1177916064 12.571 100 85 171 0 1604253056 405718544 20618448
12:00:43 968 8192000000 10497777488 11010920448 1201308240 12.788 100 98 193 0 1599685888 375662160 22715488
12:01:45 1726 8192000000 10471737840 10969899008 1190688544 12.690 100 80 157 1 1579128912 369326800 19113568
12:02:54 4806 8192000000 10472703200 10909540352 1187954576 12.664 100 55 108 0 1577217344 376181472 13081296
12:04:02 16428 8192000000 10479175312 10964533248 1189934928 12.683 100 39 76 0 1574332592 374049312 10348352
12:05:28 23413 8192000000 10430398560 10938757120 1178487184 12.576 100 31 62 0 1549508352 362757888 8263280
12:06:53 55177 8192000000 10321608144 10813140992 1124130032 12.066 96 22 44 1 1405046960 275215824 5701104
12:08:49 23223 8192000000 10303058096 10774552576 1137724592 12.194 100 23 44 1 1423519920 279353152 6442176
12:11:17 35686 8192000000 10086121328 10519392256 1115023744 11.980 99 41 80 2 1187383776 63428304 8931728
12:12:54 28984 8192000000 10104028832 10531766272 1127368400 12.097 100 60 113 2 1191036048 51367072 12300576
12:14:25 43197 8192000000 10116220928 10542571520 1100211456 11.840 97 65 123 1 1197919952 84260208 13448288
12:17:25 19749 8192000000 10101860480 10540560384 1124516800 12.070 99 33 64 0 1183101280 52632432 5952048
12:18:53 49469 8192000000 10139311072 10589331456 1138450832 12.201 99 21 42 0 1224296368 81221008 4624528
12:20:44 115396 8192000000 10156645408 10594443264 1083621168 11.682 94 16 32 0 1193626016 106500400 3504448
12:23:41 63901 8192000000 10135216640 10598793216 1129296864 12.115 99 16 31 0 1268493472 135398272 3798336
12:26:09 47773 8192000000 10100052496 10607591424 1125520320 12.079 99 11 20 1 1244478112 116702256 2255536
12:28:01 40731 8192000000 10091385072 10527215616 1133208080 12.152 99 33 62 1 1170801632 30349088 7244464
12:29:46 52212 8192000000 10145625744 10625314816 1131727728 12.138 98 34 67 0 1242942768 104279680 6935360
12:31:41 49460 8192000000 10047536016 10475921408 1111558544 11.947 98 30 54 0 1140696160 23596480 5541136
12:33:37 66988 8192000000 10116608864 10614702080 1128858272 12.111 99 26 52 0 1203010416 68984560 5167584
12:35:45 56731 8192000000 10107767344 10544930816 1136359936 12.181 99 31 58 1 1213123344 70112928 6650480
12:38:01 28419 8192000000 10123435136 10612060160 1132775312 12.148 99 33 66 0 1201848272 62882608 6190352
12:39:33 123424 8192000000 10119466880 10565058560 1141356640 12.228 99 44 88 0 1187067952 36488368 9222944
12:42:53 75308 8192000000 10103966240 10597736448 1143176224 12.245 99 14 28 0 1217874832 71743664 2954944
12:45:09 27884 8192000000 10069636976 10513920000 1131340992 12.134 99 33 66 1 1171626576 33460416 6825168
12:46:59 62974 8192000000 10063718672 10534187008 1132342752 12.143 100 15 28 0 1174255392 38926608 2986032
12:49:03 39001 8192000000 10073641184 10563067904 1138669472 12.203 100 7 14 0 1197941744 57937376 1334896
12:51:36 83554 8192000000 10090192512 10544017408 1116742160 11.996 98 15 30 0 1176022480 55941248 3339072
12:54:03 31262 8192000000 10135214736 10571395072 1114595632 11.976 97 26 50 0 1191817728 71783024 5439072
12:55:36 42566 8192000000 10196445344 10635866112 1131155824 12.132 98 34 66 0 1264056208 125881904 7018480
12:57:23 41781 8192000000 10210703120 10694057984 1145500240 12.267 100 34 69 0 1286103040 133351568 7251232
12:59:49 41908 8192000000 10060902176 10515005440 1096318384 11.803 97 27 54 0 1160491280 58800464 5372432
13:01:31 18950 8192000000 10136069488 10561904640 1095990544 11.800 97 54 109 0 1199299152 92432432 10876176
13:02:50 13018 8192000000 10095632912 10517667840 1112069632 11.952 99 49 98 1 1186013648 64395200 9548816
13:04:04 39665 8192000000 10128746160 10577870848 1126993744 12.093 100 75 146 0 1248564640 103982640 17588256
13:07:14 34219 8192000000 10029608064 10487255040 1084912672 11.694 97 9 18 0 1120146512 32969216 2264624
13:08:52 64839 8192000000 10031906224 10496397312 1096471376 11.804 98 10 19 0 1139581296 41008720 2101200
13:11:17 77204 8192000000 10080207376 10515734528 1106616384 11.900 98 20 40 0 1153456496 42240400 4599712
13:13:36 28822 8192000000 10158797600 10583969792 1104693936 11.882 97 39 76 1 1223146336 109813264 8639136
13:15:09 28595 8192000000 10152063088 10587062272 1123763904 12.063 99 25 50 0 1247090320 117441824 5884592
13:16:38 47403 8192000000 10189398448 10677571584 1114426560 11.974 98 38 76 0 1283651152 160793648 8430944
13:18:35 72484 8192000000 10084141856 10559827968 1102769760 11.864 97 19 38 0 1204795536 97918544 4107232
13:21:19 44119 8192000000 10115161040 10583592960 1106961408 11.904 97 41 80 0 1193045824 78181776 7902640
13:23:14 54526 8192000000 10124631040 10578817024 1104052592 11.876 97 23 44 1 1234001536 125481424 4467520
13:25:14 103198 8192000000 10086005728 10536828928 1139730960 12.213 100 44 88 0 1191237360 42599808 8906592
13:27:59 37447 8192000000 10054535312 10517098496 1137363728 12.191 99 14 29 0 1161281168 21274192 2643248
13:29:37 30243 8192000000 10079688384 10549276672 1149686480 12.307 100 5 10 0 1190836672 39854944 1295248
13:31:08 72144 8192000000 10067116160 10549649408 1110827008 11.940 97 9 18 0 1164000048 51321248 1851792
13:33:20 49686 8192000000 10102274416 10556682240 1145006400 12.263 100 25 51 1 1206299344 56114688 5178256
13:35:10 83479 8192000000 10052943616 10522361856 1105457408 11.889 97 18 36 1 1164542336 55553296 3531632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 25-09-2022 13:38:31 to 25-09-2022 13:38:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 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 25-09-2022 13:38:48 to 25-09-2022 13:38:50.

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          213067
MON$OLDEST_ACTIVE               215310
MON$OLDEST_SNAPSHOT             215310
MON$NEXT_TRANSACTION            215310
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                       2048288
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2849 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2849 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2849 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2849 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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Sun Sep 25 13:37:22 2022
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Sun Sep 25 13:37:23 2022
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
:100: 
:101: mscs-test-161.budzdorov.ru    Sun Sep 25 13:37:23 2022
:102:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:103: 
:104: 
:105: mscs-test-161.budzdorov.ru    Sun Sep 25 13:37:24 2022
:106:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:107: 
:108: 
:109: mscs-test-161.budzdorov.ru    Sun Sep 25 13:37:25 2022
:110:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:111: 
:112: 
:113: mscs-test-161.budzdorov.ru    Sun Sep 25 13:37:27 2022
:114:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:115: 
--- end of diff output ---

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

25.09.22 13:38:51. 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-09-25 13:38:53 No severe PSQL-related problems occured
25.09.22 13:38:55. End of report.