Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2023 13:40
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 14925080 kB
MemAvailable 30458116 kB
Buffers 0 kB
Cached 16795948 kB
SwapCached 3032 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 2023-03-11 13:37:27
DTS_END                         2023-03-11 13:37:27.7150
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/41710
TRN_ID                          219240
ATT_ID                          653
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: 9047.92 31523 1085750
customer order: creation 170.24 434 20429
customer order: refuse 14.73 473 1767
order to supplier: creation 44.91 1379 5389
order to supplier: removal 3.17 177363 380
invoice (draft): creation 47.19 3208 5663
invoice (draft): removal 50.50 1031 6060
invoice accept: apply 41.21 27639 4945
invoice accept: cancel 45.80 1225 5496
customer reserve: creation 3853.30 260 462396
customer reserve: removal 4480.13 96 537616
realization accept: apply 83.61 296 10033
realization accept: cancel 83.70 166 10044
payment from customer: creation 59.43 197 7131
payment from customer: removal 5.73 148 688
payment to supplier: creation 48.69 204 5843
payment to supplier: removal 3.48 162 417
service: total inventory turnovers 3.63 4245 436
service: total monetary turnovers 7.58 413 909
service: aggregate perf. data 0.86 15910 103
service: refresh index statistics 0.04 395618 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-03-2023 13:40:26 to 11-03-2023 13:40:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 10306
TEST_TIME 2 6716
TEST_TIME 3 5984
TEST_TIME 4 5688
TEST_TIME 5 5662
TEST_TIME 6 5395
TEST_TIME 7 5904
TEST_TIME 8 5818
TEST_TIME 9 5822
TEST_TIME 10 5741
TEST_TIME 11 5645
TEST_TIME 12 5869
TEST_TIME 13 6010
TEST_TIME 14 6101
TEST_TIME 15 6287
TEST_TIME 16 6354
TEST_TIME 17 6502
TEST_TIME 18 6489
TEST_TIME 19 6454
TEST_TIME 20 6767
TEST_TIME 21 7026
TEST_TIME 22 7066
TEST_TIME 23 7082
TEST_TIME 24 7153
TEST_TIME 25 7531
TEST_TIME 26 7860
TEST_TIME 27 7979
TEST_TIME 28 7982
TEST_TIME 29 8342
TEST_TIME 30 8715
TEST_TIME 31 9042
TEST_TIME 32 9218
TEST_TIME 33 9550
TEST_TIME 34 9812
TEST_TIME 35 9798
TEST_TIME 36 10231
TEST_TIME 37 10487
TEST_TIME 38 10659
TEST_TIME 39 10697
TEST_TIME 40 10604
TEST_TIME 41 10490
TEST_TIME 42 10331
TEST_TIME 43 10196
TEST_TIME 44 9969
TEST_TIME 45 9796
TEST_TIME 46 9799
TEST_TIME 47 9825
TEST_TIME 48 9721
TEST_TIME 49 9746
TEST_TIME 50 9747
TEST_TIME 51 9780
TEST_TIME 52 9728
TEST_TIME 53 9677
TEST_TIME 54 9580
TEST_TIME 55 9564
TEST_TIME 56 9574
TEST_TIME 57 9448
TEST_TIME 58 9442
TEST_TIME 59 9391
TEST_TIME 60 9331
TEST_TIME 61 9286
TEST_TIME 62 9183
TEST_TIME 63 9173
TEST_TIME 64 9103
TEST_TIME 65 9120
TEST_TIME 66 9100
TEST_TIME 67 9047
TEST_TIME 68 9117
TEST_TIME 69 9061
TEST_TIME 70 9141
TEST_TIME 71 9135
TEST_TIME 72 9421
TEST_TIME 73 9487
TEST_TIME 74 9454
TEST_TIME 75 9435
TEST_TIME 76 9443
TEST_TIME 77 9542
TEST_TIME 78 9448
TEST_TIME 79 9450
TEST_TIME 80 9413
TEST_TIME 81 9445
TEST_TIME 82 9386
TEST_TIME 83 9315
TEST_TIME 84 9283
TEST_TIME 85 9285
TEST_TIME 86 9331
TEST_TIME 87 9297
TEST_TIME 88 9270
TEST_TIME 89 9298
TEST_TIME 90 9268
TEST_TIME 91 9229
TEST_TIME 92 9280
TEST_TIME 93 9246
TEST_TIME 94 9298
TEST_TIME 95 9344
TEST_TIME 96 9298
TEST_TIME 97 9243
TEST_TIME 98 9183
TEST_TIME 99 9171
TEST_TIME 100 9155
TEST_TIME 101 9116
TEST_TIME 102 9096
TEST_TIME 103 9072
TEST_TIME 104 9034
TEST_TIME 105 9039
TEST_TIME 106 9016
TEST_TIME 107 9047
TEST_TIME 108 9037
TEST_TIME 109 9060
TEST_TIME 110 9065
TEST_TIME 111 9047
TEST_TIME 112 9086
TEST_TIME 113 9117
TEST_TIME 114 9134
TEST_TIME 115 9136
TEST_TIME 116 9103
TEST_TIME 117 9129
TEST_TIME 118 9160
TEST_TIME 119 9137
TEST_TIME 120 9075

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-03-2023 13:40:30 to 11-03-2023 13:40:33.

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 1510810 1510810 0 0.00 0 18401 14 0 0
doc_list_biud 1510794 1510794 0 0.00 0 119191 40 0 0
sp_add_invoice_to_stock 5045 4945 100 1.98 41 217422 27639 0 100
sp_cancel_adding_invoice 5496 5496 0 0.00 5 121292 1226 0 0
sp_cancel_client_order 1767 1767 0 0.00 6 13972 474 0 0
sp_cancel_customer_reserve 537616 537616 0 0.00 1 42799 97 0 0
sp_cancel_pay_from_customer 688 688 0 0.00 4 7111 149 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 4 3292 163 0 0
sp_cancel_supplier_invoice 6067 6060 7 0.11 2 119201 1032 0 7
sp_cancel_supplier_order 380 380 0 0.00 7 1658343 177364 0 0
sp_cancel_write_off 10044 10044 0 0.00 2 14686 167 0 0
sp_client_order 20429 20429 0 0.00 5 22627 434 0 0
sp_customer_reserve 462396 462396 0 0.00 0 133938 261 0 0
sp_fill_shopping_cart 31465 31465 0 0.00 1 7801 81 0 0
sp_fill_shopping_cart_clo_res 462360 462360 0 0.00 0 36619 16 0 0
sp_get_clo_for_invoice 4898 4898 0 0.00 0 35537 258 0 0
sp_kill_cost_storno 16645 16645 0 0.00 0 10459 80 0 0
sp_kill_qstorno_ret_qs2qd 1091716 1091716 0 0.00 0 119189 42 0 0
sp_kill_qty_storno 576292 576292 0 0.00 0 119190 98 0 0
sp_lock_selected_doc 20645 20645 0 0.00 0 9670 8 0 0
sp_make_cost_storno 27905 27905 0 0.00 0 18395 86 0 0
sp_multiply_rows_for_pdistr 27903 27903 0 0.00 0 8493 44 0 0
sp_multiply_rows_for_qdistr 20428 20428 0 0.00 0 13422 151 0 0
sp_pay_from_customer 7131 7131 0 0.00 5 11066 198 0 0
sp_pay_to_supplier 5843 5843 0 0.00 6 15463 204 0 0
sp_payment_common 12974 12974 0 0.00 2 11532 52 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 1 13955 397 0 0
sp_qd_handle_on_invoice_upd_sts 10392 10392 0 0.00 0 41116 531 0 0
sp_qd_handle_on_reserve_upd_sts 10033 10033 0 0.00 0 15453 60 0 0
sp_reserve_write_off 10033 10033 0 0.00 9 22041 296 0 0
sp_supplier_invoice 5663 5663 0 0.00 9 133949 3208 0 0
sp_supplier_order 5389 5389 0 0.00 7 47085 1380 0 0
srv_aggregate_perf_data 103 103 0 0.00 2343 169961 15910 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 835 238118 34909 0 0
srv_find_qd_qs_mism 1036527 1036527 0 0.00 0 16686 11 0 0
srv_make_invnt_saldo 436 436 0 0.00 63 57197 4246 0 0
srv_make_money_saldo 909 909 0 0.00 1 12162 414 0 0
srv_recalc_idx_stat 5 5 0 0.00 277383 514642 395619 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4948 4948 0 0.00 1 10247 974 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 3 6924 554 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 3276 32 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 4821 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 688 688 0 0.00 0 623 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 711 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1132 41 0 0
t$perf-norm:sp_cancel_supplier_order 380 380 0 0.00 0 107310 7466 0 0
t$perf-norm:sp_cancel_write_off 967 967 0 0.00 0 3432 27 0 0
t$perf-norm:sp_client_order 20429 20429 0 0.00 0 7436 37 0 0
t$perf-norm:sp_customer_reserve 967 967 0 0.00 0 736 13 0 0
t$perf-norm:sp_pay_from_customer 7131 7131 0 0.00 0 4705 21 0 0
t$perf-norm:sp_pay_to_supplier 5843 5843 0 0.00 0 7376 24 0 0
t$perf-norm:sp_reserve_write_off 10033 10033 0 0.00 0 6095 24 0 0
t$perf-norm:sp_supplier_invoice 5663 5663 0 0.00 0 12628 34 0 0
t$perf-norm:sp_supplier_order 5389 5389 0 0.00 0 7086 34 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 421 25 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 100 4 0 0
t$perf-norm:srv_make_invnt_saldo 436 436 0 0.00 0 1027 21 0 0
t$perf-norm:srv_make_money_saldo 909 909 0 0.00 0 1087 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 181 78 0 0
v_add_invoice_to_stock 9986 9786 200 2.00 0 9318 28 0 200
v_all_customers 37542 37542 0 0.00 0 166 4 0 0
v_all_suppliers 30718 30718 0 0.00 0 167 4 0 0
v_all_wares 40850 40850 0 0.00 0 168 4 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 897 18 0 0
v_cancel_client_order 3534 3534 0 0.00 0 7676 15 0 0
v_cancel_customer_prepayment 1376 1376 0 0.00 0 1500 13 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 1 7578 20 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 2784 23 0 0
v_cancel_supplier_invoice 868 854 14 1.61 0 4321 33 0 14
v_cancel_supplier_order 760 760 0 0.00 0 497 10 0 0
v_cancel_write_off 1934 1934 0 0.00 0 1455 25 0 0
v_max_id_clo_ord 5388 5388 0 0.00 0 4132 6 0 0
v_max_id_clo_res 967 967 0 0.00 0 2393 15 0 0
v_max_id_ord_sup 5650 5650 0 0.00 0 6900 7 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 1252 9 0 0
v_max_non_paid_realizn 3690 3690 0 0.00 0 704 7 0 0
v_min_id_clo_ord 5388 5388 0 0.00 0 3804 11 0 0
v_min_id_clo_res 967 967 0 0.00 0 1598 10 0 0
v_min_id_ord_sup 5650 5650 0 0.00 0 4414 9 0 0
v_min_non_paid_invoice 5843 5843 0 0.00 0 11522 21 0 0
v_min_non_paid_realizn 7131 7131 0 0.00 0 7659 21 0 0
v_our_firm 10190 10190 0 0.00 0 167 5 0 0
v_reserve_write_off 20066 20066 0 0.00 0 5418 14 0 0
x_lock_depdocs_on_canc_invoice 11129 11129 0 0.00 0 13699 125 0 0
x_lock_depdocs_on_canc_sup_ord 380 380 0 0.00 0 26106 506 0 0
x_make_qstorno_1000_1200 5389 5389 0 0.00 3 47075 1203 0 0
x_make_qstorno_1200_2000 5663 5663 0 0.00 3 133931 2997 0 0
x_make_qstorno_2100_3300 462396 462396 0 0.00 0 133936 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2023 13:40:34 to 11-03-2023 13:40:38.

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:31 279 8192000000 8859690160 8892841984 34815968 0.423 8 8 10 0 35278720 237728 225024
11:08:33 36680 8192000000 9726468560 10023956480 786602176 8.760 100 86 172 0 815975920 7796448 21577296
11:10:12 17727 8192000000 9761986880 10069319680 835473776 9.254 100 86 172 0 867813216 12140928 20198512
11:11:36 26329 8192000000 9807987456 10124926976 868021008 9.580 100 90 180 0 910463024 22296048 20145968
11:13:06 9374 8192000000 9862617792 10194595840 916526032 10.062 100 85 168 0 976520528 41988064 18006432
11:14:20 4093 8192000000 9914770224 10256588800 944805616 10.340 100 81 162 0 1027822560 66689328 16327616
11:15:28 2125 8192000000 9964874592 10317115392 982584944 10.709 100 88 169 1 1077108368 78022864 16500560
11:16:33 2064 8192000000 9986725024 10344280064 1007868512 10.955 100 90 176 1 1104923536 79448496 17606528
11:17:39 1420 8192000000 9984194704 10350186496 1031518000 11.183 100 75 141 0 1102296176 57139648 13638528
11:18:42 872 8192000000 10015866960 10389512192 1051097472 11.371 100 93 177 1 1136341248 68437552 16806224
11:19:44 1093 8192000000 10031707696 10405502976 1060253328 11.459 100 98 195 1 1154339008 74689696 19395984
11:20:47 1030 8192000000 10037177520 10416967680 1065094784 11.505 100 95 181 1 1156497024 74007312 17394928
11:21:49 1393 8192000000 10043982624 10431508480 1071263888 11.564 100 86 171 0 1166886944 78227648 17395408
11:22:52 1183 8192000000 10035268592 10418401280 1075397808 11.604 100 87 172 1 1155104880 62395632 17311440
11:23:53 990 8192000000 10056129840 10447302656 1082112960 11.668 100 94 172 1 1182266544 80898176 19255408
11:24:55 1223 8192000000 10053033264 10438930432 1084510496 11.690 100 96 181 1 1177683056 74430064 18742496
11:25:58 1142 8192000000 10059784896 10444910592 1090175632 11.744 100 97 188 2 1181686480 72030416 19480432
11:27:01 1071 8192000000 10030739664 10421661696 1090259072 11.745 100 96 179 1 1154818032 46072784 18486176
11:28:03 1135 8192000000 10031093392 10416873472 1088374208 11.727 100 98 186 1 1152382000 45289680 18718112
11:29:05 1256 8192000000 10032578864 10418016256 1082585632 11.672 100 94 174 3 1156013072 55489792 17937648
11:30:10 910 8192000000 10048261632 10434277376 1080809472 11.655 100 90 166 1 1170256096 72219328 17227296
11:31:12 1136 8192000000 10063424992 10450505728 1081584800 11.663 100 92 176 1 1187837856 88435424 17817632
11:32:14 977 8192000000 10048034688 10433044480 1072526240 11.576 100 94 182 3 1173905888 82735776 18643872
11:33:17 939 8192000000 9999908272 10372464640 1043475040 11.298 100 87 169 1 1123569200 61744848 18349312
11:34:19 3907 8192000000 9978630128 10351947776 1017740368 11.050 98 72 130 4 1091812976 59672096 14400512
11:35:24 4809 8192000000 9955534448 10323054592 999689888 10.876 98 76 146 4 1075115088 61098672 14326528
11:36:30 4713 8192000000 9975420928 10356260864 1034570992 11.212 100 64 122 0 1090788624 43803568 12414064
11:37:37 4923 8192000000 9995091872 10379104256 1043822336 11.301 100 74 133 4 1124540192 66772208 13945648
11:38:53 1842 8192000000 10006558256 10388496384 1055223456 11.411 100 77 145 2 1127486032 57287536 14975040
11:40:03 9220 8192000000 10018952800 10408546304 1061776944 11.473 100 59 116 0 1138131856 64714144 11640768
11:41:17 10602 8192000000 10054668480 10447413248 1082199392 11.668 100 66 124 1 1167673776 72671648 12802736
11:42:32 13375 8192000000 10091955312 10477428736 1094107296 11.782 100 69 133 2 1200033920 92084304 13842320
11:43:49 3779 8192000000 10117022336 10517893120 1095754992 11.797 100 90 169 2 1243495904 131386768 16354144
11:45:01 2837 8192000000 10130536512 10525425664 1111026624 11.942 100 71 128 5 1247198224 123159408 13012192
11:46:06 6669 8192000000 10169757168 10566070272 1120994240 12.036 99 63 121 0 1281104832 146893456 13217136
11:47:17 1061 8192000000 10215559968 10604183552 1147774288 12.289 100 89 172 3 1326004256 160181936 18048032
11:48:22 1218 8192000000 10286230656 10680516608 1164098720 12.442 100 91 177 0 1395713440 212060080 19554640
11:49:28 846 8192000000 10325370416 10726903808 1177544288 12.567 100 95 172 2 1438018080 240271520 20202272
11:50:32 915 8192000000 10363401056 10765074432 1189537472 12.679 100 96 184 0 1472597872 260612848 22447552
11:51:36 1201 8192000000 10390138672 10791837696 1191658768 12.699 100 93 182 0 1498717088 286319968 20738352
11:52:41 1193 8192000000 10429353968 10848563200 1193044080 12.712 100 76 152 0 1540336256 329496496 17795680
11:53:46 889 8192000000 10481434000 10886987776 1195241056 12.732 100 97 183 2 1592369680 375397376 21731248
11:54:50 1154 8192000000 10492966256 10905640960 1185908048 12.645 100 96 182 0 1600883760 394284224 20691488
11:55:56 2598 8192000000 10512138080 10939961344 1188964032 12.674 100 95 181 1 1624507456 415221568 20321856
11:57:01 901 8192000000 10520045344 10976600064 1186815312 12.654 100 91 180 0 1626150368 417422864 21912192
11:58:05 824 8192000000 10534038112 10975989760 1193398912 12.715 100 97 183 3 1642286576 427826768 21060896
11:59:11 2640 8192000000 10536667616 10958077952 1197485536 12.753 100 93 180 0 1648142048 427975232 22681280
12:00:17 1790 8192000000 10591916896 11007725568 1193980512 12.720 99 91 179 2 1696678784 480454224 22244048
12:01:22 3572 8192000000 10612698128 11063951360 1199729184 12.774 100 97 193 0 1720630912 496475584 24426144
12:02:29 2720 8192000000 10563947664 11070881792 1189643152 12.680 100 78 148 0 1671103968 462801248 18659568
12:03:34 1465 8192000000 10575463264 11011538944 1189503648 12.679 100 85 169 3 1674863696 465588656 19771392
12:04:39 5937 8192000000 10534336016 10980315136 1179003376 12.581 100 49 92 1 1636624720 445610608 12010736
12:05:49 10975 8192000000 10573411696 11033186304 1175207104 12.545 100 60 119 0 1679054272 489427552 14419616
12:07:02 9064 8192000000 10466038832 10979348480 1162696480 12.429 100 53 100 0 1591806640 415272400 13837760
12:08:18 20072 8192000000 10405214176 10846023680 1152215808 12.330 99 31 61 0 1507409632 347347168 7846656
12:09:40 17656 8192000000 10346778000 10825338880 1142963312 12.243 99 23 46 0 1448737632 299948960 5825360
12:11:10 24616 8192000000 10267017760 10736558080 1137941776 12.196 99 18 36 0 1397446240 255246176 4258288
12:12:37 19956 8192000000 10237782928 10690973696 1101758944 11.854 98 38 76 0 1336313120 224784064 9770112
12:13:58 55032 8192000000 10124618800 10563354624 1106602368 11.900 98 5 10 0 1185605056 77926736 1075952
12:15:56 71414 8192000000 10073772304 10530267136 1086166224 11.706 97 14 24 0 1162185392 73377440 2641728
12:18:12 49682 8192000000 10091384128 10505285632 1100185264 11.839 97 24 45 1 1162383200 57321408 4876528
12:20:03 19547 8192000000 10158708192 10585481216 1144077776 12.254 100 53 93 0 1251081792 96852416 10151600
12:21:26 95814 8192000000 10215458432 10656145408 1152279360 12.331 100 56 108 0 1302996832 139219280 11498192
12:24:08 42902 8192000000 10141221648 10601689088 1143683920 12.250 99 16 32 0 1238578560 91570672 3323968
12:25:52 31808 8192000000 10171711552 10633420800 1148076464 12.291 100 44 86 0 1270854000 112230208 10547328
12:27:54 238118 8192000000 10090207760 10556620800 1045038128 11.313 91 17 34 0 1092510544 44201376 3271040
12:32:55 49744 8192000000 10076267104 10565763072 1122059744 12.046 99 17 34 0 1198588576 72895440 3633392
12:34:45 85352 8192000000 10052911936 10525089792 1101025008 11.847 97 25 50 0 1132354288 26055248 5274032
12:37:49 66093 8192000000 10046256768 10519736320 1106161648 11.896 97 13 26 1 1134188736 25248992 2778096
12:40:21 70918 8192000000 10053950384 10505158656 1125742112 12.081 100 8 16 0 1165176384 37804896 1629376
12:42:37 36706 8192000000 10136182224 10605301760 1133083408 12.150 99 26 52 0 1223494624 84264112 6147104
12:44:17 28166 8192000000 10192294336 10636095488 1110513168 11.937 96 33 66 1 1236472992 118769472 7190352
12:45:53 30020 8192000000 10206902656 10654437376 1128633888 12.108 98 22 44 0 1282445360 148739328 5072144
12:47:24 67538 8192000000 10198937696 10666590208 1102611232 11.862 96 23 47 1 1268458624 160475904 5371488
12:49:40 63215 8192000000 10106384400 10586464256 1121758624 12.044 98 16 32 0 1188657808 63795296 3103888
12:52:00 28934 8192000000 10149345552 10599784448 1132798800 12.148 99 39 78 0 1222425328 81587408 8039120
12:53:37 38904 8192000000 10144343056 10642673664 1123498832 12.060 98 37 74 0 1224965984 94116256 7350896
12:55:22 126722 8192000000 10146038160 10658004992 1143722544 12.251 100 40 80 0 1247302256 95402976 8176736
12:58:52 72572 8192000000 10101186640 10599026688 1111896000 11.950 97 10 20 0 1168372528 54414800 2061728
13:01:08 71541 8192000000 10100816384 10547142656 1096558688 11.805 97 16 32 0 1167310416 67330752 3420976
13:03:20 70937 8192000000 10054774144 10499112960 1085927296 11.704 96 21 42 2 1125698848 35296752 4474800
13:05:34 37150 8192000000 10058722896 10495062016 1055586032 11.414 95 34 67 0 1123433552 61117456 6730064
13:07:11 42156 8192000000 10062499824 10487738368 1096372224 11.803 99 35 69 1 1138192672 34651376 7169072
13:08:55 5368 8192000000 10109455632 10524250112 1115260992 11.982 100 63 124 0 1197222640 69887328 12074320
13:10:03 30819 8192000000 10119525616 10547642368 1120640448 12.033 100 34 68 0 1183834352 56386544 6807360
13:11:36 77484 8192000000 10073666240 10531123200 1075428496 11.604 96 19 38 0 1147163392 67736800 3998096
13:13:54 136645 8192000000 10033525856 10495959040 1087719472 11.721 98 31 62 0 1140391296 46121808 6550016
13:17:18 27187 8192000000 10048119488 10491596800 1119385376 12.021 100 30 48 0 1155967008 31591152 4990480
13:18:53 52784 8192000000 10083228096 10547691520 1116390256 11.993 99 19 38 0 1173257488 52702288 4164944
13:21:54 67898 8192000000 10078720896 10576498688 1127351248 12.096 100 14 28 0 1193773840 62928848 3493744
13:24:13 54392 8192000000 10064283968 10502688768 1091112016 11.753 96 24 43 1 1114572928 19822560 3638352
13:26:10 43954 8192000000 10072362944 10541883392 1126420528 12.088 99 26 51 1 1193460112 61456304 5583280
13:27:56 12941 8192000000 10122215776 10550628352 1134873264 12.167 100 54 102 1 1204037040 57847360 11316416
13:29:12 22654 8192000000 10155914064 10612289536 1142412896 12.238 100 45 90 0 1265964384 114182768 9368720
13:30:40 39617 8192000000 10094017760 10541514752 1087781584 11.722 95 25 50 0 1157138416 64465296 4891536
13:32:20 45551 8192000000 10125268304 10567311360 1129811856 12.120 99 26 52 0 1179328528 43845152 5671520
13:34:16 52403 8192000000 10093982368 10574118912 1100487456 11.842 97 42 84 0 1241723536 132650416 8585664
13:36:11 9851 8192000000 10091790416 10520580096 1130981328 12.131 100 47 92 0 1180412608 40398512 9032768
13:37:26 56121 8192000000 9071840416 9327161344 228960880 2.718 21 4 7 0 237887856 8140400 786576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-03-2023 13:40:40 to 11-03-2023 13:40:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-03-2023 13:40:58 to 11-03-2023 13:41:00.

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          220750
MON$OLDEST_ACTIVE               220751
MON$OLDEST_SNAPSHOT             220751
MON$NEXT_TRANSACTION            220751
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                       2076320
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2908 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2908 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2908 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2908 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Sat Mar 11 13:38:24 2023
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

11.03.23 13:41:01. 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
2023-03-11 13:41:04 No severe PSQL-related problems occured
11.03.23 13:41:05. End of report.