Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.05.2022 10: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 16081948 kB
MemAvailable 31549140 kB
Buffers 0 kB
Cached 15648904 kB
SwapCached 3280 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-05-04 10:37:16
DTS_END                         2022-05-04 10:37:16.6790
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58526
TRN_ID                          224727
ATT_ID                          646
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: 9384.77 25874 1126172
customer order: creation 172.23 451 20668
customer order: refuse 14.68 377 1762
order to supplier: creation 45.36 1358 5443
order to supplier: removal 3.58 169416 430
invoice (draft): creation 46.72 3117 5606
invoice (draft): removal 52.92 966 6350
invoice accept: apply 41.26 26810 4951
invoice accept: cancel 47.48 1084 5698
customer reserve: creation 3935.58 251 472269
customer reserve: removal 4732.07 101 567848
realization accept: apply 83.97 268 10076
realization accept: cancel 78.34 169 9401
payment from customer: creation 59.24 174 7109
payment from customer: removal 5.83 144 700
payment to supplier: creation 49.56 201 5947
payment to supplier: removal 3.53 138 423
service: total inventory turnovers 4.08 4201 489
service: total monetary turnovers 7.48 340 898
service: aggregate perf. data 0.83 17394 100
service: refresh index statistics 0.03 290519 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-05-2022 10:39:42 to 04-05-2022 10:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5459
TEST_TIME 2 5602
TEST_TIME 3 5682
TEST_TIME 4 5419
TEST_TIME 5 5825
TEST_TIME 6 5645
TEST_TIME 7 5454
TEST_TIME 8 5758
TEST_TIME 9 6404
TEST_TIME 10 6290
TEST_TIME 11 6513
TEST_TIME 12 6750
TEST_TIME 13 6812
TEST_TIME 14 6765
TEST_TIME 15 6893
TEST_TIME 16 6862
TEST_TIME 17 6960
TEST_TIME 18 7335
TEST_TIME 19 7358
TEST_TIME 20 7537
TEST_TIME 21 7626
TEST_TIME 22 7628
TEST_TIME 23 7578
TEST_TIME 24 7719
TEST_TIME 25 7691
TEST_TIME 26 8271
TEST_TIME 27 8626
TEST_TIME 28 8634
TEST_TIME 29 9252
TEST_TIME 30 9424
TEST_TIME 31 10458
TEST_TIME 32 10974
TEST_TIME 33 11400
TEST_TIME 34 11486
TEST_TIME 35 11403
TEST_TIME 36 11423
TEST_TIME 37 11521
TEST_TIME 38 11309
TEST_TIME 39 11124
TEST_TIME 40 10983
TEST_TIME 41 11025
TEST_TIME 42 10969
TEST_TIME 43 10881
TEST_TIME 44 10802
TEST_TIME 45 10747
TEST_TIME 46 10603
TEST_TIME 47 10602
TEST_TIME 48 10502
TEST_TIME 49 10512
TEST_TIME 50 10504
TEST_TIME 51 10420
TEST_TIME 52 10447
TEST_TIME 53 10460
TEST_TIME 54 10462
TEST_TIME 55 10322
TEST_TIME 56 10351
TEST_TIME 57 10235
TEST_TIME 58 10165
TEST_TIME 59 10138
TEST_TIME 60 9994
TEST_TIME 61 9942
TEST_TIME 62 9842
TEST_TIME 63 9826
TEST_TIME 64 9852
TEST_TIME 65 9827
TEST_TIME 66 9854
TEST_TIME 67 9799
TEST_TIME 68 9773
TEST_TIME 69 9913
TEST_TIME 70 9859
TEST_TIME 71 9860
TEST_TIME 72 9868
TEST_TIME 73 9912
TEST_TIME 74 9852
TEST_TIME 75 9915
TEST_TIME 76 9909
TEST_TIME 77 9886
TEST_TIME 78 9832
TEST_TIME 79 9815
TEST_TIME 80 9822
TEST_TIME 81 9759
TEST_TIME 82 9680
TEST_TIME 83 9674
TEST_TIME 84 9626
TEST_TIME 85 9628
TEST_TIME 86 9616
TEST_TIME 87 9590
TEST_TIME 88 9549
TEST_TIME 89 9535
TEST_TIME 90 9579
TEST_TIME 91 9544
TEST_TIME 92 9553
TEST_TIME 93 9528
TEST_TIME 94 9536
TEST_TIME 95 9691
TEST_TIME 96 9717
TEST_TIME 97 9698
TEST_TIME 98 9725
TEST_TIME 99 9654
TEST_TIME 100 9635
TEST_TIME 101 9581
TEST_TIME 102 9564
TEST_TIME 103 9523
TEST_TIME 104 9500
TEST_TIME 105 9451
TEST_TIME 106 9442
TEST_TIME 107 9410
TEST_TIME 108 9415
TEST_TIME 109 9392
TEST_TIME 110 9391
TEST_TIME 111 9381
TEST_TIME 112 9380
TEST_TIME 113 9381
TEST_TIME 114 9404
TEST_TIME 115 9370
TEST_TIME 116 9399
TEST_TIME 117 9398
TEST_TIME 118 9432
TEST_TIME 119 9455
TEST_TIME 120 9402

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-05-2022 10:39:46 to 04-05-2022 10:39:49.

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 1560760 1560760 0 0.00 0 17605 14 0 0
doc_list_biud 1560747 1560747 0 0.00 0 27339 41 0 0
sp_add_invoice_to_stock 5102 4951 151 2.95 82 220238 26810 0 151
sp_cancel_adding_invoice 5698 5698 0 0.00 7 80634 1084 0 0
sp_cancel_client_order 1762 1762 0 0.00 7 8687 377 0 0
sp_cancel_customer_reserve 567848 567848 0 0.00 2 22555 101 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 5807 145 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 5671 138 0 0
sp_cancel_supplier_invoice 6362 6350 12 0.18 3 18263 967 0 12
sp_cancel_supplier_order 430 430 0 0.00 19 1458952 169416 0 0
sp_cancel_write_off 9401 9401 0 0.00 2 5373 170 0 0
sp_client_order 20668 20668 0 0.00 6 17373 451 0 0
sp_customer_reserve 472269 472269 0 0.00 1 134045 252 0 0
sp_fill_shopping_cart 31699 31699 0 0.00 2 13151 79 0 0
sp_fill_shopping_cart_clo_res 472240 472240 0 0.00 0 13364 18 0 0
sp_get_clo_for_invoice 4913 4913 0 0.00 0 17715 276 0 0
sp_kill_cost_storno 16222 16222 0 0.00 0 5627 68 0 0
sp_kill_qstorno_ret_qs2qd 1151877 1151877 0 0.00 0 22395 43 0 0
sp_kill_qty_storno 606478 606478 0 0.00 0 27338 98 0 0
sp_lock_selected_doc 20831 20831 0 0.00 0 8864 10 0 0
sp_make_cost_storno 28045 28045 0 0.00 0 14541 78 0 0
sp_multiply_rows_for_pdistr 28044 28044 0 0.00 0 10384 37 0 0
sp_multiply_rows_for_qdistr 20667 20667 0 0.00 0 11438 166 0 0
sp_pay_from_customer 7109 7109 0 0.00 6 20528 175 0 0
sp_pay_to_supplier 5947 5947 0 0.00 7 17613 201 0 0
sp_payment_common 13054 13054 0 0.00 2 9315 46 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 1 8669 297 0 0
sp_qd_handle_on_invoice_upd_sts 10611 10611 0 0.00 0 27337 484 0 0
sp_qd_handle_on_reserve_upd_sts 10076 10076 0 0.00 0 12202 65 0 0
sp_reserve_write_off 10076 10076 0 0.00 9 25605 269 0 0
sp_supplier_invoice 5606 5606 0 0.00 9 93268 3118 0 0
sp_supplier_order 5443 5443 0 0.00 8 75392 1358 0 0
srv_aggregate_perf_data 100 100 0 0.00 2701 320347 17395 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 879 153895 35417 0 0
srv_find_qd_qs_mism 1076546 1076546 0 0.00 0 9702 12 0 0
srv_make_invnt_saldo 489 489 0 0.00 76 193133 4201 0 0
srv_make_money_saldo 898 898 0 0.00 1 15313 340 0 0
srv_recalc_idx_stat 4 4 0 0.00 82715 457750 290520 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 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 4955 4955 0 0.00 1 9386 841 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 5330 429 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 2589 30 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 1390 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 202 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 560 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 1112 24 0 0
t$perf-norm:sp_cancel_supplier_order 430 430 0 0.00 0 108468 6526 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 1359 16 0 0
t$perf-norm:sp_client_order 20668 20668 0 0.00 0 11583 33 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 930 15 0 0
t$perf-norm:sp_pay_from_customer 7109 7109 0 0.00 0 6186 18 0 0
t$perf-norm:sp_pay_to_supplier 5948 5948 0 0.00 0 6743 18 0 0
t$perf-norm:sp_reserve_write_off 10076 10076 0 0.00 0 7415 22 0 0
t$perf-norm:sp_supplier_invoice 5606 5606 0 0.00 0 6359 28 0 0
t$perf-norm:sp_supplier_order 5443 5443 0 0.00 0 2240 29 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 739 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 705 16 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 684 12 0 0
t$perf-norm:srv_make_money_saldo 898 898 0 0.00 0 3655 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 174 50 0 0
v_add_invoice_to_stock 10120 9818 302 2.98 0 7942 23 0 302
v_all_customers 37482 37482 0 0.00 0 161 3 0 0
v_all_suppliers 31340 31340 0 0.00 0 117 4 0 0
v_all_wares 41318 41318 0 0.00 0 279 4 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 3914 19 0 0
v_cancel_client_order 3524 3524 0 0.00 0 6427 12 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 4047 15 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1910 11 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 1534 13 0 0
v_cancel_supplier_invoice 864 840 24 2.77 0 372 19 0 24
v_cancel_supplier_order 860 860 0 0.00 0 2826 17 0 0
v_cancel_write_off 2036 2036 0 0.00 0 1748 20 0 0
v_max_id_clo_ord 5440 5440 0 0.00 0 3985 5 0 0
v_max_id_clo_res 966 966 0 0.00 0 1614 12 0 0
v_max_id_ord_sup 5595 5595 0 0.00 0 11415 8 0 0
v_max_non_paid_invoice 1312 1312 0 0.00 0 141 8 0 0
v_max_non_paid_realizn 3802 3802 0 0.00 0 446 6 0 0
v_min_id_clo_ord 5440 5440 0 0.00 0 1574 8 0 0
v_min_id_clo_res 966 966 0 0.00 0 1158 8 0 0
v_min_id_ord_sup 5595 5595 0 0.00 0 6126 17 0 0
v_min_non_paid_invoice 5947 5947 0 0.00 0 8189 20 0 0
v_min_non_paid_realizn 7107 7107 0 0.00 0 6594 17 0 0
v_our_firm 10444 10444 0 0.00 0 282 4 0 0
v_reserve_write_off 20152 20152 0 0.00 0 3967 10 0 0
x_lock_depdocs_on_canc_invoice 11628 11628 0 0.00 0 18217 119 0 0
x_lock_depdocs_on_canc_sup_ord 430 430 0 0.00 0 9046 429 0 0
x_make_qstorno_1000_1200 5443 5443 0 0.00 3 75363 1198 0 0
x_make_qstorno_1200_2000 5606 5606 0 0.00 5 88038 2901 0 0
x_make_qstorno_2100_3300 472267 472267 0 0.00 0 134041 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-05-2022 10:39:50 to 04-05-2022 10:39:54.

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
08:07:20 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:21 22016 8192000000 9717972064 10014982144 798110688 8.877 100 88 175 0 827621424 8086896 21423840
08:09:48 16853 8192000000 9748415712 10051964928 823804768 9.137 100 90 180 0 856970176 11475568 21689840
08:11:14 12596 8192000000 9779306048 10097483776 855276416 9.453 100 84 168 0 896341712 21535136 19530160
08:12:39 7687 8192000000 9861319632 10192728064 912625360 10.023 100 81 161 0 972793616 43019744 17148512
08:13:52 4704 8192000000 9922389600 10266554368 956330384 10.453 100 89 172 0 1041227984 66519008 18378592
08:14:59 1724 8192000000 9968231936 10318987264 984518160 10.728 100 94 179 0 1083000960 80965520 17517280
08:16:04 1089 8192000000 9973537280 10338131968 1017175776 11.045 100 93 183 1 1098199600 62991360 18032464
08:17:06 849 8192000000 10021472144 10392260608 1039900336 11.264 100 96 179 3 1143163968 85230624 18033008
08:18:08 1178 8192000000 10043781888 10414612480 1056650320 11.424 100 98 194 1 1162383568 85733680 19999568
08:19:10 1077 8192000000 10037849712 10410307584 1062342048 11.479 100 97 190 1 1161213072 79322656 19548368
08:20:12 1111 8192000000 10057848912 10435756032 1073224832 11.583 100 96 189 0 1177954704 86046112 18683760
08:21:14 1010 8192000000 10043083584 10425700352 1083522512 11.681 100 94 183 1 1163677920 61561280 18594128
08:22:16 1086 8192000000 10052468000 10442383360 1085495648 11.700 100 90 181 0 1177873552 72965632 19412272
08:23:19 1550 8192000000 10053005168 10437582848 1090632400 11.749 100 90 168 2 1176345584 68619408 17093776
08:24:22 2530 8192000000 10056697568 10447585280 1094097824 11.782 100 80 151 4 1181212048 72604608 14509616
08:25:25 1069 8192000000 10065040496 10452221952 1098247040 11.821 100 98 187 0 1185804800 68810848 18746912
08:26:28 5032 8192000000 10067595472 10461941760 1095617536 11.796 100 65 125 2 1181769424 73182224 12969664
08:27:34 1491 8192000000 10053627504 10441617408 1100612624 11.843 100 93 181 2 1177905072 59418496 17873952
08:28:36 2975 8192000000 10069855104 10455437312 1090265776 11.745 100 86 167 2 1192704784 86208000 16231008
08:29:40 973 8192000000 10026402768 10399662080 1070556464 11.557 100 97 190 0 1144468272 53800368 20111440
08:30:42 1279 8192000000 10035248432 10415050752 1072152160 11.573 100 96 181 1 1156376448 65043200 19181088
08:31:44 1204 8192000000 10023775408 10402758656 1052703936 11.387 100 91 180 0 1147311616 76750080 17857600
08:32:46 1293 8192000000 10001111328 10369835008 1026173232 11.132 99 92 169 1 1132137648 86192528 19771888
08:33:48 1263 8192000000 10017805136 10391498752 1047629344 11.338 100 91 174 0 1140199040 73309712 19259984
08:34:50 8522 8192000000 9986432304 10363879424 1021127344 11.083 98 73 137 1 1107187536 71863024 14197168
08:36:00 1382 8192000000 9967168176 10336538624 1017435856 11.047 99 85 162 0 1087779360 54132656 16210848
08:37:02 1764 8192000000 9990073552 10365071360 1044872432 11.311 100 75 137 3 1106283920 48347072 13064416
08:38:10 12395 8192000000 10015995040 10402521088 1066062224 11.514 100 58 112 0 1132053712 55116912 10874576
08:39:31 2271 8192000000 10050266832 10425794560 1079984448 11.647 100 80 149 3 1171977296 77087632 14905216
08:40:37 8056 8192000000 10066500000 10454958080 1093863344 11.779 100 72 140 0 1185394224 76207616 15323264
08:41:51 2770 8192000000 10114104592 10509262848 1105514288 11.890 100 72 141 0 1231793360 110750320 15528752
08:43:04 15593 8192000000 10142458384 10543562752 1106608896 11.900 99 58 113 1 1257090208 138251712 12229600
08:44:25 13284 8192000000 10166814480 10568400896 1128947360 12.111 100 60 116 2 1271658672 130129984 12581328
08:45:58 1099 8192000000 10222045456 10627387392 1145443360 12.267 100 92 170 2 1335544448 171451168 18649920
08:47:02 2891 8192000000 10227688992 10628866048 1155647328 12.362 100 82 156 0 1340104048 168445104 16011616
08:48:09 1366 8192000000 10306710320 10706944000 1174046960 12.535 100 86 164 0 1417926432 225406096 18473376
08:49:13 1864 8192000000 10319598720 10728509440 1178514832 12.576 100 96 190 1 1430535824 229796208 22224784
08:50:18 3558 8192000000 10364286784 10783649792 1186915008 12.655 100 84 168 0 1470130240 263291008 19924224
08:51:27 1274 8192000000 10413578736 10821255168 1200183776 12.778 100 96 187 1 1522898320 299278768 23435776
08:52:31 872 8192000000 10485530912 10894237696 1211662928 12.885 100 93 183 2 1584156240 351580240 20913072
08:53:36 2889 8192000000 10506533824 10921988096 1209482608 12.864 100 91 179 0 1614668928 383005264 22181056
08:54:41 1420 8192000000 10520638496 10957770752 1210654560 12.875 100 94 186 0 1629067952 394574256 23839136
08:55:46 931 8192000000 10490874656 10923106304 1190227600 12.685 99 93 182 2 1597670096 386068336 21374160
08:56:51 2439 8192000000 10543117040 10968592384 1215692352 12.922 100 92 183 1 1648377008 411172112 21512544
08:57:57 1042 8192000000 10578408944 10991628288 1210436912 12.873 99 93 184 0 1680270080 446822016 23011152
08:59:00 3979 8192000000 10621609568 11055251456 1220525056 12.967 100 83 166 0 1721634480 480359840 20749584
09:00:07 895 8192000000 10694126224 11118653440 1224113392 13.000 100 90 178 1 1794454176 548555760 21785024
09:01:16 4395 8192000000 10712787408 11136614400 1221097824 12.972 100 75 150 0 1814825024 574190016 19537184
09:02:24 9152 8192000000 10689820288 11177443328 1197175360 12.750 99 57 114 0 1827969248 615395600 15398288
09:03:35 10265 8192000000 10687975968 11124674560 1185436576 12.641 98 53 104 1 1765962384 566560672 13965136
09:05:04 15456 8192000000 10656320208 11144994816 1192860368 12.710 100 38 75 0 1760958768 558108160 9990240
09:06:21 25707 8192000000 10585796960 11119546368 1174462096 12.539 99 37 72 0 1673508496 488712752 10333648
09:07:47 35829 8192000000 10422159392 11007262720 1159720912 12.401 100 29 56 1 1622323680 455075760 7527008
09:09:27 63317 8192000000 10074926720 10536902656 1093862512 11.779 96 15 30 2 1227263136 129939392 3461232
09:11:32 58174 8192000000 10099078240 10541334528 1136589056 12.183 99 13 26 0 1175925120 36741056 2595008
09:13:31 109531 8192000000 10065471440 10524217344 1085135472 11.696 94 21 41 2 1121574224 31785664 4653088
09:16:21 73778 8192000000 10080116096 10517430272 1131517440 12.136 99 43 74 0 1170202608 31138832 7546336
09:18:37 81497 8192000000 10099648736 10534178816 1128817776 12.110 98 26 47 0 1156155264 23615872 3721616
09:21:09 41764 8192000000 10071279136 10519154688 1092748304 11.769 96 32 60 1 1154512224 54247616 7516304
09:23:02 9311 8192000000 10081920608 10520006656 1134059952 12.160 100 33 64 0 1178505520 37310064 7135504
09:24:14 2056 8192000000 10121607200 10540126208 1127979920 12.102 100 70 135 2 1211492032 69353392 14158720
09:25:28 11963 8192000000 10133253072 10552016896 1111678848 11.948 99 72 137 1 1220263568 93309104 15275616
09:26:42 16566 8192000000 10167564416 10615537664 1140561376 12.221 100 39 78 0 1280130528 131206352 8362800
09:28:24 153895 8192000000 10123416240 10592796672 1146229824 12.274 100 13 27 0 1281382960 131700240 3452896
09:32:13 88974 8192000000 10082628320 10596024320 1142694160 12.241 100 18 36 0 1196371392 49637568 4039664
09:35:17 34123 8192000000 10099784832 10534297600 1137028016 12.188 100 36 72 1 1185818704 41237616 7553072
09:36:52 82687 8192000000 10096466336 10549055488 1127697616 12.100 98 13 26 1 1157628608 28016096 1914896
09:39:21 83587 8192000000 10093665888 10570641408 1123031728 12.056 98 8 16 0 1161728208 37183472 1513008
09:41:49 109716 8192000000 10065211888 10545229824 1093930176 11.780 95 13 27 0 1157189328 59772160 3486992
09:44:45 19118 8192000000 10135476000 10567254016 1128696560 12.109 99 40 80 1 1208493984 71680000 8117424
09:46:05 53976 8192000000 10073936960 10595295232 1095430320 11.794 96 25 50 1 1188360240 87389600 5540320
09:47:59 12388 8192000000 10078114000 10511204352 1115591936 11.985 99 52 100 0 1168089056 42079664 10417456
09:49:19 23622 8192000000 10129096624 10562392064 1118354352 12.011 99 40 79 0 1218983856 92926560 7702944
09:50:43 26184 8192000000 10109400992 10548711424 1129153952 12.113 100 39 78 1 1194157648 57319456 7684240
09:52:39 50753 8192000000 10114234240 10571948032 1104795504 11.883 98 40 80 0 1162035680 49183440 8056736
09:55:15 82235 8192000000 10116355072 10574544896 1128657856 12.109 100 19 38 1 1203809824 70524976 4626992
09:58:49 67306 8192000000 10089848624 10548396032 1129182432 12.114 100 16 29 1 1184514608 52240848 3091328
10:01:40 54629 8192000000 10074597472 10546868224 1104381680 11.879 98 11 22 2 1164031248 57377456 2272112
10:03:37 46026 8192000000 10065464768 10516221952 1133923216 12.158 100 25 48 1 1166975776 28617376 4435184
10:05:24 55208 8192000000 10131256224 10580185088 1125116144 12.075 99 24 48 3 1211533792 81313040 5104608
10:07:20 31737 8192000000 10058933632 10491260928 1096239856 11.802 97 33 65 0 1176907552 73918480 6749216
10:08:52 42063 8192000000 10133572112 10575032320 1120188912 12.029 98 27 54 0 1207802880 82183536 5430432
10:10:34 46327 8192000000 10213645376 10655477760 1138727792 12.204 99 42 82 1 1294572320 147103488 8741040
10:12:24 88627 8192000000 10097084208 10587877376 1104935184 11.884 97 16 32 0 1201716816 92877632 3904000
10:14:53 74261 8192000000 10092702896 10543411200 1127073184 12.094 99 34 68 1 1213364384 79248592 7042608
10:17:35 49382 8192000000 10074224976 10509111296 1133175696 12.151 100 35 70 1 1184855312 44642448 7037168
10:19:44 44800 8192000000 10059160080 10522906624 1123457392 12.060 99 31 58 1 1172484384 42591232 6435760
10:21:29 37510 8192000000 10067062592 10520711168 1120686336 12.033 99 17 34 0 1171128352 46306912 4135104
10:23:32 43520 8192000000 10056473664 10536239104 1118722640 12.015 99 19 38 0 1159996656 36903904 4370112
10:27:14 25649 8192000000 10047544896 10489282560 1096670768 11.806 98 20 39 0 1132334624 32044880 3618976
10:28:45 41062 8192000000 10113642976 10554433536 1125248032 12.077 100 39 78 1 1217317776 84359312 7710432
10:30:26 34274 8192000000 10094872176 10577743872 1131766768 12.138 100 38 76 0 1231925648 92499680 7659200
10:32:02 29196 8192000000 10122798624 10559967232 1101087232 11.848 97 43 86 0 1192129888 81430624 9612032
10:33:36 31228 8192000000 10145104496 10577584128 1118318304 12.011 100 55 108 0 1235530864 105434592 11777968
10:35:10 94368 8192000000 10053323424 10526826496 1093810704 11.779 97 23 47 1 1172358528 73128976 5418848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-05-2022 10:39:56 to 04-05-2022 10:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 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 04-05-2022 10:40:13 to 04-05-2022 10:40:15.

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          224228
MON$OLDEST_ACTIVE               224866
MON$OLDEST_SNAPSHOT             224866
MON$NEXT_TRANSACTION            224866
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                       2093888
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2762 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2762 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2762 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2762 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 ---
:119: 
:120: mscs-test-161.budzdorov.ru    Wed May  4 10:37:32 2022
:121:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:122: 
:123: 
:124: mscs-test-161.budzdorov.ru    Wed May  4 10:37:35 2022
:125:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:126: 
:127: 
:128: mscs-test-161.budzdorov.ru    Wed May  4 10:37:51 2022
:129:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:130: 
:131: 
:132: mscs-test-161.budzdorov.ru    Wed May  4 10:37:52 2022
:133:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:134: 
--- end of diff output ---

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

04.05.22 10:40:16. 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-05-04 10:40:19 No severe PSQL-related problems occured
04.05.22 10:40:20. End of report.