Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2022 07:47
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 14810508 kB
MemAvailable 30137400 kB
Buffers 0 kB
Cached 16917272 kB
SwapCached 3260 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-09 07:45:13
DTS_END                         2022-12-09 07:45:13.1980
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/40852
TRN_ID                          218091
ATT_ID                          668
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: 8853.78 25842 1062454
customer order: creation 165.04 506 19805
customer order: refuse 14.24 418 1709
order to supplier: creation 43.78 1436 5254
order to supplier: removal 3.23 176370 388
invoice (draft): creation 44.30 3292 5316
invoice (draft): removal 50.18 1011 6021
invoice accept: apply 39.99 27235 4799
invoice accept: cancel 45.36 1314 5443
customer reserve: creation 3674.54 260 440945
customer reserve: removal 4485.62 100 538274
realization accept: apply 81.50 307 9780
realization accept: cancel 79.93 167 9591
payment from customer: creation 56.23 194 6748
payment from customer: removal 5.55 141 666
payment to supplier: creation 48.56 206 5827
payment to supplier: removal 3.38 113 406
service: total inventory turnovers 4.05 4279 486
service: total monetary turnovers 7.44 478 893
service: aggregate perf. data 0.82 18911 98
service: refresh index statistics 0.04 280092 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2022 07:47:43 to 09-12-2022 07:47:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8269
TEST_TIME 2 6343
TEST_TIME 3 5580
TEST_TIME 4 5851
TEST_TIME 5 5772
TEST_TIME 6 6063
TEST_TIME 7 6208
TEST_TIME 8 6128
TEST_TIME 9 6430
TEST_TIME 10 6683
TEST_TIME 11 6479
TEST_TIME 12 6582
TEST_TIME 13 6575
TEST_TIME 14 6699
TEST_TIME 15 7090
TEST_TIME 16 7418
TEST_TIME 17 7539
TEST_TIME 18 7499
TEST_TIME 19 7533
TEST_TIME 20 7616
TEST_TIME 21 7580
TEST_TIME 22 7570
TEST_TIME 23 7797
TEST_TIME 24 7932
TEST_TIME 25 8112
TEST_TIME 26 8127
TEST_TIME 27 8134
TEST_TIME 28 8524
TEST_TIME 29 8633
TEST_TIME 30 8899
TEST_TIME 31 9054
TEST_TIME 32 9368
TEST_TIME 33 9472
TEST_TIME 34 10020
TEST_TIME 35 10302
TEST_TIME 36 10547
TEST_TIME 37 10492
TEST_TIME 38 10444
TEST_TIME 39 10402
TEST_TIME 40 10384
TEST_TIME 41 10339
TEST_TIME 42 10149
TEST_TIME 43 9929
TEST_TIME 44 9924
TEST_TIME 45 9784
TEST_TIME 46 9752
TEST_TIME 47 9616
TEST_TIME 48 9601
TEST_TIME 49 9680
TEST_TIME 50 9552
TEST_TIME 51 9455
TEST_TIME 52 9363
TEST_TIME 53 9389
TEST_TIME 54 9340
TEST_TIME 55 9396
TEST_TIME 56 9445
TEST_TIME 57 9400
TEST_TIME 58 9391
TEST_TIME 59 9398
TEST_TIME 60 9347
TEST_TIME 61 9443
TEST_TIME 62 9554
TEST_TIME 63 9590
TEST_TIME 64 9628
TEST_TIME 65 9583
TEST_TIME 66 9612
TEST_TIME 67 9599
TEST_TIME 68 9577
TEST_TIME 69 9525
TEST_TIME 70 9456
TEST_TIME 71 9352
TEST_TIME 72 9419
TEST_TIME 73 9347
TEST_TIME 74 9295
TEST_TIME 75 9224
TEST_TIME 76 9240
TEST_TIME 77 9190
TEST_TIME 78 9214
TEST_TIME 79 9168
TEST_TIME 80 9121
TEST_TIME 81 9199
TEST_TIME 82 9219
TEST_TIME 83 9176
TEST_TIME 84 9183
TEST_TIME 85 9178
TEST_TIME 86 9210
TEST_TIME 87 9271
TEST_TIME 88 9229
TEST_TIME 89 9203
TEST_TIME 90 9360
TEST_TIME 91 9273
TEST_TIME 92 9199
TEST_TIME 93 9168
TEST_TIME 94 9141
TEST_TIME 95 9077
TEST_TIME 96 9042
TEST_TIME 97 8978
TEST_TIME 98 9005
TEST_TIME 99 9004
TEST_TIME 100 9003
TEST_TIME 101 8972
TEST_TIME 102 8972
TEST_TIME 103 8929
TEST_TIME 104 8992
TEST_TIME 105 8994
TEST_TIME 106 9011
TEST_TIME 107 9056
TEST_TIME 108 9109
TEST_TIME 109 9137
TEST_TIME 110 9163
TEST_TIME 111 9171
TEST_TIME 112 9195
TEST_TIME 113 9131
TEST_TIME 114 9090
TEST_TIME 115 9036
TEST_TIME 116 9066
TEST_TIME 117 9029
TEST_TIME 118 8989
TEST_TIME 119 8949
TEST_TIME 120 8876

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-12-2022 07:47:46 to 09-12-2022 07:47:50.

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 1469820 1469820 0 0.00 0 19136 14 0 0
doc_list_biud 1469816 1469816 0 0.00 0 43173 43 0 0
sp_add_invoice_to_stock 4928 4799 129 2.61 43 193558 27236 0 129
sp_cancel_adding_invoice 5443 5443 0 0.00 7 105797 1314 0 0
sp_cancel_client_order 1709 1709 0 0.00 6 10537 419 0 0
sp_cancel_customer_reserve 538274 538274 0 0.00 1 43175 101 0 0
sp_cancel_pay_from_customer 666 666 0 0.00 4 7234 141 0 0
sp_cancel_pay_to_supplier 406 406 0 0.00 3 1186 113 0 0
sp_cancel_supplier_invoice 6033 6021 12 0.19 3 34640 1011 0 12
sp_cancel_supplier_order 388 388 0 0.00 9 1525186 176371 0 0
sp_cancel_write_off 9591 9591 0 0.00 2 13571 168 0 0
sp_client_order 19805 19805 0 0.00 5 27711 506 0 0
sp_customer_reserve 440945 440945 0 0.00 0 41200 261 0 0
sp_fill_shopping_cart 30374 30374 0 0.00 1 15240 89 0 0
sp_fill_shopping_cart_clo_res 440920 440920 0 0.00 0 14785 20 0 0
sp_get_clo_for_invoice 4759 4759 0 0.00 0 38935 313 0 0
sp_kill_cost_storno 16106 16106 0 0.00 0 7218 77 0 0
sp_kill_qstorno_ret_qs2qd 1092548 1092548 0 0.00 0 43169 44 0 0
sp_kill_qty_storno 575964 575964 0 0.00 0 43172 101 0 0
sp_lock_selected_doc 20085 20085 0 0.00 0 12353 10 0 0
sp_make_cost_storno 27113 27113 0 0.00 0 18457 84 0 0
sp_multiply_rows_for_pdistr 27112 27112 0 0.00 0 14977 44 0 0
sp_multiply_rows_for_qdistr 19805 19805 0 0.00 0 12916 187 0 0
sp_pay_from_customer 6748 6748 0 0.00 6 18810 194 0 0
sp_pay_to_supplier 5827 5827 0 0.00 6 18076 206 0 0
sp_payment_common 12574 12574 0 0.00 2 13040 50 0 0
sp_qd_handle_on_cancel_clo 1709 1709 0 0.00 1 10523 345 0 0
sp_qd_handle_on_invoice_upd_sts 10201 10201 0 0.00 0 34633 560 0 0
sp_qd_handle_on_reserve_upd_sts 9780 9780 0 0.00 0 20654 75 0 0
sp_reserve_write_off 9780 9780 0 0.00 8 20699 307 0 0
sp_supplier_invoice 5316 5316 0 0.00 9 82989 3292 0 0
sp_supplier_order 5254 5254 0 0.00 8 33837 1437 0 0
srv_aggregate_perf_data 98 98 0 0.00 2796 180730 18912 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 653 127044 31815 0 0
srv_find_qd_qs_mism 1016072 1016072 0 0.00 0 14785 10 0 0
srv_make_invnt_saldo 486 486 0 0.00 83 86221 4279 0 0
srv_make_money_saldo 893 893 0 0.00 1 16885 478 0 0
srv_recalc_idx_stat 5 5 0 0.00 84559 538847 280092 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 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 4800 4800 0 0.00 1 12860 1050 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 2 9659 561 0 0
t$perf-norm:sp_cancel_client_order 1709 1709 0 0.00 0 6147 32 0 0
t$perf-norm:sp_cancel_customer_reserve 624 624 0 0.00 0 5884 40 0 0
t$perf-norm:sp_cancel_pay_from_customer 666 666 0 0.00 0 2121 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 406 406 0 0.00 0 1169 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 541 26 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 103438 7614 0 0
t$perf-norm:sp_cancel_write_off 940 940 0 0.00 0 2937 24 0 0
t$perf-norm:sp_client_order 19805 19805 0 0.00 0 10405 42 0 0
t$perf-norm:sp_customer_reserve 965 965 0 0.00 0 490 13 0 0
t$perf-norm:sp_pay_from_customer 6748 6748 0 0.00 0 4209 21 0 0
t$perf-norm:sp_pay_to_supplier 5827 5827 0 0.00 0 10432 23 0 0
t$perf-norm:sp_reserve_write_off 9780 9780 0 0.00 0 4851 26 0 0
t$perf-norm:sp_supplier_invoice 5316 5316 0 0.00 0 13602 35 0 0
t$perf-norm:sp_supplier_order 5254 5254 0 0.00 0 5854 37 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 897 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 1624 39 0 0
t$perf-norm:srv_make_invnt_saldo 486 486 0 0.00 0 1339 28 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 6123 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 974 206 0 0
v_add_invoice_to_stock 9774 9516 258 2.63 0 5178 24 0 258
v_all_customers 36006 36006 0 0.00 0 67 4 0 0
v_all_suppliers 29828 29828 0 0.00 0 73 5 0 0
v_all_wares 39610 39610 0 0.00 0 97 4 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 410 16 0 0
v_cancel_client_order 3418 3418 0 0.00 0 2392 10 0 0
v_cancel_customer_prepayment 1332 1332 0 0.00 0 1563 15 0 0
v_cancel_customer_reserve 1248 1248 0 0.00 0 11912 21 0 0
v_cancel_payment_to_supplier 812 812 0 0.00 0 1145 12 0 0
v_cancel_supplier_invoice 824 800 24 2.91 0 1754 29 0 24
v_cancel_supplier_order 776 776 0 0.00 0 1731 13 0 0
v_cancel_write_off 1880 1880 0 0.00 0 1418 25 0 0
v_max_id_clo_ord 5254 5254 0 0.00 0 13463 8 0 0
v_max_id_clo_res 965 965 0 0.00 0 6490 26 0 0
v_max_id_ord_sup 5315 5315 0 0.00 0 6432 9 0 0
v_max_non_paid_invoice 1482 1482 0 0.00 0 3184 11 0 0
v_max_non_paid_realizn 3605 3605 0 0.00 0 3986 9 0 0
v_min_id_clo_ord 5254 5254 0 0.00 0 5055 10 0 0
v_min_id_clo_res 965 965 0 0.00 0 5691 26 0 0
v_min_id_ord_sup 5315 5315 0 0.00 0 7655 11 0 0
v_min_non_paid_invoice 5827 5827 0 0.00 0 13031 18 0 0
v_min_non_paid_realizn 6747 6747 0 0.00 0 5842 21 0 0
v_our_firm 9886 9886 0 0.00 0 64 5 0 0
v_reserve_write_off 19558 19558 0 0.00 0 11946 13 0 0
x_lock_depdocs_on_canc_invoice 11064 11064 0 0.00 0 19078 138 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 4720 316 0 0
x_make_qstorno_1000_1200 5254 5254 0 0.00 3 33813 1253 0 0
x_make_qstorno_1200_2000 5316 5316 0 0.00 4 82960 3053 0 0
x_make_qstorno_2100_3300 440945 440945 0 0.00 0 38562 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2022 07:47:51 to 09-12-2022 07:47:55.

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
05:15:17 7 8192000000 8798431376 8809533440 4473472 0.054 2 1 2 0 4572864 63616 35776
05:16:17 21305 8192000000 9734232944 10035212288 810535168 9.003 100 88 176 0 839567488 7715408 21316912
05:17:44 14226 8192000000 9777486160 10088366080 855901216 9.459 100 88 174 1 888026592 11386256 20739120
05:19:09 16574 8192000000 9810881232 10133618688 881903856 9.719 100 85 170 0 919701664 18469440 19328368
05:20:32 9579 8192000000 9874093408 10207989760 923284112 10.128 100 82 160 1 982385424 41704576 17396736
05:21:47 2777 8192000000 9932829808 10277711872 967181568 10.559 100 94 177 0 1045884016 60600560 18101888
05:22:53 1115 8192000000 9985902768 10339598336 1001107936 10.889 100 90 174 1 1104842432 85954704 17779792
05:23:57 1755 8192000000 10005158272 10371366912 1037933328 11.245 100 88 170 1 1126852624 71878176 17041120
05:25:01 1312 8192000000 10016332912 10385211392 1046814704 11.330 100 86 166 1 1130819312 67940784 16063824
05:26:04 887 8192000000 10021517312 10397757440 1064528320 11.500 100 97 176 1 1141060912 58073424 18459168
05:27:06 1540 8192000000 10043970560 10423967744 1071048544 11.562 100 95 183 1 1165929552 76581936 18299072
05:28:09 1112 8192000000 10049283600 10431889408 1079061280 11.639 100 89 167 2 1171609296 74611904 17936112
05:29:12 1010 8192000000 10060777584 10446192640 1086643968 11.711 100 95 182 0 1182833280 76908224 19281088
05:30:14 1279 8192000000 10058455584 10445516800 1094197600 11.783 100 95 182 3 1183552192 71043312 18311280
05:31:17 1293 8192000000 10078791984 10468777984 1098084048 11.819 100 89 168 2 1204078352 89520208 16474096
05:32:19 1402 8192000000 10090448064 10478792704 1104746848 11.883 100 98 187 1 1213207616 88877440 19583328
05:33:21 1141 8192000000 10083443840 10472345600 1102450864 11.861 100 94 178 1 1205235136 84893136 17891136
05:34:24 6883 8192000000 10057663920 10460422144 1102642864 11.863 100 59 115 0 1182216432 68261280 11312288
05:35:32 2184 8192000000 10059555120 10453061632 1102423696 11.861 100 85 159 1 1177180304 57932688 16823920
05:36:35 1282 8192000000 10031430240 10418192384 1078702832 11.635 100 86 156 3 1152586304 58811168 15072304
05:37:38 1584 8192000000 10025990960 10413752320 1078384976 11.632 99 79 144 2 1145375648 53475024 13515648
05:38:42 1098 8192000000 10034668384 10419220480 1070003072 11.552 100 95 180 3 1160284112 72979008 17302032
05:39:43 1293 8192000000 9986117872 10358685696 1036034848 11.227 99 86 163 3 1108866848 56664416 16167584
05:40:45 7020 8192000000 9954214864 10327236608 1016846416 11.042 99 60 120 1 1078376304 49278096 12251792
05:41:57 1325 8192000000 10005439040 10388492288 1050692736 11.367 100 80 150 5 1128052976 62179840 15180400
05:42:59 1154 8192000000 10013638624 10384388096 1029437248 11.163 100 91 177 1 1130396224 83557136 17401840
05:44:02 1267 8192000000 9988497568 10362851328 1047497104 11.337 100 98 190 2 1113542544 45441840 20603600
05:45:03 1097 8192000000 10016871984 10394464256 1056088496 11.419 99 93 177 2 1139805440 66082320 17634624
05:46:15 10481 8192000000 10032649648 10416328704 1079119488 11.639 100 76 142 0 1159694368 66742544 13832336
05:47:33 7771 8192000000 10066682528 10456846336 1093782208 11.779 100 70 129 0 1182726864 76218752 12725904
05:48:45 1348 8192000000 10071610064 10454044672 1108669888 11.920 100 90 165 1 1186864144 60526992 17667264
05:49:53 770 8192000000 10136027232 10524246016 1118286128 12.011 100 93 177 1 1254589216 117586320 18716768
05:50:58 1476 8192000000 10195235040 10591076352 1127822960 12.101 100 92 173 0 1310291040 163171984 19296096
05:52:04 883 8192000000 10201072000 10590781440 1131106272 12.132 100 98 181 2 1315172608 165149792 18916544
05:53:08 3767 8192000000 10212566080 10616135680 1139516432 12.211 100 65 122 0 1331786128 178993840 13275856
05:54:17 3609 8192000000 10195823760 10624376832 1147393872 12.285 100 71 133 0 1309095632 145891808 15809952
05:55:29 906 8192000000 10227258464 10625847296 1157432096 12.379 100 89 168 2 1340081168 164501376 18147696
05:56:32 787 8192000000 10257003760 10659786752 1166511328 12.464 100 91 166 1 1370140176 186127632 17501216
05:57:37 1174 8192000000 10302369232 10704142336 1161921120 12.421 99 91 173 2 1415558400 233866576 19770704
05:58:43 1991 8192000000 10325146928 10729664512 1173936336 12.534 100 86 168 0 1438536048 245547872 19051840
05:59:57 949 8192000000 10334424880 10762338304 1186038160 12.646 100 97 192 2 1446138192 236890288 23209744
06:01:03 1720 8192000000 10340439248 10760441856 1176572080 12.558 100 95 173 2 1448455904 252109760 19774064
06:02:06 751 8192000000 10324736784 10744999936 1180484000 12.595 100 92 182 0 1437850256 236965616 20400640
06:03:12 1114 8192000000 10348565312 10759233536 1176191024 12.555 100 92 169 4 1457727584 262155440 19381120
06:04:16 1606 8192000000 10361598448 10790027264 1179428608 12.585 100 93 179 4 1469694912 270299888 19966416
06:05:21 643 8192000000 10397971232 10803634176 1183110400 12.619 100 96 184 1 1504622528 300581520 20930608
06:06:26 1112 8192000000 10409608288 10815311872 1173544704 12.530 100 91 173 2 1519378640 325302640 20531296
06:07:32 1230 8192000000 10465833440 10884165632 1184349568 12.631 100 95 187 0 1569824272 362706384 22768320
06:08:36 800 8192000000 10467492416 10888839168 1179207440 12.583 100 96 181 1 1575862016 373872688 22781888
06:09:39 1092 8192000000 10456021200 10891694080 1171132160 12.507 100 99 197 1 1556197872 361335840 23729872
06:10:44 818 8192000000 10471153136 10879045632 1171886688 12.514 100 97 192 1 1573785936 379585184 22314064
06:11:47 852 8192000000 10534549088 10948087808 1187901856 12.664 100 97 182 1 1639086528 429936320 21248352
06:12:49 1104 8192000000 10493769920 10983374848 1176880768 12.561 100 84 156 0 1592709264 396612704 19215792
06:13:57 1301 8192000000 10511016784 10976305152 1186700992 12.653 100 71 138 0 1615575392 411242624 17631776
06:15:04 16568 8192000000 10458693648 10922819584 1188253616 12.667 100 51 96 1 1578905248 378014800 12636832
06:16:32 15465 8192000000 10428468400 10886705152 1173343344 12.528 100 35 70 0 1545529344 363170128 9015872
06:17:58 20036 8192000000 10330225616 10789568512 1148509232 12.296 99 42 84 1 1445665856 287897632 9258992
06:20:23 55726 8192000000 10132150080 10648526848 1135209344 12.170 99 12 24 0 1271532528 133704080 2619104
06:22:20 35995 8192000000 10076523808 10503995392 1118344464 12.011 98 48 93 0 1159530960 31413968 9772528
06:23:59 99076 8192000000 10088767968 10558050304 1081841584 11.665 95 7 14 1 1160890240 77862160 1186496
06:26:39 78547 8192000000 10069242320 10511015936 1124248624 12.067 99 19 38 1 1159848096 31358096 4241376
06:29:22 67842 8192000000 10051314864 10524819456 1088169520 11.725 96 20 40 0 1129661104 37056736 4434848
06:31:45 32269 8192000000 10091131264 10524262400 1124439632 12.069 99 22 42 0 1168400000 39261040 4699328
06:33:18 10320 8192000000 10144166704 10588180480 1135784032 12.176 100 61 112 2 1232441744 84596288 12061424
06:34:32 31805 8192000000 10083529344 10583531520 1138633008 12.203 100 19 38 0 1212803120 69698752 4471360
06:36:12 26945 8192000000 10120825744 10584145920 1143811232 12.251 100 18 37 0 1223156416 74841584 4503600
06:37:49 33988 8192000000 10164953792 10619588608 1135736064 12.175 99 21 43 0 1259936832 119039280 5161488
06:39:32 78962 8192000000 10111409728 10621030400 1132126960 12.141 99 12 24 0 1245727568 110937248 2663360
06:41:52 38601 8192000000 10080487104 10540683264 1135720352 12.175 100 23 46 0 1171748928 30859856 5168720
06:43:43 41709 8192000000 10135158784 10587181056 1146017376 12.272 100 39 74 0 1230838944 77332496 7489072
06:45:26 36225 8192000000 10184573424 10637205504 1141822480 12.233 99 30 58 0 1260129216 111504768 6801968
06:47:15 87278 8192000000 10126473456 10582659072 1069913792 11.551 93 13 26 0 1164159712 91770288 2475632
06:50:15 15997 8192000000 10082799104 10514919424 1122213824 12.048 99 50 96 1 1162820816 30892624 9714368
06:51:35 56279 8192000000 10071532912 10514915328 1079924464 11.647 96 32 64 0 1160958144 74754480 6279200
06:53:37 33320 8192000000 10137279872 10577883136 1122064000 12.046 99 47 91 1 1224208624 93123664 9020960
06:55:14 103016 8192000000 10112893776 10571972608 1092794896 11.769 97 24 46 1 1202506160 104411776 5299488
06:58:03 47898 8192000000 10077522448 10524975104 1085130784 11.696 96 35 63 0 1147953440 56165936 6656720
06:59:51 55546 8192000000 10093090624 10566455296 1106947408 11.904 99 10 20 0 1170222048 61294112 1980528
07:01:48 23781 8192000000 10087842944 10566291456 1118816176 12.016 100 15 29 0 1209255664 87137136 3302352
07:04:02 54499 8192000000 10092460560 10549579776 1126328816 12.087 100 17 34 0 1191749408 62098352 3322240
07:05:56 49576 8192000000 10096883952 10565963776 1125549184 12.079 100 25 50 0 1230425280 99432720 5443376
07:07:54 22706 8192000000 10057389536 10494492672 1090953328 11.752 97 27 52 0 1130819616 34828800 5037488
07:09:19 44144 8192000000 10096787664 10520043520 1081537056 11.662 96 47 93 0 1147745040 56700832 9507152
07:11:03 56445 8192000000 10102697584 10538086400 1124764800 12.072 100 33 66 0 1190106336 58903312 6438224
07:13:05 13546 8192000000 10081294688 10528882688 1103581920 11.872 98 40 76 1 1171314704 60141280 7591504
07:14:25 10986 8192000000 10159039120 10590064640 1125347360 12.077 100 62 117 2 1251413600 113044576 13021664
07:15:37 116613 8192000000 10079436784 10565287936 1109244192 11.925 99 19 38 0 1207061952 93952576 3865184
07:18:40 30766 8192000000 10056567232 10510237696 1098782944 11.826 98 25 48 1 1144352720 40709104 4860672
07:20:42 82518 8192000000 10081783856 10548953088 1124010432 12.065 100 11 22 0 1185613744 59541920 2061392
07:23:05 43683 8192000000 10086187584 10582859776 1121830784 12.044 99 19 32 0 1187116016 62154384 3130848
07:24:49 54889 8192000000 10040862768 10476957696 1091548928 11.757 97 15 30 0 1150227184 55675408 3002848
07:26:50 37082 8192000000 10099602032 10552934400 1127839472 12.101 100 33 64 0 1200760144 66087248 6833424
07:28:47 26888 8192000000 10184453920 10619211776 1131447136 12.135 99 43 83 0 1265261200 124872896 8941168
07:30:20 43804 8192000000 10168860208 10610540544 1138191792 12.199 100 26 52 0 1263508912 119408320 5908800
07:32:04 31170 8192000000 10128186432 10580955136 1112896960 11.960 98 31 60 2 1222036432 102641296 6498176
07:33:37 94064 8192000000 10085097520 10589167616 1142613808 12.240 100 15 30 0 1241860080 96148800 3097472
07:36:12 37688 8192000000 10110064208 10558148608 1128192000 12.104 99 44 85 1 1204229168 67124320 8912848
07:37:49 127044 8192000000 10063497072 10521677824 1101645792 11.853 97 17 34 0 1152075440 47165616 3264032
07:40:58 56453 8192000000 10070214320 10554531840 1110089888 11.933 99 26 52 0 1172043328 56677152 5276288
07:43:02 62621 8192000000 10069782432 10526748672 1108917504 11.922 98 12 24 0 1167458304 56058240 2482560

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-12-2022 07:47:57 to 09-12-2022 07:48:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 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 09-12-2022 07:48:14 to 09-12-2022 07:48:16.

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          215330
MON$OLDEST_ACTIVE               218460
MON$OLDEST_SNAPSHOT             218460
MON$NEXT_TRANSACTION            218460
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                       2067168
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2883 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2883 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2883 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2883 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Fri Dec  9 07:45:15 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Fri Dec  9 07:45:36 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Fri Dec  9 07:45:43 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Fri Dec  9 07:45:43 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:21: 
--- end of diff output ---

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

09.12.22 07:48:17. 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-09 07:48:20 No severe PSQL-related problems occured
09.12.22 07:48:21. End of report.