Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.08.2022 13:39
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 15777556 kB
MemAvailable 30976816 kB
Buffers 0 kB
Cached 15963104 kB
SwapCached 2512 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-08-09 13:37:21
DTS_END                         2022-08-09 13:37:21.5680
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/57822
TRN_ID                          220589
ATT_ID                          626
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: 9049.48 20604 1085938
customer order: creation 171.08 478 20529
customer order: refuse 14.38 351 1726
order to supplier: creation 45.78 1262 5493
order to supplier: removal 3.41 156146 409
invoice (draft): creation 45.93 2969 5511
invoice (draft): removal 50.54 994 6065
invoice accept: apply 41.73 27016 5007
invoice accept: cancel 45.82 1135 5498
customer reserve: creation 3894.73 259 467368
customer reserve: removal 4444.15 93 533298
realization accept: apply 83.51 272 10021
realization accept: cancel 79.70 148 9564
payment from customer: creation 57.93 169 6952
payment from customer: removal 5.81 130 697
payment to supplier: creation 48.78 183 5854
payment to supplier: removal 3.35 126 402
service: total inventory turnovers 4.29 3507 515
service: total monetary turnovers 7.53 280 903
service: aggregate perf. data 1.02 13254 122
service: refresh index statistics 0.03 203314 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-08-2022 13:40:14 to 09-08-2022 13:40:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4942
TEST_TIME 2 3710
TEST_TIME 3 5093
TEST_TIME 4 4823
TEST_TIME 5 4957
TEST_TIME 6 5787
TEST_TIME 7 6507
TEST_TIME 8 6314
TEST_TIME 9 6246
TEST_TIME 10 6277
TEST_TIME 11 6621
TEST_TIME 12 6586
TEST_TIME 13 6837
TEST_TIME 14 6860
TEST_TIME 15 6869
TEST_TIME 16 6992
TEST_TIME 17 7054
TEST_TIME 18 7056
TEST_TIME 19 7269
TEST_TIME 20 7143
TEST_TIME 21 7167
TEST_TIME 22 7465
TEST_TIME 23 8001
TEST_TIME 24 8400
TEST_TIME 25 8211
TEST_TIME 26 8335
TEST_TIME 27 8574
TEST_TIME 28 9063
TEST_TIME 29 9785
TEST_TIME 30 10762
TEST_TIME 31 11052
TEST_TIME 32 10882
TEST_TIME 33 10749
TEST_TIME 34 10733
TEST_TIME 35 11041
TEST_TIME 36 10875
TEST_TIME 37 10757
TEST_TIME 38 10683
TEST_TIME 39 10531
TEST_TIME 40 10463
TEST_TIME 41 10329
TEST_TIME 42 10343
TEST_TIME 43 10293
TEST_TIME 44 10382
TEST_TIME 45 10369
TEST_TIME 46 10300
TEST_TIME 47 10123
TEST_TIME 48 9980
TEST_TIME 49 9850
TEST_TIME 50 9844
TEST_TIME 51 9711
TEST_TIME 52 9784
TEST_TIME 53 9749
TEST_TIME 54 9761
TEST_TIME 55 9790
TEST_TIME 56 9712
TEST_TIME 57 9640
TEST_TIME 58 9498
TEST_TIME 59 9492
TEST_TIME 60 9513
TEST_TIME 61 9562
TEST_TIME 62 9542
TEST_TIME 63 9582
TEST_TIME 64 9611
TEST_TIME 65 9606
TEST_TIME 66 9615
TEST_TIME 67 9640
TEST_TIME 68 9656
TEST_TIME 69 9716
TEST_TIME 70 9664
TEST_TIME 71 9687
TEST_TIME 72 9657
TEST_TIME 73 9752
TEST_TIME 74 9730
TEST_TIME 75 9603
TEST_TIME 76 9561
TEST_TIME 77 9546
TEST_TIME 78 9510
TEST_TIME 79 9482
TEST_TIME 80 9423
TEST_TIME 81 9395
TEST_TIME 82 9363
TEST_TIME 83 9287
TEST_TIME 84 9286
TEST_TIME 85 9341
TEST_TIME 86 9296
TEST_TIME 87 9284
TEST_TIME 88 9288
TEST_TIME 89 9252
TEST_TIME 90 9345
TEST_TIME 91 9351
TEST_TIME 92 9396
TEST_TIME 93 9392
TEST_TIME 94 9408
TEST_TIME 95 9429
TEST_TIME 96 9476
TEST_TIME 97 9439
TEST_TIME 98 9357
TEST_TIME 99 9310
TEST_TIME 100 9378
TEST_TIME 101 9305
TEST_TIME 102 9262
TEST_TIME 103 9255
TEST_TIME 104 9203
TEST_TIME 105 9182
TEST_TIME 106 9160
TEST_TIME 107 9132
TEST_TIME 108 9124
TEST_TIME 109 9139
TEST_TIME 110 9120
TEST_TIME 111 9184
TEST_TIME 112 9160
TEST_TIME 113 9138
TEST_TIME 114 9189
TEST_TIME 115 9181
TEST_TIME 116 9176
TEST_TIME 117 9179
TEST_TIME 118 9134
TEST_TIME 119 9117
TEST_TIME 120 9073

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2022 13:40:18 to 09-08-2022 13:40:21.

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 1516209 1516209 0 0.00 0 40226 14 0 0
doc_list_biud 1516199 1516199 0 0.00 0 38425 38 0 0
sp_add_invoice_to_stock 5175 5007 168 3.24 28 280692 27016 0 168
sp_cancel_adding_invoice 5498 5498 0 0.00 6 53135 1136 0 0
sp_cancel_client_order 1726 1726 0 0.00 4 7950 351 0 0
sp_cancel_customer_reserve 533298 533298 0 0.00 1 38464 93 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 4 8801 130 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 4 7068 126 0 0
sp_cancel_supplier_invoice 6080 6065 15 0.24 3 37626 995 0 15
sp_cancel_supplier_order 409 409 0 0.00 9 1478730 156146 0 0
sp_cancel_write_off 9564 9564 0 0.00 2 15123 149 0 0
sp_client_order 20529 20529 0 0.00 5 40323 478 0 0
sp_customer_reserve 467368 467368 0 0.00 0 122879 259 0 0
sp_fill_shopping_cart 31519 31519 0 0.00 1 14925 79 0 0
sp_fill_shopping_cart_clo_res 467336 467336 0 0.00 0 14491 18 0 0
sp_get_clo_for_invoice 4971 4971 0 0.00 0 17258 254 0 0
sp_kill_cost_storno 16161 16161 0 0.00 0 37903 71 0 0
sp_kill_qstorno_ret_qs2qd 1082634 1082634 0 0.00 0 38376 40 0 0
sp_kill_qty_storno 571550 571550 0 0.00 0 38421 93 0 0
sp_lock_selected_doc 20708 20708 0 0.00 0 8784 8 0 0
sp_make_cost_storno 27797 27797 0 0.00 0 14410 79 0 0
sp_multiply_rows_for_pdistr 27795 27795 0 0.00 0 9011 36 0 0
sp_multiply_rows_for_qdistr 20526 20526 0 0.00 0 14438 165 0 0
sp_pay_from_customer 6952 6952 0 0.00 5 11724 170 0 0
sp_pay_to_supplier 5854 5854 0 0.00 6 12870 184 0 0
sp_payment_common 12805 12805 0 0.00 2 7550 42 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 7940 281 0 0
sp_qd_handle_on_invoice_upd_sts 10468 10468 0 0.00 0 19623 484 0 0
sp_qd_handle_on_reserve_upd_sts 10020 10020 0 0.00 0 19368 62 0 0
sp_reserve_write_off 10021 10021 0 0.00 8 19420 273 0 0
sp_supplier_invoice 5511 5511 0 0.00 10 89709 2970 0 0
sp_supplier_order 5493 5493 0 0.00 8 31961 1263 0 0
srv_aggregate_perf_data 122 122 0 0.00 2292 169254 13255 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 832 173665 36691 0 0
srv_find_qd_qs_mism 1036939 1036939 0 0.00 0 40223 12 0 0
srv_make_invnt_saldo 515 515 0 0.00 54 59518 3507 0 0
srv_make_money_saldo 903 903 0 0.00 1 8385 281 0 0
srv_recalc_idx_stat 4 4 0 0.00 48351 343684 203314 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5014 5014 0 0.00 1 15996 853 0 0
t$perf-norm:sp_cancel_adding_invoice 440 440 0 0.00 1 5163 379 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 4796 26 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1694 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 2873 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 360 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 575 32 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 108188 6200 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 714 16 0 0
t$perf-norm:sp_client_order 20531 20531 0 0.00 0 5896 32 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 694 14 0 0
t$perf-norm:sp_pay_from_customer 6952 6952 0 0.00 0 4494 15 0 0
t$perf-norm:sp_pay_to_supplier 5855 5855 0 0.00 0 8063 19 0 0
t$perf-norm:sp_reserve_write_off 10022 10022 0 0.00 0 4754 20 0 0
t$perf-norm:sp_supplier_invoice 5511 5511 0 0.00 0 4604 27 0 0
t$perf-norm:sp_supplier_order 5493 5493 0 0.00 0 5742 28 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 376 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 99 4 0 0
t$perf-norm:srv_make_invnt_saldo 515 515 0 0.00 0 618 9 0 0
t$perf-norm:srv_make_money_saldo 903 903 0 0.00 0 773 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 17 210 71 0 0
v_add_invoice_to_stock 10266 9930 336 3.27 0 4674 24 0 336
v_all_customers 37164 37164 0 0.00 0 62 4 0 0
v_all_suppliers 30676 30676 0 0.00 0 91 4 0 0
v_all_wares 41047 41047 0 0.00 0 155 4 0 0
v_cancel_adding_invoice 880 880 0 0.00 0 6435 34 0 0
v_cancel_client_order 3452 3452 0 0.00 0 4736 11 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 3751 12 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 4734 13 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 794 12 0 0
v_cancel_supplier_invoice 822 792 30 3.64 0 2821 26 0 30
v_cancel_supplier_order 818 818 0 0.00 0 398 10 0 0
v_cancel_write_off 1980 1980 0 0.00 0 2757 26 0 0
v_max_id_clo_ord 5488 5488 0 0.00 0 14826 8 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 1045 11 0 0
v_max_id_ord_sup 5506 5506 0 0.00 0 5022 7 0 0
v_max_non_paid_invoice 1510 1510 0 0.00 0 588 8 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 1212 7 0 0
v_min_id_clo_ord 5488 5488 0 0.00 0 2302 7 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 618 8 0 0
v_min_id_ord_sup 5506 5506 0 0.00 0 8630 17 0 0
v_min_non_paid_invoice 5854 5854 0 0.00 0 5120 15 0 0
v_min_non_paid_realizn 6950 6950 0 0.00 0 7546 17 0 0
v_our_firm 10248 10248 0 0.00 0 65 4 0 0
v_reserve_write_off 20040 20040 0 0.00 0 4412 10 0 0
x_lock_depdocs_on_canc_invoice 11167 11167 0 0.00 0 17303 122 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 6117 313 0 0
x_make_qstorno_1000_1200 5493 5493 0 0.00 3 31918 1109 0 0
x_make_qstorno_1200_2000 5511 5511 0 0.00 4 89475 2752 0 0
x_make_qstorno_2100_3300 467367 467367 0 0.00 0 122877 230 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2022 13:40:22 to 09-08-2022 13:40:26.

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:25 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:26 10086 8192000000 9717788464 10016235520 802249328 8.919 100 89 178 0 831238608 7860720 21128560
11:09:42 27672 8192000000 9757491216 10061942784 830982864 9.209 100 90 180 0 865317488 13064352 21270272
11:11:23 16278 8192000000 9790606384 10105991168 854078560 9.441 100 83 166 0 894578672 20986304 19513808
11:12:48 6353 8192000000 9838588464 10172035072 903336064 9.931 100 81 162 0 955930208 35101072 17493072
11:14:00 3569 8192000000 9903088384 10242125824 944202656 10.334 100 85 170 0 1017365920 55184048 17979216
11:15:06 2745 8192000000 9958707120 10311106560 979815200 10.682 100 81 160 0 1077161888 80693856 16652832
11:16:10 1579 8192000000 9983228704 10343227392 1009600624 10.972 100 91 176 0 1107856624 80273136 17982864
11:17:14 794 8192000000 10018879104 10386309120 1035600992 11.222 100 94 185 0 1134396144 80105056 18690096
11:18:16 884 8192000000 10018368240 10390503424 1057575536 11.433 100 96 188 1 1142014288 65967824 18470928
11:19:18 1092 8192000000 10023732624 10404732928 1061027568 11.466 100 86 161 1 1146761056 70195488 15538000
11:20:20 981 8192000000 10024589664 10402361344 1067546400 11.529 100 92 173 0 1149307840 63253280 18508160
11:21:22 2270 8192000000 10004064624 10392612864 1069793920 11.550 100 62 116 4 1128967008 47080080 12093008
11:22:26 6870 8192000000 10030980384 10412687360 1075973888 11.609 100 81 150 2 1153091584 62742528 14375168
11:23:33 997 8192000000 10039416000 10431369216 1082910752 11.675 100 85 161 2 1163870608 64542976 16416880
11:24:35 1111 8192000000 10063093104 10447290368 1089450992 11.737 100 95 181 0 1180878640 72843856 18583792
11:25:38 1193 8192000000 10087596656 10473340928 1094600048 11.786 100 97 188 0 1206414464 92534304 19280112
11:26:40 1560 8192000000 10065243248 10454257664 1092070736 11.762 100 81 159 1 1187476704 79568704 15837264
11:27:42 1212 8192000000 10085881088 10478497792 1092969616 11.771 100 95 187 2 1208458000 96749936 18738448
11:28:44 1216 8192000000 10084464800 10473234432 1094030064 11.781 100 94 183 2 1206158720 93007968 19120688
11:29:46 1297 8192000000 10036937632 10422161408 1084923664 11.694 100 97 184 2 1158991040 55664448 18402928
11:30:48 1337 8192000000 10047771904 10433015808 1073931360 11.590 99 92 179 2 1167518704 75610752 17976592
11:31:51 1218 8192000000 9984003904 10354860032 1021708672 11.089 98 94 175 1 1103884480 63736544 18439264
11:32:53 2502 8192000000 10016472768 10396856320 1049562080 11.356 100 75 148 1 1139710496 74187040 15961376
11:33:56 1081 8192000000 9969141520 10342920192 1029689616 11.165 100 86 155 2 1092644000 46606368 16348016
11:34:58 843 8192000000 9956233024 10321657856 1012693536 11.001 98 87 170 1 1081166256 50586416 17886304
11:36:00 5756 8192000000 9994297664 10373726208 1034936496 11.216 100 66 121 2 1114406384 67447184 12022704
11:37:06 8279 8192000000 10004284464 10391982080 1050920320 11.370 100 59 112 0 1135513792 73508336 11085136
11:38:21 9724 8192000000 9999899968 10370969600 1053828336 11.397 99 81 148 4 1111235168 43839744 13567088
11:39:34 5717 8192000000 10051825088 10436313088 1074130944 11.592 100 70 134 1 1170530336 82648656 13750736
11:40:42 3460 8192000000 10064309872 10452025344 1095126048 11.791 100 70 140 0 1190038736 79534176 15378512
11:41:52 15999 8192000000 10120915408 10521092096 1104285920 11.878 100 57 110 0 1237447616 121249088 11912608
11:43:11 1192 8192000000 10152070160 10546704384 1117959184 12.008 100 90 164 1 1268087472 132838448 17289840
11:44:14 2417 8192000000 10182046032 10576871424 1128166960 12.104 100 84 157 2 1299215824 154283040 16765824
11:45:20 2807 8192000000 10202695712 10600824832 1140688752 12.222 100 74 144 1 1309818672 153786576 15343344
11:46:28 2453 8192000000 10229203680 10633338880 1158030032 12.385 100 67 131 0 1347101952 174570304 14501616
11:47:34 1292 8192000000 10275208384 10677690368 1170756816 12.504 100 86 170 1 1388863184 199460176 18646192
11:48:38 1077 8192000000 10329715200 10739597312 1180083168 12.591 100 96 186 1 1442268352 240591536 21593648
11:49:43 963 8192000000 10348877696 10753540096 1191819104 12.700 100 94 184 1 1462817728 250124176 20874448
11:50:46 1703 8192000000 10389567568 10793787392 1205813712 12.830 100 94 177 2 1498508384 272416672 20278000
11:51:51 1049 8192000000 10435411216 10842710016 1210582528 12.875 100 96 190 0 1545716896 313401600 21732768
11:52:54 822 8192000000 10467268736 10887929856 1209540384 12.865 100 92 180 0 1575358048 345082928 20734736
11:53:57 872 8192000000 10488359568 10904338432 1213938336 12.906 100 96 187 0 1597136272 360680960 22516976
11:55:00 927 8192000000 10571621808 10984792064 1230832112 13.062 100 94 184 1 1679957632 426403424 22722096
11:56:05 2469 8192000000 10592368400 11023851520 1224583136 13.004 100 96 190 1 1694837376 446247648 24006592
11:57:10 1466 8192000000 10649860272 11060563968 1231536624 13.068 100 97 190 1 1753445376 499319472 22589280
11:58:14 825 8192000000 10692118272 11136122880 1222171712 12.982 100 98 196 0 1797905392 551212992 24520688
11:59:17 1215 8192000000 10641147040 11135143936 1219951712 12.961 100 96 182 1 1742539504 500144336 22443456
12:00:20 1677 8192000000 10652757616 11134922752 1201601440 12.791 100 78 154 1 1755996144 534740112 19654592
12:01:25 5187 8192000000 10601630432 11052859392 1188716480 12.671 100 54 108 0 1699193552 496671760 13805312
12:02:43 20968 8192000000 10623743328 11085455360 1175035152 12.544 100 46 92 0 1716621568 529064448 12521968
12:04:07 6043 8192000000 10602618992 11072532480 1159840704 12.402 100 39 76 0 1733731264 562852352 11038208
12:05:25 15823 8192000000 10492942512 11008532480 1133277568 12.152 100 33 66 0 1611707744 469082624 9347552
12:07:06 50520 8192000000 10144686416 10632871936 1088431168 11.728 96 24 48 0 1270825088 176875840 5518080
12:09:20 27123 8192000000 10093413184 10516279296 1119948560 12.027 98 42 73 0 1170971440 43440480 7582400
12:10:52 33536 8192000000 10133489344 10578685952 1106694384 11.901 97 19 38 0 1208078064 97457072 3926608
12:12:43 72827 8192000000 10063412000 10501967872 1119345984 12.021 99 23 46 1 1185220112 61174688 4699440
12:15:09 11613 8192000000 10071864912 10499772416 1129796960 12.119 100 52 109 1 1169974720 28186400 11991360
12:16:21 83692 8192000000 10057720800 10518482944 1080660208 11.654 95 17 34 0 1118863808 34698432 3505168
12:18:46 62974 8192000000 10067314384 10542542848 1108493920 11.918 98 19 33 0 1174028128 62362688 3171520
12:20:50 107753 8192000000 10059034336 10543316992 1099687184 11.835 97 15 30 0 1164563104 61664048 3211872
12:23:38 123527 8192000000 10091044272 10533691392 1110532256 11.937 98 28 54 0 1157566480 40220432 6813792
12:26:49 48415 8192000000 10058307632 10516668416 1109094304 11.924 98 47 88 3 1181639104 62937584 9607216
12:28:38 35008 8192000000 10164599280 10608459776 1140498752 12.220 100 52 102 0 1248666112 97272112 10895248
12:30:33 63630 8192000000 10105816368 10578456576 1127062800 12.094 99 15 31 0 1224568816 93866064 3639952
12:32:37 173664 8192000000 10086916240 10540216320 1099070928 11.829 96 13 24 0 1139824128 38340336 2412864
12:36:36 60070 8192000000 10158618272 10619019264 1151898896 12.327 100 22 42 4 1271992592 115953040 4140656
12:38:44 18902 8192000000 10103761552 10551676928 1152316496 12.331 100 26 47 0 1195147120 37902000 4928624
12:40:05 58893 8192000000 10108605344 10607976448 1147619600 12.287 100 20 39 0 1207482096 55400112 4462384
12:42:04 43315 8192000000 10144899376 10593812480 1155555120 12.362 100 15 30 0 1215457936 56863488 3039328
12:43:48 43766 8192000000 10164673408 10639228928 1155883584 12.365 100 16 32 0 1248843408 89154944 3804880
12:45:34 34303 8192000000 10106058384 10565693440 1133173776 12.151 99 34 66 0 1203716288 63575792 6966720
12:47:09 40888 8192000000 10118802256 10544431104 1141029696 12.225 99 31 59 1 1210005408 63333264 5642448
12:48:56 41003 8192000000 10123964016 10565914624 1095743856 11.797 96 42 84 0 1173559472 69451216 8364400
12:50:41 65965 8192000000 10117045904 10590265344 1102522192 11.862 97 22 44 0 1188350032 81065520 4762320
12:52:51 78604 8192000000 10080504800 10537021440 1101525328 11.852 97 41 82 1 1179155216 68386784 9243104
12:55:13 50344 8192000000 10125253200 10598744064 1143455424 12.248 100 28 55 0 1216345056 67455632 5434000
12:57:11 47583 8192000000 10118356944 10608726016 1130155568 12.123 99 15 30 1 1218066224 84389952 3520704
12:59:01 70638 8192000000 10120525632 10584166400 1129851072 12.120 98 9 18 0 1220963216 89174256 1937888
13:01:13 34277 8192000000 10083723920 10537189376 1109761184 11.930 98 16 26 2 1180262800 67466800 3034816
13:02:53 27180 8192000000 10063530912 10502619136 1097131888 11.810 98 20 40 0 1125957296 24662416 4162992
13:04:23 41085 8192000000 10095562912 10551476224 1097069568 11.810 98 21 40 0 1174645104 72940672 4634864
13:06:05 32847 8192000000 10127787184 10558529536 1107926000 11.913 98 34 68 0 1189804176 74367312 7510864
13:07:39 26724 8192000000 10116883232 10583035904 1089134752 11.734 97 27 52 0 1232470000 137396304 5938944
13:09:13 61321 8192000000 10095820416 10559930368 1083181488 11.678 97 19 40 0 1164277472 77539648 3556336
13:11:17 44684 8192000000 10089766832 10540474368 1110673248 11.939 99 46 92 0 1187486080 67607856 9204976
13:13:02 73395 8192000000 10079822560 10529427456 1139666336 12.212 100 22 44 0 1226003712 81698768 4638608
13:15:16 123778 8192000000 10130907296 10610950144 1149473600 12.305 100 33 66 0 1236482864 79631664 7377600
13:18:26 46213 8192000000 10082670768 10583093248 1135895760 12.177 99 8 16 0 1174008496 36466784 1645952
13:20:14 62248 8192000000 10061644640 10554806272 1131332592 12.134 99 8 16 0 1162649936 29574800 1742544
13:22:16 93514 8192000000 10057390224 10544111616 1113233376 11.963 97 13 26 0 1155207744 39767936 2206432
13:24:50 25470 8192000000 10085648896 10524332032 1112723520 11.958 97 27 54 1 1158034432 39656816 5654096
13:26:17 44694 8192000000 10104175792 10546954240 1116611088 11.995 98 34 67 0 1167901712 43889920 7400704
13:28:04 27119 8192000000 10108398720 10553806848 1126150352 12.085 99 29 58 0 1188800336 57090160 5559824
13:29:34 36524 8192000000 10106572240 10553069568 1137519440 12.192 99 36 68 0 1193458672 48709344 7229888
13:31:12 44275 8192000000 10083842128 10536923136 1132838208 12.148 99 30 58 0 1205532480 66557664 6136608
13:33:01 18265 8192000000 10135239264 10569396224 1127248880 12.095 99 54 108 0 1220734112 82785664 10699568
13:34:20 8052 8192000000 10107503440 10533847040 1131875104 12.139 100 57 112 0 1199748432 56423696 11449632
13:35:29 76410 8192000000 10142073840 10599391232 1130209440 12.123 99 50 100 0 1213703632 73207456 10286736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-08-2022 13:40:28 to 09-08-2022 13:40:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 168 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2022 13:40:45 to 09-08-2022 13:40:47.

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          218704
MON$OLDEST_ACTIVE               220708
MON$OLDEST_SNAPSHOT             220708
MON$NEXT_TRANSACTION            220708
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                       2054176
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             772
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2816 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2816 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2816 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2816 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 ---
:36: 
:37: mscs-test-161.budzdorov.ru    Tue Aug  9 13:38:26 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

09.08.22 13:40:48. 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-08-09 13:40:50 No severe PSQL-related problems occured
09.08.22 13:40:52. End of report.