Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.12.2022 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 15024960 kB
MemAvailable 30112836 kB
Buffers 0 kB
Cached 16704916 kB
SwapCached 3264 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-12 10:37:22
DTS_END                         2022-12-12 10:37:22.4700
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/60010
TRN_ID                          220766
ATT_ID                          655
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: 8747.03 20476 1049644
customer order: creation 169.95 459 20394
customer order: refuse 14.64 406 1757
order to supplier: creation 45.68 1283 5481
order to supplier: removal 3.48 142239 418
invoice (draft): creation 45.07 2746 5408
invoice (draft): removal 47.68 947 5722
invoice accept: apply 41.56 27503 4987
invoice accept: cancel 43.52 1300 5222
customer reserve: creation 3829.18 266 459501
customer reserve: removal 4220.20 93 506424
realization accept: apply 82.25 282 9870
realization accept: cancel 75.93 163 9112
payment from customer: creation 58.39 178 7007
payment from customer: removal 6.05 112 726
payment to supplier: creation 47.63 185 5716
payment to supplier: removal 3.55 116 426
service: total inventory turnovers 3.98 4028 478
service: total monetary turnovers 7.24 363 869
service: aggregate perf. data 1.01 14122 121
service: refresh index statistics 0.04 212728 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-12-2022 10:39:03 to 12-12-2022 10:39:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8309
TEST_TIME 2 7595
TEST_TIME 3 7440
TEST_TIME 4 7146
TEST_TIME 5 7289
TEST_TIME 6 6975
TEST_TIME 7 6715
TEST_TIME 8 6729
TEST_TIME 9 6492
TEST_TIME 10 6457
TEST_TIME 11 6563
TEST_TIME 12 6478
TEST_TIME 13 6599
TEST_TIME 14 6786
TEST_TIME 15 6946
TEST_TIME 16 7033
TEST_TIME 17 7029
TEST_TIME 18 7353
TEST_TIME 19 7429
TEST_TIME 20 7732
TEST_TIME 21 8183
TEST_TIME 22 8543
TEST_TIME 23 8712
TEST_TIME 24 9083
TEST_TIME 25 9058
TEST_TIME 26 9417
TEST_TIME 27 9562
TEST_TIME 28 9904
TEST_TIME 29 10159
TEST_TIME 30 10648
TEST_TIME 31 11032
TEST_TIME 32 10952
TEST_TIME 33 10928
TEST_TIME 34 10837
TEST_TIME 35 10736
TEST_TIME 36 10774
TEST_TIME 37 10662
TEST_TIME 38 10536
TEST_TIME 39 10276
TEST_TIME 40 10101
TEST_TIME 41 10125
TEST_TIME 42 10135
TEST_TIME 43 10146
TEST_TIME 44 10005
TEST_TIME 45 9970
TEST_TIME 46 9972
TEST_TIME 47 9854
TEST_TIME 48 9847
TEST_TIME 49 9696
TEST_TIME 50 9584
TEST_TIME 51 9526
TEST_TIME 52 9472
TEST_TIME 53 9341
TEST_TIME 54 9254
TEST_TIME 55 9294
TEST_TIME 56 9358
TEST_TIME 57 9294
TEST_TIME 58 9266
TEST_TIME 59 9393
TEST_TIME 60 9344
TEST_TIME 61 9346
TEST_TIME 62 9450
TEST_TIME 63 9433
TEST_TIME 64 9540
TEST_TIME 65 9497
TEST_TIME 66 9496
TEST_TIME 67 9555
TEST_TIME 68 9518
TEST_TIME 69 9441
TEST_TIME 70 9390
TEST_TIME 71 9393
TEST_TIME 72 9317
TEST_TIME 73 9270
TEST_TIME 74 9178
TEST_TIME 75 9112
TEST_TIME 76 9150
TEST_TIME 77 9084
TEST_TIME 78 9214
TEST_TIME 79 9192
TEST_TIME 80 9146
TEST_TIME 81 9163
TEST_TIME 82 9144
TEST_TIME 83 9136
TEST_TIME 84 9157
TEST_TIME 85 9160
TEST_TIME 86 9289
TEST_TIME 87 9321
TEST_TIME 88 9314
TEST_TIME 89 9257
TEST_TIME 90 9224
TEST_TIME 91 9236
TEST_TIME 92 9172
TEST_TIME 93 9180
TEST_TIME 94 9143
TEST_TIME 95 9100
TEST_TIME 96 9065
TEST_TIME 97 9014
TEST_TIME 98 9007
TEST_TIME 99 8984
TEST_TIME 100 8944
TEST_TIME 101 8943
TEST_TIME 102 8977
TEST_TIME 103 8940
TEST_TIME 104 8941
TEST_TIME 105 8949
TEST_TIME 106 8936
TEST_TIME 107 8970
TEST_TIME 108 9007
TEST_TIME 109 8990
TEST_TIME 110 9016
TEST_TIME 111 9049
TEST_TIME 112 9001
TEST_TIME 113 8936
TEST_TIME 114 8924
TEST_TIME 115 8913
TEST_TIME 116 8870
TEST_TIME 117 8863
TEST_TIME 118 8836
TEST_TIME 119 8817
TEST_TIME 120 8768

Performance per minute, chart


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

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 1473972 1473972 0 0.00 0 20272 14 0 0
doc_list_biud 1473972 1473972 0 0.00 0 48619 38 0 0
sp_add_invoice_to_stock 5101 4987 114 2.23 40 226412 27504 0 114
sp_cancel_adding_invoice 5222 5222 0 0.00 5 113793 1300 0 0
sp_cancel_client_order 1757 1757 0 0.00 6 16445 406 0 0
sp_cancel_customer_reserve 506424 506424 0 0.00 1 48622 93 0 0
sp_cancel_pay_from_customer 726 726 0 0.00 4 4486 112 0 0
sp_cancel_pay_to_supplier 426 426 0 0.00 4 4197 117 0 0
sp_cancel_supplier_invoice 5730 5722 8 0.13 2 27724 948 0 8
sp_cancel_supplier_order 418 418 0 0.00 18 1441311 142240 0 0
sp_cancel_write_off 9112 9112 0 0.00 2 17312 163 0 0
sp_client_order 20394 20394 0 0.00 5 27882 460 0 0
sp_customer_reserve 459501 459501 0 0.00 0 103081 266 0 0
sp_fill_shopping_cart 31273 31273 0 0.00 1 17091 79 0 0
sp_fill_shopping_cart_clo_res 459496 459496 0 0.00 0 14081 19 0 0
sp_get_clo_for_invoice 4945 4945 0 0.00 0 28805 292 0 0
sp_kill_cost_storno 15486 15486 0 0.00 0 9731 70 0 0
sp_kill_qstorno_ret_qs2qd 1028100 1028100 0 0.00 0 48617 41 0 0
sp_kill_qty_storno 543468 543468 0 0.00 0 48619 95 0 0
sp_lock_selected_doc 20648 20648 0 0.00 0 7642 7 0 0
sp_make_cost_storno 27536 27536 0 0.00 0 19345 83 0 0
sp_multiply_rows_for_pdistr 27536 27536 0 0.00 0 8218 35 0 0
sp_multiply_rows_for_qdistr 20394 20394 0 0.00 0 12190 186 0 0
sp_pay_from_customer 7007 7007 0 0.00 5 18473 179 0 0
sp_pay_to_supplier 5716 5716 0 0.00 6 18482 185 0 0
sp_payment_common 12723 12723 0 0.00 2 12696 52 0 0
sp_qd_handle_on_cancel_clo 1757 1757 0 0.00 0 16424 325 0 0
sp_qd_handle_on_invoice_upd_sts 10165 10165 0 0.00 0 35254 500 0 0
sp_qd_handle_on_reserve_upd_sts 9870 9870 0 0.00 0 12722 68 0 0
sp_reserve_write_off 9870 9870 0 0.00 8 20368 283 0 0
sp_supplier_invoice 5408 5408 0 0.00 9 66786 2746 0 0
sp_supplier_order 5481 5481 0 0.00 7 44233 1283 0 0
srv_aggregate_perf_data 121 121 0 0.00 2106 155835 14123 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 749 137934 37213 0 0
srv_find_qd_qs_mism 1001790 1001790 0 0.00 0 16708 12 0 0
srv_make_invnt_saldo 478 478 0 0.00 80 83986 4029 0 0
srv_make_money_saldo 869 869 0 0.00 1 18469 364 0 0
srv_recalc_idx_stat 5 5 0 0.00 66690 314937 212728 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4987 4987 0 0.00 1 13722 899 0 0
t$perf-norm:sp_cancel_adding_invoice 430 430 0 0.00 1 6876 465 0 0
t$perf-norm:sp_cancel_client_order 1757 1757 0 0.00 0 1546 30 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 517 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 726 726 0 0.00 0 936 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 426 426 0 0.00 0 978 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1221 37 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 90105 5811 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 2303 20 0 0
t$perf-norm:sp_client_order 20394 20394 0 0.00 0 10071 33 0 0
t$perf-norm:sp_customer_reserve 1038 1038 0 0.00 0 3418 16 0 0
t$perf-norm:sp_pay_from_customer 7007 7007 0 0.00 0 11300 22 0 0
t$perf-norm:sp_pay_to_supplier 5716 5716 0 0.00 0 17079 23 0 0
t$perf-norm:sp_reserve_write_off 9870 9870 0 0.00 0 17072 26 0 0
t$perf-norm:sp_supplier_invoice 5408 5408 0 0.00 0 12283 32 0 0
t$perf-norm:sp_supplier_order 5481 5481 0 0.00 0 5995 30 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 388 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 238 6 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 466 9 0 0
t$perf-norm:srv_make_money_saldo 869 869 0 0.00 0 312 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 208 66 0 0
v_add_invoice_to_stock 10114 9886 228 2.25 0 10625 25 0 228
v_all_customers 37020 37020 0 0.00 0 88 4 0 0
v_all_suppliers 29814 29814 0 0.00 0 97 4 0 0
v_all_wares 40786 40786 0 0.00 0 95 4 0 0
v_cancel_adding_invoice 860 860 0 0.00 0 229 13 0 0
v_cancel_client_order 3514 3514 0 0.00 0 9618 14 0 0
v_cancel_customer_prepayment 1452 1452 0 0.00 0 468 10 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 1391 11 0 0
v_cancel_payment_to_supplier 852 852 0 0.00 0 2649 13 0 0
v_cancel_supplier_invoice 838 822 16 1.90 0 4095 31 0 16
v_cancel_supplier_order 836 836 0 0.00 0 6946 18 0 0
v_cancel_write_off 2060 2060 0 0.00 0 5649 26 0 0
v_max_id_clo_ord 5481 5481 0 0.00 0 125 5 0 0
v_max_id_clo_res 1038 1038 0 0.00 0 2439 17 0 0
v_max_id_ord_sup 5399 5399 0 0.00 0 7022 9 0 0
v_max_non_paid_invoice 1689 1689 0 0.00 0 4948 10 0 0
v_max_non_paid_realizn 3801 3801 0 0.00 0 7237 12 0 0
v_min_id_clo_ord 5481 5481 0 0.00 0 4974 10 0 0
v_min_id_clo_res 1038 1038 0 0.00 0 7126 15 0 0
v_min_id_ord_sup 5399 5399 0 0.00 0 4157 8 0 0
v_min_non_paid_invoice 5716 5716 0 0.00 0 11160 20 0 0
v_min_non_paid_realizn 7007 7007 0 0.00 0 9164 20 0 0
v_our_firm 10178 10178 0 0.00 0 82 5 0 0
v_reserve_write_off 19740 19740 0 0.00 0 6322 10 0 0
x_lock_depdocs_on_canc_invoice 10533 10533 0 0.00 0 16721 116 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 2512 289 0 0
x_make_qstorno_1000_1200 5481 5481 0 0.00 3 39743 1123 0 0
x_make_qstorno_1200_2000 5408 5408 0 0.00 4 66637 2530 0 0
x_make_qstorno_2100_3300 459501 459501 0 0.00 0 91949 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-12-2022 10:39:11 to 12-12-2022 10:39:15.

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:26 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:28 32362 8192000000 9740851120 10043863040 819610816 9.095 100 87 175 0 849119488 8048400 21460272
08:10:05 11586 8192000000 9782369648 10096795648 858551744 9.486 100 86 172 0 891523392 12686544 20285104
08:11:23 13987 8192000000 9820773424 10145447936 886176560 9.761 100 87 172 0 927134304 21443040 19514704
08:12:44 8733 8192000000 9866681952 10201387008 919823744 10.094 100 85 170 0 982815744 44394912 18597088
08:13:56 3535 8192000000 9920845328 10265108480 958375728 10.473 100 86 166 0 1043538064 67831024 17331312
08:15:02 2554 8192000000 9954223056 10307702784 987220832 10.754 100 86 171 0 1067340448 62368208 17751408
08:16:08 1276 8192000000 9995346736 10355548160 1010209264 10.977 100 90 174 0 1118926400 91683760 17033376
08:17:11 2113 8192000000 10002738880 10373369856 1033868448 11.206 100 82 156 1 1120679264 72528448 14282368
08:18:15 1287 8192000000 10030558720 10404376576 1058082128 11.438 100 97 178 3 1152722448 75740704 18899616
08:19:19 2462 8192000000 10023124192 10395000832 1062569632 11.481 100 94 173 0 1137812960 60032064 15211264
08:20:24 1442 8192000000 10039355280 10418028544 1068670496 11.539 100 93 181 3 1158719872 70834496 19214880
08:21:26 1010 8192000000 10035664640 10414501888 1073986992 11.590 100 97 190 0 1157114512 63662544 19464976
08:22:29 1218 8192000000 10056153104 10440355840 1075883872 11.608 100 94 186 0 1174899552 80031168 18984512
08:23:31 896 8192000000 10048196176 10437545984 1076793424 11.617 100 86 162 2 1172958464 78832928 17332112
08:24:33 998 8192000000 10055388688 10443833344 1082078176 11.667 100 95 187 0 1177666816 76275760 19312880
08:25:34 1078 8192000000 10046392368 10429358080 1082138000 11.668 100 98 191 0 1170220816 68082880 19999936
08:26:37 1635 8192000000 10022305184 10409484288 1073976560 11.590 99 80 157 1 1143604448 52908912 16718976
08:27:39 3111 8192000000 10051795200 10434207744 1079881920 11.646 100 75 140 1 1170210496 77302336 13026240
08:28:43 1182 8192000000 10015098848 10401464320 1073194416 11.583 100 92 171 3 1136770704 47141152 16435136
08:29:45 1194 8192000000 10044192960 10427944960 1075951440 11.609 100 90 173 1 1165933216 72292416 17689360
08:30:49 1133 8192000000 10024341840 10414227456 1066780400 11.521 99 84 152 2 1146409264 63410096 16218768
08:31:51 1578 8192000000 10034080704 10419290112 1076796480 11.617 100 88 167 1 1160795392 65665536 18333376
08:32:53 1041 8192000000 10002063488 10383228928 1051076912 11.371 100 88 168 4 1124383808 55445456 17861440
08:33:55 1057 8192000000 9995983536 10370129920 1035829248 11.225 100 93 174 1 1119570752 64452480 19289024
08:34:57 1045 8192000000 10010675776 10388717568 1049319744 11.354 100 92 172 5 1132480704 66681328 16479632
08:35:59 1169 8192000000 10024983136 10400108544 1042653952 11.290 100 93 179 2 1145644000 84858512 18131536
08:37:01 1055 8192000000 10016578832 10390683648 1052853776 11.388 100 91 170 0 1142165936 70898256 18413904
08:38:06 1198 8192000000 10010302624 10388512768 1074567952 11.596 100 81 147 2 1129380688 41133664 13679072
08:39:13 1306 8192000000 10046163952 10428391424 1080484896 11.652 100 88 159 0 1169083392 72484256 16114240
08:40:21 1205 8192000000 10064151424 10450169856 1093643552 11.777 100 86 165 1 1178761696 68844048 16274096
08:41:34 1466 8192000000 10066134272 10459283456 1105214112 11.887 100 85 152 5 1187768368 65658640 16895616
08:42:39 2289 8192000000 10080306000 10477367296 1111401328 11.946 99 81 150 1 1197206864 70068640 15736896
08:43:45 11416 8192000000 10121352640 10525757440 1129329200 12.115 100 56 112 0 1244363232 102644672 12389360
08:45:01 1292 8192000000 10189734256 10582216704 1157753936 12.382 100 94 174 1 1302162960 125678192 18730832
08:46:05 2083 8192000000 10214398640 10610032640 1167533040 12.474 100 85 144 1 1323060144 141380848 14146256
08:47:16 1646 8192000000 10252073856 10653331456 1178224464 12.574 100 85 150 3 1363059632 169395184 15439984
08:48:21 910 8192000000 10281771232 10676117504 1172026928 12.516 99 93 177 4 1391646128 200560016 19059184
08:49:25 1202 8192000000 10330147072 10742808576 1195215744 12.732 100 97 193 0 1446599952 229774256 21609952
08:50:29 2631 8192000000 10343851808 10751684608 1196336048 12.742 100 95 180 1 1455367408 237914352 21117008
08:51:35 890 8192000000 10384449120 10790924288 1201416064 12.789 100 97 189 1 1492699584 269211328 22072192
08:52:38 899 8192000000 10424411008 10833612800 1204752288 12.820 100 98 189 1 1534162720 306863360 22547072
08:53:40 1053 8192000000 10483335712 10893811712 1202132832 12.796 100 95 185 0 1596714768 372501888 22080048
08:54:43 1649 8192000000 10488466800 10904965120 1184140608 12.629 100 90 173 0 1598669808 393691760 20837440
08:55:48 1306 8192000000 10501154736 10930327552 1194469792 12.725 100 97 194 1 1607302064 389533792 23298480
08:56:53 992 8192000000 10482715360 10942492672 1175978688 12.553 100 97 188 3 1588634144 389582304 23073152
08:57:57 992 8192000000 10468728160 10940837888 1178104464 12.573 100 94 179 0 1575102272 376479136 20518672
08:59:00 820 8192000000 10446779120 10882838528 1163301872 12.434 100 95 186 1 1554062672 368737120 22023680
09:00:04 741 8192000000 10471224992 10902036480 1181327280 12.603 100 86 169 1 1579916864 377852896 20736688
09:01:08 8713 8192000000 10420326560 10906832896 1176618416 12.559 100 52 104 0 1545950208 355903952 13427840
09:02:20 2719 8192000000 10441090576 10876317696 1168505296 12.483 100 49 94 0 1554096944 373795520 11796128
09:03:27 17726 8192000000 10388056944 10871816192 1176257040 12.555 100 39 76 1 1500916512 315502256 9157216
09:04:47 20109 8192000000 10317008592 10871922688 1148227072 12.293 99 35 68 0 1480302512 323385808 8689632
09:06:11 18051 8192000000 10304186640 10768068608 1142519792 12.239 99 42 84 0 1399449152 246720592 10208768
09:07:38 42012 8192000000 10160820448 10692988928 1142516448 12.239 100 14 28 1 1280771632 135280432 2974752
09:09:21 71080 8192000000 10080879616 10568724480 1109322592 11.926 96 14 24 0 1144302768 32757536 2222640
09:11:33 25600 8192000000 10119940800 10551144448 1149859904 12.308 100 51 91 4 1211645472 50814176 10971392
09:13:00 58186 8192000000 10127530400 10587557888 1135785440 12.176 99 29 51 0 1201382688 60481232 5116016
09:15:05 137932 8192000000 10134627328 10602897408 1139819040 12.214 99 35 64 1 1229450784 82949344 6682400
09:18:31 48847 8192000000 10153046768 10605047808 1153979616 12.347 100 47 86 3 1243924544 80509664 9435264
09:20:24 61354 8192000000 10106761904 10572836864 1132429280 12.144 99 11 22 0 1208223824 73029312 2765232
09:22:35 72072 8192000000 10084854576 10587975680 1099816768 11.836 96 16 32 1 1177948576 74490848 3640960
09:24:49 46862 8192000000 10047340976 10497384448 1077028416 11.619 95 27 52 0 1097080320 14616848 5435056
09:26:37 91534 8192000000 10025028560 10498252800 1075064064 11.600 96 22 42 0 1122298608 42929056 4305488
09:29:12 36102 8192000000 10079824240 10528796672 1116663008 11.995 99 35 70 1 1168273552 44316640 7293904
09:30:49 64808 8192000000 10128831632 10598195200 1127378208 12.097 99 22 45 0 1214182768 81206688 5597872
09:33:00 64283 8192000000 10061710752 10543267840 1092097296 11.763 97 15 28 0 1141484672 45965472 3421904
09:35:05 32398 8192000000 10110515696 10548326400 1115265808 11.982 99 39 78 0 1194913712 71011760 8636144
09:36:40 26875 8192000000 10116286960 10579578880 1130074768 12.122 100 29 58 0 1215117536 79176640 5866128
09:38:09 29730 8192000000 10167022752 10623283200 1142234576 12.237 100 34 68 0 1261925584 112460368 7230640
09:39:41 33456 8192000000 10157856560 10606231552 1136417152 12.182 99 30 57 0 1220094912 78103056 5574704
09:41:21 30772 8192000000 10098641328 10532679680 1127066384 12.094 99 39 78 0 1227729520 93193776 7469360
09:42:54 26238 8192000000 10153558112 10588540928 1113375472 11.964 98 40 80 0 1210664816 89397104 7892240
09:44:21 27971 8192000000 10089623584 10509324288 1108870192 11.922 99 51 102 0 1192254432 73335312 10048928
09:45:50 57249 8192000000 10171396400 10611970048 1122126240 12.047 100 65 130 0 1260165248 124603984 13435024
09:48:36 70997 8192000000 10087649920 10545532928 1128389488 12.106 100 15 30 0 1171508304 40184960 2933856
09:50:47 97704 8192000000 10134990016 10595540992 1115546112 11.985 98 29 54 1 1229161632 106672208 6943312
09:53:27 74195 8192000000 10149206896 10639118336 1114912480 11.979 98 14 26 0 1234300816 116372400 3015936
09:56:00 19173 8192000000 10087043088 10518114304 1112916736 11.960 98 37 70 1 1170478768 49522416 8039616
09:57:20 35512 8192000000 10133791888 10573090816 1132563712 12.146 99 33 66 0 1214601376 74178624 7859040
09:59:01 63737 8192000000 10177340336 10617122816 1090991872 11.752 95 22 44 0 1194829744 98853568 4984304
10:01:22 47549 8192000000 10191605920 10628624384 1127266304 12.096 99 30 60 0 1244164736 110685936 6212496
10:03:22 64731 8192000000 10116304016 10572713984 1145671344 12.269 100 35 70 0 1224471536 72196816 6603376
10:05:43 98671 8192000000 10115227760 10582880256 1149650688 12.306 100 22 44 0 1224773872 71003168 4120016
10:08:30 61837 8192000000 10082894064 10548424704 1121714592 12.043 98 18 36 0 1181676256 56011536 3950128
10:10:35 70731 8192000000 10086030816 10574798848 1105445600 11.889 97 11 22 0 1171998176 64138176 2414400
10:12:45 56742 8192000000 10052573456 10513633280 1135984848 12.178 100 17 30 2 1167352528 28156320 3211360
10:14:44 94404 8192000000 10067979568 10539540480 1130934080 12.130 99 11 19 0 1159601376 26702304 1964992
10:17:19 32609 8192000000 10101791648 10546434048 1149375296 12.304 100 39 76 2 1201132240 43880368 7876576
10:18:58 69026 8192000000 10056770896 10497699840 1067269344 11.526 93 18 36 0 1098941408 28281184 3390880
10:21:10 15112 8192000000 10086808048 10519703552 1096167568 11.801 97 52 104 0 1155951280 49367296 10416416
10:22:25 49249 8192000000 10099399104 10549268480 1128172480 12.104 100 38 76 0 1195714576 60214416 7327680
10:24:15 20990 8192000000 10122089392 10564538368 1126920768 12.092 99 45 88 1 1178401920 42856528 8624624
10:25:59 18137 8192000000 10097949856 10523906048 1138973840 12.206 100 39 74 0 1185679712 38966784 7739088
10:27:18 44725 8192000000 10104064432 10552225792 1130087888 12.122 98 33 66 0 1216207856 79633728 6486240
10:29:06 130834 8192000000 10102662576 10568933376 1137026736 12.188 100 15 30 0 1209891056 69423936 3440384
10:32:31 118717 8192000000 10059417040 10521980928 1069822768 11.550 94 15 30 0 1116236176 43365440 3047968
10:35:33 58591 8192000000 10081576208 10526638080 1119531984 12.023 99 21 42 1 1157404592 33299040 4573568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-12-2022 10:39:34 to 12-12-2022 10:39:36.

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          216277
MON$OLDEST_ACTIVE               220930
MON$OLDEST_SNAPSHOT             220930
MON$NEXT_TRANSACTION            220930
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                       2038560
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2884 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2884 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2884 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2884 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 ---
:135: 
:136: mscs-test-161.budzdorov.ru    Mon Dec 12 10:37:29 2022
:137:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:138: 
:139: 
:140: mscs-test-161.budzdorov.ru    Mon Dec 12 10:37:31 2022
:141:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:142: 
--- end of diff output ---

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

12.12.22 10:39:38. 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-12 10:39:40 No severe PSQL-related problems occured
12.12.22 10:39:41. End of report.