Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.12.2022 10:39
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 14604696 kB
MemAvailable 30097804 kB
Buffers 0 kB
Cached 17118140 kB
SwapCached 3424 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-18 10:37:15
DTS_END                         2022-12-18 10:37:15.4490
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/36382
TRN_ID                          221793
ATT_ID                          631
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: 9296.23 23174 1115547
customer order: creation 175.07 479 21008
customer order: refuse 14.81 414 1777
order to supplier: creation 44.75 1337 5370
order to supplier: removal 3.48 191807 418
invoice (draft): creation 45.57 2993 5468
invoice (draft): removal 53.18 955 6381
invoice accept: apply 41.72 25631 5006
invoice accept: cancel 48.55 1233 5826
customer reserve: creation 3850.94 244 462113
customer reserve: removal 4715.63 114 565875
realization accept: apply 82.94 272 9953
realization accept: cancel 88.38 178 10606
payment from customer: creation 59.57 178 7148
payment from customer: removal 5.51 140 661
payment to supplier: creation 49.68 186 5961
payment to supplier: removal 3.67 108 440
service: total inventory turnovers 3.98 4135 478
service: total monetary turnovers 7.88 340 946
service: aggregate perf. data 0.89 15264 107
service: refresh index statistics 0.04 217480 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-12-2022 10:39:46 to 18-12-2022 10:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8633
TEST_TIME 2 7141
TEST_TIME 3 6160
TEST_TIME 4 5775
TEST_TIME 5 5850
TEST_TIME 6 6060
TEST_TIME 7 6401
TEST_TIME 8 6382
TEST_TIME 9 7006
TEST_TIME 10 6748
TEST_TIME 11 6644
TEST_TIME 12 6735
TEST_TIME 13 6744
TEST_TIME 14 6982
TEST_TIME 15 7238
TEST_TIME 16 7266
TEST_TIME 17 7353
TEST_TIME 18 7625
TEST_TIME 19 7651
TEST_TIME 20 7678
TEST_TIME 21 7772
TEST_TIME 22 8000
TEST_TIME 23 7892
TEST_TIME 24 8162
TEST_TIME 25 8242
TEST_TIME 26 8128
TEST_TIME 27 8271
TEST_TIME 28 8389
TEST_TIME 29 8441
TEST_TIME 30 8984
TEST_TIME 31 9271
TEST_TIME 32 9523
TEST_TIME 33 9824
TEST_TIME 34 10220
TEST_TIME 35 10856
TEST_TIME 36 10672
TEST_TIME 37 10567
TEST_TIME 38 10637
TEST_TIME 39 10628
TEST_TIME 40 10566
TEST_TIME 41 10422
TEST_TIME 42 10321
TEST_TIME 43 10162
TEST_TIME 44 10212
TEST_TIME 45 10188
TEST_TIME 46 10066
TEST_TIME 47 10043
TEST_TIME 48 9958
TEST_TIME 49 9909
TEST_TIME 50 9973
TEST_TIME 51 9962
TEST_TIME 52 9944
TEST_TIME 53 9873
TEST_TIME 54 9795
TEST_TIME 55 9729
TEST_TIME 56 9693
TEST_TIME 57 9612
TEST_TIME 58 9558
TEST_TIME 59 9499
TEST_TIME 60 9387
TEST_TIME 61 9334
TEST_TIME 62 9256
TEST_TIME 63 9183
TEST_TIME 64 9492
TEST_TIME 65 9530
TEST_TIME 66 9486
TEST_TIME 67 9590
TEST_TIME 68 9566
TEST_TIME 69 9561
TEST_TIME 70 9643
TEST_TIME 71 9625
TEST_TIME 72 9717
TEST_TIME 73 9757
TEST_TIME 74 9716
TEST_TIME 75 9777
TEST_TIME 76 9706
TEST_TIME 77 9660
TEST_TIME 78 9640
TEST_TIME 79 9615
TEST_TIME 80 9536
TEST_TIME 81 9475
TEST_TIME 82 9426
TEST_TIME 83 9428
TEST_TIME 84 9369
TEST_TIME 85 9322
TEST_TIME 86 9286
TEST_TIME 87 9265
TEST_TIME 88 9241
TEST_TIME 89 9202
TEST_TIME 90 9192
TEST_TIME 91 9162
TEST_TIME 92 9169
TEST_TIME 93 9167
TEST_TIME 94 9248
TEST_TIME 95 9443
TEST_TIME 96 9547
TEST_TIME 97 9570
TEST_TIME 98 9533
TEST_TIME 99 9494
TEST_TIME 100 9474
TEST_TIME 101 9418
TEST_TIME 102 9422
TEST_TIME 103 9367
TEST_TIME 104 9329
TEST_TIME 105 9309
TEST_TIME 106 9291
TEST_TIME 107 9252
TEST_TIME 108 9219
TEST_TIME 109 9225
TEST_TIME 110 9199
TEST_TIME 111 9220
TEST_TIME 112 9228
TEST_TIME 113 9195
TEST_TIME 114 9205
TEST_TIME 115 9208
TEST_TIME 116 9266
TEST_TIME 117 9315
TEST_TIME 118 9407
TEST_TIME 119 9393
TEST_TIME 120 9321

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-12-2022 10:39:50 to 18-12-2022 10:39:53.

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 1543035 1543035 0 0.00 0 28893 14 0 0
doc_list_biud 1543022 1543022 0 0.00 0 44227 47 0 0
sp_add_invoice_to_stock 5155 5006 149 2.89 46 179437 25631 0 149
sp_cancel_adding_invoice 5826 5826 0 0.00 5 73189 1234 0 0
sp_cancel_client_order 1777 1777 0 0.00 6 8641 414 0 0
sp_cancel_customer_reserve 565875 565875 0 0.00 1 19774 114 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 5593 141 0 0
sp_cancel_pay_to_supplier 440 440 0 0.00 3 1212 108 0 0
sp_cancel_supplier_invoice 6395 6381 14 0.21 2 44239 955 0 14
sp_cancel_supplier_order 418 418 0 0.00 12 1289810 191808 0 0
sp_cancel_write_off 10606 10606 0 0.00 2 28954 179 0 0
sp_client_order 21008 21008 0 0.00 5 24915 480 0 0
sp_customer_reserve 462113 462113 0 0.00 1 58348 245 0 0
sp_fill_shopping_cart 31832 31832 0 0.00 1 14778 81 0 0
sp_fill_shopping_cart_clo_res 462074 462074 0 0.00 0 14911 19 0 0
sp_get_clo_for_invoice 4962 4962 0 0.00 0 23389 277 0 0
sp_kill_cost_storno 17533 17533 0 0.00 0 11824 70 0 0
sp_kill_qstorno_ret_qs2qd 1149155 1149155 0 0.00 0 44225 49 0 0
sp_kill_qty_storno 605795 605795 0 0.00 0 44227 111 0 0
sp_lock_selected_doc 20769 20769 0 0.00 0 6891 7 0 0
sp_make_cost_storno 28022 28022 0 0.00 0 15159 75 0 0
sp_multiply_rows_for_pdistr 28020 28020 0 0.00 0 9632 38 0 0
sp_multiply_rows_for_qdistr 21008 21008 0 0.00 0 22536 208 0 0
sp_pay_from_customer 7148 7148 0 0.00 5 16255 179 0 0
sp_pay_to_supplier 5961 5961 0 0.00 6 14955 187 0 0
sp_payment_common 13108 13108 0 0.00 2 16241 50 0 0
sp_qd_handle_on_cancel_clo 1777 1777 0 0.00 0 8594 331 0 0
sp_qd_handle_on_invoice_upd_sts 10787 10787 0 0.00 0 30146 505 0 0
sp_qd_handle_on_reserve_upd_sts 9951 9951 0 0.00 0 12095 56 0 0
sp_reserve_write_off 9953 9953 0 0.00 7 13940 273 0 0
sp_supplier_invoice 5468 5468 0 0.00 10 89532 2994 0 0
sp_supplier_order 5370 5370 0 0.00 7 31923 1337 0 0
srv_aggregate_perf_data 107 107 0 0.00 2459 211364 15264 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 801 146925 31711 0 0
srv_find_qd_qs_mism 1067325 1067325 0 0.00 0 28892 10 0 0
srv_make_invnt_saldo 478 478 0 0.00 86 63919 4135 0 0
srv_make_money_saldo 946 946 0 0.00 1 11532 341 0 0
srv_recalc_idx_stat 5 5 0 0.00 68469 454352 217481 0 0
t$perf-abend:sp_add_invoice_to_stock 149 149 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 5009 5009 0 0.00 1 12563 964 0 0
t$perf-norm:sp_cancel_adding_invoice 447 447 0 0.00 1 7314 502 0 0
t$perf-norm:sp_cancel_client_order 1777 1777 0 0.00 0 1064 26 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 5921 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 4477 22 0 0
t$perf-norm:sp_cancel_pay_to_supplier 440 440 0 0.00 0 417 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 786 32 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 89884 6736 0 0
t$perf-norm:sp_cancel_write_off 990 990 0 0.00 0 1509 22 0 0
t$perf-norm:sp_client_order 21008 21008 0 0.00 0 5604 39 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 688 14 0 0
t$perf-norm:sp_pay_from_customer 7148 7148 0 0.00 0 9106 21 0 0
t$perf-norm:sp_pay_to_supplier 5962 5962 0 0.00 0 3113 19 0 0
t$perf-norm:sp_reserve_write_off 9953 9953 0 0.00 0 5913 24 0 0
t$perf-norm:sp_supplier_invoice 5468 5468 0 0.00 0 3122 31 0 0
t$perf-norm:sp_supplier_order 5370 5370 0 0.00 0 4910 34 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 353 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 501 12 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 2132 16 0 0
t$perf-norm:srv_make_money_saldo 946 946 0 0.00 0 476 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 205 87 0 0
v_add_invoice_to_stock 10206 9908 298 2.91 0 6819 28 0 298
v_all_customers 38486 38486 0 0.00 0 147 4 0 0
v_all_suppliers 30676 30676 0 0.00 0 138 5 0 0
v_all_wares 42008 42008 0 0.00 0 153 4 0 0
v_cancel_adding_invoice 894 894 0 0.00 0 170 15 0 0
v_cancel_client_order 3554 3554 0 0.00 0 3680 14 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 5401 17 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 2481 14 0 0
v_cancel_payment_to_supplier 880 880 0 0.00 0 427 11 0 0
v_cancel_supplier_invoice 838 810 28 3.34 0 1914 25 0 28
v_cancel_supplier_order 836 836 0 0.00 0 2895 18 0 0
v_cancel_write_off 1980 1980 0 0.00 0 2103 24 0 0
v_max_id_clo_ord 5369 5369 0 0.00 0 1953 6 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 1299 13 0 0
v_max_id_ord_sup 5458 5458 0 0.00 0 2419 6 0 0
v_max_non_paid_invoice 1450 1450 0 0.00 0 450 9 0 0
v_max_non_paid_realizn 3673 3673 0 0.00 0 1323 8 0 0
v_min_id_clo_ord 5369 5369 0 0.00 0 739 8 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 5623 18 0 0
v_min_id_ord_sup 5458 5458 0 0.00 0 3885 8 0 0
v_min_non_paid_invoice 5961 5961 0 0.00 0 8237 18 0 0
v_min_non_paid_realizn 7147 7147 0 0.00 0 16227 22 0 0
v_our_firm 10470 10470 0 0.00 0 160 5 0 0
v_reserve_write_off 19902 19902 0 0.00 0 4181 12 0 0
x_lock_depdocs_on_canc_invoice 11802 11802 0 0.00 0 15055 129 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 12814 416 0 0
x_make_qstorno_1000_1200 5370 5370 0 0.00 2 31889 1166 0 0
x_make_qstorno_1200_2000 5468 5468 0 0.00 5 78113 2784 0 0
x_make_qstorno_2100_3300 462113 462113 0 0.00 0 58343 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-12-2022 10:39:54 to 18-12-2022 10:39:58.

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:19 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:20 24303 8192000000 9739659216 10042015744 814908032 9.047 100 91 181 0 844228432 7986656 21333744
08:09:47 18869 8192000000 9775228736 10088431616 852024368 9.420 100 88 176 0 884731232 12382960 20323904
08:11:08 16695 8192000000 9820015712 10141917184 884492720 9.744 100 89 178 0 922267440 17631904 20142816
08:12:31 10590 8192000000 9858854352 10195914752 920316848 10.099 100 80 160 0 972082192 34089504 17675840
08:13:45 2871 8192000000 9912970864 10256044032 956621904 10.456 100 87 172 0 1031039568 56302240 18115424
08:14:51 1542 8192000000 9967966096 10321018880 990047840 10.782 100 96 174 2 1083896256 75960912 17887504
08:15:55 2009 8192000000 9983179840 10345951232 1012216848 10.997 100 87 164 1 1100993328 72930928 15845552
08:16:59 1491 8192000000 9994376576 10359009280 1038063136 11.246 100 91 173 2 1112202240 56925040 17214064
08:18:02 950 8192000000 10016348192 10387939328 1047232096 11.334 100 94 182 4 1132222448 66616144 18374208
08:19:05 1601 8192000000 10007474304 10389258240 1056976864 11.428 100 86 170 0 1139595792 65849968 16768960
08:20:09 1075 8192000000 10066112368 10442129408 1061369712 11.470 100 98 184 0 1184680224 103915520 19394992
08:21:12 1186 8192000000 10048734528 10431148032 1075092880 11.601 100 97 193 1 1163700896 69460096 19147920
08:22:15 1576 8192000000 10049542336 10437120000 1076018336 11.610 100 89 176 0 1175547776 81639520 17889920
08:23:17 1262 8192000000 10044089856 10433093632 1081474688 11.662 100 94 179 0 1165096560 64995296 18626576
08:24:19 1168 8192000000 10057070816 10439831552 1082759296 11.674 100 97 191 1 1178189616 76245408 19184912
08:25:22 1644 8192000000 10058405200 10448617472 1086339136 11.708 100 74 146 2 1177427232 75978032 15110064
08:26:25 1295 8192000000 10068311520 10461138944 1093848048 11.779 100 94 184 0 1189515984 78621344 17046592
08:27:27 1165 8192000000 10053450944 10441748480 1096713728 11.806 100 97 184 0 1172038224 55685920 19638576
08:28:29 1307 8192000000 10060303520 10445643776 1081135840 11.658 100 96 186 0 1178751040 79025472 18589728
08:29:32 1189 8192000000 10054031184 10437398528 1076946800 11.618 100 96 183 1 1177711600 81625600 19139200
08:30:34 1284 8192000000 10033525632 10418135040 1073716000 11.588 100 93 178 2 1154448608 62028048 18704560
08:31:37 1956 8192000000 10019782192 10400129024 1054131712 11.400 100 85 161 0 1147686448 77950800 15603936
08:32:40 1262 8192000000 10024850240 10398265344 1047982112 11.341 100 95 179 1 1151676960 84342960 19351888
08:33:42 1169 8192000000 10008038784 10380591104 1033196112 11.199 100 95 185 1 1132544608 80666560 18681936
08:34:44 1170 8192000000 10002233440 10380062720 1046245408 11.325 100 94 179 2 1127582864 62410032 18927424
08:35:48 1070 8192000000 9996587504 10369019904 1036591424 11.232 100 97 185 1 1120378096 64422944 19363728
08:36:50 2420 8192000000 9990308848 10372730880 1043532432 11.299 100 69 131 1 1104496544 47982464 12981648
08:37:58 5174 8192000000 9991478208 10370650112 1054897488 11.408 99 74 125 1 1112352704 46168256 11286960
08:39:10 3503 8192000000 10018152480 10397360128 1067542880 11.529 100 83 152 1 1138417360 56768768 14105712
08:40:17 27836 8192000000 10058495232 10446671872 1094693328 11.787 100 68 133 1 1182336480 73896624 13746528
08:41:52 3367 8192000000 10078070656 10469740544 1104456432 11.880 100 68 128 0 1195405952 77649728 13299792
08:43:01 1626 8192000000 10115529984 10503782400 1115152400 11.981 100 96 185 1 1234746048 98398160 21195488
08:44:07 1762 8192000000 10135458880 10527993856 1120126864 12.028 100 78 146 1 1250218448 114021344 16070240
08:45:13 1888 8192000000 10155614672 10550960128 1132379232 12.144 100 81 150 2 1269200224 121445920 15375072
08:46:20 2538 8192000000 10155400176 10575478784 1140865568 12.224 100 89 167 1 1277901392 118152752 18883072
08:47:26 11114 8192000000 10171116304 10576228352 1145287520 12.265 100 65 124 5 1279315056 120058080 13969456
08:48:42 4035 8192000000 10212152688 10615250944 1149937136 12.309 100 68 133 1 1325491664 160284256 15270272
08:49:51 847 8192000000 10254537728 10656399360 1158651968 12.391 100 98 189 2 1363980096 182727264 22600864
08:50:59 3219 8192000000 10255545504 10672918528 1166690160 12.466 100 74 136 1 1362002496 179765600 15546736
08:52:06 1908 8192000000 10270923104 10690195456 1173129168 12.526 100 85 162 0 1380562576 189879712 17553696
08:53:12 2609 8192000000 10293666592 10704781312 1177486512 12.567 100 89 176 0 1400034224 203004336 19543376
08:54:23 1143 8192000000 10324594752 10734903296 1184031792 12.628 100 97 189 1 1436233040 228962512 23238736
08:55:28 1123 8192000000 10339017040 10758012928 1181668976 12.606 100 97 186 2 1445755904 241927168 22159760
08:56:31 1329 8192000000 10381380528 10790842368 1193617664 12.717 100 93 186 0 1486522224 271873200 21031360
08:57:35 1130 8192000000 10421009328 10828492800 1188148912 12.666 100 94 186 0 1521555392 312167344 21239136
08:58:38 954 8192000000 10398917552 10840305664 1163730032 12.438 100 95 179 0 1508869264 323434320 21704912
08:59:44 1946 8192000000 10434505824 10838405120 1182299120 12.612 100 92 182 1 1533623568 329530624 21793824
09:00:50 792 8192000000 10514597664 10934980608 1190355120 12.687 100 97 187 1 1620530432 406956048 23219264
09:01:53 1113 8192000000 10491144816 10926837760 1200276832 12.779 100 84 166 1 1596059376 375496160 20286384
09:02:58 5875 8192000000 10525106864 10936496128 1192141360 12.703 100 76 138 3 1626116512 416877776 17097376
09:04:06 1470 8192000000 10561699600 10996588544 1203612752 12.810 100 83 162 0 1665100064 441171312 20316000
09:05:10 7769 8192000000 10589488336 11052359680 1187320384 12.658 100 64 128 0 1693837744 489378624 17138736
09:06:21 1423 8192000000 10629195536 11072860160 1189799728 12.681 100 71 142 0 1732589184 524350544 18438912
09:07:26 9145 8192000000 10514258704 11000745984 1184243792 12.630 100 52 104 0 1634094208 436374752 13475664
09:08:37 7306 8192000000 10492827712 10953822208 1171676688 12.512 100 38 76 0 1594446880 412963584 9806608
09:10:09 34930 8192000000 10362452800 10890027008 1162362128 12.425 99 28 54 0 1536271616 366492400 7417088
09:11:46 65092 8192000000 10075269584 10590171136 1122748096 12.053 99 8 16 0 1215882528 91709104 1425328
09:13:52 12616 8192000000 10094010752 10514554880 1149381408 12.304 100 57 108 0 1184644576 24173248 11089920
09:15:05 101125 8192000000 10084203552 10546941952 1102423552 11.861 95 14 24 1 1161449264 56615296 2410416
09:17:49 49316 8192000000 10130761184 10556776448 1150603840 12.315 99 52 90 2 1211037264 51415936 9017488
09:19:41 136512 8192000000 10119197744 10602291200 1128350000 12.106 98 24 48 0 1210759152 77016240 5392912
09:22:59 40348 8192000000 10085767040 10546778112 1141183424 12.227 100 31 60 0 1183363872 35943712 6236736
09:24:41 30226 8192000000 10095580624 10544992256 1121755472 12.044 98 27 54 0 1179217152 50937936 6523744
09:26:13 15632 8192000000 10125573056 10566492160 1127795648 12.101 99 67 120 0 1209867376 68733248 13338480
09:27:30 27228 8192000000 10132806928 10633535488 1142600256 12.240 100 17 34 0 1228320080 82074928 3644896
09:29:03 23169 8192000000 10090860160 10605457408 1138235040 12.199 100 9 18 0 1195847888 55555936 2056912
09:30:31 2207 8192000000 10046467568 10489200640 1133445600 12.154 100 51 89 3 1152822240 7115792 12260848
09:31:34 29050 8192000000 10093944512 10542379008 1127657664 12.099 99 23 44 1 1172018816 39423728 4937424
09:33:06 110269 8192000000 10075793968 10522423296 1123943680 12.064 99 23 45 0 1173040592 44242992 4853920
09:35:59 32212 8192000000 10087550368 10540310528 1122483312 12.050 98 35 67 0 1175731456 45690256 7557888
09:37:37 75486 8192000000 10119845680 10594275328 1126897088 12.092 99 16 32 0 1195744128 65412944 3434096
09:40:02 66638 8192000000 10154486336 10686152704 1117169424 12.000 97 27 54 0 1298174800 174656320 6349056
09:42:28 28969 8192000000 10095564512 10548375552 1126074048 12.084 98 45 84 0 1176404528 42339584 7990896
09:43:59 8148 8192000000 10129940352 10557739008 1122653168 12.052 99 48 95 0 1228689296 96610336 9425792
09:45:10 10940 8192000000 10110676560 10538659840 1128234896 12.105 100 49 98 0 1202810320 64823408 9752016
09:46:26 70176 8192000000 10089807120 10537967616 1085322448 11.698 96 23 46 0 1142804752 52470480 5011824
09:48:37 66963 8192000000 10109350208 10594041856 1113564160 11.966 98 19 38 0 1207269696 89610016 4095520
09:50:47 45414 8192000000 10110640384 10562052096 1119646016 12.024 99 27 54 0 1179850752 55008912 5195824
09:52:43 87235 8192000000 10079842240 10573955072 1100228720 11.840 97 17 34 1 1170181184 66309440 3643024
09:55:42 25819 8192000000 10059772112 10527498240 1079822768 11.646 97 27 53 1 1128689088 43728496 5137824
09:57:09 73855 8192000000 10058366640 10525040640 1103636560 11.872 98 15 30 0 1139940304 33198656 3105088
09:59:25 75806 8192000000 10054151552 10555199488 1123121392 12.056 100 10 20 0 1191931968 66657264 2153312
10:01:41 40645 8192000000 10032414192 10495971328 1112919712 11.960 99 12 16 0 1139993056 25798432 1274912
10:03:23 29095 8192000000 10117044816 10562056192 1129595584 12.118 99 26 48 1 1201465424 66518352 5351488
10:04:55 25353 8192000000 10157870384 10593193984 1124317232 12.068 98 32 64 0 1226492672 94448784 7726656
10:06:21 14459 8192000000 10191103104 10629386240 1149888272 12.308 100 35 68 0 1282657024 124353888 8414864
10:07:36 3086 8192000000 10220989280 10648489984 1144335600 12.256 100 66 123 1 1315066528 157341648 13389280
10:08:41 9205 8192000000 10268024064 10694307840 1142286000 12.237 100 55 107 1 1357855120 203529520 12039600
10:09:55 38157 8192000000 10305298992 10745790464 1140511968 12.220 99 37 73 1 1371684816 223168880 8003968
10:11:35 65544 8192000000 10198659264 10736148480 1156920048 12.374 100 25 50 0 1402546576 239238208 6388320
10:13:53 70764 8192000000 10086751632 10550882304 1093848768 11.779 96 21 43 1 1161732672 63184944 4698960
10:16:09 146925 8192000000 10107394560 10592280576 1128981024 12.112 99 30 58 1 1212825920 77170336 6674560
10:19:42 52417 8192000000 10077864400 10583490560 1129201104 12.114 99 8 16 0 1174205600 43315136 1689360
10:22:11 65622 8192000000 10075272784 10561486848 1120116960 12.028 98 11 22 0 1184924384 62331600 2475824
10:24:17 48934 8192000000 10123746080 10586677248 1145472352 12.267 100 28 56 1 1219757872 67453600 6831920
10:26:12 45367 8192000000 10163588944 10608156672 1151526272 12.324 100 27 52 0 1266050000 108620896 5902832
10:27:58 35850 8192000000 10174812784 10618019840 1126483776 12.088 98 29 56 1 1247615856 114641072 6491008
10:29:34 28155 8192000000 10187253456 10645221376 1132975792 12.149 99 27 54 0 1284904624 145610336 6318496
10:31:03 30753 8192000000 10234426432 10687946752 1126939440 12.093 98 33 66 1 1293172208 158850192 7382576
10:32:46 29602 8192000000 10212020464 10676936704 1141375296 12.228 100 37 74 0 1326241136 176676976 8188864
10:34:23 40094 8192000000 10152533792 10604498944 1125351488 12.078 99 33 66 0 1240499216 108082272 7065456
10:36:05 98646 8192000000 9746876384 10214563840 808795136 8.985 72 16 32 0 871431760 59416432 3220192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 18-12-2022 10:40:00 to 18-12-2022 10:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 149 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 18-12-2022 10:40:18 to 18-12-2022 10:40:20.

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          222124
MON$OLDEST_ACTIVE               222125
MON$OLDEST_SNAPSHOT             222125
MON$NEXT_TRANSACTION            222125
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                       2079008
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             776
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 ---
--- end of diff output ---

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

18.12.22 10:40:21. 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-18 10:40:23 No severe PSQL-related problems occured
18.12.22 10:40:25. End of report.