Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2023 10: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 15170152 kB
MemAvailable 30348320 kB
Buffers 0 kB
Cached 16519500 kB
SwapCached 4432 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 2023-01-08 10:37:24
DTS_END                         2023-01-08 10:37:24.7950
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/48204
TRN_ID                          225412
ATT_ID                          612
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: 8976.30 23201 1077156
customer order: creation 174.82 453 20978
customer order: refuse 14.56 372 1747
order to supplier: creation 46.31 1232 5557
order to supplier: removal 3.25 157302 390
invoice (draft): creation 46.05 2866 5526
invoice (draft): removal 49.21 992 5905
invoice accept: apply 42.54 25415 5105
invoice accept: cancel 44.50 1042 5340
customer reserve: creation 3922.70 246 470724
customer reserve: removal 4331.83 92 519820
realization accept: apply 84.78 257 10174
realization accept: cancel 83.56 156 10027
payment from customer: creation 60.32 160 7238
payment from customer: removal 6.06 124 727
payment to supplier: creation 49.98 177 5998
payment to supplier: removal 3.27 162 392
service: total inventory turnovers 4.25 3417 510
service: total monetary turnovers 7.33 339 879
service: aggregate perf. data 0.96 14746 115
service: refresh index statistics 0.03 254466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-01-2023 10:38:42 to 08-01-2023 10:38:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5485
TEST_TIME 2 5457
TEST_TIME 3 6284
TEST_TIME 4 5872
TEST_TIME 5 5913
TEST_TIME 6 6022
TEST_TIME 7 5800
TEST_TIME 8 5975
TEST_TIME 9 5936
TEST_TIME 10 5923
TEST_TIME 11 6393
TEST_TIME 12 6350
TEST_TIME 13 6410
TEST_TIME 14 6519
TEST_TIME 15 6603
TEST_TIME 16 6745
TEST_TIME 17 7175
TEST_TIME 18 7184
TEST_TIME 19 7505
TEST_TIME 20 7787
TEST_TIME 21 8017
TEST_TIME 22 8154
TEST_TIME 23 8175
TEST_TIME 24 8907
TEST_TIME 25 9204
TEST_TIME 26 9638
TEST_TIME 27 9831
TEST_TIME 28 10272
TEST_TIME 29 10405
TEST_TIME 30 10666
TEST_TIME 31 10721
TEST_TIME 32 10612
TEST_TIME 33 10538
TEST_TIME 34 10430
TEST_TIME 35 10706
TEST_TIME 36 10649
TEST_TIME 37 10514
TEST_TIME 38 10499
TEST_TIME 39 10469
TEST_TIME 40 10300
TEST_TIME 41 10094
TEST_TIME 42 10169
TEST_TIME 43 10159
TEST_TIME 44 9985
TEST_TIME 45 9904
TEST_TIME 46 9931
TEST_TIME 47 9889
TEST_TIME 48 9824
TEST_TIME 49 9783
TEST_TIME 50 9750
TEST_TIME 51 9684
TEST_TIME 52 9607
TEST_TIME 53 9533
TEST_TIME 54 9547
TEST_TIME 55 9830
TEST_TIME 56 9904
TEST_TIME 57 9955
TEST_TIME 58 9973
TEST_TIME 59 9958
TEST_TIME 60 10073
TEST_TIME 61 9990
TEST_TIME 62 9931
TEST_TIME 63 9984
TEST_TIME 64 9970
TEST_TIME 65 9930
TEST_TIME 66 9805
TEST_TIME 67 9769
TEST_TIME 68 9689
TEST_TIME 69 9646
TEST_TIME 70 9635
TEST_TIME 71 9572
TEST_TIME 72 9536
TEST_TIME 73 9444
TEST_TIME 74 9393
TEST_TIME 75 9426
TEST_TIME 76 9498
TEST_TIME 77 9467
TEST_TIME 78 9442
TEST_TIME 79 9536
TEST_TIME 80 9520
TEST_TIME 81 9516
TEST_TIME 82 9461
TEST_TIME 83 9616
TEST_TIME 84 9613
TEST_TIME 85 9614
TEST_TIME 86 9525
TEST_TIME 87 9496
TEST_TIME 88 9475
TEST_TIME 89 9387
TEST_TIME 90 9317
TEST_TIME 91 9278
TEST_TIME 92 9268
TEST_TIME 93 9228
TEST_TIME 94 9202
TEST_TIME 95 9175
TEST_TIME 96 9195
TEST_TIME 97 9180
TEST_TIME 98 9122
TEST_TIME 99 9266
TEST_TIME 100 9286
TEST_TIME 101 9261
TEST_TIME 102 9313
TEST_TIME 103 9346
TEST_TIME 104 9299
TEST_TIME 105 9297
TEST_TIME 106 9357
TEST_TIME 107 9302
TEST_TIME 108 9248
TEST_TIME 109 9239
TEST_TIME 110 9218
TEST_TIME 111 9187
TEST_TIME 112 9152
TEST_TIME 113 9089
TEST_TIME 114 9088
TEST_TIME 115 9080
TEST_TIME 116 9088
TEST_TIME 117 9065
TEST_TIME 118 9043
TEST_TIME 119 9027
TEST_TIME 120 8998

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2023 10:38:46 to 08-01-2023 10:38:49.

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 1511149 1511149 0 0.00 0 15363 12 0 0
doc_list_biud 1511136 1511136 0 0.00 0 47814 38 0 0
sp_add_invoice_to_stock 5198 5105 93 1.78 41 206074 25416 0 93
sp_cancel_adding_invoice 5340 5340 0 0.00 5 61988 1043 0 0
sp_cancel_client_order 1747 1747 0 0.00 6 9752 373 0 0
sp_cancel_customer_reserve 519820 519820 0 0.00 1 47815 92 0 0
sp_cancel_pay_from_customer 727 727 0 0.00 4 7362 125 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 4 18271 163 0 0
sp_cancel_supplier_invoice 5919 5905 14 0.23 3 33741 992 0 14
sp_cancel_supplier_order 390 390 0 0.00 12 1260823 157303 0 0
sp_cancel_write_off 10027 10027 0 0.00 1 11161 157 0 0
sp_client_order 20978 20978 0 0.00 5 22648 453 0 0
sp_customer_reserve 470724 470724 0 0.00 0 127550 247 0 0
sp_fill_shopping_cart 32045 32045 0 0.00 1 17577 72 0 0
sp_fill_shopping_cart_clo_res 470695 470695 0 0.00 0 17691 19 0 0
sp_get_clo_for_invoice 5069 5069 0 0.00 0 29235 287 0 0
sp_kill_cost_storno 16486 16486 0 0.00 0 12618 65 0 0
sp_kill_qstorno_ret_qs2qd 1055962 1055962 0 0.00 0 47812 42 0 0
sp_kill_qty_storno 558470 558470 0 0.00 0 47814 95 0 0
sp_lock_selected_doc 20961 20961 0 0.00 0 8314 9 0 0
sp_make_cost_storno 28478 28478 0 0.00 0 13728 70 0 0
sp_multiply_rows_for_pdistr 28477 28477 0 0.00 0 5581 33 0 0
sp_multiply_rows_for_qdistr 20978 20978 0 0.00 0 12395 179 0 0
sp_pay_from_customer 7238 7238 0 0.00 5 15561 160 0 0
sp_pay_to_supplier 5998 5998 0 0.00 6 14854 178 0 0
sp_payment_common 13235 13235 0 0.00 2 5103 39 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 0 9724 308 0 0
sp_qd_handle_on_invoice_upd_sts 10408 10408 0 0.00 0 39934 440 0 0
sp_qd_handle_on_reserve_upd_sts 10173 10173 0 0.00 0 24862 62 0 0
sp_reserve_write_off 10174 10174 0 0.00 8 24889 258 0 0
sp_supplier_invoice 5526 5526 0 0.00 10 81286 2866 0 0
sp_supplier_order 5557 5557 0 0.00 7 19365 1233 0 0
srv_aggregate_perf_data 115 115 0 0.00 2826 276670 14747 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 816 139767 37546 0 0
srv_find_qd_qs_mism 1027646 1027646 0 0.00 0 13347 10 0 0
srv_make_invnt_saldo 510 510 0 0.00 57 61587 3418 0 0
srv_make_money_saldo 879 879 0 0.00 1 15651 340 0 0
srv_recalc_idx_stat 4 4 0 0.00 102368 381968 254466 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5112 5112 0 0.00 1 10079 753 0 0
t$perf-norm:sp_cancel_adding_invoice 396 396 0 0.00 1 5642 360 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 1889 22 0 0
t$perf-norm:sp_cancel_customer_reserve 652 652 0 0.00 0 2124 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 727 727 0 0.00 0 814 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 350 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 905 22 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 64532 5371 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 670 15 0 0
t$perf-norm:sp_client_order 20978 20978 0 0.00 0 12807 32 0 0
t$perf-norm:sp_customer_reserve 1046 1046 0 0.00 0 3175 17 0 0
t$perf-norm:sp_pay_from_customer 7238 7238 0 0.00 0 4994 19 0 0
t$perf-norm:sp_pay_to_supplier 5998 5998 0 0.00 0 5480 18 0 0
t$perf-norm:sp_reserve_write_off 10174 10174 0 0.00 0 4353 20 0 0
t$perf-norm:sp_supplier_invoice 5526 5526 0 0.00 0 3308 27 0 0
t$perf-norm:sp_supplier_order 5558 5558 0 0.00 0 1953 26 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 176 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 166 5 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 859 15 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 698 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 339 111 0 0
v_add_invoice_to_stock 10308 10122 186 1.80 0 14420 23 0 186
v_all_customers 38250 38250 0 0.00 0 98 3 0 0
v_all_suppliers 31130 31130 0 0.00 0 106 4 0 0
v_all_wares 41948 41948 0 0.00 0 142 4 0 0
v_cancel_adding_invoice 792 792 0 0.00 0 1300 13 0 0
v_cancel_client_order 3494 3494 0 0.00 0 2012 10 0 0
v_cancel_customer_prepayment 1454 1454 0 0.00 0 5831 18 0 0
v_cancel_customer_reserve 1304 1304 0 0.00 0 1555 12 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 5595 24 0 0
v_cancel_supplier_invoice 844 816 28 3.31 0 788 20 0 28
v_cancel_supplier_order 780 780 0 0.00 0 1285 10 0 0
v_cancel_write_off 2028 2028 0 0.00 0 6687 27 0 0
v_max_id_clo_ord 5548 5548 0 0.00 0 405 5 0 0
v_max_id_clo_res 1046 1046 0 0.00 0 9269 27 0 0
v_max_id_ord_sup 5519 5519 0 0.00 0 6859 9 0 0
v_max_non_paid_invoice 1497 1497 0 0.00 0 60 7 0 0
v_max_non_paid_realizn 3791 3791 0 0.00 0 3121 8 0 0
v_min_id_clo_ord 5547 5547 0 0.00 0 4580 7 0 0
v_min_id_clo_res 1046 1046 0 0.00 0 6041 15 0 0
v_min_id_ord_sup 5519 5519 0 0.00 0 5837 9 0 0
v_min_non_paid_invoice 5997 5997 0 0.00 0 5097 13 0 0
v_min_non_paid_realizn 7238 7238 0 0.00 0 4498 15 0 0
v_our_firm 10587 10587 0 0.00 0 63 4 0 0
v_reserve_write_off 20346 20346 0 0.00 0 5282 10 0 0
x_lock_depdocs_on_canc_invoice 10837 10837 0 0.00 0 12700 129 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 5176 316 0 0
x_make_qstorno_1000_1200 5557 5557 0 0.00 2 19350 1091 0 0
x_make_qstorno_1200_2000 5526 5526 0 0.00 4 81091 2664 0 0
x_make_qstorno_2100_3300 470723 470723 0 0.00 0 127460 217 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-01-2023 10:38:50 to 08-01-2023 10:38: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
08:07:28 5 8192000000 8807018112 8821202944 11216656 0.136 5 2 3 0 11362864 84848 61360
08:08:29 32977 8192000000 9738264464 10040696832 814120816 9.039 100 90 181 0 843855520 7920704 21814000
08:10:04 26916 8192000000 9771143616 10079903744 842523776 9.325 100 92 184 0 877786352 13250288 22012288
08:11:32 14379 8192000000 9807026368 10128543744 871434416 9.614 100 82 164 0 918770656 28877360 18458880
08:12:52 7307 8192000000 9872917504 10212143104 926864848 10.164 100 76 152 0 984692032 41157872 16669312
08:14:03 4244 8192000000 9937020848 10288410624 975340848 10.639 100 85 159 0 1050284864 57971200 16972816
08:15:12 2735 8192000000 9975375168 10332385280 1007615616 10.952 100 96 187 1 1097958768 71994640 18348512
08:16:17 5595 8192000000 10007814448 10374582272 1023366432 11.104 100 83 162 0 1125260624 86179616 15714576
08:17:24 945 8192000000 10007734448 10375122944 1042844800 11.292 100 96 186 0 1126523216 64781216 18897200
08:18:26 5864 8192000000 10035232256 10404876288 1052079968 11.381 100 98 189 1 1150486688 81159280 17247440
08:19:34 2405 8192000000 10031235680 10416996352 1063381408 11.489 100 86 160 0 1157094384 76304544 17408432
08:20:37 1300 8192000000 10036379616 10417905664 1066242304 11.516 100 95 184 0 1158988160 74307296 18438560
08:21:39 1013 8192000000 10055471744 10436755456 1072980496 11.581 100 97 188 0 1177875664 86306592 18588576
08:22:41 1196 8192000000 10052837152 10437263360 1084345968 11.689 100 97 187 3 1176581184 74113776 18121440
08:23:44 1027 8192000000 10069268624 10458828800 1085293664 11.698 100 92 172 0 1193994560 92148160 16552736
08:24:46 1676 8192000000 10072399632 10456928256 1091114528 11.753 100 96 180 3 1186083232 76471536 18497168
08:25:48 2681 8192000000 10075363440 10478137344 1097602816 11.815 100 67 126 1 1195605856 84807328 13195712
08:26:52 1007 8192000000 10063654352 10452250624 1094351728 11.784 100 89 169 2 1184431104 72723920 17355456
08:27:54 1509 8192000000 10050947296 10440421376 1096205872 11.802 100 82 157 0 1169592912 57734880 15652160
08:28:56 1067 8192000000 10045335040 10433171456 1091034880 11.752 100 95 182 1 1166939920 57560320 18344720
08:29:58 2293 8192000000 10019882048 10410221568 1083001600 11.676 99 76 151 1 1144527616 46079504 15446512
08:31:02 4861 8192000000 10000726288 10382077952 1052394688 11.384 100 71 127 2 1118336928 53100976 12841264
08:32:08 1183 8192000000 10031167792 10406555648 1055401056 11.412 100 96 188 1 1152213936 77766160 19046720
08:33:10 982 8192000000 10014118576 10387738624 1040157120 11.266 100 92 179 1 1138707056 79982256 18567680
08:34:11 1162 8192000000 9998214672 10371936256 1038826176 11.253 100 93 169 4 1123220336 66547360 17846800
08:35:13 1150 8192000000 10008454832 10383155200 1035415200 11.221 100 98 180 0 1130139344 76917472 17806672
08:36:16 1321 8192000000 9992307984 10370068480 1049233568 11.353 100 82 158 2 1118309472 52643120 16432784
08:37:18 2941 8192000000 9985335808 10363518976 1042697712 11.291 99 69 132 2 1102322480 46733840 12890928
08:38:29 7766 8192000000 10003295552 10380369920 1068798560 11.541 100 66 122 1 1122521264 41973584 11749120
08:39:43 11406 8192000000 10048574224 10436386816 1092141616 11.763 100 73 140 0 1162287216 56109536 14036064
08:41:00 4644 8192000000 10099626224 10490118144 1119019600 12.018 100 83 159 0 1216690032 81992528 15677904
08:42:10 1513 8192000000 10138150320 10528079872 1128628432 12.108 100 83 165 0 1261177872 115343504 17205936
08:43:17 2216 8192000000 10189925104 10587258880 1140208240 12.217 100 94 172 2 1299957568 140205456 19543872
08:44:26 11592 8192000000 10217816336 10613968896 1152987792 12.338 100 79 151 0 1327126640 156952192 17186656
08:45:45 1122 8192000000 10249320304 10649972736 1163777952 12.439 100 89 172 1 1369699264 186412992 19508320
08:46:50 3267 8192000000 10259938480 10674151424 1172568928 12.521 100 71 135 0 1372867472 185200304 15098240
08:48:00 9711 8192000000 10270571680 10694131712 1165500336 12.455 99 77 152 1 1386038576 203307376 17230864
08:49:14 3778 8192000000 10294111632 10704080896 1185550496 12.642 100 70 136 1 1410860288 209371584 15938208
08:50:23 1090 8192000000 10365492608 10778247168 1198607104 12.763 100 82 159 0 1478486720 261561840 18317776
08:51:27 1059 8192000000 10405020832 10812604416 1204955936 12.822 100 93 179 2 1517083824 290750880 21377008
08:52:30 872 8192000000 10432400000 10850643968 1201544624 12.791 100 94 182 2 1539180080 316440448 21195008
08:53:33 1961 8192000000 10460304800 10891034624 1213243056 12.899 100 92 173 1 1569517936 336188768 20086112
08:54:37 1032 8192000000 10486203904 10902581248 1208581760 12.856 100 96 185 1 1595263248 363317072 23364416
08:55:41 807 8192000000 10527817008 10952499200 1213713072 12.903 100 93 173 0 1638232304 402781408 21737824
08:56:45 3010 8192000000 10501029824 10919739392 1201195232 12.787 100 79 157 0 1612252832 391517440 19540160
08:57:52 1583 8192000000 10504130080 10927992832 1202151184 12.796 100 75 142 4 1608081104 387908800 18021120
08:58:56 8273 8192000000 10475228816 10963410944 1175741376 12.550 99 54 108 0 1580245456 390676160 13827920
09:00:09 19694 8192000000 10489687984 10928107520 1179101168 12.582 100 37 74 0 1596710320 407461504 10147648
09:01:34 11519 8192000000 10408135424 10918060032 1153067232 12.338 99 39 78 0 1515926528 352033184 10826112
09:02:47 9458 8192000000 10347068672 10794942464 1148839152 12.299 99 35 68 0 1463487120 305952272 8695696
09:04:46 19713 8192000000 10202941936 10707496960 1130243824 12.124 99 28 56 0 1320831920 183895776 6692320
09:06:07 14773 8192000000 10161089408 10610372608 1119164432 12.019 99 27 51 0 1236528912 111868928 5495552
09:07:36 45770 8192000000 10106356832 10624851968 1126352784 12.087 99 9 18 0 1230971664 102709360 1909520
09:09:22 55417 8192000000 10107068608 10548514816 1117964032 12.008 97 20 40 0 1175573696 53388992 4220672
09:11:18 104527 8192000000 10096774496 10624266240 1112262400 11.954 97 22 38 4 1164712336 49361072 3088864
09:14:19 19154 8192000000 10091887936 10520743936 1148481616 12.295 100 42 63 1 1189114816 35295008 5338192
09:15:40 86035 8192000000 10108725040 10557739008 1109539712 11.928 96 16 32 0 1166907728 53822240 3545776
09:18:07 108522 8192000000 10119219280 10590306304 1155636752 12.362 100 35 64 2 1237583104 74897984 7048368
09:21:01 86234 8192000000 10082322416 10501001216 1050697792 11.367 92 75 142 3 1118423872 51969456 15756624
09:23:30 21059 8192000000 10141278128 10562998272 1129474176 12.116 99 47 92 1 1217893056 78515808 9903072
09:24:53 15982 8192000000 10182358720 10602749952 1150492704 12.314 100 65 114 0 1266666704 103849360 12324640
09:26:11 131119 8192000000 10205288752 10686828544 1144468064 12.258 99 28 56 0 1300884688 149203536 7213088
09:29:31 36837 8192000000 10270231456 10719141888 1156452512 12.370 100 31 62 0 1357770000 194326128 6991360
09:31:10 38894 8192000000 10261182320 10743398400 1144003008 12.253 99 29 58 0 1376567168 225671200 6892960
09:32:50 68444 8192000000 10126059696 10579742720 1089240640 11.735 95 20 40 0 1183037504 88809840 4987024
09:35:07 22416 8192000000 10066786624 10507792384 1114859888 11.978 99 37 65 2 1152075632 30535200 6680544
09:36:34 36252 8192000000 10119760528 10561015808 1113899264 11.969 99 27 54 0 1199558320 80425936 5233120
09:38:15 8307 8192000000 10062241152 10482475008 1128350272 12.106 100 66 110 1 1155129568 16403872 10375424
09:39:24 3481 8192000000 10126752144 10547638272 1139228144 12.208 100 64 126 0 1214008848 62761344 12019360
09:40:30 29559 8192000000 10085506592 10514305024 1101137632 11.848 97 42 84 0 1157103712 47765072 8201008
09:42:04 17486 8192000000 10077500144 10515771392 1129860976 12.120 100 50 97 2 1174622240 35353984 9407280
09:43:22 139767 8192000000 10082553120 10540486656 1104494608 11.880 97 30 60 0 1175683632 65220864 5968160
09:46:47 43343 8192000000 10066753152 10537033728 1107107328 11.905 99 33 66 1 1175083312 60957920 7018064
09:49:36 49926 8192000000 10097487008 10550812672 1125867520 12.082 99 11 22 0 1184641664 56346464 2427680
09:51:27 71077 8192000000 10107905584 10589253632 1093197200 11.773 97 14 28 0 1199448960 102882368 3369392
09:53:45 47960 8192000000 10081621312 10512031744 1089448688 11.737 97 31 60 0 1147917968 52262912 6206368
09:55:38 86949 8192000000 10077260320 10577760256 1101919456 11.856 98 27 54 0 1222968928 115214048 5835424
09:58:05 37681 8192000000 10108839856 10570252288 1130533168 12.126 100 45 87 1 1201637808 61868736 9235904
09:59:44 32961 8192000000 10185440960 10652712960 1140976032 12.225 100 39 78 0 1258422832 109288272 8158528
10:01:24 32642 8192000000 10110456272 10540085248 1142776080 12.242 100 36 69 0 1199256160 49299936 7180144
10:03:00 99914 8192000000 10081216848 10513502208 1105061664 11.886 97 38 72 0 1158783248 46307424 7414160
10:06:16 80271 8192000000 10076800176 10539700224 1113486992 11.965 98 10 16 0 1154506016 38985344 2033680
10:09:35 78500 8192000000 10098587024 10555052032 1106133776 11.896 97 19 38 0 1149810640 40143648 3533216
10:11:54 48832 8192000000 10158411696 10611671040 1156727600 12.373 100 26 52 0 1243961696 82121136 5112960
10:13:49 18782 8192000000 10186081264 10638454784 1165861216 12.458 100 28 55 0 1277218752 105607040 5750496
10:15:19 55356 8192000000 10203908816 10673627136 1146537744 12.277 99 31 62 0 1308980256 155226768 7215744
10:17:36 35167 8192000000 10111076032 10543128576 1130802192 12.129 98 46 92 0 1200458048 60611328 9044528
10:19:12 42225 8192000000 10135647184 10622889984 1123909600 12.064 98 27 54 0 1212503952 83172432 5421920
10:20:54 26430 8192000000 10094212832 10516480000 1115592912 11.985 97 40 78 0 1164046976 40854960 7599104
10:22:22 96277 8192000000 10070348928 10514055168 1085493024 11.700 95 16 30 1 1186154272 97178128 3483120
10:25:04 74889 8192000000 10079766848 10542600192 1138875712 12.205 100 33 63 0 1203021728 56405456 7740560
10:27:21 43659 8192000000 10052042544 10520608768 1133600128 12.155 99 12 24 0 1150831136 15137280 2093728
10:29:05 63062 8192000000 10072090992 10529701888 1143610256 12.249 100 12 25 0 1176834800 30854288 2370256
10:31:08 86197 8192000000 10067555664 10569490432 1123411216 12.059 98 8 16 0 1173338544 48208496 1718832
10:33:34 33316 8192000000 10073819920 10522755072 1135002976 12.168 99 19 38 0 1172364544 32961456 4400112
10:35:15 34255 8192000000 10112311200 10548457472 1121282240 12.039 98 22 44 0 1187604080 61435264 4886576
10:36:52 43604 8192000000 9289623344 9652989952 405562752 4.717 35 17 34 0 489835376 80616912 3655712

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-01-2023 10:38:57 to 08-01-2023 10:39:13.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2023 10:39:14 to 08-01-2023 10:39: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          222644
MON$OLDEST_ACTIVE               225732
MON$OLDEST_SNAPSHOT             225732
MON$NEXT_TRANSACTION            225732
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                       2043872
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             782
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Sun Jan  8 10:37:51 2023
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

08.01.23 10:39: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
2023-01-08 10:39:20 No severe PSQL-related problems occured
08.01.23 10:39:21. End of report.