Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 15327112 kB
MemAvailable 30427284 kB
Buffers 0 kB
Cached 16405460 kB
SwapCached 4452 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-11 10:37:24
DTS_END                         2023-01-11 10:37:24.8460
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/37662
TRN_ID                          220313
ATT_ID                          619
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: 8806.83 19520 1056820
customer order: creation 176.93 466 21232
customer order: refuse 14.59 374 1751
order to supplier: creation 47.04 1361 5645
order to supplier: removal 3.29 145662 395
invoice (draft): creation 47.74 3132 5729
invoice (draft): removal 47.54 915 5705
invoice accept: apply 42.81 26575 5137
invoice accept: cancel 42.68 1167 5121
customer reserve: creation 3932.51 256 471901
customer reserve: removal 4156.70 91 498804
realization accept: apply 84.21 263 10105
realization accept: cancel 79.73 168 9568
payment from customer: creation 59.48 177 7138
payment from customer: removal 5.95 127 714
payment to supplier: creation 49.32 200 5918
payment to supplier: removal 3.33 134 400
service: total inventory turnovers 4.23 3504 508
service: total monetary turnovers 7.63 321 916
service: aggregate perf. data 1.08 14254 129
service: refresh index statistics 0.03 191255 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-01-2023 10:38:52 to 11-01-2023 10:38:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11512
TEST_TIME 2 8028
TEST_TIME 3 7998
TEST_TIME 4 7509
TEST_TIME 5 7413
TEST_TIME 6 7009
TEST_TIME 7 6969
TEST_TIME 8 6705
TEST_TIME 9 6913
TEST_TIME 10 6882
TEST_TIME 11 7100
TEST_TIME 12 7265
TEST_TIME 13 7325
TEST_TIME 14 7458
TEST_TIME 15 7535
TEST_TIME 16 7432
TEST_TIME 17 7543
TEST_TIME 18 7671
TEST_TIME 19 7801
TEST_TIME 20 7965
TEST_TIME 21 8020
TEST_TIME 22 8265
TEST_TIME 23 8161
TEST_TIME 24 8461
TEST_TIME 25 8540
TEST_TIME 26 8624
TEST_TIME 27 9225
TEST_TIME 28 9625
TEST_TIME 29 9691
TEST_TIME 30 10018
TEST_TIME 31 10454
TEST_TIME 32 10897
TEST_TIME 33 10876
TEST_TIME 34 11039
TEST_TIME 35 10816
TEST_TIME 36 10541
TEST_TIME 37 10447
TEST_TIME 38 10319
TEST_TIME 39 10203
TEST_TIME 40 10142
TEST_TIME 41 10213
TEST_TIME 42 10177
TEST_TIME 43 10161
TEST_TIME 44 10041
TEST_TIME 45 9887
TEST_TIME 46 9840
TEST_TIME 47 9911
TEST_TIME 48 9771
TEST_TIME 49 9652
TEST_TIME 50 9581
TEST_TIME 51 9519
TEST_TIME 52 9385
TEST_TIME 53 9450
TEST_TIME 54 9376
TEST_TIME 55 9340
TEST_TIME 56 9358
TEST_TIME 57 9418
TEST_TIME 58 9364
TEST_TIME 59 9479
TEST_TIME 60 9469
TEST_TIME 61 9533
TEST_TIME 62 9657
TEST_TIME 63 9620
TEST_TIME 64 9607
TEST_TIME 65 9597
TEST_TIME 66 9591
TEST_TIME 67 9480
TEST_TIME 68 9406
TEST_TIME 69 9434
TEST_TIME 70 9346
TEST_TIME 71 9286
TEST_TIME 72 9213
TEST_TIME 73 9157
TEST_TIME 74 9104
TEST_TIME 75 9074
TEST_TIME 76 9192
TEST_TIME 77 9157
TEST_TIME 78 9132
TEST_TIME 79 9174
TEST_TIME 80 9237
TEST_TIME 81 9266
TEST_TIME 82 9287
TEST_TIME 83 9288
TEST_TIME 84 9304
TEST_TIME 85 9294
TEST_TIME 86 9200
TEST_TIME 87 9247
TEST_TIME 88 9151
TEST_TIME 89 9128
TEST_TIME 90 9107
TEST_TIME 91 9056
TEST_TIME 92 9043
TEST_TIME 93 9077
TEST_TIME 94 9070
TEST_TIME 95 9051
TEST_TIME 96 9050
TEST_TIME 97 9009
TEST_TIME 98 8997
TEST_TIME 99 9078
TEST_TIME 100 9054
TEST_TIME 101 9104
TEST_TIME 102 9097
TEST_TIME 103 9075
TEST_TIME 104 9113
TEST_TIME 105 9142
TEST_TIME 106 9200
TEST_TIME 107 9119
TEST_TIME 108 9064
TEST_TIME 109 9068
TEST_TIME 110 9010
TEST_TIME 111 8980
TEST_TIME 112 8956
TEST_TIME 113 8939
TEST_TIME 114 8883
TEST_TIME 115 8911
TEST_TIME 116 8888
TEST_TIME 117 8861
TEST_TIME 118 8869
TEST_TIME 119 8873
TEST_TIME 120 8842

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-01-2023 10:38:56 to 11-01-2023 10:38:59.

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 1489898 1489898 0 0.00 0 18299 13 0 0
doc_list_biud 1489890 1489890 0 0.00 0 34118 36 0 0
sp_add_invoice_to_stock 5227 5137 90 1.72 45 266871 26576 0 90
sp_cancel_adding_invoice 5121 5121 0 0.00 7 77727 1167 0 0
sp_cancel_client_order 1751 1751 0 0.00 6 10911 375 0 0
sp_cancel_customer_reserve 498804 498804 0 0.00 1 26842 91 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 4 3055 128 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 4 6850 135 0 0
sp_cancel_supplier_invoice 5712 5705 7 0.12 2 15266 916 0 7
sp_cancel_supplier_order 395 395 0 0.00 12 1481554 145663 0 0
sp_cancel_write_off 9568 9568 0 0.00 2 34298 169 0 0
sp_client_order 21232 21232 0 0.00 5 31983 466 0 0
sp_customer_reserve 471901 471901 0 0.00 0 110143 257 0 0
sp_fill_shopping_cart 32602 32602 0 0.00 1 16363 88 0 0
sp_fill_shopping_cart_clo_res 471865 471865 0 0.00 0 21523 18 0 0
sp_get_clo_for_invoice 5082 5082 0 0.00 0 41787 287 0 0
sp_kill_cost_storno 15803 15803 0 0.00 0 7815 68 0 0
sp_kill_qstorno_ret_qs2qd 1013276 1013276 0 0.00 0 34106 40 0 0
sp_kill_qty_storno 536531 536531 0 0.00 0 34113 92 0 0
sp_lock_selected_doc 20958 20958 0 0.00 0 5485 8 0 0
sp_make_cost_storno 28243 28243 0 0.00 0 16485 80 0 0
sp_multiply_rows_for_pdistr 28242 28242 0 0.00 0 8617 35 0 0
sp_multiply_rows_for_qdistr 21232 21232 0 0.00 0 10804 183 0 0
sp_pay_from_customer 7138 7138 0 0.00 5 24678 178 0 0
sp_pay_to_supplier 5918 5918 0 0.00 7 12929 201 0 0
sp_payment_common 13055 13055 0 0.00 2 24664 51 0 0
sp_qd_handle_on_cancel_clo 1751 1751 0 0.00 1 10862 306 0 0
sp_qd_handle_on_invoice_upd_sts 10203 10203 0 0.00 0 32099 479 0 0
sp_qd_handle_on_reserve_upd_sts 10105 10105 0 0.00 0 11719 56 0 0
sp_reserve_write_off 10105 10105 0 0.00 8 19873 264 0 0
sp_supplier_invoice 5729 5729 0 0.00 8 77706 3132 0 0
sp_supplier_order 5645 5645 0 0.00 8 68705 1362 0 0
srv_aggregate_perf_data 129 129 0 0.00 2592 187808 14255 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 889 157752 37954 0 0
srv_find_qd_qs_mism 1006135 1006135 0 0.00 0 18297 11 0 0
srv_make_invnt_saldo 508 508 0 0.00 59 49250 3505 0 0
srv_make_money_saldo 916 916 0 0.00 1 7873 321 0 0
srv_recalc_idx_stat 4 4 0 0.00 33807 282726 191256 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5144 5144 0 0.00 1 16049 922 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 1 6874 492 0 0
t$perf-norm:sp_cancel_client_order 1751 1751 0 0.00 0 4560 30 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 1267 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 8710 31 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 532 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 405 405 0 0.00 0 3040 33 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 89086 5414 0 0
t$perf-norm:sp_cancel_write_off 980 980 0 0.00 0 870 17 0 0
t$perf-norm:sp_client_order 21232 21232 0 0.00 0 14572 36 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 1115 15 0 0
t$perf-norm:sp_pay_from_customer 7138 7138 0 0.00 0 13880 27 0 0
t$perf-norm:sp_pay_to_supplier 5918 5918 0 0.00 0 6523 22 0 0
t$perf-norm:sp_reserve_write_off 10105 10105 0 0.00 0 14897 26 0 0
t$perf-norm:sp_supplier_invoice 5729 5729 0 0.00 0 5130 31 0 0
t$perf-norm:sp_supplier_order 5645 5645 0 0.00 0 13908 34 0 0
t$perf-norm:srv_aggregate_perf_data 129 129 0 0.00 0 445 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 151 6 0 0
t$perf-norm:srv_make_invnt_saldo 508 508 0 0.00 0 2230 12 0 0
t$perf-norm:srv_make_money_saldo 916 916 0 0.00 0 749 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 190 67 0 0
v_add_invoice_to_stock 10340 10160 180 1.74 0 4796 25 0 180
v_all_customers 38320 38320 0 0.00 0 190 4 0 0
v_all_suppliers 31336 31336 0 0.00 0 91 4 0 0
v_all_wares 42460 42460 0 0.00 0 85 4 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 237 14 0 0
v_cancel_client_order 3502 3502 0 0.00 0 6673 10 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 2929 15 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 3966 13 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 6672 21 0 0
v_cancel_supplier_invoice 824 810 14 1.69 0 1944 23 0 14
v_cancel_supplier_order 790 790 0 0.00 0 1987 11 0 0
v_cancel_write_off 1960 1960 0 0.00 0 2288 27 0 0
v_max_id_clo_ord 5641 5641 0 0.00 0 223 5 0 0
v_max_id_clo_res 991 991 0 0.00 0 3598 15 0 0
v_max_id_ord_sup 5727 5727 0 0.00 0 12664 9 0 0
v_max_non_paid_invoice 1617 1617 0 0.00 0 373 8 0 0
v_max_non_paid_realizn 3862 3862 0 0.00 0 3915 9 0 0
v_min_id_clo_ord 5641 5641 0 0.00 0 2109 9 0 0
v_min_id_clo_res 991 991 0 0.00 0 3091 16 0 0
v_min_id_ord_sup 5727 5727 0 0.00 0 4284 9 0 0
v_min_non_paid_invoice 5918 5918 0 0.00 0 8072 18 0 0
v_min_non_paid_realizn 7137 7137 0 0.00 0 24655 25 0 0
v_our_firm 10688 10688 0 0.00 0 77 5 0 0
v_reserve_write_off 20210 20210 0 0.00 0 6584 10 0 0
x_lock_depdocs_on_canc_invoice 10421 10421 0 0.00 0 17267 129 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 9167 340 0 0
x_make_qstorno_1000_1200 5645 5645 0 0.00 2 68672 1200 0 0
x_make_qstorno_1200_2000 5729 5729 0 0.00 3 76550 2877 0 0
x_make_qstorno_2100_3300 471899 471899 0 0.00 0 110136 227 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 11-01-2023 10:39:00 to 11-01-2023 10:39:05.

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 919 8192000000 8930578144 8988643328 18314304 0.223 4 3 5 0 18707664 121904 271456
08:08:31 19651 8192000000 9719429984 10021396480 801807408 8.915 100 89 178 0 830353920 7005520 21540992
08:09:57 19752 8192000000 9763805824 10070601728 833592896 9.235 100 91 182 0 868399472 13009904 21796672
08:11:33 13859 8192000000 9811988416 10133524480 869738368 9.597 100 83 164 0 916542608 27960496 18843744
08:12:49 9156 8192000000 9849701552 10185162752 908529792 9.983 100 77 154 0 967971744 42593936 16848016
08:14:02 4787 8192000000 9904936512 10247352320 951104912 10.402 100 87 172 0 1026571744 57449440 18017392
08:15:11 3221 8192000000 9957278496 10305445888 985582880 10.739 100 92 172 1 1076794544 73796496 17415168
08:16:17 1391 8192000000 10001465568 10357366784 1018569936 11.058 100 91 178 1 1118653776 82333872 17749968
08:17:20 1125 8192000000 10019547936 10391846912 1035445952 11.221 100 96 183 0 1142446032 89832576 17167504
08:18:23 949 8192000000 10031577248 10410102784 1053399440 11.393 100 96 184 1 1158377808 86481504 18496864
08:19:24 1001 8192000000 10049996752 10429067264 1067116048 11.525 100 96 181 4 1172129616 85925264 19088304
08:20:26 1319 8192000000 10055198416 10437406720 1070056160 11.553 100 96 190 2 1174607840 85476784 19074896
08:21:29 1016 8192000000 10052939392 10436440064 1083881984 11.684 100 96 173 1 1180188672 78561488 17745200
08:22:31 1216 8192000000 10070098752 10457464832 1083718656 11.683 100 95 187 1 1186259456 83775024 18765776
08:23:33 1296 8192000000 10055578800 10447036416 1085497232 11.700 100 79 152 1 1173768544 73050720 15220592
08:24:35 3769 8192000000 10039606368 10427981824 1094750784 11.788 100 88 171 1 1177712432 64115776 18845872
08:25:40 1019 8192000000 10061434048 10452946944 1094838768 11.789 100 93 171 1 1179158384 67008576 17311040
08:26:42 1314 8192000000 10100734240 10486718464 1091822848 11.760 99 96 183 1 1219990064 109650720 18516496
08:27:44 2958 8192000000 10063218096 10451529728 1096168224 11.801 100 74 139 2 1185614608 75627184 13819200
08:28:48 6751 8192000000 10044321360 10444070912 1096155728 11.801 100 73 142 1 1167192304 57382080 13654496
08:29:56 4458 8192000000 10047973600 10440302592 1081819232 11.665 100 73 143 0 1179669424 83450992 14399200
08:31:01 1629 8192000000 10023658960 10414620672 1067580448 11.529 100 82 160 2 1150262960 65624224 17058288
08:32:04 1067 8192000000 10000387888 10377924608 1040454800 11.269 100 96 178 5 1123093264 65311024 17327440
08:33:07 997 8192000000 10021500944 10399961088 1055166816 11.410 100 86 163 1 1145856016 74725168 15964032
08:34:09 2185 8192000000 9974816592 10353205248 1033669536 11.204 99 72 139 1 1100001648 52405264 13926848
08:35:11 6601 8192000000 10003354592 10396106752 1045803392 11.320 100 63 126 0 1108244352 49885056 12555904
08:36:23 1192 8192000000 9998627024 10383982592 1055839376 11.417 100 75 138 1 1123268416 52763200 14665840
08:37:24 1003 8192000000 10011193168 10381398016 1039281152 11.258 100 96 181 2 1134626256 76958720 18386384
08:38:35 5164 8192000000 10035594480 10422706176 1069568176 11.548 100 74 130 2 1149550032 66300544 13681312
08:39:45 9114 8192000000 10057374640 10450526208 1082880880 11.675 100 89 174 2 1169784368 68688576 18214912
08:40:59 3557 8192000000 10090946288 10478137344 1096521072 11.805 100 80 152 0 1206481712 94255312 15705328
08:42:09 20364 8192000000 10106344496 10512990208 1110668352 11.939 100 58 113 1 1214728096 92410784 11648960
08:43:36 2772 8192000000 10128659936 10529251328 1136500784 12.183 100 72 137 2 1248928576 98411632 14016160
08:44:43 1498 8192000000 10204950432 10602385408 1152482576 12.333 100 87 163 1 1321331456 151459200 17389680
08:45:47 3337 8192000000 10212749968 10620014592 1152988512 12.338 100 71 140 0 1330325632 161228864 16108256
08:46:55 1122 8192000000 10255089280 10652585984 1166296208 12.462 100 88 172 1 1365070272 181076352 17697712
08:47:59 876 8192000000 10296208400 10694868992 1174970288 12.543 100 95 188 0 1411064528 214985392 21108848
08:49:02 2233 8192000000 10312640880 10725715968 1168918400 12.487 100 80 152 0 1420036112 234337632 16780080
08:50:10 1285 8192000000 10352506672 10756911104 1182406688 12.613 100 98 192 2 1466567040 262528624 21631728
08:51:13 1356 8192000000 10377212592 10794438656 1191572960 12.698 100 86 172 0 1486768704 276125488 19070256
08:52:19 1640 8192000000 10379005152 10795888640 1194230000 12.723 100 100 194 2 1486823392 270956032 21637360
08:53:25 9013 8192000000 10401261184 10812035072 1177260320 12.565 99 82 164 1 1508425408 312214096 18950992
08:54:42 1232 8192000000 10459438272 10892439552 1203972544 12.813 100 84 168 1 1570224800 345706368 20545888
08:55:48 1345 8192000000 10467229152 10899025920 1195467312 12.734 100 94 179 0 1575960720 358367952 22125456
08:56:52 1194 8192000000 10484637168 10919469056 1184637424 12.633 99 96 184 1 1590156368 384061136 21457808
08:57:56 1252 8192000000 10519256944 10936389632 1189230976 12.676 100 93 187 1 1624906800 412244304 23431520
08:58:59 1254 8192000000 10534437264 10976665600 1194828352 12.728 100 84 162 2 1645646496 429383568 21434576
09:00:02 5677 8192000000 10536390848 10973454336 1190063392 12.684 100 48 95 0 1647212912 444203920 12945600
09:01:16 2466 8192000000 10557236832 11021754368 1194620864 12.726 100 70 138 1 1678690256 467146960 16922432
09:02:21 2937 8192000000 10551016320 10999058432 1179227440 12.583 100 64 122 1 1661263808 465439760 16596608
09:03:45 3470 8192000000 10523827168 11021230080 1173699920 12.531 100 52 101 1 1630976704 444232064 13044720
09:04:54 16956 8192000000 10459575632 10897584128 1175927312 12.552 100 40 77 0 1583326352 397202464 10196576
09:06:16 50636 8192000000 10345150480 10827464704 1143298816 12.247 98 21 42 0 1460611776 311700864 5612096
09:08:08 29088 8192000000 10221152880 10714447872 1143153840 12.245 99 19 38 0 1347733808 200606672 3973296
09:09:38 37345 8192000000 10121386064 10541723648 1119590176 12.023 97 58 94 3 1192070416 63077088 9403152
09:11:21 117041 8192000000 10083549984 10512293888 1068062160 11.534 93 24 42 2 1094663856 22402448 4199248
09:15:05 31530 8192000000 10125881088 10575278080 1127723808 12.100 99 38 72 1 1223008432 87634016 7650608
09:16:38 70306 8192000000 10155056592 10601627648 1104480480 11.880 97 42 78 0 1184464720 71404416 8579824
09:19:33 86032 8192000000 10072330000 10541297664 1104794960 11.883 96 28 52 1 1165207392 53975952 6436480
09:22:07 43290 8192000000 10107288576 10553675776 1147323120 12.284 100 16 33 0 1203559424 52521328 3714976
09:24:04 34901 8192000000 10105812608 10582880256 1133291264 12.152 99 18 36 0 1190683952 53514720 3877968
09:25:39 64923 8192000000 10078677872 10548891648 1143485984 12.248 100 12 24 0 1206408624 60243264 2679376
09:27:45 4853 8192000000 10049068160 10526765056 1129065984 12.113 99 39 72 0 1162778704 26822992 6889728
09:28:57 50583 8192000000 10106004912 10561429504 1125494816 12.079 98 16 32 0 1179068256 50175824 3397616
09:32:40 64383 8192000000 10153341408 10631864320 1132544864 12.145 98 28 57 0 1244034400 104954880 6534656
09:34:51 47210 8192000000 10132897504 10590396416 1104296512 11.878 98 20 40 0 1220606320 111587696 4722112
09:36:39 48980 8192000000 10115509408 10561581056 1114730112 11.977 98 26 52 0 1186129808 65865232 5534464
09:38:28 70430 8192000000 10066063456 10534486016 1127474800 12.098 99 18 34 0 1206437392 75563632 3398960
09:40:39 11180 8192000000 10085518976 10513207296 1117296912 12.001 99 54 102 1 1175066672 47690256 10079504
09:41:53 33930 8192000000 10099074512 10537418752 1104163216 11.877 98 50 97 0 1167587456 53960208 9464032
09:43:38 85576 8192000000 10125671168 10561294336 1132606896 12.146 100 53 106 0 1214964720 70735808 11622016
09:46:07 44126 8192000000 10049688736 10546749440 1120687088 12.033 99 9 18 0 1185494976 63033696 1774192
09:47:54 81373 8192000000 10066752000 10515365888 1111393008 11.946 98 18 36 0 1158414384 43383136 3638240
09:50:28 77190 8192000000 10122671776 10578591744 1122251568 12.048 99 17 35 0 1211380704 85375472 3753664
09:52:46 59330 8192000000 10120685248 10612051968 1139019088 12.206 100 21 42 1 1237137760 93296752 4821920
09:54:46 67596 8192000000 10110648784 10554060800 1107506464 11.909 97 18 34 2 1163234480 52136576 3591440
09:56:54 55151 8192000000 10073200128 10526273536 1102144544 11.858 97 33 64 0 1203662416 94754192 6763680
09:58:54 28693 8192000000 10103551504 10533642240 1117429552 12.003 99 36 70 1 1187205776 62545376 7230848
10:00:23 28461 8192000000 10109201344 10540740608 1120705264 12.034 99 39 77 0 1194051968 65584704 7762000
10:01:58 57135 8192000000 10099588032 10544971776 1113148064 11.962 98 29 58 0 1166241520 46964208 6129248
10:03:57 103115 8192000000 10093407712 10524971008 1113458880 11.965 98 39 76 0 1197702688 76630944 7612864
10:06:48 85527 8192000000 10088691632 10585415680 1128076080 12.103 99 10 20 0 1213983840 83991728 1916032
10:09:14 60714 8192000000 10096902560 10577825792 1127506848 12.098 99 16 31 0 1209888640 79195104 3186688
10:11:15 55720 8192000000 10078327120 10511052800 1088741296 11.731 95 29 57 0 1123363872 28599520 6023056
10:13:11 42563 8192000000 10104752336 10555551744 1111255328 11.944 98 23 46 0 1178837856 62660320 4922208
10:14:57 44395 8192000000 10161327328 10610266112 1128411712 12.106 99 37 74 0 1224139248 87932832 7794704
10:16:45 25555 8192000000 10096412928 10550198272 1127885520 12.101 99 49 98 0 1186203248 47734640 10583088
10:18:15 32381 8192000000 10125920448 10592542720 1130460016 12.126 100 41 82 0 1242550672 103509904 8580752
10:19:54 17044 8192000000 10106678352 10528661504 1129971184 12.121 99 47 94 0 1170656736 31545600 9139952
10:21:14 33869 8192000000 10194342160 10629373952 1142795488 12.242 100 48 92 0 1270201616 117901664 9504464
10:22:49 157751 8192000000 10039157408 10471354368 1063981264 11.495 94 18 34 1 1094317424 25707264 4628896
10:26:28 111674 8192000000 10044407584 10498772992 1094467312 11.785 98 14 27 0 1136753248 39676368 2609568
10:29:23 69065 8192000000 10064538224 10523611136 1085580272 11.701 97 9 18 0 1128541984 41048288 1913424
10:31:33 75941 8192000000 10051223408 10581442560 1108035600 11.914 99 10 19 0 1196163648 86149808 1978240
10:33:49 54464 8192000000 10063507152 10509897728 1091260720 11.755 98 25 48 1 1150100112 53700720 5138672
10:35:45 65440 8192000000 10098262496 10543149056 1108811056 11.921 99 32 64 0 1196422768 81046608 6565104

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 11-01-2023 10:39:06 to 11-01-2023 10:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-01-2023 10:39:24 to 11-01-2023 10:39:26.

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          220312
MON$OLDEST_ACTIVE               223903
MON$OLDEST_SNAPSHOT             223903
MON$NEXT_TRANSACTION            223903
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                       2036768
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             786
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 ---
:191: 
:192: mscs-test-161.budzdorov.ru    Wed Jan 11 10:37:27 2023
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Wed Jan 11 10:37:27 2023
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Wed Jan 11 10:37:37 2023
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:202: 
--- end of diff output ---

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

11.01.23 10:39:27. 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-11 10:39:30 No severe PSQL-related problems occured
11.01.23 10:39:31. End of report.