Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.06.2022 13:38
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 16145820 kB
MemAvailable 31384856 kB
Buffers 0 kB
Cached 15592216 kB
SwapCached 2444 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-06-01 13:37:17
DTS_END                         2022-06-01 13:37:17.4520
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/34636
TRN_ID                          223970
ATT_ID                          620
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: 9104.22 22758 1092506
customer order: creation 173.28 471 20793
customer order: refuse 14.56 374 1747
order to supplier: creation 46.07 1338 5528
order to supplier: removal 3.33 176831 399
invoice (draft): creation 47.01 3255 5641
invoice (draft): removal 48.53 1066 5823
invoice accept: apply 43.08 26512 5170
invoice accept: cancel 43.74 1190 5249
customer reserve: creation 4058.73 248 487048
customer reserve: removal 4329.55 107 519546
realization accept: apply 83.53 281 10023
realization accept: cancel 80.29 161 9635
payment from customer: creation 61.12 183 7334
payment from customer: removal 6.38 117 765
payment to supplier: creation 48.67 194 5840
payment to supplier: removal 3.53 116 424
service: total inventory turnovers 3.84 4050 461
service: total monetary turnovers 8.06 322 967
service: aggregate perf. data 0.90 18277 108
service: refresh index statistics 0.04 220060 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 01-06-2022 13:38:55 to 01-06-2022 13:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7347
TEST_TIME 2 7169
TEST_TIME 3 7686
TEST_TIME 4 7047
TEST_TIME 5 6891
TEST_TIME 6 6585
TEST_TIME 7 6761
TEST_TIME 8 6597
TEST_TIME 9 6491
TEST_TIME 10 6452
TEST_TIME 11 6396
TEST_TIME 12 6642
TEST_TIME 13 6976
TEST_TIME 14 7041
TEST_TIME 15 7228
TEST_TIME 16 7250
TEST_TIME 17 7503
TEST_TIME 18 7494
TEST_TIME 19 7720
TEST_TIME 20 7738
TEST_TIME 21 7660
TEST_TIME 22 7631
TEST_TIME 23 7821
TEST_TIME 24 7967
TEST_TIME 25 8462
TEST_TIME 26 8465
TEST_TIME 27 8677
TEST_TIME 28 9039
TEST_TIME 29 9467
TEST_TIME 30 10092
TEST_TIME 31 10108
TEST_TIME 32 10406
TEST_TIME 33 11053
TEST_TIME 34 11394
TEST_TIME 35 11282
TEST_TIME 36 11184
TEST_TIME 37 11190
TEST_TIME 38 11008
TEST_TIME 39 10840
TEST_TIME 40 10597
TEST_TIME 41 10568
TEST_TIME 42 10568
TEST_TIME 43 10459
TEST_TIME 44 10358
TEST_TIME 45 10293
TEST_TIME 46 10126
TEST_TIME 47 10096
TEST_TIME 48 10161
TEST_TIME 49 10131
TEST_TIME 50 10068
TEST_TIME 51 9923
TEST_TIME 52 9843
TEST_TIME 53 9883
TEST_TIME 54 10100
TEST_TIME 55 10105
TEST_TIME 56 10052
TEST_TIME 57 10130
TEST_TIME 58 10100
TEST_TIME 59 10119
TEST_TIME 60 10118
TEST_TIME 61 10127
TEST_TIME 62 10100
TEST_TIME 63 10133
TEST_TIME 64 10155
TEST_TIME 65 10102
TEST_TIME 66 9982
TEST_TIME 67 9882
TEST_TIME 68 9884
TEST_TIME 69 9762
TEST_TIME 70 9666
TEST_TIME 71 9645
TEST_TIME 72 9605
TEST_TIME 73 9563
TEST_TIME 74 9602
TEST_TIME 75 9617
TEST_TIME 76 9723
TEST_TIME 77 9729
TEST_TIME 78 9668
TEST_TIME 79 9662
TEST_TIME 80 9726
TEST_TIME 81 9727
TEST_TIME 82 9749
TEST_TIME 83 9689
TEST_TIME 84 9740
TEST_TIME 85 9661
TEST_TIME 86 9567
TEST_TIME 87 9539
TEST_TIME 88 9545
TEST_TIME 89 9469
TEST_TIME 90 9471
TEST_TIME 91 9451
TEST_TIME 92 9414
TEST_TIME 93 9406
TEST_TIME 94 9387
TEST_TIME 95 9363
TEST_TIME 96 9412
TEST_TIME 97 9384
TEST_TIME 98 9428
TEST_TIME 99 9425
TEST_TIME 100 9394
TEST_TIME 101 9481
TEST_TIME 102 9541
TEST_TIME 103 9495
TEST_TIME 104 9514
TEST_TIME 105 9509
TEST_TIME 106 9435
TEST_TIME 107 9414
TEST_TIME 108 9354
TEST_TIME 109 9343
TEST_TIME 110 9345
TEST_TIME 111 9309
TEST_TIME 112 9286
TEST_TIME 113 9245
TEST_TIME 114 9237
TEST_TIME 115 9229
TEST_TIME 116 9205
TEST_TIME 117 9210
TEST_TIME 118 9177
TEST_TIME 119 9188
TEST_TIME 120 9124

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-06-2022 13:38:59 to 01-06-2022 13:39:02.

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 1539599 1539599 0 0.00 0 36367 14 0 0
doc_list_biud 1539599 1539599 0 0.00 0 30966 41 0 0
sp_add_invoice_to_stock 5347 5170 177 3.31 50 191733 26512 0 177
sp_cancel_adding_invoice 5249 5249 0 0.00 5 59080 1190 0 0
sp_cancel_client_order 1747 1747 0 0.00 8 11736 375 0 0
sp_cancel_customer_reserve 519546 519546 0 0.00 1 26609 107 0 0
sp_cancel_pay_from_customer 765 765 0 0.00 3 3484 117 0 0
sp_cancel_pay_to_supplier 424 424 0 0.00 3 5225 116 0 0
sp_cancel_supplier_invoice 5841 5823 18 0.30 3 30978 1067 0 18
sp_cancel_supplier_order 399 399 0 0.00 8 1703372 176832 0 0
sp_cancel_write_off 9635 9635 0 0.00 2 27623 162 0 0
sp_client_order 20793 20793 0 0.00 6 30966 472 0 0
sp_customer_reserve 487048 487048 0 0.00 1 71686 248 0 0
sp_fill_shopping_cart 31961 31961 0 0.00 1 21550 83 0 0
sp_fill_shopping_cart_clo_res 487031 487031 0 0.00 0 19251 19 0 0
sp_get_clo_for_invoice 5134 5134 0 0.00 0 19505 285 0 0
sp_kill_cost_storno 16073 16073 0 0.00 0 27606 68 0 0
sp_kill_qstorno_ret_qs2qd 1054949 1054949 0 0.00 0 30965 46 0 0
sp_kill_qty_storno 557555 557555 0 0.00 0 30966 105 0 0
sp_lock_selected_doc 21026 21026 0 0.00 0 8303 8 0 0
sp_make_cost_storno 28330 28330 0 0.00 0 13175 82 0 0
sp_multiply_rows_for_pdistr 28330 28330 0 0.00 0 7455 39 0 0
sp_multiply_rows_for_qdistr 20793 20793 0 0.00 0 9680 172 0 0
sp_pay_from_customer 7334 7334 0 0.00 5 12233 184 0 0
sp_pay_to_supplier 5840 5840 0 0.00 7 12416 195 0 0
sp_payment_common 13174 13174 0 0.00 2 8354 46 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 1 11698 307 0 0
sp_qd_handle_on_invoice_upd_sts 10382 10382 0 0.00 0 14018 526 0 0
sp_qd_handle_on_reserve_upd_sts 10023 10023 0 0.00 0 15700 64 0 0
sp_reserve_write_off 10023 10023 0 0.00 9 21421 281 0 0
sp_supplier_invoice 5641 5641 0 0.00 10 108638 3256 0 0
sp_supplier_order 5528 5528 0 0.00 8 22644 1339 0 0
srv_aggregate_perf_data 108 108 0 0.00 2518 165801 18278 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 884 110209 36474 0 0
srv_find_qd_qs_mism 1041403 1041403 0 0.00 0 36364 11 0 0
srv_make_invnt_saldo 461 461 0 0.00 53 52419 4051 0 0
srv_make_money_saldo 967 967 0 0.00 1 10453 323 0 0
srv_recalc_idx_stat 5 5 0 0.00 70876 406933 220061 0 0
t$perf-abend:sp_add_invoice_to_stock 177 177 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5170 5170 0 0.00 1 11849 937 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 2 5581 459 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 2069 26 0 0
t$perf-norm:sp_cancel_customer_reserve 702 702 0 0.00 0 1563 27 0 0
t$perf-norm:sp_cancel_pay_from_customer 765 765 0 0.00 0 3943 29 0 0
t$perf-norm:sp_cancel_pay_to_supplier 424 424 0 0.00 0 239 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 844 46 0 0
t$perf-norm:sp_cancel_supplier_order 399 399 0 0.00 0 100277 6759 0 0
t$perf-norm:sp_cancel_write_off 1025 1025 0 0.00 0 1008 17 0 0
t$perf-norm:sp_client_order 20793 20793 0 0.00 0 11063 34 0 0
t$perf-norm:sp_customer_reserve 996 996 0 0.00 0 4075 19 0 0
t$perf-norm:sp_pay_from_customer 7334 7334 0 0.00 0 15738 22 0 0
t$perf-norm:sp_pay_to_supplier 5840 5840 0 0.00 0 8330 21 0 0
t$perf-norm:sp_reserve_write_off 10023 10023 0 0.00 0 3901 20 0 0
t$perf-norm:sp_supplier_invoice 5641 5641 0 0.00 0 9285 32 0 0
t$perf-norm:sp_supplier_order 5528 5528 0 0.00 0 8625 35 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 1098 31 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 346 10 0 0
t$perf-norm:srv_make_invnt_saldo 461 461 0 0.00 0 418 10 0 0
t$perf-norm:srv_make_money_saldo 967 967 0 0.00 0 591 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 15 310 130 0 0
v_add_invoice_to_stock 10620 10266 354 3.33 0 6478 28 0 354
v_all_customers 38508 38508 0 0.00 0 70 4 0 0
v_all_suppliers 30836 30836 0 0.00 0 72 4 0 0
v_all_wares 41586 41586 0 0.00 0 111 4 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 4700 22 0 0
v_cancel_client_order 3494 3494 0 0.00 0 1576 9 0 0
v_cancel_customer_prepayment 1530 1530 0 0.00 0 3412 15 0 0
v_cancel_customer_reserve 1404 1404 0 0.00 0 657 9 0 0
v_cancel_payment_to_supplier 848 848 0 0.00 0 261 9 0 0
v_cancel_supplier_invoice 870 834 36 4.13 0 884 26 0 36
v_cancel_supplier_order 798 798 0 0.00 0 5149 17 0 0
v_cancel_write_off 2050 2050 0 0.00 0 2540 23 0 0
v_max_id_clo_ord 5528 5528 0 0.00 0 213 5 0 0
v_max_id_clo_res 996 996 0 0.00 0 2521 20 0 0
v_max_id_ord_sup 5640 5640 0 0.00 0 10380 9 0 0
v_max_non_paid_invoice 1590 1590 0 0.00 0 61 7 0 0
v_max_non_paid_realizn 3754 3754 0 0.00 0 6653 9 0 0
v_min_id_clo_ord 5528 5528 0 0.00 0 1743 8 0 0
v_min_id_clo_res 996 996 0 0.00 0 8210 17 0 0
v_min_id_ord_sup 5640 5640 0 0.00 0 4234 7 0 0
v_min_non_paid_invoice 5840 5840 0 0.00 0 6111 14 0 0
v_min_non_paid_realizn 7334 7334 0 0.00 0 7165 17 0 0
v_our_firm 10238 10238 0 0.00 0 70 5 0 0
v_reserve_write_off 20046 20046 0 0.00 0 8778 12 0 0
x_lock_depdocs_on_canc_invoice 10655 10655 0 0.00 0 19502 129 0 0
x_lock_depdocs_on_canc_sup_ord 399 399 0 0.00 0 20050 362 0 0
x_make_qstorno_1000_1200 5528 5528 0 0.00 2 22635 1169 0 0
x_make_qstorno_1200_2000 5641 5641 0 0.00 5 99792 3032 0 0
x_make_qstorno_2100_3300 487048 487048 0 0.00 0 71685 217 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-06-2022 13:39:03 to 01-06-2022 13:39:07.

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:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 30311 8192000000 9725742512 10026053632 802347856 8.920 100 88 176 0 831499600 7654752 21496992
11:09:57 17886 8192000000 9761438000 10069401600 836363408 9.263 100 88 174 2 869735968 12119232 21253328
11:11:22 19698 8192000000 9811493120 10129846272 869423184 9.594 100 86 172 0 909377872 19946240 20008448
11:12:51 10918 8192000000 9866482720 10202353664 915439072 10.051 100 83 164 0 977160000 43267216 18453712
11:14:05 6049 8192000000 9928722336 10276392960 960840640 10.497 100 88 173 1 1047704320 68535232 18328448
11:15:15 1252 8192000000 9952039280 10306285568 996492576 10.845 100 92 172 1 1071320304 57285328 17542400
11:16:18 1317 8192000000 9999891424 10366263296 1022472544 11.096 100 90 169 1 1123635520 83872304 17290672
11:17:21 984 8192000000 10005569792 10373390336 1043634464 11.300 100 96 180 1 1127935888 66086912 18214512
11:18:24 1115 8192000000 10022362736 10390769664 1055002112 11.409 100 93 174 1 1141607696 69083456 17522128
11:19:28 1221 8192000000 10032683136 10406838272 1061509744 11.471 100 94 178 2 1153591808 74211952 17870112
11:20:30 1295 8192000000 10026844320 10404794368 1067738960 11.530 100 94 178 1 1149873088 62801472 19332656
11:21:35 1172 8192000000 10047071040 10428116992 1073482128 11.585 100 97 188 1 1169302048 75951744 19868176
11:22:38 1328 8192000000 10076204240 10461544448 1078194976 11.630 100 92 170 2 1196337552 99877936 18264640
11:23:40 1345 8192000000 10081079232 10466639872 1087953280 11.723 100 79 151 3 1202728352 100564832 14210240
11:24:43 1392 8192000000 10081831008 10465898496 1090532256 11.748 100 97 188 0 1204894384 96062672 18299456
11:25:45 1203 8192000000 10059599600 10448867328 1095491760 11.795 100 95 189 1 1180827440 66197232 19138448
11:26:47 1347 8192000000 10058618144 10448920576 1103600336 11.872 100 97 184 3 1182143184 58874768 19668080
11:27:50 1134 8192000000 10045414432 10428047360 1088043808 11.724 100 97 185 1 1170972192 64246384 18682000
11:28:52 1287 8192000000 10047478192 10428854272 1086336160 11.708 100 95 184 1 1166592160 60884384 19371616
11:29:54 949 8192000000 10053084752 10437632000 1092727808 11.769 100 95 181 0 1173733264 60944640 20060816
11:30:56 1148 8192000000 10043822672 10425626624 1078295696 11.631 100 98 189 0 1168263888 71154816 18813376
11:31:58 1042 8192000000 10023546048 10403938304 1074606000 11.596 100 91 171 1 1149481104 54646512 20228592
11:32:59 1424 8192000000 10019760064 10394464256 1058829328 11.445 100 94 184 2 1139322448 62504864 17988256
11:34:02 1142 8192000000 10005450480 10381316096 1045456496 11.317 100 97 183 1 1130125296 64962016 19706784
11:35:04 944 8192000000 9982337664 10359304192 1034558720 11.212 99 82 156 1 1104605600 52725312 17321568
11:36:05 1217 8192000000 9997090368 10371690496 1037518880 11.241 100 88 161 3 1116313488 63128896 15665712
11:37:07 11434 8192000000 9991003296 10384437248 1037495040 11.241 99 56 110 0 1100499328 52273328 10730960
11:38:28 4402 8192000000 9991519824 10371096576 1064121696 11.496 100 77 139 0 1112331632 34754800 13455136
11:39:37 4567 8192000000 10040333392 10418073600 1074247808 11.593 100 69 133 1 1162798064 76215456 12334800
11:40:45 884 8192000000 10067536320 10447888384 1084645888 11.692 100 93 179 0 1190944720 85142384 21156448
11:41:49 1435 8192000000 10092098848 10476425216 1098165904 11.820 100 83 161 3 1214197328 98828896 17202528
11:42:54 1207 8192000000 10105358480 10494812160 1097924640 11.818 99 82 160 0 1229056784 112161568 18970576
11:43:59 11701 8192000000 10152653232 10553126912 1105252448 11.887 99 66 125 0 1259431232 140446592 13732192
11:45:15 2703 8192000000 10175973872 10570776576 1132537680 12.145 100 77 148 0 1296393776 147671376 16184720
11:46:22 1905 8192000000 10213771472 10609135616 1145137072 12.264 100 81 159 1 1325297120 162842912 17317136
11:47:28 8311 8192000000 10283265552 10681815040 1157123920 12.376 100 79 158 0 1388697856 214639008 16934928
11:48:41 4160 8192000000 10304970128 10706661376 1162837712 12.430 100 82 161 0 1421782224 240653984 18290528
11:49:50 1190 8192000000 10333405616 10743758848 1173611856 12.531 100 93 180 0 1446928256 252138384 21178016
11:50:55 1299 8192000000 10359401568 10765484032 1182466464 12.613 100 92 176 0 1470409536 267496688 20446384
11:51:59 921 8192000000 10412152864 10815221760 1192869088 12.710 100 98 188 0 1520153856 305500528 21784240
11:53:05 876 8192000000 10442682512 10859921408 1201792304 12.793 100 97 185 1 1550988976 327951216 21245456
11:54:08 1759 8192000000 10427639808 10843045888 1190770976 12.691 100 96 186 1 1540204000 326445056 22987968
11:55:16 1045 8192000000 10508377936 10914164736 1209025680 12.860 100 91 175 1 1613886080 385339568 19520832
11:56:20 890 8192000000 10537318352 10971561984 1215780832 12.923 100 92 183 0 1643687920 405223232 22683856
11:57:22 1272 8192000000 10573373008 10992652288 1221529600 12.976 100 98 195 0 1679455264 435229088 22696576
11:58:26 1880 8192000000 10616353248 11025059840 1211053472 12.879 100 96 189 1 1722116112 487866960 23195680
11:59:33 1131 8192000000 10660414176 11099922432 1206725376 12.839 100 95 184 0 1767201712 537647536 22828800
12:00:36 1055 8192000000 10634978016 11098226688 1202159968 12.796 100 93 183 0 1740718560 516415248 22143344
12:01:40 3090 8192000000 10635510432 11107966976 1198292384 12.760 100 80 161 0 1738865664 519817088 20756192
12:02:48 3070 8192000000 10640583200 11108696064 1192510560 12.707 100 65 130 0 1750260336 541373552 16376224
12:03:57 9623 8192000000 10616995408 11088785408 1173885568 12.533 100 53 106 1 1734820224 547213104 13721552
12:05:21 8365 8192000000 10537570784 11031941120 1164650384 12.447 100 45 90 2 1639496432 462552224 12293824
12:06:30 17947 8192000000 10429272832 10961375232 1138847200 12.205 98 37 74 0 1514814048 366136480 9830368
12:07:50 33114 8192000000 10356248240 10834882560 1161794000 12.420 100 31 60 1 1492493232 323102464 7596768
12:09:26 13733 8192000000 10314953968 10816724992 1154583904 12.353 100 21 42 0 1446858288 287091440 5182944
12:10:45 92056 8192000000 10051197088 10519744512 1044669568 11.310 91 10 18 1 1074466032 28298320 1498144
12:13:17 35612 8192000000 10107349776 10563899392 1132348368 12.143 99 43 84 1 1205241328 63615824 9277136
12:14:54 93917 8192000000 10074202000 10507272192 1095719264 11.797 95 38 76 0 1132669520 28353088 8597168
12:17:32 99710 8192000000 10090825024 10558881792 1118513936 12.013 98 40 78 1 1188267264 60953984 8799344
12:20:26 52409 8192000000 10066306320 10501152768 1103733424 11.873 97 30 55 2 1142638896 32729648 6175824
12:22:21 47239 8192000000 10131287984 10579857408 1150518928 12.314 100 17 34 1 1230246800 75319184 4408688
12:24:14 24572 8192000000 10230403648 10685550592 1160923776 12.412 100 52 102 2 1333482448 161074032 11484640
12:25:39 78193 8192000000 10162764544 10641387520 1147661056 12.288 99 18 36 0 1281589248 129536400 4391792
12:28:50 110208 8192000000 10218854256 10720591872 1151241776 12.321 100 18 36 0 1351691216 195828160 4621280
12:32:40 31349 8192000000 10121307920 10569035776 1146386160 12.276 100 36 69 1 1200373344 46274496 7712688
12:34:16 40008 8192000000 10096330192 10557452288 1119775552 12.025 98 27 54 2 1163878704 38923104 5180048
12:35:59 27894 8192000000 10067319680 10510512128 1088682624 11.730 95 44 86 3 1163647792 64065328 10899840
12:37:28 34932 8192000000 10093276144 10523987968 1084924016 11.694 96 59 115 3 1167469472 70949456 11596000
12:39:10 52860 8192000000 10094982688 10534629376 1120731600 12.034 99 33 66 2 1176175136 48954496 6489040
12:41:03 60169 8192000000 10080731840 10543841280 1069001952 11.543 94 23 44 1 1123640768 50330144 4308672
12:43:07 83496 8192000000 10083605952 10504241152 1109231248 11.925 98 57 113 0 1160525808 40843024 10451536
12:45:31 49894 8192000000 10066299312 10536607744 1124345792 12.068 99 8 16 0 1169640672 43470704 1824176
12:47:27 67619 8192000000 10127721712 10600046592 1120109520 12.028 98 18 36 0 1215700832 91316208 4275104
12:49:36 68005 8192000000 10160891888 10629038080 1142324576 12.237 99 26 52 2 1267047872 118913312 5809984
12:52:06 92548 8192000000 10082278240 10571890688 1130684544 12.128 99 12 23 1 1199553584 66261024 2608016
12:54:40 33908 8192000000 10090787360 10521370624 1121219184 12.039 98 36 69 2 1158387904 29894000 7274720
12:56:20 60413 8192000000 10076968480 10573221888 1125014912 12.074 99 25 49 1 1212121040 81835264 5270864
12:58:34 87738 8192000000 10068630208 10508976128 1099616000 11.834 97 22 44 1 1135330816 31673408 4041408
13:01:02 90489 8192000000 10085992592 10567954432 1122068928 12.047 99 35 70 0 1245756560 115481376 8206256
13:03:34 9327 8192000000 10062779504 10522214400 1124358256 12.068 99 37 64 2 1161946208 29877648 7710304
13:04:44 91260 8192000000 10072095616 10546798592 1098453088 11.823 97 10 20 0 1172255600 71524000 2278512
13:07:26 83528 8192000000 10047826336 10522058752 1127472848 12.098 100 13 26 0 1173344736 43291888 2580000
13:09:49 58562 8192000000 10090552560 10554843136 1126046512 12.084 99 27 54 1 1179321200 47454208 5820480
13:11:53 25414 8192000000 10132073840 10589376512 1127589904 12.099 99 36 72 1 1221130928 85825088 7715936
13:13:24 57277 8192000000 10133038432 10594803712 1146491632 12.277 100 27 54 3 1264474560 112257264 5725664
13:15:42 46786 8192000000 10132027360 10570641408 1136664976 12.184 100 25 50 1 1220043024 78190352 5187696
13:17:32 22555 8192000000 10188135888 10623139840 1133670624 12.156 99 45 90 0 1260137520 116769936 9696960
13:18:58 55294 8192000000 10076562096 10583269376 1135085184 12.169 100 14 28 0 1228502368 90541488 2875696
13:21:07 50375 8192000000 10130385856 10573983744 1146254144 12.274 100 40 78 1 1231916704 77447952 8214608
13:23:00 86422 8192000000 10063851328 10509553664 1110087776 11.933 97 39 78 0 1155008368 36772000 8148592
13:25:33 52870 8192000000 10109304656 10565619712 1131425552 12.135 99 40 80 0 1189686704 50051568 8209584
13:27:46 56679 8192000000 10063886992 10536128512 1143292240 12.246 100 15 30 0 1184326256 38021984 3012032
13:29:43 81445 8192000000 10089327648 10547908608 1120925728 12.036 98 23 46 2 1182721248 56771296 5024224
13:32:15 33780 8192000000 10072013120 10540208128 1117418704 12.003 98 36 72 0 1168146656 43157408 7570544
13:33:59 34287 8192000000 10121198608 10579308544 1136281536 12.181 99 22 43 3 1212874768 72155936 4437296
13:35:41 36855 8192000000 10146274736 10591219712 1086057504 11.705 95 40 79 1 1220250592 125953024 8240064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 01-06-2022 13:39:09 to 01-06-2022 13:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 177 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-06-2022 13:39:26 to 01-06-2022 13:39:28.

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          218085
MON$OLDEST_ACTIVE               224440
MON$OLDEST_SNAPSHOT             224440
MON$NEXT_TRANSACTION            224440
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                       2065632
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2771 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2771 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2771 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2771 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 ---
:28: 
:29: mscs-test-161.budzdorov.ru    Wed Jun  1 13:37:19 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Wed Jun  1 13:37:26 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Wed Jun  1 13:37:26 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Wed Jun  1 13:37:26 2022
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

01.06.22 13:39:29. 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-06-01 13:39:32 No severe PSQL-related problems occured
01.06.22 13:39:33. End of report.