Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.06.2022 13:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16117724 kB
MemAvailable 31368956 kB
Buffers 0 kB
Cached 15620504 kB
SwapCached 2448 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-04 13:37:19
DTS_END                         2022-06-04 13:37:19.2520
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/52264
TRN_ID                          218021
ATT_ID                          616
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: 9360.98 21882 1123318
customer order: creation 167.43 464 20092
customer order: refuse 14.36 360 1723
order to supplier: creation 44.63 1325 5356
order to supplier: removal 3.53 154773 423
invoice (draft): creation 44.73 3074 5367
invoice (draft): removal 52.88 920 6345
invoice accept: apply 40.97 25448 4916
invoice accept: cancel 48.08 1109 5769
customer reserve: creation 3857.66 239 462919
customer reserve: removal 4787.02 89 574443
realization accept: apply 82.95 261 9954
realization accept: cancel 89.16 152 10699
payment from customer: creation 58.28 163 6994
payment from customer: removal 5.79 124 695
payment to supplier: creation 47.59 203 5711
payment to supplier: removal 3.65 110 438
service: total inventory turnovers 4.08 3946 489
service: total monetary turnovers 7.33 333 879
service: aggregate perf. data 0.85 18462 102
service: refresh index statistics 0.03 226085 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-06-2022 13:39:25 to 04-06-2022 13:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8372
TEST_TIME 2 6404
TEST_TIME 3 5823
TEST_TIME 4 5396
TEST_TIME 5 5546
TEST_TIME 6 5625
TEST_TIME 7 5632
TEST_TIME 8 5819
TEST_TIME 9 5793
TEST_TIME 10 6175
TEST_TIME 11 6684
TEST_TIME 12 6651
TEST_TIME 13 7024
TEST_TIME 14 7083
TEST_TIME 15 7212
TEST_TIME 16 7294
TEST_TIME 17 7736
TEST_TIME 18 7711
TEST_TIME 19 7763
TEST_TIME 20 7870
TEST_TIME 21 7986
TEST_TIME 22 8240
TEST_TIME 23 8348
TEST_TIME 24 8343
TEST_TIME 25 8663
TEST_TIME 26 8831
TEST_TIME 27 8912
TEST_TIME 28 9192
TEST_TIME 29 9609
TEST_TIME 30 9657
TEST_TIME 31 10407
TEST_TIME 32 10868
TEST_TIME 33 10909
TEST_TIME 34 10956
TEST_TIME 35 10936
TEST_TIME 36 10943
TEST_TIME 37 10777
TEST_TIME 38 10666
TEST_TIME 39 10568
TEST_TIME 40 10532
TEST_TIME 41 10300
TEST_TIME 42 10240
TEST_TIME 43 10215
TEST_TIME 44 10094
TEST_TIME 45 9985
TEST_TIME 46 9955
TEST_TIME 47 9960
TEST_TIME 48 9927
TEST_TIME 49 9921
TEST_TIME 50 9864
TEST_TIME 51 9864
TEST_TIME 52 9862
TEST_TIME 53 9875
TEST_TIME 54 9855
TEST_TIME 55 9844
TEST_TIME 56 9738
TEST_TIME 57 9815
TEST_TIME 58 9860
TEST_TIME 59 9773
TEST_TIME 60 9730
TEST_TIME 61 9837
TEST_TIME 62 9816
TEST_TIME 63 9808
TEST_TIME 64 9886
TEST_TIME 65 9933
TEST_TIME 66 9978
TEST_TIME 67 9933
TEST_TIME 68 9916
TEST_TIME 69 9975
TEST_TIME 70 9921
TEST_TIME 71 9799
TEST_TIME 72 9723
TEST_TIME 73 9779
TEST_TIME 74 9774
TEST_TIME 75 9783
TEST_TIME 76 9715
TEST_TIME 77 9691
TEST_TIME 78 9641
TEST_TIME 79 9584
TEST_TIME 80 9560
TEST_TIME 81 9501
TEST_TIME 82 9434
TEST_TIME 83 9492
TEST_TIME 84 9397
TEST_TIME 85 9434
TEST_TIME 86 9404
TEST_TIME 87 9372
TEST_TIME 88 9354
TEST_TIME 89 9360
TEST_TIME 90 9442
TEST_TIME 91 9410
TEST_TIME 92 9391
TEST_TIME 93 9410
TEST_TIME 94 9392
TEST_TIME 95 9435
TEST_TIME 96 9467
TEST_TIME 97 9688
TEST_TIME 98 9654
TEST_TIME 99 9622
TEST_TIME 100 9616
TEST_TIME 101 9529
TEST_TIME 102 9506
TEST_TIME 103 9479
TEST_TIME 104 9429
TEST_TIME 105 9377
TEST_TIME 106 9344
TEST_TIME 107 9404
TEST_TIME 108 9365
TEST_TIME 109 9328
TEST_TIME 110 9352
TEST_TIME 111 9356
TEST_TIME 112 9335
TEST_TIME 113 9336
TEST_TIME 114 9332
TEST_TIME 115 9386
TEST_TIME 116 9350
TEST_TIME 117 9446
TEST_TIME 118 9390
TEST_TIME 119 9449
TEST_TIME 120 9387

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 04-06-2022 13:39:29 to 04-06-2022 13:39: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 1550120 1550120 0 0.00 0 21294 13 0 0
doc_list_biud 1550104 1550104 0 0.00 0 24387 39 0 0
sp_add_invoice_to_stock 4996 4916 80 1.60 51 277567 25448 0 80
sp_cancel_adding_invoice 5769 5769 0 0.00 7 88813 1110 0 0
sp_cancel_client_order 1723 1723 0 0.00 6 15225 361 0 0
sp_cancel_customer_reserve 574443 574443 0 0.00 1 26561 89 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 4 5379 125 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 4 2552 111 0 0
sp_cancel_supplier_invoice 6353 6345 8 0.12 3 24422 920 0 8
sp_cancel_supplier_order 423 423 0 0.00 18 1269405 154774 0 0
sp_cancel_write_off 10699 10699 0 0.00 2 15351 153 0 0
sp_client_order 20092 20092 0 0.00 5 22338 465 0 0
sp_customer_reserve 462919 462919 0 0.00 1 174721 239 0 0
sp_fill_shopping_cart 30794 30794 0 0.00 1 19534 79 0 0
sp_fill_shopping_cart_clo_res 462887 462887 0 0.00 0 13628 17 0 0
sp_get_clo_for_invoice 4878 4878 0 0.00 0 26036 263 0 0
sp_kill_cost_storno 17601 17601 0 0.00 0 13352 69 0 0
sp_kill_qstorno_ret_qs2qd 1166353 1166353 0 0.00 0 24378 39 0 0
sp_kill_qty_storno 614229 614229 0 0.00 0 24382 90 0 0
sp_lock_selected_doc 20547 20547 0 0.00 0 10618 7 0 0
sp_make_cost_storno 27535 27535 0 0.00 0 12918 76 0 0
sp_multiply_rows_for_pdistr 27530 27530 0 0.00 0 21271 42 0 0
sp_multiply_rows_for_qdistr 20091 20091 0 0.00 0 22290 173 0 0
sp_pay_from_customer 6994 6994 0 0.00 5 14849 163 0 0
sp_pay_to_supplier 5711 5711 0 0.00 6 21302 204 0 0
sp_payment_common 12703 12703 0 0.00 2 4073 42 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 15180 294 0 0
sp_qd_handle_on_invoice_upd_sts 10644 10644 0 0.00 0 23669 501 0 0
sp_qd_handle_on_reserve_upd_sts 9952 9952 0 0.00 0 14801 60 0 0
sp_reserve_write_off 9954 9954 0 0.00 9 15427 262 0 0
sp_supplier_invoice 5367 5367 0 0.00 8 83954 3075 0 0
sp_supplier_order 5356 5356 0 0.00 8 44188 1326 0 0
srv_aggregate_perf_data 102 102 0 0.00 2268 166059 18462 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 782 187993 37927 0 0
srv_find_qd_qs_mism 1075269 1075269 0 0.00 0 19086 10 0 0
srv_make_invnt_saldo 489 489 0 0.00 65 59035 3947 0 0
srv_make_money_saldo 879 879 0 0.00 1 26313 333 0 0
srv_recalc_idx_stat 4 4 0 0.00 52040 391835 226085 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4920 4920 0 0.00 1 19439 929 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 8323 492 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 4507 35 0 0
t$perf-norm:sp_cancel_customer_reserve 681 681 0 0.00 0 11204 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 3145 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 797 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 1128 30 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 102405 6314 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 721 16 0 0
t$perf-norm:sp_client_order 20092 20092 0 0.00 0 6253 36 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 2974 22 0 0
t$perf-norm:sp_pay_from_customer 6994 6994 0 0.00 0 4864 21 0 0
t$perf-norm:sp_pay_to_supplier 5711 5711 0 0.00 0 4625 19 0 0
t$perf-norm:sp_reserve_write_off 9954 9954 0 0.00 0 5691 24 0 0
t$perf-norm:sp_supplier_invoice 5367 5367 0 0.00 0 3641 31 0 0
t$perf-norm:sp_supplier_order 5356 5356 0 0.00 0 3776 30 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 680 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 233 7 0 0
t$perf-norm:srv_make_invnt_saldo 489 489 0 0.00 0 782 15 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 281 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 281 83 0 0
v_add_invoice_to_stock 9900 9740 160 1.61 0 6037 23 0 160
v_all_customers 36965 36965 0 0.00 0 60 3 0 0
v_all_suppliers 29960 29960 0 0.00 0 81 4 0 0
v_all_wares 40176 40176 0 0.00 0 127 4 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 1447 15 0 0
v_cancel_client_order 3446 3446 0 0.00 0 1638 8 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 1279 9 0 0
v_cancel_customer_reserve 1362 1362 0 0.00 1 920 9 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 213 8 0 0
v_cancel_supplier_invoice 794 778 16 2.01 0 1210 21 0 16
v_cancel_supplier_order 846 846 0 0.00 0 295 8 0 0
v_cancel_write_off 1976 1976 0 0.00 0 5107 28 0 0
v_max_id_clo_ord 5348 5348 0 0.00 0 136 5 0 0
v_max_id_clo_res 971 971 0 0.00 0 1687 15 0 0
v_max_id_ord_sup 5356 5356 0 0.00 0 3925 8 0 0
v_max_non_paid_invoice 1433 1433 0 0.00 0 166 7 0 0
v_max_non_paid_realizn 3512 3512 0 0.00 0 1105 7 0 0
v_min_id_clo_ord 5348 5348 0 0.00 0 2423 10 0 0
v_min_id_clo_res 971 971 0 0.00 0 1135 8 0 0
v_min_id_ord_sup 5356 5356 0 0.00 0 5398 7 0 0
v_min_non_paid_invoice 5710 5710 0 0.00 0 4071 14 0 0
v_min_non_paid_realizn 6993 6993 0 0.00 0 3326 17 0 0
v_our_firm 10172 10172 0 0.00 0 64 4 0 0
v_reserve_write_off 19902 19902 0 0.00 0 5610 11 0 0
x_lock_depdocs_on_canc_invoice 11725 11725 0 0.00 0 26534 120 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 14263 314 0 0
x_make_qstorno_1000_1200 5356 5356 0 0.00 2 44121 1157 0 0
x_make_qstorno_1200_2000 5367 5367 0 0.00 3 83945 2870 0 0
x_make_qstorno_2100_3300 462919 462919 0 0.00 0 174717 210 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-06-2022 13:39:34 to 04-06-2022 13:39: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:23 2 8192000000 8798448560 8809533440 4481168 0.054 2 1 2 0 4581120 64176 35776
11:08:23 25034 8192000000 9717653488 10014908416 790651200 8.801 100 88 177 0 820073664 7857904 21564560
11:09:53 27573 8192000000 9769598480 10078089216 843751424 9.337 100 88 176 1 877779744 13298752 20729568
11:11:27 14694 8192000000 9806777280 10124967936 875576640 9.656 100 86 172 0 917899744 22528672 19794432
11:12:48 9267 8192000000 9873453808 10210959360 919156208 10.088 100 80 159 0 986950496 50531840 17262448
11:14:07 4931 8192000000 9945830928 10293501952 973157696 10.618 100 84 168 0 1056667120 65984464 17524960
11:15:17 2241 8192000000 9971655856 10330714112 1003504400 10.912 100 84 163 0 1097264880 77005552 16754928
11:16:21 1051 8192000000 10015259728 10381254656 1031215872 11.180 100 92 178 1 1138155408 89320848 17618688
11:17:25 1109 8192000000 10021986816 10395385856 1049652816 11.357 100 97 183 3 1146103424 77041888 19408720
11:18:27 1298 8192000000 10043878560 10418454528 1057356064 11.431 100 91 175 1 1161742016 87448048 16937904
11:19:31 9757 8192000000 10029368464 10407059456 1071201408 11.564 100 74 145 0 1152559440 67291408 14066624
11:20:43 1434 8192000000 10042966704 10425118720 1079327216 11.641 100 92 175 0 1159551504 62308848 17915440
11:21:46 1261 8192000000 10071790784 10456018944 1089731232 11.740 100 96 191 0 1192962352 85003328 18227792
11:22:49 1418 8192000000 10049004288 10434424832 1095126176 11.791 100 95 174 2 1174632704 60968928 18537600
11:23:53 1326 8192000000 10052619792 10441269248 1093892352 11.780 100 92 173 0 1173399152 61371664 18135136
11:24:55 996 8192000000 10066490832 10455748608 1096884032 11.808 100 88 176 0 1186172272 72205040 17083200
11:25:57 1089 8192000000 10068842032 10457935872 1102087328 11.857 100 94 183 1 1192036320 71186176 18762816
11:26:59 1260 8192000000 10077573504 10468179968 1103724976 11.873 100 96 188 1 1188988800 66151552 19112272
11:28:02 933 8192000000 10042555984 10433503232 1094773808 11.788 100 84 168 1 1169913616 56714304 18425504
11:29:03 4483 8192000000 10044206032 10435518464 1074603760 11.596 99 71 138 1 1165918640 77871040 13443840
11:30:09 1219 8192000000 10052087264 10438524928 1078410288 11.632 100 91 169 3 1173301776 76368480 18523008
11:31:11 921 8192000000 10074819312 10464067584 1083848240 11.684 100 86 167 0 1194415760 92628032 17939488
11:32:13 1168 8192000000 10015591024 10395799552 1065396368 11.508 100 97 187 1 1138256080 53386304 19473408
11:33:15 1221 8192000000 9975178352 10354450432 1044770288 11.310 99 71 129 2 1097289792 38714800 13804704
11:34:18 1464 8192000000 10000912576 10384674816 1052680368 11.386 100 82 163 1 1127871568 56515392 18675808
11:35:20 1941 8192000000 10000543664 10383048704 1055270176 11.411 100 74 142 1 1119357776 48873568 15214032
11:36:23 2345 8192000000 9985065584 10361200640 1032132464 11.189 99 69 128 2 1104633616 58786608 13714544
11:37:28 949 8192000000 10004011552 10382479360 1057714896 11.435 100 93 171 2 1124206496 47870976 18620624
11:38:37 4424 8192000000 10005414032 10394611712 1067442400 11.528 100 65 123 0 1128806176 49564416 11799360
11:39:47 6014 8192000000 10052703152 10447843328 1082059184 11.667 100 64 126 0 1173772896 78298336 13415376
11:41:08 14560 8192000000 10056256160 10453250048 1096342464 11.803 100 55 108 0 1180601840 72835104 11424272
11:42:39 1198 8192000000 10122644544 10514939904 1118359984 12.011 100 93 183 0 1240573904 101536176 20677744
11:43:43 2058 8192000000 10162585984 10565033984 1131568160 12.136 100 80 159 0 1284663296 134985280 18109856
11:44:48 1933 8192000000 10179922048 10586501120 1136795552 12.185 100 82 162 1 1296046064 141784416 17466096
11:45:54 1586 8192000000 10202988752 10598670336 1132970480 12.149 100 78 149 0 1303254320 154810576 15473264
11:47:01 771 8192000000 10243629360 10638368768 1153651888 12.344 100 90 173 1 1358037696 185664144 18721664
11:48:06 1114 8192000000 10257040464 10665607168 1161824096 12.420 100 94 188 1 1370526944 187184032 21518816
11:49:12 4448 8192000000 10255260784 10657468416 1160711568 12.410 100 79 156 2 1367255872 189495648 17048656
11:50:20 1292 8192000000 10287399648 10712113152 1167531632 12.474 100 85 160 0 1406517808 221085168 17901008
11:51:25 1721 8192000000 10296337376 10714263552 1175029088 12.544 100 86 162 1 1405550960 211476736 19045136
11:52:30 2915 8192000000 10352106528 10755391488 1182065184 12.609 100 89 175 1 1454933488 253508336 19359968
11:53:36 1301 8192000000 10348233424 10777149440 1182492944 12.613 100 93 182 1 1453163664 249755792 20914928
11:54:40 1150 8192000000 10390239712 10810359808 1208382080 12.854 100 91 181 1 1497536144 269080896 20073168
11:55:43 1212 8192000000 10435195360 10837745664 1206085968 12.833 100 97 186 3 1543453696 315460784 21906944
11:56:47 1311 8192000000 10448351760 10874404864 1191783632 12.700 100 95 180 1 1556822880 343277792 21761456
11:57:52 1668 8192000000 10465838240 10929008640 1204829200 12.821 100 95 182 1 1564729136 338338080 21561856
11:58:55 1357 8192000000 10499848032 10941562880 1202144832 12.796 100 92 178 0 1605598384 381608720 21844832
12:00:00 5527 8192000000 10490925376 10931523584 1192018224 12.702 100 61 122 0 1604428688 396717472 15692992
12:01:24 3802 8192000000 10484164784 10940850176 1183290992 12.621 100 51 102 1 1599685744 403532992 12861760
12:02:34 3748 8192000000 10474537424 10935230464 1178470800 12.576 100 52 100 1 1580201280 387882928 13847552
12:03:43 5191 8192000000 10465629824 10929954816 1186190944 12.648 100 69 135 0 1579774816 376044048 17539824
12:04:51 9377 8192000000 10474578784 10931412992 1185187216 12.639 100 58 116 0 1583320928 382574752 15558960
12:06:17 12520 8192000000 10423669376 10906288128 1171212480 12.508 100 32 64 0 1541421328 361098256 9110592
12:07:31 20807 8192000000 10300735616 10824785920 1154214240 12.349 99 31 62 2 1436161248 273750704 8196304
12:08:57 24612 8192000000 10187906544 10687475712 1143726960 12.251 99 19 38 1 1306587024 158435488 4424576
12:10:36 115734 8192000000 10051689216 10516803584 1056941120 11.427 92 10 20 0 1093589648 35060720 1587808
12:13:37 60979 8192000000 10045396480 10489405440 1095508272 11.795 96 7 14 1 1117443600 20634144 1301184
12:15:48 67374 8192000000 10075477120 10505871360 1106350416 11.898 98 26 50 3 1149264320 37675504 5238400
12:18:01 158205 8192000000 10092381792 10533318656 1114557600 11.976 99 31 62 1 1222418320 100157552 7703168
12:21:41 78440 8192000000 10123668288 10558574592 1106760720 11.902 97 30 60 1 1195992080 83082080 6149280
12:24:00 15417 8192000000 10121561408 10583973888 1135907936 12.177 100 28 55 2 1212294432 69834688 6551808
12:25:18 34979 8192000000 10161393408 10598457344 1137667744 12.194 100 59 101 6 1244481344 93132992 13680608
12:26:57 187992 8192000000 10092637184 10563903488 1123588096 12.061 98 19 37 0 1270242992 142733632 3921264
12:31:13 53040 8192000000 10088167312 10568114176 1144591584 12.259 100 6 12 0 1201658688 55658944 1408160
12:33:10 80054 8192000000 10127688624 10587811840 1116411968 11.993 97 15 30 0 1194383024 74511120 3459936
12:35:39 48716 8192000000 10104087120 10543722496 1132339760 12.143 99 20 40 0 1182054480 45078832 4635888
12:37:31 72368 8192000000 10090743056 10579079168 1102950144 11.866 96 20 39 0 1166795968 59971888 3873936
12:40:29 38214 8192000000 10153877184 10620641280 1138590032 12.202 99 24 48 0 1254639616 111121584 4928000
12:42:15 68624 8192000000 10100026048 10570575872 1130966800 12.130 99 12 24 1 1192213184 58956448 2289936
12:45:26 39271 8192000000 10130002448 10586202112 1144101008 12.254 100 28 56 1 1204198208 54450864 5646336
12:47:09 86033 8192000000 10088733584 10529914880 1103349408 11.869 97 19 38 0 1162609920 55108208 4152304
12:50:07 91050 8192000000 10071971632 10599751680 1089945056 11.742 97 17 34 0 1208135136 113827296 4362784
12:52:48 65741 8192000000 10048295936 10506821632 1090490672 11.747 97 12 24 0 1142960736 50056128 2413936
12:54:58 64641 8192000000 10063017168 10514149376 1081271152 11.660 97 18 36 0 1132180048 47150720 3758176
12:57:43 85733 8192000000 10088097024 10561130496 1126517984 12.089 100 12 24 1 1198275456 69193744 2563728
13:00:52 93357 8192000000 10068572448 10535092224 1099648448 11.834 98 5 10 0 1150627536 49256704 1722384
13:03:26 41973 8192000000 10056834320 10497245184 1096857840 11.808 98 25 49 0 1136292224 33897616 5536768
13:05:09 25571 8192000000 10093787328 10537771008 1110046912 11.933 99 40 80 0 1188075664 69937536 8091216
13:06:35 19329 8192000000 10132120448 10584317952 1124977936 12.074 100 41 82 0 1222949456 89642800 8328720
13:08:00 7319 8192000000 10071287904 10493890560 1104068992 11.876 99 51 98 3 1147924624 34145920 9709712
13:09:09 18264 8192000000 10150087376 10581291008 1135929792 12.177 100 57 112 0 1241597072 94553568 11113712
13:10:28 38779 8192000000 10191665264 10639634432 1136586800 12.183 100 18 36 0 1277555968 137176864 3792304
13:12:22 34717 8192000000 10213776080 10648248320 1142163568 12.236 100 27 54 0 1269327856 120961920 6202368
13:14:08 40098 8192000000 10124043168 10672709632 1129903056 12.120 100 19 38 1 1296542160 162235088 4404016
13:15:52 29743 8192000000 10125030896 10554355712 1126433984 12.088 99 46 90 0 1187860752 52103632 9323136
13:17:24 118449 8192000000 10058373520 10495950848 1124535856 12.070 99 29 58 0 1180485600 49845664 6104080
13:20:40 70200 8192000000 10124656592 10591977472 1137579120 12.193 99 16 32 0 1213244288 71675856 3989312
13:23:03 66310 8192000000 10118283664 10603708416 1128929296 12.111 98 19 38 1 1240304704 106593344 4782064
13:25:22 61100 8192000000 10106949280 10576769024 1128764192 12.110 99 15 30 1 1191533008 59561040 3207776
13:27:23 61192 8192000000 10045455616 10500255744 1091455392 11.756 96 14 28 1 1157285936 62969696 2860848
13:29:27 36574 8192000000 10140030448 10590601216 1127993792 12.102 99 38 76 1 1224343024 88542864 7806368
13:31:07 46642 8192000000 10132924832 10582540288 1128117376 12.104 99 21 40 2 1212315072 79886080 4311616
13:32:54 20636 8192000000 10123839968 10557906944 1128619264 12.108 99 46 90 1 1197148848 59429216 9100368
13:34:18 58558 8192000000 10078228976 10542641152 1127376672 12.097 99 23 46 2 1193918320 61993520 4548128
13:36:19 38819 8192000000 10108730896 10588635136 1135272928 12.171 100 35 70 1 1214825312 72713616 6838768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-06-2022 13:39:40 to 04-06-2022 13:39:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 80 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-06-2022 13:39:56 to 04-06-2022 13:39:58.

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          215424
MON$OLDEST_ACTIVE               218993
MON$OLDEST_SNAPSHOT             218993
MON$NEXT_TRANSACTION            218993
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                       2066912
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:116: 
:117: mscs-test-161.budzdorov.ru    Sat Jun  4 13:37:20 2022
:118:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:119: 
:120: 
:121: mscs-test-161.budzdorov.ru    Sat Jun  4 13:37:37 2022
:122:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:123: 
:124: 
:125: mscs-test-161.budzdorov.ru    Sat Jun  4 13:37:37 2022
:126:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:127: 
--- end of diff output ---

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

04.06.22 13:39:59. 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-04 13:40:02 No severe PSQL-related problems occured
04.06.22 13:40:03. End of report.