Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.12.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 14384692 kB
MemAvailable 30060216 kB
Buffers 20 kB
Cached 17268284 kB
SwapCached 3224 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-16 04:37:20
DTS_END                         2022-12-16 04:37:20.3830
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/60976
TRN_ID                          213254
ATT_ID                          597
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: 8996.08 26153 1079529
customer order: creation 168.67 494 20240
customer order: refuse 13.71 477 1645
order to supplier: creation 44.15 1445 5298
order to supplier: removal 3.23 192856 388
invoice (draft): creation 44.85 3531 5382
invoice (draft): removal 51.10 1021 6132
invoice accept: apply 40.16 27271 4819
invoice accept: cancel 46.54 1305 5585
customer reserve: creation 3784.98 252 454197
customer reserve: removal 4509.15 110 541098
realization accept: apply 80.08 334 9609
realization accept: cancel 85.50 173 10260
payment from customer: creation 56.32 199 6758
payment from customer: removal 5.31 181 637
payment to supplier: creation 46.96 208 5635
payment to supplier: removal 3.50 134 420
service: total inventory turnovers 3.23 5757 388
service: total monetary turnovers 7.63 452 915
service: aggregate perf. data 0.98 15785 118
service: refresh index statistics 0.04 271067 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-12-2022 04:38:50 to 16-12-2022 04:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7969
TEST_TIME 2 6330
TEST_TIME 3 6616
TEST_TIME 4 6249
TEST_TIME 5 6765
TEST_TIME 6 6412
TEST_TIME 7 6203
TEST_TIME 8 6169
TEST_TIME 9 6470
TEST_TIME 10 6698
TEST_TIME 11 6712
TEST_TIME 12 6683
TEST_TIME 13 6631
TEST_TIME 14 6841
TEST_TIME 15 6779
TEST_TIME 16 6791
TEST_TIME 17 6817
TEST_TIME 18 6889
TEST_TIME 19 7235
TEST_TIME 20 7352
TEST_TIME 21 7577
TEST_TIME 22 7618
TEST_TIME 23 7800
TEST_TIME 24 8052
TEST_TIME 25 8091
TEST_TIME 26 8282
TEST_TIME 27 8155
TEST_TIME 28 8080
TEST_TIME 29 8541
TEST_TIME 30 9008
TEST_TIME 31 9244
TEST_TIME 32 9353
TEST_TIME 33 9802
TEST_TIME 34 9862
TEST_TIME 35 9837
TEST_TIME 36 10076
TEST_TIME 37 10009
TEST_TIME 38 9936
TEST_TIME 39 9961
TEST_TIME 40 10017
TEST_TIME 41 10169
TEST_TIME 42 10579
TEST_TIME 43 10411
TEST_TIME 44 10275
TEST_TIME 45 10139
TEST_TIME 46 10217
TEST_TIME 47 10244
TEST_TIME 48 10152
TEST_TIME 49 10044
TEST_TIME 50 9929
TEST_TIME 51 9834
TEST_TIME 52 9761
TEST_TIME 53 9842
TEST_TIME 54 9787
TEST_TIME 55 9669
TEST_TIME 56 9589
TEST_TIME 57 9524
TEST_TIME 58 9470
TEST_TIME 59 9410
TEST_TIME 60 9384
TEST_TIME 61 9428
TEST_TIME 62 9475
TEST_TIME 63 9467
TEST_TIME 64 9524
TEST_TIME 65 9530
TEST_TIME 66 9464
TEST_TIME 67 9533
TEST_TIME 68 9500
TEST_TIME 69 9490
TEST_TIME 70 9424
TEST_TIME 71 9394
TEST_TIME 72 9454
TEST_TIME 73 9506
TEST_TIME 74 9507
TEST_TIME 75 9461
TEST_TIME 76 9427
TEST_TIME 77 9529
TEST_TIME 78 9562
TEST_TIME 79 9544
TEST_TIME 80 9539
TEST_TIME 81 9456
TEST_TIME 82 9404
TEST_TIME 83 9356
TEST_TIME 84 9281
TEST_TIME 85 9246
TEST_TIME 86 9239
TEST_TIME 87 9227
TEST_TIME 88 9256
TEST_TIME 89 9239
TEST_TIME 90 9185
TEST_TIME 91 9168
TEST_TIME 92 9219
TEST_TIME 93 9227
TEST_TIME 94 9234
TEST_TIME 95 9220
TEST_TIME 96 9222
TEST_TIME 97 9211
TEST_TIME 98 9218
TEST_TIME 99 9356
TEST_TIME 100 9365
TEST_TIME 101 9335
TEST_TIME 102 9327
TEST_TIME 103 9362
TEST_TIME 104 9341
TEST_TIME 105 9276
TEST_TIME 106 9308
TEST_TIME 107 9257
TEST_TIME 108 9195
TEST_TIME 109 9188
TEST_TIME 110 9186
TEST_TIME 111 9210
TEST_TIME 112 9213
TEST_TIME 113 9169
TEST_TIME 114 9109
TEST_TIME 115 9106
TEST_TIME 116 9079
TEST_TIME 117 9054
TEST_TIME 118 9033
TEST_TIME 119 9062
TEST_TIME 120 9021

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-12-2022 04:38:54 to 16-12-2022 04:38:57.

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 1497557 1497557 0 0.00 0 16188 14 0 0
doc_list_biud 1497554 1497554 0 0.00 0 64591 46 0 0
sp_add_invoice_to_stock 5029 4819 210 4.17 26 210103 27272 0 210
sp_cancel_adding_invoice 5585 5585 0 0.00 6 93139 1306 0 0
sp_cancel_client_order 1645 1645 0 0.00 7 11858 477 0 0
sp_cancel_customer_reserve 541098 541098 0 0.00 1 20108 110 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 4 17499 182 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 7280 135 0 0
sp_cancel_supplier_invoice 6144 6132 12 0.19 3 64653 1021 0 12
sp_cancel_supplier_order 388 388 0 0.00 16 1462092 192856 0 0
sp_cancel_write_off 10260 10260 0 0.00 2 14082 174 0 0
sp_client_order 20240 20240 0 0.00 5 31789 495 0 0
sp_customer_reserve 454197 454197 0 0.00 0 73608 252 0 0
sp_fill_shopping_cart 30916 30916 0 0.00 1 11536 91 0 0
sp_fill_shopping_cart_clo_res 454171 454171 0 0.00 0 16482 20 0 0
sp_get_clo_for_invoice 4782 4782 0 0.00 0 33846 293 0 0
sp_kill_cost_storno 16902 16902 0 0.00 0 16171 81 0 0
sp_kill_qstorno_ret_qs2qd 1098976 1098976 0 0.00 0 64589 48 0 0
sp_kill_qty_storno 579499 579499 0 0.00 0 64590 110 0 0
sp_lock_selected_doc 19861 19861 0 0.00 0 14087 8 0 0
sp_make_cost_storno 26784 26784 0 0.00 0 14585 94 0 0
sp_multiply_rows_for_pdistr 26784 26784 0 0.00 0 8278 44 0 0
sp_multiply_rows_for_qdistr 20240 20240 0 0.00 0 16712 189 0 0
sp_pay_from_customer 6758 6758 0 0.00 6 11777 199 0 0
sp_pay_to_supplier 5635 5635 0 0.00 6 13592 209 0 0
sp_payment_common 12392 12392 0 0.00 2 10718 54 0 0
sp_qd_handle_on_cancel_clo 1645 1645 0 0.00 0 11849 392 0 0
sp_qd_handle_on_invoice_upd_sts 10367 10367 0 0.00 0 21048 569 0 0
sp_qd_handle_on_reserve_upd_sts 9609 9609 0 0.00 0 21337 77 0 0
sp_reserve_write_off 9609 9609 0 0.00 7 23868 335 0 0
sp_supplier_invoice 5382 5382 0 0.00 10 106050 3531 0 0
sp_supplier_order 5298 5298 0 0.00 7 21113 1445 0 0
srv_aggregate_perf_data 118 118 0 0.00 2266 168612 15786 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 787 129580 30701 0 0
srv_find_qd_qs_mism 1031810 1031810 0 0.00 0 11621 9 0 0
srv_make_invnt_saldo 388 388 0 0.00 142 74252 5758 0 0
srv_make_money_saldo 915 915 0 0.00 1 16111 452 0 0
srv_recalc_idx_stat 5 5 0 0.00 73699 434772 271068 0 0
t$perf-abend:sp_add_invoice_to_stock 210 210 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4819 4819 0 0.00 1 11698 1093 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 7282 568 0 0
t$perf-norm:sp_cancel_client_order 1645 1645 0 0.00 0 2847 32 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 875 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 1100 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 402 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 921 38 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 108266 8573 0 0
t$perf-norm:sp_cancel_write_off 930 930 0 0.00 0 1024 23 0 0
t$perf-norm:sp_client_order 20240 20240 0 0.00 0 4924 41 0 0
t$perf-norm:sp_customer_reserve 959 959 0 0.00 0 5576 27 0 0
t$perf-norm:sp_pay_from_customer 6758 6758 0 0.00 0 5544 22 0 0
t$perf-norm:sp_pay_to_supplier 5635 5635 0 0.00 0 3035 21 0 0
t$perf-norm:sp_reserve_write_off 9609 9609 0 0.00 0 6987 24 0 0
t$perf-norm:sp_supplier_invoice 5382 5382 0 0.00 0 17597 39 0 0
t$perf-norm:sp_supplier_order 5298 5298 0 0.00 0 1383 35 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 568 20 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 15 3 0 0
t$perf-norm:srv_make_invnt_saldo 388 388 0 0.00 0 600 16 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 5042 11 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 128 61 0 0
v_add_invoice_to_stock 9984 9564 420 4.20 0 5240 30 0 420
v_all_customers 36718 36718 0 0.00 0 62 4 0 0
v_all_suppliers 29497 29497 0 0.00 0 69 5 0 0
v_all_wares 40480 40480 0 0.00 0 139 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 5261 22 0 0
v_cancel_client_order 3290 3290 0 0.00 0 4932 14 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 5281 20 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 0 1116 14 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4296 16 0 0
v_cancel_supplier_invoice 782 758 24 3.06 0 411 27 0 24
v_cancel_supplier_order 776 776 0 0.00 0 1850 17 0 0
v_cancel_write_off 1860 1860 0 0.00 0 9091 39 0 0
v_max_id_clo_ord 5298 5298 0 0.00 0 835 6 0 0
v_max_id_clo_res 959 959 0 0.00 0 3165 18 0 0
v_max_id_ord_sup 5378 5378 0 0.00 0 5938 8 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 1958 10 0 0
v_max_non_paid_realizn 3444 3444 0 0.00 0 1567 9 0 0
v_min_id_clo_ord 5298 5298 0 0.00 0 1625 9 0 0
v_min_id_clo_res 959 959 0 0.00 0 4047 20 0 0
v_min_id_ord_sup 5378 5378 0 0.00 0 6377 11 0 0
v_min_non_paid_invoice 5635 5635 0 0.00 0 6169 21 0 0
v_min_non_paid_realizn 6757 6757 0 0.00 0 7123 23 0 0
v_our_firm 10388 10388 0 0.00 0 72 5 0 0
v_reserve_write_off 19218 19218 0 0.00 0 5781 13 0 0
x_lock_depdocs_on_canc_invoice 11338 11338 0 0.00 0 17451 129 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 19868 514 0 0
x_make_qstorno_1000_1200 5298 5298 0 0.00 2 20913 1254 0 0
x_make_qstorno_1200_2000 5382 5382 0 0.00 4 105609 3287 0 0
x_make_qstorno_2100_3300 454197 454197 0 0.00 0 73600 218 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-12-2022 04:38:58 to 16-12-2022 04:39:02.

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:24 3 8192000000 8800631840 8812486656 6289056 0.076 2 2 3 0 6433712 83296 61360
02:08:26 13787 8192000000 9711331232 10010529792 794598928 8.842 100 87 175 0 823700000 7466864 21634208
02:09:50 19117 8192000000 9755199200 10064576512 834083536 9.240 100 89 178 0 866023744 10403552 21536656
02:11:13 12987 8192000000 9792623168 10112069632 870345296 9.603 100 84 168 0 905688896 16345808 18997792
02:12:35 10518 8192000000 9856200928 10187173888 916151968 10.058 100 84 168 0 966566368 32283792 18130608
02:13:48 6091 8192000000 9910831040 10248212480 951084448 10.402 100 95 190 0 1024379072 53692912 19601712
02:14:57 3684 8192000000 9946131552 10296569856 977232048 10.657 100 88 172 0 1055163600 59733920 18197632
02:16:05 1479 8192000000 9986564160 10342936576 1000780432 10.886 100 87 167 0 1104832352 87414384 16637536
02:17:08 1512 8192000000 10017418480 10381303808 1023966144 11.110 100 95 185 0 1139547712 97303264 18278304
02:18:12 1073 8192000000 10015668176 10384568320 1038082032 11.246 100 94 176 0 1135413744 79957968 17373744
02:19:16 908 8192000000 10045141728 10415345664 1061090416 11.467 100 95 184 0 1164115552 84798240 18226896
02:20:18 1834 8192000000 10035687328 10406318080 1073836912 11.589 100 92 182 6 1156088992 63943664 18308416
02:21:22 1219 8192000000 10054148832 10437468160 1081112640 11.658 100 90 174 0 1174615104 75625840 17876624
02:22:25 1058 8192000000 10056885488 10439229440 1086688432 11.711 100 93 177 0 1182331264 77280496 18362336
02:23:27 1360 8192000000 10055898656 10441650176 1093210832 11.773 100 96 186 1 1178793280 67018112 18564336
02:24:29 1102 8192000000 10057922048 10449817600 1094812480 11.788 100 88 162 6 1177588336 65366096 17409760
02:25:31 2190 8192000000 10047631872 10441592832 1099434608 11.832 100 80 151 0 1166877008 51920368 15522032
02:26:36 3571 8192000000 10076675632 10476150784 1108051280 11.914 100 60 114 1 1200582736 81678656 10852800
02:27:41 1180 8192000000 10086763968 10481049600 1107720784 11.911 100 97 184 1 1208163152 81763216 18679152
02:28:44 1132 8192000000 10066462960 10464010240 1105877680 11.893 100 82 162 0 1193925248 71469408 16578160
02:29:45 1310 8192000000 10043156320 10430500864 1090543280 11.748 99 94 182 1 1164311920 55263280 18505360
02:30:49 1148 8192000000 10059020368 10449518592 1084997984 11.695 99 94 172 1 1187865984 85724624 17143376
02:31:50 1051 8192000000 10032760432 10411319296 1077497568 11.624 100 98 185 0 1151471984 54905744 19068672
02:32:53 1095 8192000000 10014790160 10391773184 1057411376 11.432 99 90 169 0 1132785824 57631072 17743376
02:33:54 9222 8192000000 9974244864 10368790528 1027229392 11.142 99 55 109 0 1103004800 64717488 11057920
02:35:08 670 8192000000 9989512560 10369458176 1042581248 11.289 100 89 167 2 1113445616 54402944 16461424
02:36:10 14014 8192000000 9957454496 10349084672 1028977968 11.159 98 51 100 1 1097120400 57343840 10798592
02:37:26 2349 8192000000 10009365776 10394333184 1055326048 11.412 100 85 161 2 1135507184 63940416 16240720
02:38:42 3986 8192000000 10017314448 10402775040 1069339280 11.546 100 71 134 0 1142680384 58632976 14708128
02:39:56 3787 8192000000 10035437584 10411413504 1069177216 11.544 99 88 170 3 1147977120 62955408 15844496
02:41:05 1890 8192000000 10078969840 10464227328 1100198720 11.840 100 76 147 0 1201059040 86047792 14812528
02:42:11 2981 8192000000 10123649712 10517385216 1118866544 12.016 100 86 156 1 1241975056 106734256 16374256
02:43:27 2870 8192000000 10141219168 10528276480 1115139328 11.981 99 75 149 2 1248795824 118244160 15412336
02:44:33 3369 8192000000 10164594368 10562183168 1135758576 12.176 100 76 143 0 1284122880 133047776 15316528
02:45:41 2580 8192000000 10195570416 10598215680 1142171056 12.236 100 86 155 1 1310474720 151793744 16509920
02:46:52 2485 8192000000 10221565808 10621829120 1154392016 12.351 100 84 158 1 1339171744 167197008 17582720
02:47:59 1998 8192000000 10236904560 10635784192 1154391568 12.351 100 78 155 1 1357203648 186069936 16742144
02:49:06 1019 8192000000 10276279056 10673532928 1156905824 12.374 100 93 186 0 1390096816 211364736 21826256
02:50:12 1049 8192000000 10317990016 10718777344 1169647168 12.494 100 97 189 2 1429641872 236893872 23100832
02:51:17 7773 8192000000 10365130784 10786119680 1172825408 12.523 100 90 177 0 1472917568 279467328 20624832
02:52:35 2193 8192000000 10374403712 10788196352 1186016528 12.646 100 85 163 3 1490005376 284373632 19615216
02:53:41 1089 8192000000 10422965744 10834837504 1185280288 12.639 100 94 182 0 1532086480 326383808 20422384
02:54:48 2313 8192000000 10439174944 10869293056 1186663904 12.652 100 88 173 1 1539115888 331055616 21396368
02:55:55 779 8192000000 10430028544 10869104640 1177977488 12.571 99 91 180 0 1542494592 343942016 20575088
02:56:58 2127 8192000000 10453204672 10885148672 1188066816 12.665 100 85 163 1 1551704576 343941408 19696352
02:58:04 1385 8192000000 10464598256 10899390464 1193134128 12.713 100 97 191 1 1569150704 353857424 22159152
02:59:09 1647 8192000000 10484991680 10894057472 1189438992 12.678 100 96 185 0 1592834272 381944384 21450896
03:00:13 896 8192000000 10459374880 10918473728 1186032720 12.646 100 90 172 0 1564183664 357443536 20707408
03:01:18 930 8192000000 10468843392 10897682432 1169028256 12.488 100 81 157 2 1576109120 388190192 18890672
03:02:23 2844 8192000000 10480838560 10910035968 1168122992 12.479 100 69 133 2 1587158288 403133712 15901584
03:03:31 4230 8192000000 10462281552 10906419200 1159929664 12.403 100 57 112 2 1568505296 395172448 13403184
03:04:40 4715 8192000000 10497790736 10932002816 1158036176 12.385 100 50 98 1 1602753088 432127616 12589296
03:05:54 8245 8192000000 10426637040 10986213376 1159851776 12.402 100 52 97 0 1535445872 362208640 13385456
03:07:05 5741 8192000000 10412009760 10913198080 1166752720 12.466 100 48 93 2 1520227120 342125088 11349312
03:08:15 14911 8192000000 10324608736 10819911680 1146572464 12.277 99 30 60 1 1441620128 287614144 7433520
03:09:44 36135 8192000000 10243415040 10765889536 1143738256 12.251 99 42 81 0 1398804560 245559008 9507296
03:11:28 13049 8192000000 10216449088 10669846528 1141607808 12.231 99 37 72 0 1293283104 144157744 7517552
03:12:45 1075 8192000000 10179158896 10635722752 1159568416 12.399 100 85 165 0 1276289392 98809264 17911712
03:13:47 1069 8192000000 10267168432 10711420928 1184413584 12.631 100 97 186 1 1366465888 160774768 21277536
03:14:49 27148 8192000000 10270111392 10695708672 1178617376 12.577 100 52 104 1 1368379984 177314848 12447760
03:16:21 23429 8192000000 10297356528 10781634560 1177131872 12.563 100 24 48 0 1388706672 205658208 5916592
03:18:21 41565 8192000000 10180954336 10749247488 1126899088 12.092 99 15 30 0 1307022992 176029104 4094800
03:20:05 87222 8192000000 10085950080 10531237888 1091265120 11.755 96 34 63 1 1152423216 55120960 6037136
03:22:47 12306 8192000000 10111942464 10531266560 1114399136 11.974 98 46 90 0 1194365040 69778080 10187824
03:24:01 124254 8192000000 10065298848 10482638848 1093690672 11.778 97 17 32 0 1157951952 60691536 3569744
03:27:10 49308 8192000000 10085810592 10506354688 1118124304 12.009 98 40 78 1 1182823504 56039232 8659968
03:29:01 123097 8192000000 10082499792 10577760256 1116357808 11.993 98 43 81 0 1211705520 85923760 9423952
03:32:14 38815 8192000000 10087639264 10523332608 1128860384 12.111 99 35 69 1 1186522480 50615056 7047040
03:34:44 9552 8192000000 10137650896 10562580480 1129737888 12.119 98 60 108 1 1225233056 84608208 10886960
03:36:05 5706 8192000000 10195239536 10631389184 1151753472 12.326 100 50 99 1 1292849264 130154080 10941712
03:37:25 20174 8192000000 10246457936 10693210112 1156476560 12.370 100 34 64 2 1358218576 193714080 8027936
03:38:45 32641 8192000000 10223366096 10729512960 1148773152 12.298 100 16 32 0 1336007952 182742144 4492656
03:40:20 31973 8192000000 10149075760 10620956672 1128446960 12.107 98 15 30 0 1241781296 109807952 3526384
03:42:00 23371 8192000000 10122551136 10580729856 1117109472 12.000 98 25 47 0 1184649664 62637216 4902976
03:43:43 60394 8192000000 10057432432 10561904640 1122199664 12.048 98 9 18 0 1204125792 80066816 1859312
03:45:46 22194 8192000000 10070980912 10505117696 1122095616 12.047 99 55 95 1 1142489360 11098016 9295728
03:47:14 16421 8192000000 10126365488 10564055040 1144716192 12.260 100 64 120 0 1214615952 57304496 12595264
03:48:42 45519 8192000000 10163459552 10638053376 1142175776 12.236 99 24 46 0 1261418080 114280224 4962080
03:50:28 50688 8192000000 10077567664 10509856768 1115578528 11.985 98 36 70 1 1218002464 94822608 7601328
03:52:22 65305 8192000000 10117413360 10548940800 1088274880 11.726 96 25 50 0 1160026800 66924272 4827648
03:54:33 129557 8192000000 10095716080 10548719616 1089931408 11.742 96 20 40 0 1135413856 41347888 4134560
03:57:43 94213 8192000000 10096104192 10536988672 1132790688 12.148 100 45 90 0 1195153136 53110656 9251792
04:00:45 68029 8192000000 10148095600 10587435008 1145525056 12.267 100 36 72 0 1239125600 86066912 7533632
04:02:53 42315 8192000000 10153529392 10625712128 1152805584 12.336 100 30 60 0 1284097312 124310912 6980816
04:05:00 78367 8192000000 10144699472 10632175616 1099417584 11.832 95 23 47 0 1216103120 111691056 4994480
04:07:19 66031 8192000000 10160925840 10634354688 1155492256 12.361 100 15 30 0 1268939072 110460464 2986352
04:09:28 73336 8192000000 10154220000 10604199936 1139815008 12.214 99 20 37 1 1225913600 82168384 3930208
04:11:43 37002 8192000000 10165168144 10600173568 1129267456 12.114 97 28 56 1 1220258384 84872624 6118304
04:13:45 69236 8192000000 10188274448 10657939456 1112575584 11.957 95 22 44 0 1242744144 125492816 4675744
04:16:00 60051 8192000000 10087943280 10559336448 1091560336 11.757 95 18 34 1 1223718800 127780176 4378288
04:18:03 20939 8192000000 10080935520 10499751936 1111844608 11.950 98 52 92 2 1160426384 38810064 9771712
04:19:28 36582 8192000000 10082879600 10538815488 1123936864 12.064 99 41 82 0 1197713568 65960224 7816480
04:21:06 46781 8192000000 10118364448 10548932608 1154736032 12.354 100 36 69 1 1227932560 65536400 7660128
04:23:00 97576 8192000000 10095619776 10544263168 1128205728 12.104 98 30 58 1 1202434464 67375360 6853376
04:25:39 22313 8192000000 10153208880 10585444352 1140883840 12.224 99 39 77 0 1234552256 85436064 8232352
04:27:04 57115 8192000000 10129127952 10602328064 1108105296 11.914 97 31 62 0 1237215008 122251552 6858160
04:29:13 31136 8192000000 10063423216 10587389952 1119858672 12.026 100 11 22 0 1200603296 78129232 2615392
04:30:46 41407 8192000000 10065079872 10524024832 1134117120 12.160 100 15 30 0 1173098592 35497952 3483520
04:32:28 62152 8192000000 10083000208 10542280704 1115604016 11.985 98 6 12 0 1165509200 48645456 1259728
04:34:54 42459 8192000000 10147115744 10592894976 1136973888 12.187 100 28 55 0 1234498480 91779808 5744784
04:36:46 40280 8192000000 9406410432 9733197824 471638976 5.443 41 15 28 0 569491008 94730624 3121408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 210 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-12-2022 04:39:22 to 16-12-2022 04:39:24.

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          209539
MON$OLDEST_ACTIVE               214851
MON$OLDEST_SNAPSHOT             214851
MON$NEXT_TRANSACTION            214851
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                       2102592
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.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 ---
:85: 
:86: mscs-test-161.budzdorov.ru    Fri Dec 16 04:37:34 2022
:87:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:88: 
--- end of diff output ---

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

16.12.22 04:39:25. 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-16 04:39:27 No severe PSQL-related problems occured
16.12.22 04:39:29. End of report.