Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.10.2022 04: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 15101860 kB
MemAvailable 30545040 kB
Buffers 20 kB
Cached 16576244 kB
SwapCached 2036 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-10-05 04:37:21
DTS_END                         2022-10-05 04:37:21.1180
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/50740
TRN_ID                          211804
ATT_ID                          606
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: 8405.30 33217 1008636
customer order: creation 155.47 493 18656
customer order: refuse 13.36 459 1603
order to supplier: creation 41.96 1360 5035
order to supplier: removal 3.13 174969 375
invoice (draft): creation 41.73 3452 5007
invoice (draft): removal 45.89 1077 5507
invoice accept: apply 39.43 27133 4732
invoice accept: cancel 41.73 1363 5007
customer reserve: creation 3639.60 258 436752
customer reserve: removal 4109.43 104 493131
realization accept: apply 76.21 290 9145
realization accept: cancel 76.78 183 9213
payment from customer: creation 56.00 183 6720
payment from customer: removal 5.18 155 622
payment to supplier: creation 44.63 196 5355
payment to supplier: removal 3.38 158 405
service: total inventory turnovers 3.40 5081 408
service: total monetary turnovers 7.09 478 851
service: aggregate perf. data 0.90 16501 108
service: refresh index statistics 0.03 430437 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7641
TEST_TIME 2 8015
TEST_TIME 3 6596
TEST_TIME 4 6771
TEST_TIME 5 6654
TEST_TIME 6 6264
TEST_TIME 7 6323
TEST_TIME 8 6347
TEST_TIME 9 6128
TEST_TIME 10 6070
TEST_TIME 11 6368
TEST_TIME 12 6425
TEST_TIME 13 6379
TEST_TIME 14 6546
TEST_TIME 15 6773
TEST_TIME 16 6868
TEST_TIME 17 7001
TEST_TIME 18 7057
TEST_TIME 19 7347
TEST_TIME 20 7396
TEST_TIME 21 7410
TEST_TIME 22 7704
TEST_TIME 23 8161
TEST_TIME 24 8561
TEST_TIME 25 8834
TEST_TIME 26 8700
TEST_TIME 27 8976
TEST_TIME 28 8897
TEST_TIME 29 9050
TEST_TIME 30 9426
TEST_TIME 31 9399
TEST_TIME 32 9737
TEST_TIME 33 9777
TEST_TIME 34 9889
TEST_TIME 35 10165
TEST_TIME 36 10536
TEST_TIME 37 10491
TEST_TIME 38 10258
TEST_TIME 39 10206
TEST_TIME 40 10257
TEST_TIME 41 10124
TEST_TIME 42 10208
TEST_TIME 43 10100
TEST_TIME 44 10000
TEST_TIME 45 9878
TEST_TIME 46 9789
TEST_TIME 47 9643
TEST_TIME 48 9506
TEST_TIME 49 9666
TEST_TIME 50 9645
TEST_TIME 51 9521
TEST_TIME 52 9481
TEST_TIME 53 9345
TEST_TIME 54 9304
TEST_TIME 55 9230
TEST_TIME 56 9146
TEST_TIME 57 9234
TEST_TIME 58 9156
TEST_TIME 59 9048
TEST_TIME 60 9025
TEST_TIME 61 8890
TEST_TIME 62 9021
TEST_TIME 63 9051
TEST_TIME 64 9018
TEST_TIME 65 8950
TEST_TIME 66 8882
TEST_TIME 67 8835
TEST_TIME 68 8773
TEST_TIME 69 8688
TEST_TIME 70 8729
TEST_TIME 71 8702
TEST_TIME 72 8665
TEST_TIME 73 8616
TEST_TIME 74 8642
TEST_TIME 75 8644
TEST_TIME 76 8624
TEST_TIME 77 8660
TEST_TIME 78 8650
TEST_TIME 79 8599
TEST_TIME 80 8645
TEST_TIME 81 8629
TEST_TIME 82 8705
TEST_TIME 83 8679
TEST_TIME 84 8769
TEST_TIME 85 8794
TEST_TIME 86 8832
TEST_TIME 87 8800
TEST_TIME 88 8705
TEST_TIME 89 8677
TEST_TIME 90 8640
TEST_TIME 91 8649
TEST_TIME 92 8610
TEST_TIME 93 8598
TEST_TIME 94 8569
TEST_TIME 95 8539
TEST_TIME 96 8491
TEST_TIME 97 8525
TEST_TIME 98 8547
TEST_TIME 99 8530
TEST_TIME 100 8517
TEST_TIME 101 8523
TEST_TIME 102 8543
TEST_TIME 103 8576
TEST_TIME 104 8594
TEST_TIME 105 8620
TEST_TIME 106 8715
TEST_TIME 107 8683
TEST_TIME 108 8663
TEST_TIME 109 8677
TEST_TIME 110 8729
TEST_TIME 111 8676
TEST_TIME 112 8633
TEST_TIME 113 8590
TEST_TIME 114 8590
TEST_TIME 115 8554
TEST_TIME 116 8525
TEST_TIME 117 8487
TEST_TIME 118 8437
TEST_TIME 119 8493
TEST_TIME 120 8432

Performance per minute, chart


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

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 1409327 1409327 0 0.00 0 19435 13 0 0
doc_list_biud 1409324 1409324 0 0.00 0 39986 43 0 0
sp_add_invoice_to_stock 4814 4732 82 1.70 23 185795 27134 0 82
sp_cancel_adding_invoice 5007 5007 0 0.00 6 85452 1363 0 0
sp_cancel_client_order 1603 1603 0 0.00 6 10571 460 0 0
sp_cancel_customer_reserve 493131 493131 0 0.00 1 39988 104 0 0
sp_cancel_pay_from_customer 622 622 0 0.00 4 6515 156 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 5861 158 0 0
sp_cancel_supplier_invoice 5508 5507 1 0.01 3 17718 1078 0 1
sp_cancel_supplier_order 375 375 0 0.00 9 1293925 174969 0 0
sp_cancel_write_off 9213 9213 0 0.00 2 11117 183 0 0
sp_client_order 18656 18656 0 0.00 5 26985 493 0 0
sp_customer_reserve 436752 436752 0 0.00 0 39842 259 0 0
sp_fill_shopping_cart 28691 28691 0 0.00 1 25685 91 0 0
sp_fill_shopping_cart_clo_res 436717 436717 0 0.00 0 17574 20 0 0
sp_get_clo_for_invoice 4691 4691 0 0.00 0 42994 440 0 0
sp_kill_cost_storno 15247 15247 0 0.00 0 13282 92 0 0
sp_kill_qstorno_ret_qs2qd 1001357 1001357 0 0.00 0 39947 46 0 0
sp_kill_qty_storno 528671 528671 0 0.00 0 39985 106 0 0
sp_lock_selected_doc 19143 19143 0 0.00 0 7260 8 0 0
sp_make_cost_storno 25911 25911 0 0.00 0 19420 82 0 0
sp_multiply_rows_for_pdistr 25910 25910 0 0.00 0 8298 39 0 0
sp_multiply_rows_for_qdistr 18656 18656 0 0.00 0 15593 177 0 0
sp_pay_from_customer 6720 6720 0 0.00 5 14864 184 0 0
sp_pay_to_supplier 5355 5355 0 0.00 6 12797 197 0 0
sp_payment_common 12075 12075 0 0.00 2 8929 45 0 0
sp_qd_handle_on_cancel_clo 1603 1603 0 0.00 0 10529 378 0 0
sp_qd_handle_on_invoice_upd_sts 9697 9697 0 0.00 0 14135 558 0 0
sp_qd_handle_on_reserve_upd_sts 9145 9145 0 0.00 0 13167 70 0 0
sp_reserve_write_off 9145 9145 0 0.00 8 13334 291 0 0
sp_supplier_invoice 5007 5007 0 0.00 10 72710 3452 0 0
sp_supplier_order 5035 5035 0 0.00 8 56511 1361 0 0
srv_aggregate_perf_data 108 108 0 0.00 2273 188232 16502 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 725 140238 36074 0 0
srv_find_qd_qs_mism 962458 962458 0 0.00 0 13034 10 0 0
srv_make_invnt_saldo 408 408 0 0.00 88 98841 5081 0 0
srv_make_money_saldo 851 851 0 0.00 1 13403 479 0 0
srv_recalc_idx_stat 4 4 0 0.00 323861 539861 430437 0 0
t$perf-abend:sp_add_invoice_to_stock 82 82 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4735 4735 0 0.00 1 17002 981 0 0
t$perf-norm:sp_cancel_adding_invoice 361 361 0 0.00 2 7361 453 0 0
t$perf-norm:sp_cancel_client_order 1603 1603 0 0.00 0 4615 25 0 0
t$perf-norm:sp_cancel_customer_reserve 609 609 0 0.00 0 961 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 622 622 0 0.00 0 999 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 649 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 8905 65 0 0
t$perf-norm:sp_cancel_supplier_order 375 375 0 0.00 0 128547 8684 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 11704 39 0 0
t$perf-norm:sp_client_order 18657 18657 0 0.00 0 6646 37 0 0
t$perf-norm:sp_customer_reserve 905 905 0 0.00 0 996 14 0 0
t$perf-norm:sp_pay_from_customer 6720 6720 0 0.00 0 6002 19 0 0
t$perf-norm:sp_pay_to_supplier 5355 5355 0 0.00 0 9362 27 0 0
t$perf-norm:sp_reserve_write_off 9145 9145 0 0.00 0 11728 32 0 0
t$perf-norm:sp_supplier_invoice 5007 5007 0 0.00 0 6054 32 0 0
t$perf-norm:sp_supplier_order 5035 5035 0 0.00 0 1619 30 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 450 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 186 8 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 1744 18 0 0
t$perf-norm:srv_make_money_saldo 851 851 0 0.00 0 804 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 167 77 0 0
v_add_invoice_to_stock 9534 9370 164 1.72 0 11366 27 0 164
v_all_customers 34838 34838 0 0.00 0 111 4 0 0
v_all_suppliers 27902 27902 0 0.00 0 105 4 0 0
v_all_wares 37310 37310 0 0.00 0 118 4 0 0
v_cancel_adding_invoice 722 722 0 0.00 0 4290 26 0 0
v_cancel_client_order 3206 3206 0 0.00 0 1467 9 0 0
v_cancel_customer_prepayment 1244 1244 0 0.00 0 1357 12 0 0
v_cancel_customer_reserve 1218 1218 0 0.00 1 2004 12 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 1627 13 0 0
v_cancel_supplier_invoice 764 762 2 0.26 0 4776 30 0 2
v_cancel_supplier_order 750 750 0 0.00 0 5987 24 0 0
v_cancel_write_off 1910 1910 0 0.00 0 3422 24 0 0
v_max_id_clo_ord 5033 5033 0 0.00 0 1967 5 0 0
v_max_id_clo_res 905 905 0 0.00 0 1676 11 0 0
v_max_id_ord_sup 5002 5002 0 0.00 0 7703 9 0 0
v_max_non_paid_invoice 1439 1439 0 0.00 0 404 8 0 0
v_max_non_paid_realizn 3299 3299 0 0.00 0 363 7 0 0
v_min_id_clo_ord 5033 5033 0 0.00 0 3548 10 0 0
v_min_id_clo_res 905 905 0 0.00 0 6822 27 0 0
v_min_id_ord_sup 5002 5002 0 0.00 0 3349 11 0 0
v_min_non_paid_invoice 5355 5355 0 0.00 0 3951 16 0 0
v_min_non_paid_realizn 6720 6720 0 0.00 0 7585 17 0 0
v_our_firm 9314 9314 0 0.00 0 112 5 0 0
v_reserve_write_off 18288 18288 0 0.00 0 11191 11 0 0
x_lock_depdocs_on_canc_invoice 10133 10133 0 0.00 0 16458 152 0 0
x_lock_depdocs_on_canc_sup_ord 375 375 0 0.00 0 5232 418 0 0
x_make_qstorno_1000_1200 5035 5035 0 0.00 3 56497 1190 0 0
x_make_qstorno_1200_2000 5007 5007 0 0.00 4 72084 3182 0 0
x_make_qstorno_2100_3300 436751 436751 0 0.00 0 36805 226 0 0

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

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
02:07:25 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:25 26404 8192000000 9731295120 10030477312 803239776 8.929 100 87 174 0 831441680 7333232 20868672
02:10:02 22129 8192000000 9768671392 10078900224 841490880 9.315 100 91 182 0 877282864 14075776 21716208
02:11:27 12001 8192000000 9794437568 10113007616 867332528 9.573 100 82 164 0 906326144 20300160 18693456
02:12:47 6040 8192000000 9861543456 10195664896 912210992 10.019 100 85 166 0 984812304 54606800 17994512
02:13:57 3461 8192000000 9924322384 10264543232 941433088 10.307 100 94 182 0 1029763968 69037152 19293728
02:15:03 2355 8192000000 9965311968 10311409664 977486192 10.660 100 98 192 0 1081716640 84289936 19940512
02:16:08 2874 8192000000 9990592512 10348568576 1000657408 10.885 100 89 171 1 1112435632 94372512 17405712
02:17:13 1107 8192000000 10009962320 10373181440 1028866880 11.158 100 92 175 1 1127951488 81732720 17351888
02:18:16 1037 8192000000 10034890464 10407890944 1046654752 11.329 100 95 182 1 1155353328 90876576 17822000
02:19:19 1176 8192000000 10027514736 10406494208 1062359504 11.479 100 91 178 0 1153246304 71391488 19495312
02:20:21 7759 8192000000 10017820624 10399453184 1066312592 11.517 100 78 141 0 1144357568 65141136 12903840
02:21:30 1700 8192000000 10048623552 10431606784 1073700784 11.587 100 77 142 2 1167585632 80164208 13720640
02:22:34 4352 8192000000 10043867824 10434502656 1083148400 11.677 100 76 144 1 1168892048 71210464 14533184
02:23:41 1249 8192000000 10060987440 10445307904 1091967856 11.761 100 96 178 2 1179286064 70064032 17254176
02:24:44 1137 8192000000 10074721440 10465239040 1096112160 11.801 100 96 187 1 1195911328 81287568 18511600
02:25:45 1185 8192000000 10057306288 10447695872 1098668240 11.825 100 85 162 0 1180804816 65740544 16396032
02:26:48 1859 8192000000 10067486688 10461569024 1099463472 11.833 100 74 140 1 1191211696 77457088 14291136
02:27:52 1741 8192000000 10051288096 10446569472 1093970832 11.780 99 78 153 1 1170914608 61211616 15732160
02:28:54 975 8192000000 10054941488 10442809344 1090158416 11.744 100 87 168 3 1177823520 70743856 16921248
02:29:56 987 8192000000 10050169104 10437804032 1089099664 11.734 100 94 175 2 1174157296 66700864 18356768
02:30:58 1030 8192000000 10040603520 10427904000 1088111520 11.725 100 90 179 0 1164118448 57717472 18289456
02:32:01 1274 8192000000 10031020192 10412703744 1065361696 11.508 100 97 184 4 1157274992 72680752 19232544
02:33:03 1292 8192000000 10008273776 10382684160 1044611216 11.309 100 97 184 0 1131689968 67250544 19828208
02:34:05 1074 8192000000 9985823760 10353418240 1021742384 11.089 100 92 174 0 1115034352 74338384 18953584
02:35:10 2820 8192000000 10017681360 10397274112 1045606176 11.319 100 82 159 2 1139347840 77977616 15764048
02:36:14 4224 8192000000 9992783856 10368077824 1031646752 11.184 100 70 137 0 1111750320 65988464 14115104
02:37:20 3729 8192000000 9998919488 10387034112 1047254272 11.334 100 76 145 2 1127366304 64164128 15947904
02:38:35 9226 8192000000 10017562768 10401091584 1054738144 11.406 100 72 135 1 1133322784 65399072 13185568
02:39:51 882 8192000000 10040093952 10421800960 1073332768 11.584 100 90 168 3 1159505856 69526160 16646928
02:40:58 1466 8192000000 10084253984 10467790848 1086913296 11.713 100 95 184 0 1206454480 99598656 19942528
02:42:02 1338 8192000000 10097252784 10490851328 1103818304 11.874 100 84 167 0 1214314432 92628352 17867776
02:43:08 19399 8192000000 10150068880 10545233920 1106995152 11.904 99 59 118 0 1251065888 130706400 13364336
02:44:39 17691 8192000000 10179673088 10580660224 1134457312 12.163 100 65 124 0 1293181968 145258928 13465728
02:46:05 1007 8192000000 10215829280 10615861248 1152085984 12.329 100 78 152 0 1333278480 164073648 17118848
02:47:13 4394 8192000000 10261055584 10657902592 1151106480 12.320 100 79 156 0 1372290880 202925104 18259296
02:48:20 777 8192000000 10289446400 10704420864 1157916624 12.384 100 90 169 1 1401885520 225657296 18311600
02:49:25 4231 8192000000 10314655296 10722111488 1179268080 12.583 100 73 146 2 1438298688 242998656 16031952
02:50:34 2342 8192000000 10350580800 10751549440 1182819616 12.616 100 89 168 0 1459252400 257539184 18893600
02:51:41 1290 8192000000 10384008656 10807943168 1191676688 12.699 100 92 180 1 1491445648 280070720 19698240
02:52:46 713 8192000000 10405492800 10816634880 1190201376 12.685 100 89 172 0 1512814768 303147792 19465600
02:53:48 1238 8192000000 10437277920 10844532736 1190082336 12.684 100 96 183 3 1543269424 332676416 20510672
02:54:54 1079 8192000000 10461675920 10884571136 1192216384 12.704 100 98 193 1 1569144112 354611696 22316032
02:56:02 2020 8192000000 10461517632 10908106752 1194728944 12.727 100 89 176 1 1569682720 353802480 21151296
02:57:11 722 8192000000 10472250128 10893856768 1188514768 12.670 100 99 195 0 1582450848 370085408 23850672
02:58:17 832 8192000000 10465000832 10933592064 1189371008 12.678 100 96 189 1 1569078336 355426448 24280880
02:59:21 1483 8192000000 10464170576 10911330304 1182603712 12.614 100 96 190 0 1569976720 363186976 24186032
03:00:26 859 8192000000 10446948048 10871820288 1176915056 12.561 100 98 191 0 1557610800 355677856 25017888
03:01:31 7037 8192000000 10422889904 10881650688 1180235744 12.592 100 58 114 0 1529038784 334079680 14723360
03:02:44 20558 8192000000 10389026976 10833317888 1172730720 12.522 100 36 70 0 1489103136 306884864 9487552
03:04:07 17468 8192000000 10403764848 10876387328 1149728768 12.307 98 46 92 2 1483232192 321565808 11937616
03:05:35 16788 8192000000 10406280992 10864734208 1163783728 12.439 100 33 66 0 1520954208 348376000 8794480
03:07:12 18339 8192000000 10327085888 10801500160 1134467712 12.163 98 36 70 0 1447937680 305150528 8319440
03:08:34 23229 8192000000 10288368496 10791604224 1128820544 12.110 99 35 68 1 1401464672 264121424 8522704
03:10:18 9819 8192000000 10260175888 10716745728 1137625920 12.193 100 36 69 0 1390176800 244069008 8481872
03:11:40 10626 8192000000 10270697904 10701590528 1116881968 11.998 99 32 64 2 1359726528 235170704 7673856
03:13:09 20169 8192000000 10138242608 10606034944 1103468192 11.871 98 32 64 1 1272268288 161493264 7306832
03:14:30 45877 8192000000 10138416608 10566365184 1128253168 12.105 100 55 106 0 1216785008 77098448 11433392
03:16:30 16958 8192000000 10128050832 10565771264 1119175504 12.019 99 44 80 0 1243031296 115031792 8824000
03:17:48 38835 8192000000 10107447728 10554793984 1118323584 12.011 99 21 42 0 1195658432 72848736 4486112
03:19:33 42150 8192000000 10039412896 10480070656 1091165200 11.754 97 41 73 1 1118712848 20324864 7222784
03:21:28 55710 8192000000 10064209472 10506207232 1101062784 11.848 97 15 30 0 1144136336 40018208 3055344
03:23:30 57226 8192000000 10108251136 10546401280 1128777456 12.110 99 20 40 1 1193535904 61216016 3542432
03:26:40 124477 8192000000 10097746608 10612084736 1095401616 11.794 97 26 52 0 1165783120 65000592 5380912
03:29:46 79204 8192000000 10098423424 10571841536 1127982208 12.102 100 27 52 0 1194168816 60889696 5296912
03:32:48 47539 8192000000 10165387856 10602954752 1145889600 12.271 100 48 94 1 1237147776 81263744 9994432
03:34:42 77850 8192000000 10117937824 10615291904 1107255664 11.906 97 10 20 0 1184537264 75246864 2034736
03:37:50 109147 8192000000 10116760032 10672513024 1133295600 12.152 100 22 41 0 1274789888 136596864 4897424
03:40:44 68732 8192000000 10057530960 10519838720 1113756304 11.968 98 37 74 1 1159233552 38177040 7300208
03:43:00 50405 8192000000 10091947120 10531180544 1134299104 12.162 100 31 60 0 1195893472 55884256 5710112
03:44:51 76906 8192000000 10071271328 10543988736 1105670352 11.891 97 10 20 0 1167016928 58974976 2371600
03:47:09 69370 8192000000 10061088016 10532892672 1139614544 12.212 100 18 36 1 1192505840 48978544 3912752
03:49:25 114265 8192000000 10069597456 10572419072 1138324736 12.200 99 11 22 0 1174335824 33870960 2140128
03:52:21 83296 8192000000 10082479152 10549886976 1121436064 12.041 97 12 20 0 1164429680 41290944 1702672
03:54:44 19880 8192000000 10088079104 10526547968 1135085296 12.169 99 35 69 0 1171449600 29621760 6742544
03:56:10 28351 8192000000 10118825552 10553614336 1134035184 12.159 98 47 93 1 1198079904 54348736 9695984
03:57:40 40440 8192000000 10170399488 10646487040 1142753072 12.241 99 27 54 1 1260170176 112115488 5301616
03:59:22 51663 8192000000 10140252352 10583273472 1133523760 12.155 99 44 88 0 1235186672 92166368 9496544
04:02:02 67586 8192000000 10094322576 10543136768 1113443856 11.965 98 22 42 0 1180526752 63187664 3895232
04:04:13 14119 8192000000 10084441264 10528006144 1113856272 11.969 98 41 77 0 1156202560 32989376 9356912
04:05:29 95151 8192000000 10075143216 10508922880 1081521808 11.662 96 34 68 0 1139818432 51319088 6977536
04:08:05 18321 8192000000 10074564656 10523074560 1115846368 11.988 99 30 60 0 1162955808 41679216 5430224
04:09:25 93544 8192000000 10091399488 10566905856 1125112720 12.075 100 13 26 0 1182291040 54407808 2770512
04:12:00 87310 8192000000 10101654656 10583711744 1107794688 11.912 98 15 30 0 1195273264 83796288 3682288
04:14:37 40134 8192000000 10084870896 10529595392 1113615056 11.967 98 18 36 0 1171915536 54792560 3507920
04:16:27 27353 8192000000 10078611712 10504851456 1099602928 11.834 97 38 72 1 1143643168 35660864 8379376
04:17:55 35761 8192000000 10146608768 10589691904 1119075072 12.018 98 28 56 0 1207782656 82962784 5744800
04:19:31 55095 8192000000 10153448736 10618273792 1139308624 12.209 100 19 38 0 1237702832 94542816 3851392
04:21:29 58386 8192000000 10153611952 10615922688 1116408624 11.993 97 27 55 0 1214646784 92476448 5761712
04:23:27 29221 8192000000 10070292720 10548740096 1117925600 12.007 98 21 43 0 1183676192 61614912 4135680
04:24:58 13888 8192000000 10099605328 10530512896 1138168656 12.198 100 38 75 0 1199107856 53349072 7590128
04:26:13 30386 8192000000 10144890896 10572587008 1114171056 11.972 98 51 102 0 1205831296 81571168 10089072
04:27:53 140238 8192000000 10063581344 10509471744 1084713760 11.692 96 19 38 1 1126165040 37709120 3742160
04:31:39 47151 8192000000 10068000640 10533961728 1129536384 12.117 100 9 18 0 1179731040 48259712 1934944
04:33:26 43678 8192000000 10076187408 10527948800 1088239504 11.726 96 16 31 0 1153650448 61952208 3458736
04:35:10 72454 8192000000 10104722112 10584178688 1106485824 11.899 98 15 30 0 1196066192 86057568 3522800

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 82 v_add_invoice_to_stock 335544517
except 1 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-10-2022 04:39:29 to 05-10-2022 04:39:31.

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          208221
MON$OLDEST_ACTIVE               212039
MON$OLDEST_SNAPSHOT             212039
MON$NEXT_TRANSACTION            212039
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                       2085088
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             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2854 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2854 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2854 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2854 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 ---
:121: 
:122: mscs-test-161.budzdorov.ru    Wed Oct  5 04:37:29 2022
:123:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:124: 
--- end of diff output ---

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

05.10.22 04:39:33. 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-10-05 04:39:35 No severe PSQL-related problems occured
05.10.22 04:39:37. End of report.