Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.02.2023 13:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14801344 kB
MemAvailable 30450260 kB
Buffers 0 kB
Cached 16911976 kB
SwapCached 3140 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-02-19 13:37:27
DTS_END                         2023-02-19 13:37:27.9780
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/45130
TRN_ID                          228414
ATT_ID                          679
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: 9254.54 23192 1110545
customer order: creation 180.78 470 21694
customer order: refuse 15.53 401 1863
order to supplier: creation 47.52 1359 5702
order to supplier: removal 3.34 166310 401
invoice (draft): creation 46.48 3076 5578
invoice (draft): removal 51.21 1030 6145
invoice accept: apply 43.53 25051 5223
invoice accept: cancel 46.10 1181 5532
customer reserve: creation 4012.26 241 481471
customer reserve: removal 4508.02 96 540962
realization accept: apply 86.75 273 10410
realization accept: cancel 77.82 163 9338
payment from customer: creation 61.69 174 7403
payment from customer: removal 6.10 112 732
payment to supplier: creation 51.70 176 6204
payment to supplier: removal 3.49 111 419
service: total inventory turnovers 3.93 4120 471
service: total monetary turnovers 7.44 364 893
service: aggregate perf. data 0.83 18690 99
service: refresh index statistics 0.04 240448 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-02-2023 13:38:56 to 19-02-2023 13:38:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6913
TEST_TIME 2 6837
TEST_TIME 3 6070
TEST_TIME 4 6941
TEST_TIME 5 6072
TEST_TIME 6 5723
TEST_TIME 7 5619
TEST_TIME 8 5534
TEST_TIME 9 5694
TEST_TIME 10 6135
TEST_TIME 11 6144
TEST_TIME 12 6701
TEST_TIME 13 6673
TEST_TIME 14 7000
TEST_TIME 15 7029
TEST_TIME 16 7184
TEST_TIME 17 7238
TEST_TIME 18 7381
TEST_TIME 19 7713
TEST_TIME 20 7808
TEST_TIME 21 7787
TEST_TIME 22 8220
TEST_TIME 23 8545
TEST_TIME 24 8522
TEST_TIME 25 8585
TEST_TIME 26 8817
TEST_TIME 27 9236
TEST_TIME 28 9915
TEST_TIME 29 9811
TEST_TIME 30 9918
TEST_TIME 31 10024
TEST_TIME 32 10727
TEST_TIME 33 11140
TEST_TIME 34 11162
TEST_TIME 35 11206
TEST_TIME 36 11298
TEST_TIME 37 11292
TEST_TIME 38 11180
TEST_TIME 39 11132
TEST_TIME 40 10915
TEST_TIME 41 10783
TEST_TIME 42 10938
TEST_TIME 43 10839
TEST_TIME 44 10810
TEST_TIME 45 10697
TEST_TIME 46 10525
TEST_TIME 47 10414
TEST_TIME 48 10390
TEST_TIME 49 10301
TEST_TIME 50 10284
TEST_TIME 51 10252
TEST_TIME 52 10241
TEST_TIME 53 10148
TEST_TIME 54 10121
TEST_TIME 55 10146
TEST_TIME 56 10183
TEST_TIME 57 10243
TEST_TIME 58 10236
TEST_TIME 59 10146
TEST_TIME 60 10170
TEST_TIME 61 10187
TEST_TIME 62 10173
TEST_TIME 63 10199
TEST_TIME 64 10246
TEST_TIME 65 10240
TEST_TIME 66 10186
TEST_TIME 67 10228
TEST_TIME 68 10245
TEST_TIME 69 10215
TEST_TIME 70 10223
TEST_TIME 71 10089
TEST_TIME 72 10077
TEST_TIME 73 9985
TEST_TIME 74 9951
TEST_TIME 75 9970
TEST_TIME 76 9914
TEST_TIME 77 9859
TEST_TIME 78 9823
TEST_TIME 79 9769
TEST_TIME 80 9728
TEST_TIME 81 9812
TEST_TIME 82 9805
TEST_TIME 83 9769
TEST_TIME 84 9719
TEST_TIME 85 9771
TEST_TIME 86 9756
TEST_TIME 87 9785
TEST_TIME 88 9780
TEST_TIME 89 9751
TEST_TIME 90 9787
TEST_TIME 91 9776
TEST_TIME 92 9900
TEST_TIME 93 9870
TEST_TIME 94 9812
TEST_TIME 95 9741
TEST_TIME 96 9718
TEST_TIME 97 9641
TEST_TIME 98 9603
TEST_TIME 99 9595
TEST_TIME 100 9568
TEST_TIME 101 9570
TEST_TIME 102 9584
TEST_TIME 103 9561
TEST_TIME 104 9548
TEST_TIME 105 9522
TEST_TIME 106 9497
TEST_TIME 107 9530
TEST_TIME 108 9641
TEST_TIME 109 9596
TEST_TIME 110 9595
TEST_TIME 111 9596
TEST_TIME 112 9526
TEST_TIME 113 9513
TEST_TIME 114 9491
TEST_TIME 115 9439
TEST_TIME 116 9399
TEST_TIME 117 9374
TEST_TIME 118 9378
TEST_TIME 119 9340
TEST_TIME 120 9280

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-02-2023 13:38:59 to 19-02-2023 13:39:03.

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 1554705 1554705 0 0.00 0 16386 13 0 0
doc_list_biud 1554687 1554687 0 0.00 0 35305 39 0 0
sp_add_invoice_to_stock 5365 5223 142 2.64 65 235189 25052 0 142
sp_cancel_adding_invoice 5532 5532 0 0.00 6 81617 1181 0 0
sp_cancel_client_order 1863 1863 0 0.00 6 22761 402 0 0
sp_cancel_customer_reserve 540962 540962 0 0.00 1 29403 96 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 3 6192 113 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 4 3059 111 0 0
sp_cancel_supplier_invoice 6156 6145 11 0.17 2 35315 1030 0 11
sp_cancel_supplier_order 401 401 0 0.00 11 1565984 166310 0 0
sp_cancel_write_off 9338 9338 0 0.00 2 14670 163 0 0
sp_client_order 21694 21694 0 0.00 5 25759 470 0 0
sp_customer_reserve 481471 481471 0 0.00 1 77362 241 0 0
sp_fill_shopping_cart 32966 32966 0 0.00 1 20619 79 0 0
sp_fill_shopping_cart_clo_res 481436 481436 0 0.00 0 18074 17 0 0
sp_get_clo_for_invoice 5181 5181 0 0.00 0 31485 278 0 0
sp_kill_cost_storno 16021 16021 0 0.00 0 8682 69 0 0
sp_kill_qstorno_ret_qs2qd 1097808 1097808 0 0.00 0 35304 42 0 0
sp_kill_qty_storno 579828 579828 0 0.00 0 35305 97 0 0
sp_lock_selected_doc 21695 21695 0 0.00 0 10902 7 0 0
sp_make_cost_storno 29195 29195 0 0.00 0 11980 76 0 0
sp_multiply_rows_for_pdistr 29194 29194 0 0.00 0 8391 35 0 0
sp_multiply_rows_for_qdistr 21694 21694 0 0.00 0 10522 178 0 0
sp_pay_from_customer 7403 7403 0 0.00 5 14546 174 0 0
sp_pay_to_supplier 6204 6204 0 0.00 6 10057 177 0 0
sp_payment_common 13607 13607 0 0.00 2 9505 42 0 0
sp_qd_handle_on_cancel_clo 1863 1863 0 0.00 1 22749 334 0 0
sp_qd_handle_on_invoice_upd_sts 10710 10710 0 0.00 0 22566 505 0 0
sp_qd_handle_on_reserve_upd_sts 10409 10409 0 0.00 0 21737 68 0 0
sp_reserve_write_off 10410 10410 0 0.00 8 21794 274 0 0
sp_supplier_invoice 5578 5578 0 0.00 9 102988 3076 0 0
sp_supplier_order 5702 5702 0 0.00 7 55015 1359 0 0
srv_aggregate_perf_data 99 99 0 0.00 2411 241432 18691 0 0
srv_fill_mon_cache_memory 68 68 0 0.00 843 112516 36920 0 0
srv_find_qd_qs_mism 1059907 1059907 0 0.00 0 16385 10 0 0
srv_make_invnt_saldo 471 471 0 0.00 91 179910 4121 0 0
srv_make_money_saldo 893 893 0 0.00 1 11241 364 0 0
srv_recalc_idx_stat 5 5 0 0.00 38081 456266 240448 0 0
t$perf-abend:sp_add_invoice_to_stock 142 142 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5228 5228 0 0.00 0 21998 867 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 2 6124 435 0 0
t$perf-norm:sp_cancel_client_order 1863 1863 0 0.00 0 1035 24 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 6677 36 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 2387 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 2858 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 466 466 0 0.00 0 754 26 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 92470 7397 0 0
t$perf-norm:sp_cancel_write_off 1100 1100 0 0.00 0 1610 18 0 0
t$perf-norm:sp_client_order 21694 21694 0 0.00 0 15312 35 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 2219 14 0 0
t$perf-norm:sp_pay_from_customer 7403 7403 0 0.00 0 9548 19 0 0
t$perf-norm:sp_pay_to_supplier 6204 6204 0 0.00 0 5444 20 0 0
t$perf-norm:sp_reserve_write_off 10410 10410 0 0.00 0 6038 23 0 0
t$perf-norm:sp_supplier_invoice 5578 5578 0 0.00 0 17102 36 0 0
t$perf-norm:sp_supplier_order 5702 5702 0 0.00 0 3346 30 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 218 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 68 68 0 0.00 0 16 3 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 2163 17 0 0
t$perf-norm:srv_make_money_saldo 893 893 0 0.00 0 315 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 202 86 0 0
v_add_invoice_to_stock 10638 10354 284 2.66 0 5700 27 0 284
v_all_customers 39794 39794 0 0.00 0 126 4 0 0
v_all_suppliers 31992 31992 0 0.00 0 130 4 0 0
v_all_wares 43382 43382 0 0.00 0 76 4 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 3136 18 0 0
v_cancel_client_order 3726 3726 0 0.00 0 2924 11 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 3424 14 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 1 1889 12 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 950 12 0 0
v_cancel_supplier_invoice 954 932 22 2.30 0 3430 40 0 22
v_cancel_supplier_order 802 802 0 0.00 0 855 12 0 0
v_cancel_write_off 2200 2200 0 0.00 0 3383 26 0 0
v_max_id_clo_ord 5699 5699 0 0.00 0 1651 5 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1739 15 0 0
v_max_id_ord_sup 5574 5574 0 0.00 0 3562 5 0 0
v_max_non_paid_invoice 1481 1481 0 0.00 0 262 7 0 0
v_max_non_paid_realizn 3904 3904 0 0.00 0 1584 7 0 0
v_min_id_clo_ord 5699 5699 0 0.00 0 3465 8 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 7852 18 0 0
v_min_id_ord_sup 5574 5574 0 0.00 0 7932 10 0 0
v_min_non_paid_invoice 6204 6204 0 0.00 0 2944 10 0 0
v_min_non_paid_realizn 7403 7403 0 0.00 0 4801 14 0 0
v_our_firm 10586 10586 0 0.00 0 78 5 0 0
v_reserve_write_off 20818 20818 0 0.00 0 4319 12 0 0
x_lock_depdocs_on_canc_invoice 11211 11211 0 0.00 0 32009 135 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 7082 376 0 0
x_make_qstorno_1000_1200 5702 5702 0 0.00 2 54984 1194 0 0
x_make_qstorno_1200_2000 5578 5578 0 0.00 4 102441 2866 0 0
x_make_qstorno_2100_3300 481471 481471 0 0.00 0 77358 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-02-2023 13:39:04 to 19-02-2023 13:39:08.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:32 4 8192000000 8801364336 8813793280 6560176 0.080 3 1 2 0 6682624 86672 35776
11:08:33 39940 8192000000 9731790336 10032578560 809397088 8.991 100 89 178 0 839478304 8058720 22022496
11:10:29 19113 8192000000 9775570160 10086289408 844747776 9.347 100 90 180 0 880611200 14543312 21320112
11:11:49 11598 8192000000 9808570672 10129342464 876169840 9.662 100 85 169 0 918243472 22708656 19364976
11:13:03 4431 8192000000 9871044304 10206085120 918780624 10.084 100 86 167 0 986628592 49972944 17875024
11:14:13 4411 8192000000 9942952832 10286891008 962390000 10.512 100 91 182 0 1059839360 78559408 18889952
11:15:20 1505 8192000000 9969828544 10323128320 996852896 10.848 100 96 190 1 1088201152 73051696 18296560
11:16:23 1241 8192000000 10005050016 10368946176 1020640752 11.078 100 94 184 3 1125756624 86707552 18408320
11:17:26 1264 8192000000 10004468160 10378178560 1040638416 11.271 100 91 171 1 1126894560 68587424 17668720
11:18:29 1075 8192000000 10037677872 10419167232 1061010912 11.466 100 96 181 1 1160972416 81625616 18335888
11:19:32 1151 8192000000 10036599392 10409492480 1061258272 11.469 100 93 186 0 1155691216 75316224 19116720
11:20:35 1279 8192000000 10055685872 10436792320 1074641856 11.596 100 99 191 2 1180885024 87795008 18448160
11:21:38 1152 8192000000 10044841600 10429665280 1084704912 11.692 100 95 184 0 1167009984 62931136 19373936
11:22:40 1098 8192000000 10057272480 10448613376 1092294080 11.764 100 95 177 2 1178983232 67681440 19007712
11:23:42 897 8192000000 10060893040 10448138240 1100298304 11.840 100 97 188 1 1186622496 67976768 18347424
11:24:44 3873 8192000000 10039149280 10429276160 1098095088 11.820 100 76 145 2 1169577168 58374048 13108032
11:25:48 1193 8192000000 10079822160 10469494784 1102908496 11.865 100 96 186 0 1205043792 82300672 19834624
11:26:50 1285 8192000000 10073481632 10463756288 1105984624 11.894 100 94 178 0 1197117680 72363152 18769904
11:27:52 1157 8192000000 10039844160 10432380928 1101308672 11.850 100 78 152 1 1166210976 49000368 15901936
11:28:54 1102 8192000000 10066465712 10452541440 1100020272 11.838 100 94 183 0 1188685440 70705776 17959392
11:29:57 1260 8192000000 10049801104 10435997696 1088435056 11.728 100 92 179 2 1170733984 64401296 17897632
11:30:59 1139 8192000000 10034419824 10424340480 1090415792 11.747 100 87 170 3 1158443568 49440624 18587152
11:32:01 1184 8192000000 10016457808 10389454848 1052406352 11.384 100 97 188 2 1136096848 65041840 18648656
11:33:03 1223 8192000000 10017489760 10396622848 1055883632 11.417 100 98 187 2 1139280384 64595648 18801104
11:34:05 1224 8192000000 9993374528 10362572800 1027662000 11.146 98 98 184 1 1115279328 69217008 18400320
11:35:07 951 8192000000 9988754224 10362208256 1025590016 11.126 100 87 169 2 1109725616 67270896 16864704
11:36:10 2758 8192000000 9979145184 10358689792 1036805920 11.234 100 72 138 2 1102108496 50732832 14569744
11:37:14 3280 8192000000 9970182400 10342584320 1026706016 11.137 99 77 135 3 1087909584 47928032 13275536
11:38:25 8493 8192000000 10005032848 10387288064 1054172816 11.401 100 73 142 0 1127531024 59327088 14031120
11:39:47 1961 8192000000 10038252416 10417057792 1080805168 11.655 100 88 157 2 1157533424 59676192 17052064
11:40:55 2554 8192000000 10055675904 10442665984 1084071136 11.686 99 75 145 0 1174906432 75212464 15622832
11:42:00 32022 8192000000 10114638016 10513637376 1118106016 12.009 100 62 116 0 1230237072 99819696 12311360
11:43:43 7373 8192000000 10169464800 10571046912 1125024464 12.074 100 64 113 1 1282196352 145586560 11585328
11:44:56 4918 8192000000 10185253920 10590412800 1138715984 12.203 100 55 102 0 1306025360 156430032 10879344
11:46:05 832 8192000000 10263348992 10663919616 1141481744 12.229 100 77 143 1 1373580688 216902448 15196496
11:47:08 1783 8192000000 10299250016 10713976832 1151866080 12.327 100 77 150 0 1416658192 247830416 16961696
11:48:13 1735 8192000000 10336233120 10739789824 1162827200 12.430 100 78 149 1 1447464448 267151632 17485616
11:49:18 1122 8192000000 10346565600 10763649024 1164667984 12.447 100 94 186 0 1453271328 268001072 20602272
11:50:23 1254 8192000000 10357190704 10767335424 1172084576 12.516 100 84 163 1 1474627856 283097040 19446240
11:51:27 1365 8192000000 10383966960 10809126912 1180921616 12.599 100 92 180 1 1493920336 292055856 20942864
11:52:33 1100 8192000000 10394399984 10810068992 1187826128 12.663 100 95 184 2 1501233568 292146064 21261376
11:53:37 1161 8192000000 10422377888 10839642112 1186932848 12.655 100 96 184 1 1530222224 320084704 23204672
11:54:39 1500 8192000000 10460309392 10873987072 1192556096 12.707 100 92 182 2 1563565648 350374384 20635168
11:55:46 1193 8192000000 10488545600 10927902720 1189456288 12.678 100 100 192 0 1594430528 382167760 22806480
11:56:49 1191 8192000000 10504128736 10923122688 1189200256 12.676 100 95 188 2 1614417904 402825520 22392128
11:57:52 837 8192000000 10523800848 10932539392 1185547616 12.642 100 98 188 1 1627898800 419970848 22380336
11:58:55 4798 8192000000 10490335328 10962784256 1163920464 12.440 99 51 100 1 1593543664 415879104 13744096
12:00:02 18826 8192000000 10476719328 10925174784 1155140128 12.358 99 46 91 0 1592933136 425626576 12166432
12:01:29 13657 8192000000 10420632176 10866647040 1137729936 12.194 99 37 74 0 1526224560 378547808 9946816
12:02:54 1132 8192000000 10484133600 10916835328 1146502192 12.277 100 88 161 2 1584575440 419262512 18810736
12:03:56 4135 8192000000 10402325808 10918567936 1160521920 12.408 100 70 136 1 1510224032 331634416 18067696
12:05:07 3538 8192000000 10355448288 10893574144 1167070544 12.469 100 60 120 0 1455452464 273985920 14396000
12:06:12 13302 8192000000 10377941504 10823573504 1179254784 12.583 100 34 68 0 1483129328 295319440 8555104
12:07:27 30317 8192000000 10400461760 10851622912 1175562240 12.549 100 25 50 0 1499135984 316966176 6607568
12:08:58 34037 8192000000 10304949888 10872467456 1145154592 12.264 99 25 50 0 1418356176 266591488 6610096
12:10:41 42117 8192000000 10073166320 10547154944 1121132288 12.038 98 12 24 0 1201620144 77554912 2932944
12:12:33 31639 8192000000 10121778560 10594865152 1147063712 12.282 100 19 36 0 1217045488 66463808 3517968
12:14:06 82585 8192000000 10106896256 10597474304 1114846144 11.978 97 9 18 1 1184727568 68106800 1774624
12:16:35 87989 8192000000 10093037632 10535546880 1132380352 12.144 99 34 62 0 1166916656 28319328 6216976
12:19:10 111410 8192000000 10096793040 10537758720 1085349152 11.698 95 26 52 0 1185650864 94834352 5467360
12:22:04 38151 8192000000 10090812464 10529882112 1137058672 12.188 100 35 69 0 1174396640 30146384 7191584
12:23:47 28035 8192000000 10119197440 10565722112 1148671408 12.297 100 34 64 1 1217451904 62335472 6445024
12:25:22 13092 8192000000 10169586096 10607104000 1134197408 12.161 98 52 99 3 1257584784 113424368 9963008
12:26:38 28039 8192000000 10218868944 10687840256 1157503744 12.380 100 47 94 0 1302124432 134234608 10386080
12:28:12 95581 8192000000 10188610368 10670706688 1167904432 12.477 100 14 27 0 1311846256 140836912 3104912
12:30:48 112516 8192000000 10187104768 10700427264 1131465136 12.135 98 20 39 0 1292877904 156469504 4943264
12:34:48 51872 8192000000 10058767936 10513235968 1117265248 12.001 98 26 45 2 1153323008 30663808 5393952
12:36:43 7531 8192000000 10104293024 10527494144 1129576832 12.117 100 63 124 2 1182465888 40026080 12862976
12:37:51 53560 8192000000 10083924400 10547519488 1081933296 11.666 96 23 46 0 1158204912 71936944 4334672
12:39:59 48598 8192000000 10080946176 10531057664 1117099328 12.000 99 27 55 0 1166403232 44081440 5222464
12:41:49 65660 8192000000 10045613648 10472484864 1073694832 11.587 95 25 46 1 1110620048 31741936 5183280
12:43:55 23728 8192000000 10131192592 10551693312 1093123408 11.772 97 58 114 0 1181046704 75697552 12225744
12:45:19 33866 8192000000 10095522464 10527129600 1101231104 11.849 98 35 70 0 1161337152 52597840 7508208
12:46:56 91359 8192000000 10074832880 10534883328 1112418624 11.955 99 33 66 0 1222383360 102661648 7303088
12:49:46 78283 8192000000 10077785216 10509815808 1080294048 11.650 97 41 81 0 1132306640 43692512 8320080
12:52:17 54462 8192000000 10042275776 10504749056 1084683216 11.692 98 24 46 0 1170917696 81518560 4715920
12:54:15 58846 8192000000 10098574512 10526945280 1103003920 11.866 98 35 70 0 1178659200 67854784 7800496
12:56:16 91815 8192000000 10108612672 10580197376 1129650416 12.118 100 14 28 0 1215271776 82645232 2976128
12:58:48 84627 8192000000 10074049232 10533666816 1116641296 11.995 99 10 20 0 1160330512 41574880 2114336
13:01:14 38323 8192000000 10125767248 10565611520 1138589888 12.202 100 56 110 0 1222944000 72668576 11685536
13:02:58 37413 8192000000 10096567728 10543595520 1132106160 12.141 100 38 76 0 1188120416 47979120 8035136
13:04:38 19794 8192000000 10078530816 10518831104 1127529616 12.098 100 44 88 0 1190886608 54515632 8841360
13:06:03 35614 8192000000 10108557808 10531807232 1125628224 12.080 99 55 104 0 1179577024 42941024 11007776
13:07:41 69122 8192000000 10142771136 10604449792 1130440720 12.126 99 20 40 0 1223575872 88916704 4218448
13:09:51 106292 8192000000 10080394080 10524303360 1109963088 11.932 96 15 28 0 1156318672 43668960 2686624
13:13:15 75921 8192000000 10103517120 10587738112 1138129904 12.198 99 14 26 0 1209909408 69224336 2555168
13:17:43 63678 8192000000 10138345456 10609184768 1120733856 12.034 97 22 44 0 1200490192 75043760 4712576
13:19:51 38113 8192000000 10129193232 10571976704 1141405056 12.229 99 25 50 0 1196495200 49824320 5265824
13:21:29 23973 8192000000 10154080672 10592210944 1150644560 12.316 99 38 76 0 1230703120 71423392 8635168
13:23:02 55108 8192000000 10184990864 10626510848 1109795424 11.930 95 25 50 0 1240770464 124867760 6107280
13:25:04 58120 8192000000 10054036816 10503708672 1072346304 11.574 95 16 30 1 1165706896 90519584 2841008
13:27:03 14468 8192000000 10103056032 10526056448 1122672336 12.052 100 49 98 1 1183915312 51721984 9520992
13:28:23 45829 8192000000 10102648448 10561036288 1119813376 12.025 99 48 92 1 1219657008 90342272 9501360
13:31:01 74309 8192000000 10046660032 10536017920 1106497984 11.899 98 7 14 0 1144205072 36178560 1528528
13:33:53 101324 8192000000 10075805040 10529550336 1095588688 11.796 97 10 20 0 1133797552 35842080 2366784
13:36:46 41453 8192000000 10063218688 10507649024 1099999440 11.838 98 23 44 0 1147685312 42800672 4885200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 19-02-2023 13:39:10 to 19-02-2023 13:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 142 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-02-2023 13:39:27 to 19-02-2023 13:39:29.

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          226525
MON$OLDEST_ACTIVE               228742
MON$OLDEST_SNAPSHOT             228742
MON$NEXT_TRANSACTION            228742
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                       2091968
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Sun Feb 19 13:37:34 2023
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
--- end of diff output ---

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

19.02.23 13:39:30. 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-02-19 13:39:33 No severe PSQL-related problems occured
19.02.23 13:39:34. End of report.