Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.07.2022 10: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 15903340 kB
MemAvailable 31145028 kB
Buffers 0 kB
Cached 15834180 kB
SwapCached 2520 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-07-12 10:37:19
DTS_END                         2022-07-12 10:37:19.3060
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/36030
TRN_ID                          213933
ATT_ID                          596
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: 8498.78 21627 1019853
customer order: creation 165.60 516 19872
customer order: refuse 13.78 479 1654
order to supplier: creation 44.56 1366 5347
order to supplier: removal 3.16 182952 379
invoice (draft): creation 44.49 3379 5339
invoice (draft): removal 46.10 1114 5532
invoice accept: apply 39.97 27396 4796
invoice accept: cancel 41.50 1415 4980
customer reserve: creation 3717.23 257 446067
customer reserve: removal 4109.33 110 493120
realization accept: apply 79.43 303 9532
realization accept: cancel 68.66 181 8239
payment from customer: creation 56.99 202 6839
payment from customer: removal 5.83 153 700
payment to supplier: creation 47.03 229 5644
payment to supplier: removal 3.13 138 375
service: total inventory turnovers 3.56 4702 427
service: total monetary turnovers 7.41 479 889
service: aggregate perf. data 0.98 15177 117
service: refresh index statistics 0.04 191994 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 12-07-2022 10:39:03 to 12-07-2022 10:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4316
TEST_TIME 2 4034
TEST_TIME 3 4311
TEST_TIME 4 4666
TEST_TIME 5 5186
TEST_TIME 6 5585
TEST_TIME 7 5726
TEST_TIME 8 5628
TEST_TIME 9 6021
TEST_TIME 10 6101
TEST_TIME 11 6344
TEST_TIME 12 6353
TEST_TIME 13 6671
TEST_TIME 14 6715
TEST_TIME 15 6892
TEST_TIME 16 6822
TEST_TIME 17 6936
TEST_TIME 18 7074
TEST_TIME 19 7002
TEST_TIME 20 7185
TEST_TIME 21 7302
TEST_TIME 22 7324
TEST_TIME 23 7511
TEST_TIME 24 7759
TEST_TIME 25 7943
TEST_TIME 26 8248
TEST_TIME 27 8272
TEST_TIME 28 8425
TEST_TIME 29 8805
TEST_TIME 30 9224
TEST_TIME 31 9313
TEST_TIME 32 9285
TEST_TIME 33 9418
TEST_TIME 34 9678
TEST_TIME 35 10036
TEST_TIME 36 10111
TEST_TIME 37 9967
TEST_TIME 38 9870
TEST_TIME 39 9943
TEST_TIME 40 9823
TEST_TIME 41 9774
TEST_TIME 42 9629
TEST_TIME 43 9804
TEST_TIME 44 9634
TEST_TIME 45 9546
TEST_TIME 46 9459
TEST_TIME 47 9464
TEST_TIME 48 9477
TEST_TIME 49 9358
TEST_TIME 50 9310
TEST_TIME 51 9265
TEST_TIME 52 9155
TEST_TIME 53 9144
TEST_TIME 54 9095
TEST_TIME 55 9039
TEST_TIME 56 8984
TEST_TIME 57 9002
TEST_TIME 58 8978
TEST_TIME 59 8998
TEST_TIME 60 8941
TEST_TIME 61 8871
TEST_TIME 62 8878
TEST_TIME 63 8826
TEST_TIME 64 8770
TEST_TIME 65 8736
TEST_TIME 66 8687
TEST_TIME 67 8592
TEST_TIME 68 8623
TEST_TIME 69 8596
TEST_TIME 70 8592
TEST_TIME 71 8542
TEST_TIME 72 8573
TEST_TIME 73 8586
TEST_TIME 74 8584
TEST_TIME 75 8606
TEST_TIME 76 8674
TEST_TIME 77 8675
TEST_TIME 78 8674
TEST_TIME 79 8783
TEST_TIME 80 8771
TEST_TIME 81 8804
TEST_TIME 82 8807
TEST_TIME 83 8729
TEST_TIME 84 8742
TEST_TIME 85 8672
TEST_TIME 86 8636
TEST_TIME 87 8585
TEST_TIME 88 8537
TEST_TIME 89 8499
TEST_TIME 90 8565
TEST_TIME 91 8617
TEST_TIME 92 8623
TEST_TIME 93 8633
TEST_TIME 94 8608
TEST_TIME 95 8600
TEST_TIME 96 8636
TEST_TIME 97 8685
TEST_TIME 98 8683
TEST_TIME 99 8672
TEST_TIME 100 8676
TEST_TIME 101 8727
TEST_TIME 102 8751
TEST_TIME 103 8814
TEST_TIME 104 8803
TEST_TIME 105 8746
TEST_TIME 106 8712
TEST_TIME 107 8679
TEST_TIME 108 8697
TEST_TIME 109 8646
TEST_TIME 110 8603
TEST_TIME 111 8580
TEST_TIME 112 8572
TEST_TIME 113 8523
TEST_TIME 114 8517
TEST_TIME 115 8516
TEST_TIME 116 8510
TEST_TIME 117 8531
TEST_TIME 118 8549
TEST_TIME 119 8562
TEST_TIME 120 8513

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 10:39:07 to 12-07-2022 10:39:10.

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 1429645 1429645 0 0.00 0 17363 14 0 0
doc_list_biud 1429645 1429645 0 0.00 0 53095 45 0 0
sp_add_invoice_to_stock 4893 4796 97 1.98 49 227324 27396 0 97
sp_cancel_adding_invoice 4980 4980 0 0.00 5 110996 1416 0 0
sp_cancel_client_order 1654 1654 0 0.00 6 15014 480 0 0
sp_cancel_customer_reserve 493120 493120 0 0.00 1 29536 111 0 0
sp_cancel_pay_from_customer 700 700 0 0.00 4 6947 154 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 3 2819 139 0 0
sp_cancel_supplier_invoice 5537 5532 5 0.09 2 16528 1115 0 5
sp_cancel_supplier_order 379 379 0 0.00 20 1387229 182952 0 0
sp_cancel_write_off 8239 8239 0 0.00 2 17657 181 0 0
sp_client_order 19872 19872 0 0.00 6 21900 516 0 0
sp_customer_reserve 446067 446067 0 0.00 0 113249 258 0 0
sp_fill_shopping_cart 30556 30556 0 0.00 1 30938 93 0 0
sp_fill_shopping_cart_clo_res 446052 446052 0 0.00 0 17521 22 0 0
sp_get_clo_for_invoice 4771 4771 0 0.00 0 24302 393 0 0
sp_kill_cost_storno 14294 14294 0 0.00 0 8142 85 0 0
sp_kill_qstorno_ret_qs2qd 1000390 1000390 0 0.00 0 29486 49 0 0
sp_kill_qty_storno 528206 528206 0 0.00 0 53094 112 0 0
sp_lock_selected_doc 19891 19891 0 0.00 0 9161 9 0 0
sp_make_cost_storno 26785 26785 0 0.00 0 17353 90 0 0
sp_multiply_rows_for_pdistr 26785 26785 0 0.00 0 7929 41 0 0
sp_multiply_rows_for_qdistr 19872 19872 0 0.00 0 15781 202 0 0
sp_pay_from_customer 6839 6839 0 0.00 5 13628 203 0 0
sp_pay_to_supplier 5644 5644 0 0.00 6 13388 229 0 0
sp_payment_common 12483 12483 0 0.00 2 10932 52 0 0
sp_qd_handle_on_cancel_clo 1654 1654 0 0.00 1 14981 402 0 0
sp_qd_handle_on_invoice_upd_sts 9750 9750 0 0.00 0 53093 590 0 0
sp_qd_handle_on_reserve_upd_sts 9532 9532 0 0.00 0 15979 71 0 0
sp_reserve_write_off 9532 9532 0 0.00 8 17588 303 0 0
sp_supplier_invoice 5339 5339 0 0.00 8 114857 3380 0 0
sp_supplier_order 5347 5347 0 0.00 7 29236 1367 0 0
srv_aggregate_perf_data 117 117 0 0.00 2176 119130 15177 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 874 117177 34230 0 0
srv_find_qd_qs_mism 972150 972150 0 0.00 0 15204 10 0 0
srv_make_invnt_saldo 427 427 0 0.00 83 74929 4702 0 0
srv_make_money_saldo 889 889 0 0.00 1 38985 479 0 0
srv_recalc_idx_stat 5 5 0 0.00 100620 290612 191994 0 0
t$perf-abend:sp_add_invoice_to_stock 97 97 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4796 4796 0 0.00 1 36312 984 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 6997 468 0 0
t$perf-norm:sp_cancel_client_order 1654 1654 0 0.00 0 8231 38 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 3963 42 0 0
t$perf-norm:sp_cancel_pay_from_customer 700 700 0 0.00 0 492 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 21909 88 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 11698 71 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 98930 8299 0 0
t$perf-norm:sp_cancel_write_off 975 975 0 0.00 0 961 18 0 0
t$perf-norm:sp_client_order 19872 19872 0 0.00 0 16798 45 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 967 13 0 0
t$perf-norm:sp_pay_from_customer 6839 6839 0 0.00 0 10811 25 0 0
t$perf-norm:sp_pay_to_supplier 5644 5644 0 0.00 0 3963 22 0 0
t$perf-norm:sp_reserve_write_off 9532 9532 0 0.00 0 11050 25 0 0
t$perf-norm:sp_supplier_invoice 5339 5339 0 0.00 0 4753 35 0 0
t$perf-norm:sp_supplier_order 5347 5347 0 0.00 0 8918 37 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 373 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1297 26 0 0
t$perf-norm:srv_make_invnt_saldo 427 427 0 0.00 0 10054 40 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 3537 10 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 8 215 77 0 0
v_add_invoice_to_stock 9728 9534 194 1.99 0 8805 27 0 194
v_all_customers 35758 35758 0 0.00 0 113 4 0 0
v_all_suppliers 29812 29812 0 0.00 0 112 4 0 0
v_all_wares 39744 39744 0 0.00 0 117 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3373 18 0 0
v_cancel_client_order 3308 3308 0 0.00 0 3569 11 0 0
v_cancel_customer_prepayment 1400 1400 0 0.00 0 6855 20 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 1 574 11 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 2088 17 0 0
v_cancel_supplier_invoice 810 800 10 1.23 0 638 21 0 10
v_cancel_supplier_order 758 758 0 0.00 0 1034 13 0 0
v_cancel_write_off 1950 1950 0 0.00 0 5441 28 0 0
v_max_id_clo_ord 5347 5347 0 0.00 0 1716 6 0 0
v_max_id_clo_res 970 970 0 0.00 0 2907 17 0 0
v_max_id_ord_sup 5336 5336 0 0.00 0 10752 11 0 0
v_max_non_paid_invoice 1421 1421 0 0.00 0 255 8 0 0
v_max_non_paid_realizn 3825 3825 0 0.00 0 4294 9 0 0
v_min_id_clo_ord 5347 5347 0 0.00 0 3411 10 0 0
v_min_id_clo_res 970 970 0 0.00 0 5354 20 0 0
v_min_id_ord_sup 5336 5336 0 0.00 0 3835 12 0 0
v_min_non_paid_invoice 5644 5644 0 0.00 0 10904 23 0 0
v_min_non_paid_realizn 6839 6839 0 0.00 1 10524 21 0 0
v_our_firm 10014 10014 0 0.00 0 55 5 0 0
v_reserve_write_off 19064 19064 0 0.00 0 7865 13 0 0
x_lock_depdocs_on_canc_invoice 10112 10112 0 0.00 0 24266 158 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 25014 516 0 0
x_make_qstorno_1000_1200 5347 5347 0 0.00 2 29208 1186 0 0
x_make_qstorno_1200_2000 5339 5339 0 0.00 4 94139 3107 0 0
x_make_qstorno_2100_3300 446067 446067 0 0.00 0 113241 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 10:39:11 to 12-07-2022 10:39:15.

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:23 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 36455 8192000000 9743053616 10045353984 811566832 9.013 100 87 174 0 840086416 7741104 20778480
08:10:07 17593 8192000000 9777193296 10084929536 849957712 9.400 100 90 180 0 883398176 12584800 20855664
08:11:27 19936 8192000000 9804469488 10128519168 875817856 9.658 100 85 170 0 915757008 20504896 19434256
08:12:50 9022 8192000000 9884126864 10213601280 913982336 10.037 100 90 178 0 992854688 59854304 19018048
08:14:01 5350 8192000000 9932628304 10277588992 954760384 10.438 100 87 172 0 1044117008 70786016 18570608
08:15:09 2968 8192000000 9959022944 10313957376 994049280 10.821 100 82 164 0 1078918496 68117600 16751616
08:16:15 974 8192000000 9972713008 10330755072 1021009696 11.082 100 85 166 0 1086744096 50121328 15613072
08:17:19 968 8192000000 10022796304 10396483584 1037972096 11.245 100 89 170 1 1140131680 84747456 17412128
08:18:22 1169 8192000000 10015977488 10390347776 1054276112 11.402 100 90 167 2 1143854208 73527104 16050992
08:19:24 1097 8192000000 10029911680 10405228544 1064559344 11.500 100 96 186 1 1152256624 69412736 18284544
08:20:30 1371 8192000000 10033190976 10412544000 1068183088 11.535 100 93 182 2 1156186336 69137968 18865280
08:21:34 1040 8192000000 10059112864 10437844992 1073756304 11.588 100 95 180 1 1185209856 93550496 17903056
08:22:37 1714 8192000000 10041231056 10428977152 1080276736 11.650 100 65 126 0 1162451216 69244528 12929952
08:23:40 1827 8192000000 10060312784 10445135872 1088163264 11.725 100 89 173 2 1177909504 71318784 18427456
08:24:42 1137 8192000000 10052264048 10445705216 1096098400 11.801 100 85 163 2 1180147104 66377872 17670832
08:25:46 1051 8192000000 10066109376 10460692480 1098985920 11.828 100 76 150 1 1190315088 75689008 15640160
08:26:48 1130 8192000000 10053136880 10439278592 1101877072 11.855 100 99 180 1 1175402256 53848416 19676768
08:27:51 1251 8192000000 10068140304 10456662016 1102533152 11.862 100 99 192 3 1195979408 73351760 20094496
08:28:53 1433 8192000000 10083074976 10479726592 1106016160 11.895 100 93 178 1 1200434912 76113728 18305024
08:29:56 1127 8192000000 10061702816 10452328448 1103682752 11.873 100 89 169 5 1183141616 60836608 18622256
08:30:58 1543 8192000000 10052406192 10443767808 1087556320 11.719 100 91 172 4 1172979536 67299536 18123680
08:32:01 1056 8192000000 10045877984 10430812160 1083616992 11.682 100 93 178 2 1171299696 68583440 19099264
08:33:03 1172 8192000000 10045507712 10425221120 1070336800 11.555 100 99 189 1 1166454192 76577056 19540336
08:34:05 1173 8192000000 10022993840 10402443264 1058197888 11.439 100 90 175 0 1147773840 71655312 17920640
08:35:08 2467 8192000000 9992959216 10368479232 1032494704 11.192 99 76 140 3 1113408624 67042080 13871840
08:36:11 2532 8192000000 9968072112 10338385920 1015166064 11.025 98 71 131 1 1085179136 56287584 13725488
08:37:14 9184 8192000000 9982841360 10359181312 1057391136 11.432 100 84 135 5 1107311136 35979056 13940944
08:38:35 10219 8192000000 10019154928 10402807808 1080364304 11.651 100 65 123 3 1140996656 48284384 12347968
08:39:52 24042 8192000000 10057908496 10449006592 1085333888 11.698 100 61 120 1 1177110192 79853376 11922928
08:41:21 12045 8192000000 10089113424 10480545792 1098464912 11.823 100 67 134 0 1202065200 89980480 13619808
08:42:40 987 8192000000 10108164544 10498727936 1111811968 11.950 100 94 183 1 1230901232 98871760 20217504
08:43:46 3829 8192000000 10144506240 10539491328 1123308416 12.058 100 78 148 3 1254902352 115863376 15730560
08:44:54 1997 8192000000 10167497824 10568306688 1133582976 12.155 100 75 142 2 1281778480 133892736 14302768
08:46:01 2394 8192000000 10202630208 10607525888 1143930384 12.252 100 81 157 0 1306003744 145161392 16911968
08:47:11 935 8192000000 10209451696 10609958912 1150305104 12.312 100 94 175 1 1326113664 154416880 21391680
08:48:17 1701 8192000000 10256256784 10664538112 1157540064 12.380 100 93 177 1 1369639696 192670608 19429024
08:49:21 1279 8192000000 10283388496 10682355712 1163736448 12.438 100 95 185 2 1392196352 206875424 21584480
08:50:26 1632 8192000000 10303217216 10729709568 1177724976 12.569 100 94 184 1 1416832064 219725008 19382080
08:51:31 919 8192000000 10335247584 10738466816 1185934192 12.646 100 96 186 0 1446443824 239558864 20950768
08:52:36 1214 8192000000 10386511120 10794991616 1188026048 12.665 100 97 190 1 1497422912 287783136 21613728
08:53:41 2398 8192000000 10416092768 10831040512 1188332080 12.668 100 84 166 0 1526693408 319099344 19261984
08:54:46 1259 8192000000 10430399504 10861518848 1195075008 12.731 100 95 191 0 1534810960 317395520 22340432
08:55:52 976 8192000000 10466804192 10882908160 1201965040 12.795 100 96 187 1 1564129520 341354928 20809552
08:56:59 1088 8192000000 10464456144 10910785536 1201546352 12.791 100 97 184 2 1572557024 348748640 22262032
08:58:04 1400 8192000000 10461721264 10885910528 1180755712 12.597 99 95 179 1 1562974256 360811552 21406992
08:59:09 1407 8192000000 10481469760 10918965248 1185588992 12.642 100 91 166 2 1601254064 395354112 20310960
09:00:16 2037 8192000000 10512162720 10953105408 1195710608 12.736 100 82 165 0 1620459664 405116320 19632736
09:01:20 3535 8192000000 10474787904 10975576064 1196825504 12.747 100 63 126 0 1582474496 369424944 16224048
09:02:46 3784 8192000000 10499798336 10948259840 1198880432 12.766 100 76 146 2 1606149216 387785424 19483360
09:04:00 8154 8192000000 10501765232 10942771200 1193187440 12.713 100 48 96 0 1610992928 404699120 13106368
09:05:12 4548 8192000000 10490923488 10945236992 1183146816 12.620 100 51 102 1 1591881392 395786656 12947920
09:06:44 17871 8192000000 10423125552 10880978944 1164447536 12.445 99 42 84 0 1519077536 343711696 10918304
09:08:12 44226 8192000000 10327239456 10817748992 1160537152 12.408 99 26 52 0 1453212224 286603232 6071840
09:10:00 8848 8192000000 10259464176 10765119488 1144293568 12.256 99 48 99 0 1357667024 201337792 12035664
09:11:13 864 8192000000 10217366784 10687885312 1147281840 12.284 100 96 187 1 1314981904 146111360 21588704
09:12:15 3617 8192000000 10170265344 10630410240 1145191632 12.264 99 47 90 1 1270164832 115205744 9767456
09:13:21 55611 8192000000 10159934192 10610122752 1156383504 12.369 100 44 81 1 1262055568 95665088 10006976
09:15:22 52387 8192000000 10130007952 10578849792 1091422912 11.756 95 44 87 2 1200191872 99400560 9368400
09:17:23 52403 8192000000 10131154960 10585182208 1141434816 12.229 100 21 41 1 1235832800 89179296 5218688
09:19:23 80243 8192000000 10118450528 10632880128 1149078080 12.301 100 28 56 0 1225946016 70746592 6121344
09:21:55 16637 8192000000 10103385392 10546442240 1144560000 12.258 100 42 78 2 1195485328 42303920 8621408
09:23:16 93091 8192000000 10113878048 10596913152 1121285872 12.039 99 23 46 0 1220186224 93910128 4990224
09:25:51 70836 8192000000 10092359792 10539163648 1090430608 11.747 96 25 45 0 1168872640 73850224 4591808
09:29:20 42041 8192000000 10100419936 10555006976 1125043312 12.075 100 35 63 0 1180846000 48380640 7422048
09:31:06 53669 8192000000 10070082672 10558484480 1113753952 11.968 100 8 16 1 1182040752 66728048 1558752
09:33:24 37690 8192000000 10108828736 10537992192 1100792384 11.845 97 39 78 0 1178642384 69316576 8533424
09:35:03 11581 8192000000 10080950736 10516099072 1125266720 12.077 100 50 99 0 1193139808 56554384 11318704
09:36:18 117175 8192000000 10073368928 10538266624 1097626848 11.815 97 13 26 0 1150790560 50367488 2796224
09:40:03 74520 8192000000 10074779808 10524897280 1128382384 12.106 99 20 40 0 1164968512 32617072 3969056
09:42:18 56410 8192000000 10058944400 10508431360 1101791984 11.855 97 27 54 0 1136183632 28625568 5766080
09:44:21 77783 8192000000 10064465520 10537598976 1122256480 12.048 99 10 20 0 1163567248 39146576 2164192
09:46:42 45979 8192000000 10061837584 10518880256 1114993744 11.980 98 18 36 0 1147543632 29010208 3539680
09:48:28 19849 8192000000 10106995360 10542702592 1140892160 12.224 100 39 76 0 1197602848 48828528 7882160
09:50:00 28682 8192000000 10097453168 10557059072 1119751360 12.025 98 32 60 1 1197057312 70721856 6584096
09:51:46 37407 8192000000 10144882704 10591473664 1121448384 12.041 98 23 46 0 1217144032 90459200 5236448
09:53:24 23173 8192000000 10067223584 10492063744 1084457008 11.690 96 32 64 0 1118137696 27020912 6659776
09:54:48 63774 8192000000 10149253952 10576998400 1099838224 11.836 97 33 64 0 1204128416 97570064 6720128
09:57:02 55869 8192000000 10113597712 10567761920 1111577696 11.947 99 31 62 0 1184859408 67182560 6099152
09:59:06 83489 8192000000 10087551696 10531995648 1122126272 12.047 99 42 82 0 1198423712 67018448 9278992
10:01:38 60641 8192000000 10037945840 10486247424 1083116592 11.677 96 26 50 0 1131733168 43544576 5072000
10:03:39 45847 8192000000 10098677280 10543292416 1131262576 12.133 100 16 32 1 1199126592 64410192 3453824
10:06:16 62984 8192000000 10149154944 10647031808 1145344800 12.266 100 30 61 0 1267577776 115617056 6615920
10:08:20 68274 8192000000 10055637392 10519044096 1092142160 11.763 96 23 44 0 1146161536 49263472 4755904
10:10:30 52971 8192000000 10061794592 10493702144 1114550256 11.975 99 24 48 0 1157841136 38181824 5109056
10:12:23 44570 8192000000 10106644784 10544525312 1129796928 12.119 100 32 62 1 1197323216 61348480 6177808
10:14:25 103763 8192000000 10065641328 10517024768 1075427456 11.604 95 19 38 0 1124647904 45420288 3800160
10:17:10 40100 8192000000 10163909984 10600779776 1131819504 12.139 100 36 72 0 1242830912 103965408 7046000
10:18:54 74426 8192000000 10139320384 10586288128 1126969808 12.093 99 12 22 0 1273557872 143641584 2946480
10:21:13 13247 8192000000 10116573584 10543493120 1132946608 12.149 100 58 116 0 1185527856 41325488 11255760
10:22:28 96385 8192000000 10109344128 10553499648 1120529696 12.032 98 43 85 0 1194156144 65162352 8464096
10:25:05 37995 8192000000 10065088256 10545008640 1137039584 12.188 100 9 18 0 1199319312 60379488 1900240
10:26:58 75854 8192000000 10107033680 10562514944 1102878928 11.865 96 12 24 0 1170420576 65075504 2466144
10:29:14 63828 8192000000 10138440048 10616500224 1127898512 12.102 98 14 24 0 1241692848 110865968 2928368
10:31:19 36769 8192000000 10202784848 10637099008 1154091104 12.348 100 36 72 0 1295860208 133629696 8139408
10:32:56 18441 8192000000 10196292000 10630320128 1116874816 11.997 97 34 66 0 1266428944 141541600 8012528
10:34:21 42198 8192000000 10198584224 10644238336 1127199664 12.095 99 29 58 2 1290061504 156185472 6676368
10:36:04 63070 8192000000 10205353856 10673819648 1104910352 11.884 96 26 52 0 1275574272 164630848 6033072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 12-07-2022 10:39:17 to 12-07-2022 10:39:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 97 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-07-2022 10:39:34 to 12-07-2022 10:39:36.

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          204387
MON$OLDEST_ACTIVE               214124
MON$OLDEST_SNAPSHOT             214124
MON$NEXT_TRANSACTION            214124
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                       2068256
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:79: 
:80: mscs-test-161.budzdorov.ru    Tue Jul 12 10:37:22 2022
:81:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:82: 
:83: 
:84: mscs-test-161.budzdorov.ru    Tue Jul 12 10:37:27 2022
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Tue Jul 12 10:37:29 2022
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
:91: 
:92: mscs-test-161.budzdorov.ru    Tue Jul 12 10:37:29 2022
:93:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:94: 
:95: 
:96: mscs-test-161.budzdorov.ru    Tue Jul 12 10:37:29 2022
:97:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:98: 
--- end of diff output ---

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

12.07.22 10:39:37. 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-07-12 10:39:40 No severe PSQL-related problems occured
12.07.22 10:39:41. End of report.