Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.10.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 15060104 kB
MemAvailable 30555832 kB
Buffers 0 kB
Cached 16670072 kB
SwapCached 2204 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-09 13:37:19
DTS_END                         2022-10-09 13:37:19.9190
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/33192
TRN_ID                          214992
ATT_ID                          621
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: 8568.13 22208 1028175
customer order: creation 164.82 496 19778
customer order: refuse 13.61 414 1633
order to supplier: creation 43.48 1384 5218
order to supplier: removal 3.30 172596 396
invoice (draft): creation 44.41 3361 5329
invoice (draft): removal 47.41 1018 5689
invoice accept: apply 40.02 27501 4802
invoice accept: cancel 42.88 1261 5146
customer reserve: creation 3736.11 258 448333
customer reserve: removal 4153.98 109 498477
realization accept: apply 79.63 299 9555
realization accept: cancel 75.08 174 9009
payment from customer: creation 56.81 189 6817
payment from customer: removal 5.01 126 601
payment to supplier: creation 46.32 189 5558
payment to supplier: removal 3.37 140 404
service: total inventory turnovers 3.74 4368 449
service: total monetary turnovers 7.29 396 875
service: aggregate perf. data 0.84 20951 101
service: refresh index statistics 0.04 208930 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-10-2022 13:38:56 to 09-10-2022 13:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11834
TEST_TIME 2 8197
TEST_TIME 3 7690
TEST_TIME 4 6983
TEST_TIME 5 6971
TEST_TIME 6 6961
TEST_TIME 7 6964
TEST_TIME 8 7257
TEST_TIME 9 7298
TEST_TIME 10 7432
TEST_TIME 11 7497
TEST_TIME 12 7454
TEST_TIME 13 7704
TEST_TIME 14 7712
TEST_TIME 15 7870
TEST_TIME 16 7923
TEST_TIME 17 7943
TEST_TIME 18 8283
TEST_TIME 19 8526
TEST_TIME 20 8489
TEST_TIME 21 8545
TEST_TIME 22 8609
TEST_TIME 23 8842
TEST_TIME 24 8848
TEST_TIME 25 9051
TEST_TIME 26 9040
TEST_TIME 27 9031
TEST_TIME 28 8997
TEST_TIME 29 9097
TEST_TIME 30 9041
TEST_TIME 31 9159
TEST_TIME 32 9864
TEST_TIME 33 10392
TEST_TIME 34 10798
TEST_TIME 35 10888
TEST_TIME 36 11044
TEST_TIME 37 11071
TEST_TIME 38 10876
TEST_TIME 39 10608
TEST_TIME 40 10439
TEST_TIME 41 10356
TEST_TIME 42 10379
TEST_TIME 43 10382
TEST_TIME 44 10357
TEST_TIME 45 10259
TEST_TIME 46 10217
TEST_TIME 47 10066
TEST_TIME 48 10030
TEST_TIME 49 9952
TEST_TIME 50 9893
TEST_TIME 51 9925
TEST_TIME 52 9784
TEST_TIME 53 9706
TEST_TIME 54 9571
TEST_TIME 55 9452
TEST_TIME 56 9394
TEST_TIME 57 9317
TEST_TIME 58 9337
TEST_TIME 59 9297
TEST_TIME 60 9235
TEST_TIME 61 9161
TEST_TIME 62 9135
TEST_TIME 63 9134
TEST_TIME 64 9064
TEST_TIME 65 9002
TEST_TIME 66 8955
TEST_TIME 67 8914
TEST_TIME 68 8945
TEST_TIME 69 9038
TEST_TIME 70 9061
TEST_TIME 71 9053
TEST_TIME 72 9029
TEST_TIME 73 9059
TEST_TIME 74 9197
TEST_TIME 75 9230
TEST_TIME 76 9328
TEST_TIME 77 9260
TEST_TIME 78 9280
TEST_TIME 79 9180
TEST_TIME 80 9152
TEST_TIME 81 9089
TEST_TIME 82 9074
TEST_TIME 83 9057
TEST_TIME 84 9038
TEST_TIME 85 9001
TEST_TIME 86 8979
TEST_TIME 87 8942
TEST_TIME 88 8868
TEST_TIME 89 8857
TEST_TIME 90 8870
TEST_TIME 91 8875
TEST_TIME 92 8885
TEST_TIME 93 8880
TEST_TIME 94 8887
TEST_TIME 95 8908
TEST_TIME 96 8898
TEST_TIME 97 8907
TEST_TIME 98 8907
TEST_TIME 99 8894
TEST_TIME 100 8932
TEST_TIME 101 8943
TEST_TIME 102 8934
TEST_TIME 103 8957
TEST_TIME 104 8964
TEST_TIME 105 8902
TEST_TIME 106 8847
TEST_TIME 107 8836
TEST_TIME 108 8808
TEST_TIME 109 8796
TEST_TIME 110 8768
TEST_TIME 111 8747
TEST_TIME 112 8708
TEST_TIME 113 8714
TEST_TIME 114 8718
TEST_TIME 115 8718
TEST_TIME 116 8702
TEST_TIME 117 8687
TEST_TIME 118 8697
TEST_TIME 119 8665
TEST_TIME 120 8604

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-10-2022 13:39:00 to 09-10-2022 13:39:04.

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 1440855 1440855 0 0.00 0 27601 14 0 0
doc_list_biud 1440845 1440845 0 0.00 0 32559 44 0 0
sp_add_invoice_to_stock 4936 4802 134 2.71 57 230032 27501 0 134
sp_cancel_adding_invoice 5146 5146 0 0.00 6 119149 1262 0 0
sp_cancel_client_order 1633 1633 0 0.00 5 10328 414 0 0
sp_cancel_customer_reserve 498477 498477 0 0.00 1 32564 109 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 4 1732 126 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 5584 141 0 0
sp_cancel_supplier_invoice 5699 5689 10 0.17 2 28788 1018 0 10
sp_cancel_supplier_order 396 396 0 0.00 7 1360286 172596 0 0
sp_cancel_write_off 9009 9009 0 0.00 2 13411 174 0 0
sp_client_order 19778 19778 0 0.00 5 17773 496 0 0
sp_customer_reserve 448333 448333 0 0.00 0 52950 258 0 0
sp_fill_shopping_cart 30314 30314 0 0.00 1 25560 99 0 0
sp_fill_shopping_cart_clo_res 448292 448292 0 0.00 0 22708 20 0 0
sp_get_clo_for_invoice 4751 4751 0 0.00 0 35040 330 0 0
sp_kill_cost_storno 15160 15160 0 0.00 0 27596 78 0 0
sp_kill_qstorno_ret_qs2qd 1012048 1012048 0 0.00 0 32549 47 0 0
sp_kill_qty_storno 534656 534656 0 0.00 0 32559 109 0 0
sp_lock_selected_doc 19698 19698 0 0.00 0 10087 8 0 0
sp_make_cost_storno 26681 26681 0 0.00 0 11145 85 0 0
sp_multiply_rows_for_pdistr 26681 26681 0 0.00 0 10719 41 0 0
sp_multiply_rows_for_qdistr 19777 19777 0 0.00 0 13035 178 0 0
sp_pay_from_customer 6817 6817 0 0.00 5 20929 189 0 0
sp_pay_to_supplier 5558 5558 0 0.00 6 11739 189 0 0
sp_payment_common 12375 12375 0 0.00 2 12050 50 0 0
sp_qd_handle_on_cancel_clo 1633 1633 0 0.00 0 10318 332 0 0
sp_qd_handle_on_invoice_upd_sts 9897 9897 0 0.00 0 23872 563 0 0
sp_qd_handle_on_reserve_upd_sts 9555 9555 0 0.00 0 15700 68 0 0
sp_reserve_write_off 9555 9555 0 0.00 8 15728 300 0 0
sp_supplier_invoice 5329 5329 0 0.00 10 73070 3362 0 0
sp_supplier_order 5218 5218 0 0.00 6 29278 1385 0 0
srv_aggregate_perf_data 101 101 0 0.00 2388 363333 20951 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 780 120133 35201 0 0
srv_find_qd_qs_mism 981079 981079 0 0.00 0 13223 11 0 0
srv_make_invnt_saldo 449 449 0 0.00 60 57866 4368 0 0
srv_make_money_saldo 875 875 0 0.00 1 10363 397 0 0
srv_recalc_idx_stat 5 5 0 0.00 39956 396700 208930 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4808 4808 0 0.00 0 11607 1062 0 0
t$perf-norm:sp_cancel_adding_invoice 390 390 0 0.00 1 6731 491 0 0
t$perf-norm:sp_cancel_client_order 1633 1633 0 0.00 0 2808 32 0 0
t$perf-norm:sp_cancel_customer_reserve 622 622 0 0.00 0 1323 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 1001 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 1567 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 370 370 0 0.00 0 574 40 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 1 102006 6968 0 0
t$perf-norm:sp_cancel_write_off 982 982 0 0.00 0 1680 19 0 0
t$perf-norm:sp_client_order 19779 19779 0 0.00 0 4935 38 0 0
t$perf-norm:sp_customer_reserve 964 964 0 0.00 0 4437 23 0 0
t$perf-norm:sp_pay_from_customer 6818 6818 0 0.00 0 5896 19 0 0
t$perf-norm:sp_pay_to_supplier 5558 5558 0 0.00 0 2307 19 0 0
t$perf-norm:sp_reserve_write_off 9555 9555 0 0.00 0 10243 26 0 0
t$perf-norm:sp_supplier_invoice 5329 5329 0 0.00 0 3571 33 0 0
t$perf-norm:sp_supplier_order 5218 5218 0 0.00 0 1743 32 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 749 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 1570 32 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 2657 21 0 0
t$perf-norm:srv_make_money_saldo 875 875 0 0.00 0 2377 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 198 85 0 0
v_add_invoice_to_stock 9762 9494 268 2.74 0 10069 27 0 268
v_all_customers 36520 36520 0 0.00 0 105 4 0 0
v_all_suppliers 29278 29278 0 0.00 0 147 5 0 0
v_all_wares 39552 39552 0 0.00 0 71 4 0 0
v_cancel_adding_invoice 780 780 0 0.00 0 1072 17 0 0
v_cancel_client_order 3266 3266 0 0.00 0 4843 15 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 1000 14 0 0
v_cancel_customer_reserve 1244 1244 0 0.00 0 2126 13 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 2198 15 0 0
v_cancel_supplier_invoice 760 740 20 2.63 0 8044 39 0 20
v_cancel_supplier_order 792 792 0 0.00 0 844 14 0 0
v_cancel_write_off 1964 1964 0 0.00 0 5975 26 0 0
v_max_id_clo_ord 5218 5218 0 0.00 0 6908 7 0 0
v_max_id_clo_res 964 964 0 0.00 0 2644 14 0 0
v_max_id_ord_sup 5320 5320 0 0.00 0 7107 8 0 0
v_max_non_paid_invoice 1457 1457 0 0.00 0 1387 10 0 0
v_max_non_paid_realizn 3433 3433 0 0.00 0 375 8 0 0
v_min_id_clo_ord 5218 5218 0 0.00 0 3891 10 0 0
v_min_id_clo_res 964 964 0 0.00 0 5325 25 0 0
v_min_id_ord_sup 5320 5320 0 0.00 0 8134 10 0 0
v_min_non_paid_invoice 5558 5558 0 0.00 0 5831 15 0 0
v_min_non_paid_realizn 6817 6817 0 0.00 0 12026 25 0 0
v_our_firm 9800 9800 0 0.00 0 102 5 0 0
v_reserve_write_off 19106 19106 0 0.00 0 7414 12 0 0
x_lock_depdocs_on_canc_invoice 10465 10465 0 0.00 0 11737 128 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 6978 410 0 0
x_make_qstorno_1000_1200 5218 5218 0 0.00 2 29180 1193 0 0
x_make_qstorno_1200_2000 5329 5329 0 0.00 5 73044 3079 0 0
x_make_qstorno_2100_3300 448328 448328 0 0.00 0 50907 225 0 0

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

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
11:08:26 30903 8192000000 9720294032 10019139584 790984016 8.805 100 90 180 0 820396288 7791344 21620928
11:10:01 19056 8192000000 9763145328 10069467136 834571072 9.245 100 90 180 0 868460032 12365776 21523184
11:11:26 13211 8192000000 9798030080 10119684096 868546800 9.586 100 82 160 0 906832624 19694976 18590848
11:12:44 8615 8192000000 9868946368 10201165824 918699872 10.083 100 84 166 0 978337072 41771904 17865296
11:13:59 4103 8192000000 9944202544 10289725440 958824352 10.478 100 81 160 0 1051476896 76176496 16476048
11:15:07 2058 8192000000 9971214800 10324930560 993140480 10.812 100 87 171 1 1089139072 78209600 17788992
11:16:12 1199 8192000000 10006932096 10369093632 1025598112 11.126 100 95 190 0 1120165024 76074000 18492912
11:17:15 971 8192000000 10040580976 10411233280 1050689648 11.367 100 95 186 0 1150660448 80839136 19131664
11:18:19 4114 8192000000 10015232544 10387234816 1054820928 11.407 100 91 175 2 1128928144 57287056 16820160
11:19:25 1180 8192000000 10041623888 10420199424 1064388320 11.498 100 92 179 1 1163704544 81288576 18027648
11:20:29 1135 8192000000 10059243472 10438496256 1069726064 11.549 100 93 182 0 1174785552 87273776 17785712
11:21:31 1195 8192000000 10039720528 10421628928 1077092496 11.620 100 94 185 2 1163150224 66368000 19689728
11:22:35 1170 8192000000 10054671568 10439000064 1081175568 11.659 100 95 186 0 1173595552 73123008 19296976
11:23:37 1587 8192000000 10053530000 10445484032 1088639600 11.730 100 76 138 3 1169712144 66619408 14453136
11:24:40 2206 8192000000 10062832656 10455773184 1091720496 11.759 100 79 154 1 1190226416 83153808 15352112
11:25:43 1138 8192000000 10080220304 10470125568 1099572976 11.834 100 96 184 2 1202472048 84214944 18684128
11:26:45 1240 8192000000 10062435280 10446741504 1100357952 11.841 100 97 190 1 1183808016 63430816 20019248
11:27:48 1219 8192000000 10047981136 10435604480 1087726864 11.721 99 94 177 1 1170836128 65348000 17761264
11:28:50 1208 8192000000 10059060768 10450870272 1100967088 11.847 100 95 177 2 1181146752 60906880 19272784
11:29:52 1213 8192000000 10032827472 10413998080 1074230896 11.592 100 94 178 1 1153234992 60146304 18857792
11:30:54 1202 8192000000 10004779024 10387210240 1054394112 11.403 99 89 171 1 1127049456 55054688 17600656
11:31:57 1928 8192000000 10023029904 10401206272 1066441584 11.518 100 91 157 1 1146957520 63484496 17031440
11:33:00 4698 8192000000 9996347248 10383798272 1045203760 11.315 98 57 98 2 1108242112 53265312 9773040
11:34:06 2939 8192000000 9997961952 10378317824 1045267104 11.315 98 74 142 1 1124885536 65481424 14137008
11:35:11 1408 8192000000 9992530352 10371870720 1044899264 11.312 100 82 158 1 1111038848 48576768 17562816
11:36:14 1058 8192000000 10009193904 10387382272 1049243504 11.353 100 95 180 0 1130234688 61430048 19561136
11:37:16 1173 8192000000 9995621776 10369978368 1032636176 11.194 100 89 158 0 1118777136 70137760 16003200
11:38:23 2304 8192000000 10012923088 10391101440 1054871840 11.407 100 85 159 0 1136958016 64801296 17284880
11:39:30 1229 8192000000 10047565872 10426093568 1068804048 11.541 100 92 180 0 1163998192 78050944 17143200
11:40:38 8261 8192000000 10023548112 10415476736 1070596080 11.558 99 69 138 0 1143166112 58144512 14425520
11:41:52 3945 8192000000 10060503392 10442215424 1081701200 11.664 99 74 145 1 1178299232 81704800 14893232
11:43:00 7994 8192000000 10105219296 10497187840 1094744432 11.788 100 63 118 1 1216958608 108410352 13803824
11:44:18 3465 8192000000 10118558752 10521452544 1107328496 11.907 100 69 132 1 1235783872 114279168 14176208
11:45:26 3974 8192000000 10125768624 10527973376 1118883120 12.016 100 74 142 0 1254821120 120964624 14973376
11:46:35 4364 8192000000 10183483232 10579070976 1138037504 12.197 100 76 148 1 1291303968 137754448 15512016
11:47:44 1240 8192000000 10212394624 10612969472 1151934640 12.328 100 92 179 3 1322899376 151936944 19027792
11:48:47 914 8192000000 10229924048 10626256896 1156049616 12.366 100 96 182 0 1346827936 170228320 20550000
11:49:52 1197 8192000000 10267931584 10673057792 1168947808 12.487 100 96 186 0 1374434016 184333376 21152832
11:50:56 828 8192000000 10284442208 10692255744 1170838336 12.505 100 93 179 0 1394605168 203458960 20307872
11:51:59 1004 8192000000 10312293248 10730815488 1168865200 12.486 100 94 173 1 1421309008 232293632 20150176
11:53:04 1300 8192000000 10320616992 10727047168 1165980640 12.459 100 96 183 0 1433241616 245408064 21852912
11:54:09 1411 8192000000 10368272048 10783174656 1181459984 12.604 100 95 183 2 1478115984 274410304 22245696
11:55:12 892 8192000000 10364764000 10801995776 1191563344 12.698 100 98 193 0 1475077392 260245056 23268992
11:56:15 777 8192000000 10389488128 10804068352 1197841168 12.756 100 96 187 1 1499299712 279265856 22192688
11:57:19 763 8192000000 10404855952 10821378048 1191966512 12.702 100 95 183 0 1513540688 299547280 22026896
11:58:22 913 8192000000 10452083488 10857447424 1182505168 12.614 100 97 190 1 1557191264 352156576 22529520
11:59:25 1270 8192000000 10475432624 10906062848 1186209584 12.648 100 96 189 2 1586861808 378825392 21826832
12:00:31 1081 8192000000 10486644224 10933755904 1187313008 12.658 100 98 187 0 1595622464 384803008 23506448
12:01:33 1983 8192000000 10484756048 10899263488 1187933104 12.664 100 78 154 2 1588676304 381613728 19129472
12:02:38 5398 8192000000 10488490656 10933903360 1183593664 12.624 100 51 100 0 1594862272 397749728 13518880
12:03:50 5772 8192000000 10518505872 10960175104 1173907856 12.533 99 54 106 0 1618388096 430072784 14407456
12:05:08 8533 8192000000 10566230352 10995707904 1184703824 12.634 100 48 96 0 1662946144 465952912 12289408
12:06:40 7748 8192000000 10551298656 11012886528 1160399968 12.407 99 36 72 1 1649570736 479891824 9278944
12:08:26 11935 8192000000 10558932368 11040526336 1158172528 12.386 100 30 60 0 1680201840 513695280 8334032
12:09:39 31358 8192000000 10370594272 10806075392 1085916192 11.704 95 21 42 0 1450900704 359277648 5706864
12:11:21 8774 8192000000 10196887504 10660605952 1117846128 12.007 100 55 108 0 1292773328 163916624 11010576
12:12:32 21214 8192000000 10132147216 10612502528 1123212544 12.057 99 47 89 1 1221103936 88289328 9602064
12:13:56 10912 8192000000 10147161440 10563596288 1145919040 12.271 100 59 119 0 1248941408 89469312 13553056
12:15:18 96810 8192000000 10169926384 10595856384 1104991312 11.885 97 36 72 0 1227051744 113073088 8987344
12:17:55 65305 8192000000 10145439072 10632224768 1127866624 12.101 100 22 42 0 1284473152 151017744 5588784
12:20:04 12897 8192000000 10094343216 10541047808 1123928800 12.064 100 53 106 0 1215807744 80824976 11053968
12:21:18 68265 8192000000 10118427136 10570788864 1128002592 12.103 100 24 48 0 1219155968 86052928 5100448
12:23:27 70626 8192000000 10095956352 10546593792 1101379248 11.851 97 16 32 0 1166016768 60676192 3961328
12:25:56 55185 8192000000 10081733728 10541191168 1104940928 11.884 98 27 54 0 1185454752 74413040 6100784
12:28:35 37433 8192000000 10042192480 10530369536 1097457744 11.814 99 5 10 0 1132439312 34167488 814080
12:30:13 64141 8192000000 10035473648 10480054272 1097332944 11.812 98 14 28 1 1140302592 39809840 3159808
12:32:37 101676 8192000000 10066476336 10512719872 1093934880 11.780 97 29 58 1 1129036176 28930288 6171008
12:35:19 113069 8192000000 10055611504 10541150208 1104467360 11.880 98 12 24 0 1138452768 31188112 2797296
12:38:17 74544 8192000000 10065507776 10538565632 1109042160 11.923 98 17 34 0 1169409616 56965248 3402208
12:41:47 45544 8192000000 10133426192 10580959232 1130755472 12.128 98 20 38 0 1195521488 60525488 4240528
12:43:32 52555 8192000000 10207287952 10657853440 1164442080 12.445 100 30 58 0 1283027104 112210880 6374144
12:45:25 39926 8192000000 10204507248 10678128640 1154962720 12.356 100 27 53 0 1303993552 142710320 6320512
12:47:11 75582 8192000000 10166300128 10636668928 1149780512 12.307 100 19 37 0 1255262800 101555536 3926752
12:49:36 33271 8192000000 10256424224 10726154240 1171721920 12.513 100 42 82 0 1338273792 157510544 9041328
12:51:11 110063 8192000000 10172932560 10674483200 1129282624 12.115 97 18 36 0 1304820752 171392592 4145536
12:54:06 12386 8192000000 10118694304 10551721984 1144873472 12.261 100 40 79 0 1209967232 56786272 8307488
12:55:21 120133 8192000000 10107645104 10554642432 1148356768 12.294 100 51 100 1 1215865152 57541504 9966880
12:58:30 15795 8192000000 10091008512 10537357312 1150265680 12.312 100 33 66 0 1189653856 32909808 6478368
12:59:47 94479 8192000000 10078333408 10569052160 1139166624 12.208 99 14 29 1 1183325584 41589712 2569248
13:02:23 38023 8192000000 10081236960 10545692672 1127217744 12.095 98 19 39 0 1173392176 41804160 4370272
13:05:04 81127 8192000000 10078310608 10571124736 1123953536 12.064 98 11 23 0 1182532336 56150400 2428400
13:07:25 73966 8192000000 10086483632 10559471616 1091511632 11.757 95 8 16 0 1149620912 56592640 1516640
13:09:44 48891 8192000000 10068945376 10512330752 1075763200 11.607 94 26 48 1 1126361696 45774208 4824288
13:11:34 42400 8192000000 10101793456 10561277952 1125728384 12.081 99 41 82 0 1204631904 70630176 8273344
13:13:26 52823 8192000000 10087413936 10541576192 1085378544 11.699 96 43 86 0 1143622192 49978096 8265552
13:15:20 25065 8192000000 10080723424 10530549760 1101274784 11.850 98 40 80 0 1163864896 54573792 8016320
13:16:45 22652 8192000000 10109562864 10556796928 1117013392 11.999 99 52 104 0 1190052416 63068736 9970288
13:18:08 62374 8192000000 10075961328 10524766208 1101576160 11.853 98 31 61 0 1175211120 67709136 5925824
13:20:15 53619 8192000000 10087691712 10550951936 1100065328 11.838 99 39 78 0 1183333952 75272512 7996112
13:22:09 108430 8192000000 10048386400 10483597312 1104205872 11.878 99 29 46 2 1151924656 42954208 4764576
13:25:07 67044 8192000000 10056286688 10528526336 1078766816 11.636 97 16 32 0 1139656208 57483984 3405408
13:27:36 59748 8192000000 10059535888 10526818304 1109103520 11.924 99 6 12 1 1165521360 54794560 1623280
13:29:36 89319 8192000000 10084396736 10528129024 1098892480 11.827 98 15 29 0 1155813936 53977232 2944224
13:32:10 30930 8192000000 10096658288 10546352128 1105262448 11.888 98 44 88 1 1173154720 58942144 8950128
13:33:41 21143 8192000000 10130604192 10565922816 1137660704 12.194 100 38 76 0 1217244528 71436912 8146912
13:35:06 49156 8192000000 10184708960 10645741568 1126068624 12.084 98 37 73 0 1252451616 117949280 8433712
13:36:57 14582 8192000000 10233576272 10660433920 1134345280 12.162 100 43 84 0 1318302000 174170528 9786192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-10-2022 13:39:11 to 09-10-2022 13:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-10-2022 13:39:28 to 09-10-2022 13:39:30.

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          207725
MON$OLDEST_ACTIVE               215535
MON$OLDEST_SNAPSHOT             215535
MON$NEXT_TRANSACTION            215535
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                       2092672
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2856 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2856 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2856 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2856 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 ---
:52: 
:53: mscs-test-161.budzdorov.ru    Sun Oct  9 13:37:23 2022
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Sun Oct  9 13:37:24 2022
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Sun Oct  9 13:37:27 2022
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:63: 
:64: 
:65: mscs-test-161.budzdorov.ru    Sun Oct  9 13:37:28 2022
:66:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:67: 
:68: 
:69: mscs-test-161.budzdorov.ru    Sun Oct  9 13:37:29 2022
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Sun Oct  9 13:37:29 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
--- end of diff output ---

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

09.10.22 13:39:31. 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-09 13:39:34 No severe PSQL-related problems occured
09.10.22 13:39:35. End of report.