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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14558356 kB
MemAvailable 30099360 kB
Buffers 0 kB
Cached 17134052 kB
SwapCached 3344 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-12-20 13:37:23
DTS_END                         2022-12-20 13:37:23.4770
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/40470
TRN_ID                          213155
ATT_ID                          643
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: 8496.09 26143 1019531
customer order: creation 164.21 531 19705
customer order: refuse 14.15 532 1698
order to supplier: creation 43.13 1520 5176
order to supplier: removal 3.01 197579 361
invoice (draft): creation 44.56 3633 5347
invoice (draft): removal 46.95 1195 5634
invoice accept: apply 40.44 27732 4853
invoice accept: cancel 41.93 1545 5032
customer reserve: creation 3697.75 262 443730
customer reserve: removal 4114.14 112 493697
realization accept: apply 79.60 324 9552
realization accept: cancel 82.08 188 9849
payment from customer: creation 56.71 201 6805
payment from customer: removal 5.42 162 650
payment to supplier: creation 46.58 208 5589
payment to supplier: removal 3.33 118 400
service: total inventory turnovers 3.72 4834 446
service: total monetary turnovers 7.53 419 903
service: aggregate perf. data 0.83 19266 99
service: refresh index statistics 0.04 262507 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2022 13:38:45 to 20-12-2022 13:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4616
TEST_TIME 2 4406
TEST_TIME 3 5482
TEST_TIME 4 5859
TEST_TIME 5 5595
TEST_TIME 6 5491
TEST_TIME 7 5674
TEST_TIME 8 5780
TEST_TIME 9 6407
TEST_TIME 10 6508
TEST_TIME 11 6750
TEST_TIME 12 6983
TEST_TIME 13 6980
TEST_TIME 14 7117
TEST_TIME 15 7212
TEST_TIME 16 7315
TEST_TIME 17 7426
TEST_TIME 18 7588
TEST_TIME 19 7788
TEST_TIME 20 7868
TEST_TIME 21 7811
TEST_TIME 22 7934
TEST_TIME 23 7837
TEST_TIME 24 8111
TEST_TIME 25 8223
TEST_TIME 26 8340
TEST_TIME 27 8362
TEST_TIME 28 8282
TEST_TIME 29 8526
TEST_TIME 30 8634
TEST_TIME 31 8939
TEST_TIME 32 9111
TEST_TIME 33 9194
TEST_TIME 34 9659
TEST_TIME 35 9520
TEST_TIME 36 9834
TEST_TIME 37 10028
TEST_TIME 38 10212
TEST_TIME 39 10276
TEST_TIME 40 10223
TEST_TIME 41 9976
TEST_TIME 42 9831
TEST_TIME 43 9793
TEST_TIME 44 9848
TEST_TIME 45 9986
TEST_TIME 46 9912
TEST_TIME 47 9755
TEST_TIME 48 9739
TEST_TIME 49 9684
TEST_TIME 50 9643
TEST_TIME 51 9609
TEST_TIME 52 9523
TEST_TIME 53 9534
TEST_TIME 54 9493
TEST_TIME 55 9425
TEST_TIME 56 9350
TEST_TIME 57 9310
TEST_TIME 58 9186
TEST_TIME 59 9131
TEST_TIME 60 9028
TEST_TIME 61 9042
TEST_TIME 62 9022
TEST_TIME 63 8936
TEST_TIME 64 8952
TEST_TIME 65 8849
TEST_TIME 66 8822
TEST_TIME 67 8893
TEST_TIME 68 8911
TEST_TIME 69 8866
TEST_TIME 70 8917
TEST_TIME 71 8969
TEST_TIME 72 9035
TEST_TIME 73 8985
TEST_TIME 74 8946
TEST_TIME 75 8937
TEST_TIME 76 9036
TEST_TIME 77 9022
TEST_TIME 78 8975
TEST_TIME 79 9011
TEST_TIME 80 8989
TEST_TIME 81 8940
TEST_TIME 82 8873
TEST_TIME 83 8909
TEST_TIME 84 8878
TEST_TIME 85 8875
TEST_TIME 86 8825
TEST_TIME 87 8772
TEST_TIME 88 8711
TEST_TIME 89 8647
TEST_TIME 90 8597
TEST_TIME 91 8624
TEST_TIME 92 8592
TEST_TIME 93 8589
TEST_TIME 94 8591
TEST_TIME 95 8578
TEST_TIME 96 8628
TEST_TIME 97 8639
TEST_TIME 98 8674
TEST_TIME 99 8685
TEST_TIME 100 8712
TEST_TIME 101 8833
TEST_TIME 102 8838
TEST_TIME 103 8817
TEST_TIME 104 8818
TEST_TIME 105 8858
TEST_TIME 106 8790
TEST_TIME 107 8763
TEST_TIME 108 8717
TEST_TIME 109 8749
TEST_TIME 110 8740
TEST_TIME 111 8699
TEST_TIME 112 8696
TEST_TIME 113 8661
TEST_TIME 114 8637
TEST_TIME 115 8603
TEST_TIME 116 8572
TEST_TIME 117 8564
TEST_TIME 118 8556
TEST_TIME 119 8571
TEST_TIME 120 8521

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2022 13:38:48 to 20-12-2022 13:38:52.

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 1428196 1428196 0 0.00 0 26485 15 0 0
doc_list_biud 1428195 1428195 0 0.00 0 60873 47 0 0
sp_add_invoice_to_stock 4979 4853 126 2.53 26 225328 27732 0 126
sp_cancel_adding_invoice 5032 5032 0 0.00 6 74592 1546 0 0
sp_cancel_client_order 1698 1698 0 0.00 6 34283 533 0 0
sp_cancel_customer_reserve 493697 493697 0 0.00 1 54417 113 0 0
sp_cancel_pay_from_customer 650 650 0 0.00 3 5760 163 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 4 2089 119 0 0
sp_cancel_supplier_invoice 5647 5634 13 0.23 3 60877 1195 0 13
sp_cancel_supplier_order 361 361 0 0.00 11 1442623 197580 0 0
sp_cancel_write_off 9849 9849 0 0.00 2 33442 188 0 0
sp_client_order 19705 19705 0 0.00 5 54596 531 0 0
sp_customer_reserve 443730 443730 0 0.00 0 210020 263 0 0
sp_fill_shopping_cart 30213 30213 0 0.00 1 16755 99 0 0
sp_fill_shopping_cart_clo_res 443699 443699 0 0.00 0 23001 20 0 0
sp_get_clo_for_invoice 4822 4822 0 0.00 0 46432 385 0 0
sp_kill_cost_storno 15931 15931 0 0.00 0 8914 87 0 0
sp_kill_qstorno_ret_qs2qd 1003238 1003238 0 0.00 0 60872 50 0 0
sp_kill_qty_storno 530645 530645 0 0.00 0 60873 115 0 0
sp_lock_selected_doc 19896 19896 0 0.00 0 6792 7 0 0
sp_make_cost_storno 26768 26768 0 0.00 0 26479 93 0 0
sp_multiply_rows_for_pdistr 26768 26768 0 0.00 0 12797 43 0 0
sp_multiply_rows_for_qdistr 19704 19704 0 0.00 0 42225 190 0 0
sp_pay_from_customer 6805 6805 0 0.00 5 17084 201 0 0
sp_pay_to_supplier 5589 5589 0 0.00 6 19272 208 0 0
sp_payment_common 12394 12394 0 0.00 2 9825 53 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 1 34172 444 0 0
sp_qd_handle_on_invoice_upd_sts 9854 9854 0 0.00 0 58745 637 0 0
sp_qd_handle_on_reserve_upd_sts 9552 9552 0 0.00 0 33602 85 0 0
sp_reserve_write_off 9552 9552 0 0.00 8 33698 324 0 0
sp_supplier_invoice 5347 5347 0 0.00 8 138201 3633 0 0
sp_supplier_order 5176 5176 0 0.00 6 75108 1521 0 0
srv_aggregate_perf_data 99 99 0 0.00 2418 211703 19266 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 795 221358 37299 0 0
srv_find_qd_qs_mism 972717 972717 0 0.00 0 25875 10 0 0
srv_make_invnt_saldo 446 446 0 0.00 37 91717 4835 0 0
srv_make_money_saldo 903 903 0 0.00 1 8789 420 0 0
srv_recalc_idx_stat 5 5 0 0.00 73474 620181 262507 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4854 4854 0 0.00 0 13206 1162 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 2 13241 657 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 3718 37 0 0
t$perf-norm:sp_cancel_customer_reserve 642 642 0 0.00 0 8079 44 0 0
t$perf-norm:sp_cancel_pay_from_customer 650 650 0 0.00 0 305 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 1507 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 4516 48 0 0
t$perf-norm:sp_cancel_supplier_order 361 361 0 0.00 0 130286 8841 0 0
t$perf-norm:sp_cancel_write_off 992 992 0 0.00 0 1146 24 0 0
t$perf-norm:sp_client_order 19705 19705 0 0.00 0 29017 47 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 1085 18 0 0
t$perf-norm:sp_pay_from_customer 6806 6806 0 0.00 0 15708 27 0 0
t$perf-norm:sp_pay_to_supplier 5589 5589 0 0.00 0 2313 22 0 0
t$perf-norm:sp_reserve_write_off 9552 9552 0 0.00 0 20109 30 0 0
t$perf-norm:sp_supplier_invoice 5347 5347 0 0.00 0 8060 37 0 0
t$perf-norm:sp_supplier_order 5176 5176 0 0.00 0 3685 36 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 605 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 38 4 0 0
t$perf-norm:srv_make_invnt_saldo 446 446 0 0.00 0 1328 30 0 0
t$perf-norm:srv_make_money_saldo 903 903 0 0.00 0 4334 20 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 9 148 84 0 0
v_add_invoice_to_stock 9892 9640 252 2.54 0 15781 30 0 252
v_all_customers 36294 36294 0 0.00 0 158 4 0 0
v_all_suppliers 29102 29102 0 0.00 0 158 5 0 0
v_all_wares 39396 39396 0 0.00 0 202 5 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 207 14 0 0
v_cancel_client_order 3396 3396 0 0.00 0 4133 15 0 0
v_cancel_customer_prepayment 1300 1300 0 0.00 0 2183 14 0 0
v_cancel_customer_reserve 1284 1284 0 0.00 1 2787 16 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 766 13 0 0
v_cancel_supplier_invoice 834 808 26 3.11 0 5097 31 0 26
v_cancel_supplier_order 722 722 0 0.00 0 3577 17 0 0
v_cancel_write_off 1984 1984 0 0.00 0 2773 30 0 0
v_max_id_clo_ord 5175 5175 0 0.00 0 3579 7 0 0
v_max_id_clo_res 995 995 0 0.00 0 6988 22 0 0
v_max_id_ord_sup 5340 5340 0 0.00 0 2770 8 0 0
v_max_non_paid_invoice 1553 1553 0 0.00 0 2363 11 0 0
v_max_non_paid_realizn 3477 3477 0 0.00 0 6796 10 0 0
v_min_id_clo_ord 5175 5175 0 0.00 0 14924 14 0 0
v_min_id_clo_res 995 995 0 0.00 0 11355 26 0 0
v_min_id_ord_sup 5340 5340 0 0.00 0 3724 8 0 0
v_min_non_paid_invoice 5589 5589 0 0.00 0 5563 18 0 0
v_min_non_paid_realizn 6805 6805 0 0.00 0 9652 21 0 0
v_our_firm 9758 9758 0 0.00 0 162 6 0 0
v_reserve_write_off 19104 19104 0 0.00 0 4765 14 0 0
x_lock_depdocs_on_canc_invoice 10262 10262 0 0.00 0 16539 160 0 0
x_lock_depdocs_on_canc_sup_ord 361 361 0 0.00 0 7686 406 0 0
x_make_qstorno_1000_1200 5176 5176 0 0.00 2 65716 1319 0 0
x_make_qstorno_1200_2000 5347 5347 0 0.00 4 138029 3365 0 0
x_make_qstorno_2100_3300 443730 443730 0 0.00 0 210019 228 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2022 13:38:53 to 20-12-2022 13:38:57.

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:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:28 33478 8192000000 9722557760 10021040128 800346240 8.900 100 84 168 0 828165392 7425552 20393600
11:10:07 23176 8192000000 9765788048 10073546752 837942432 9.279 100 94 188 0 872935440 12647760 22345248
11:11:35 14693 8192000000 9804045280 10120847360 864480336 9.545 100 88 174 0 908994976 23919280 20595360
11:12:57 6355 8192000000 9856968576 10188554240 916849984 10.065 100 84 167 0 967046896 32262848 17934064
11:14:06 5380 8192000000 9914039104 10261106688 960602032 10.495 100 79 157 0 1033234240 56108592 16523616
11:15:14 1878 8192000000 9982022208 10334838784 991846656 10.799 100 94 186 0 1099158432 88705296 18606480
11:16:18 1027 8192000000 10005416368 10365521920 1011844928 10.993 100 91 174 0 1120986208 92067712 17073568
11:17:21 1263 8192000000 10023832080 10390753280 1041370928 11.278 100 93 176 0 1144279648 85275072 17633648
11:18:25 904 8192000000 10020310528 10390736896 1054318000 11.402 100 99 191 1 1138885488 66065296 18502192
11:19:29 1499 8192000000 10060028288 10438152192 1062652240 11.482 100 94 178 0 1179860432 99809344 17398848
11:20:32 1848 8192000000 10041633168 10419290112 1071072672 11.562 100 95 186 2 1165823536 75474848 19276016
11:21:37 1192 8192000000 10048087808 10427428864 1075911376 11.608 100 96 176 0 1168762768 75206560 17644832
11:22:40 1122 8192000000 10052679856 10439008256 1079136480 11.639 100 96 182 3 1167628960 69407824 19084656
11:23:44 1135 8192000000 10035352336 10421850112 1085078848 11.696 100 84 159 1 1157912112 56556752 16276512
11:24:47 1188 8192000000 10053691536 10443853824 1091111840 11.753 100 95 183 0 1176849104 66575632 19161632
11:25:50 1207 8192000000 10085775872 10473570304 1099178672 11.830 100 94 172 0 1203803456 87260480 17364304
11:26:52 1219 8192000000 10072939120 10465107968 1101959600 11.856 100 88 173 1 1194900512 76479712 16461200
11:27:54 1082 8192000000 10076037616 10473631744 1104884800 11.884 100 89 175 1 1199072992 75996480 18191712
11:28:56 1082 8192000000 10085393328 10474745856 1107924560 11.913 100 95 178 1 1208166272 81416400 18825312
11:30:01 3464 8192000000 10035976672 10430246912 1085128672 11.696 100 67 132 1 1167405200 69385552 12890976
11:31:08 1366 8192000000 10043393728 10428473344 1091872704 11.760 100 92 169 2 1162984832 53953392 17158736
11:32:11 1095 8192000000 10051038640 10430353408 1064804080 11.502 100 96 185 0 1169793472 86438320 18551072
11:33:13 871 8192000000 10009318096 10387001344 1044158272 11.305 100 94 181 1 1129147424 67751344 17237808
11:34:14 940 8192000000 9981196192 10352041984 1030159888 11.170 100 93 174 3 1106588560 57325648 19103024
11:35:16 3035 8192000000 9983841536 10363977728 1035748496 11.224 99 85 161 3 1099931344 47316272 16866576
11:36:21 1091 8192000000 9984713392 10359971840 1036707760 11.233 100 93 178 1 1105354496 50335648 18311088
11:37:23 3146 8192000000 10007063312 10382008320 1040211488 11.267 100 85 141 4 1124153600 70547056 13395056
11:38:35 13451 8192000000 9991344416 10373652480 1054125584 11.400 100 67 127 0 1115874848 49191920 12557344
11:39:58 2089 8192000000 10051870080 10438164480 1076511600 11.614 100 77 142 0 1175450816 84480576 14458640
11:41:08 7426 8192000000 10064490128 10445053952 1085184960 11.697 100 84 157 2 1179575872 78144896 16246016
11:42:23 20021 8192000000 10099127600 10492551168 1099104576 11.829 100 66 130 0 1213311136 101088304 13118256
11:43:52 2021 8192000000 10127382848 10510594048 1098470992 11.823 99 90 167 2 1240127168 125554368 16101808
11:45:00 2608 8192000000 10163973968 10554892288 1116238656 11.991 100 73 137 0 1275853760 145015584 14599520
11:46:07 961 8192000000 10200168976 10604122112 1129210912 12.114 100 97 191 0 1315435120 165414000 20810208
11:47:14 2048 8192000000 10212509632 10607943680 1137809328 12.195 100 89 175 1 1326728864 170249616 18669920
11:48:23 1132 8192000000 10221156752 10624667648 1142263504 12.237 100 97 190 2 1332710528 167948432 22498592
11:49:28 1225 8192000000 10238078016 10647625728 1153616096 12.343 100 90 180 1 1353469680 180745824 19107760
11:50:34 946 8192000000 10255939856 10659147776 1160347280 12.407 100 93 181 0 1377038432 195296768 21394384
11:51:38 1227 8192000000 10305108272 10717007872 1165493376 12.455 100 82 160 0 1418357920 234302272 18562272
11:52:43 1858 8192000000 10300515664 10710859776 1168705008 12.485 100 89 171 3 1413351280 224079936 20566336
11:53:49 1932 8192000000 10315184112 10739527680 1166537888 12.464 100 82 158 0 1421502176 237049168 17915120
11:54:57 996 8192000000 10340192560 10748420096 1172724736 12.522 100 90 175 1 1446612896 254481680 19406480
11:56:00 1042 8192000000 10331753984 10754260992 1170927040 12.505 100 91 183 1 1440940400 248141280 21872080
11:57:06 867 8192000000 10371512976 10786938880 1190150304 12.685 100 98 186 0 1484080592 273152784 20777504
11:58:11 2916 8192000000 10396961504 10804994048 1181957872 12.608 100 90 175 0 1509461440 306401440 21102128
11:59:17 2810 8192000000 10436548176 10850414592 1185655200 12.643 100 95 186 2 1538420224 330511904 22253120
12:00:23 959 8192000000 10445830064 10870239232 1184660848 12.634 100 95 190 0 1552299664 345066976 22571840
12:01:26 917 8192000000 10450665680 10864451584 1182682464 12.615 99 97 191 4 1560534560 355073440 22778656
12:02:29 1220 8192000000 10452366960 10897305600 1176348336 12.556 99 92 174 1 1552842160 355664496 20829328
12:03:35 788 8192000000 10490239328 10924007424 1183065744 12.619 100 97 188 0 1598442576 392288416 23088416
12:04:38 11631 8192000000 10521238144 10964004864 1178373168 12.575 100 62 117 1 1620680272 427499728 14807376
12:05:56 4255 8192000000 10463019936 10938552320 1161771392 12.420 100 66 124 1 1576775664 399010960 15993312
12:07:08 5322 8192000000 10438480032 10917138432 1158670672 12.391 99 49 92 2 1541243040 370191392 12380976
12:08:18 1028 8192000000 10458858704 10914385920 1167084736 12.470 100 79 156 0 1560905424 373583104 20237584
12:09:29 7756 8192000000 10396727344 10824216576 1167914688 12.477 100 46 89 0 1500140608 321587792 10638128
12:11:09 14474 8192000000 10325919696 10831208448 1162050336 12.422 99 45 91 0 1446793984 273664112 11079536
12:12:24 14379 8192000000 10336770960 10777477120 1157085648 12.376 99 26 52 0 1420636144 257046880 6503616
12:13:40 19666 8192000000 10265242336 10790649856 1166917200 12.468 99 35 70 1 1386489328 211850144 7721984
12:15:01 41999 8192000000 10207821872 10669404160 1132322352 12.143 97 27 55 0 1294121888 155694944 6104592
12:16:54 140346 8192000000 10194747888 10628472832 1134723264 12.166 98 39 78 3 1276610928 132333328 9554336
12:20:35 10159 8192000000 10209926192 10658623488 1149597952 12.306 100 61 116 0 1301192960 139546832 12048176
12:21:48 55270 8192000000 10132861552 10612535296 1132127072 12.141 98 22 45 0 1239317360 102149776 5040512
12:23:47 68002 8192000000 10120779872 10580312064 1144814368 12.261 99 19 34 1 1218322704 69220736 4287600
12:25:56 65573 8192000000 10100817456 10547359744 1144261008 12.256 100 27 53 0 1192484896 42672208 5551680
12:28:10 221358 8192000000 10089888784 10575609856 1091731056 11.759 95 12 24 0 1148094752 53837216 2526480
12:32:52 86610 8192000000 10073524560 10563584000 1133709808 12.156 99 25 50 0 1172449408 32626128 6113472
12:35:33 53126 8192000000 10091301072 10563215360 1121058160 12.037 98 39 76 0 1166115184 37408704 7648320
12:37:51 7676 8192000000 10108703200 10548277248 1147498416 12.286 100 53 101 0 1207120576 48916336 10705824
12:39:00 69883 8192000000 10097087584 10558967808 1103225808 11.868 96 26 52 0 1179330496 70679616 5425072
12:41:10 81019 8192000000 10120326176 10595250176 1122427728 12.050 98 22 42 0 1201903472 74902832 4572912
12:44:04 50509 8192000000 10061871152 10509045760 1105046064 11.885 97 23 47 2 1187378640 77105456 5227120
12:45:56 62217 8192000000 10128206000 10596442112 1141667264 12.231 100 23 46 0 1218493456 72314016 4512176
12:47:59 91706 8192000000 10103608016 10587488256 1125515968 12.079 99 21 42 1 1203252496 73562416 4174112
12:51:00 13157 8192000000 10082593984 10507472896 1111776192 11.949 99 61 122 1 1160392832 36036016 12580624
12:52:17 40996 8192000000 10127745952 10587680768 1131528208 12.136 100 34 68 0 1262301088 123719456 7053424
12:54:01 63485 8192000000 10054433040 10473750528 1056428528 11.422 94 31 60 0 1093156400 30801328 5926544
12:56:06 5813 8192000000 10101464240 10520023040 1113396704 11.965 99 70 134 1 1197311968 70749024 13166240
12:57:18 67670 8192000000 10091850688 10567303168 1111227152 11.944 99 34 66 0 1187573792 69601872 6744768
12:59:39 109198 8192000000 10106565520 10600030208 1122841888 12.054 100 17 34 0 1217158992 90991120 3325984
13:02:32 66583 8192000000 10053089360 10492207104 1082622368 11.672 96 11 22 0 1117451760 32167632 2661760
13:04:40 49756 8192000000 10067108768 10518573056 1080552048 11.653 97 15 30 0 1130812432 46905792 3354592
13:06:35 59162 8192000000 10090007936 10538057728 1073256400 11.583 96 17 34 0 1152963360 76047776 3659184
13:08:35 77676 8192000000 10142376960 10594824192 1127928640 12.102 100 20 40 0 1234567536 102227696 4411200
13:11:10 42991 8192000000 10154069136 10593533952 1115282464 11.982 98 23 46 0 1247774320 127040096 5451760
13:12:54 26105 8192000000 10210927616 10686115840 1125084608 12.075 98 31 62 0 1300491456 168236752 7170096
13:14:25 35574 8192000000 10190701536 10643738624 1146960416 12.281 99 32 60 1 1314077584 160201200 6915968
13:16:02 64275 8192000000 10194576784 10662318080 1120724224 12.034 97 27 54 0 1272948240 146039536 6184480
13:18:42 73992 8192000000 10093777616 10550087680 1114870944 11.979 98 18 36 0 1179919856 61301280 3747632
13:21:01 28709 8192000000 10129647760 10550218752 1142379072 12.238 100 45 88 1 1227588320 75626912 9582336
13:22:33 147251 8192000000 10071726336 10510901248 1098073680 11.819 97 22 45 0 1158890112 56350656 4465776
13:26:05 76380 8192000000 10077626880 10558590976 1099621744 11.834 97 19 38 0 1151147168 47611424 3914000
13:28:22 41200 8192000000 10056496176 10519277568 1090492464 11.747 97 20 40 0 1141088304 46860912 3734928
13:30:04 63170 8192000000 10052691168 10495557632 1114727152 11.977 99 18 37 0 1151933872 32991648 4215072
13:32:07 69850 8192000000 10075235216 10520870912 1126677200 12.090 99 26 51 0 1157853472 25959472 5216800
13:34:18 91122 8192000000 10097154640 10573058048 1130271072 12.124 99 11 22 0 1188692352 55808896 2612384
13:36:52 37767 8192000000 9208110672 9493172224 344344368 4.033 30 16 32 0 383407344 35412912 3650064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-12-2022 13:38:59 to 20-12-2022 13:39:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2022 13:39:16 to 20-12-2022 13:39:18.

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          213652
MON$OLDEST_ACTIVE               213653
MON$OLDEST_SNAPSHOT             213653
MON$NEXT_TRANSACTION            213653
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                       2092160
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             756
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:264: 
:265: mscs-test-161.budzdorov.ru    Tue Dec 20 13:37:37 2022
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
--- end of diff output ---

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

20.12.22 13:39:19. 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-12-20 13:39:21 No severe PSQL-related problems occured
20.12.22 13:39:23. End of report.