Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.10.2022 04: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 15196256 kB
MemAvailable 30493512 kB
Buffers 20 kB
Cached 16484916 kB
SwapCached 2148 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-10-16 04:37:22
DTS_END                         2022-10-16 04:37:22.0620
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/51016
TRN_ID                          221880
ATT_ID                          673
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: 8851.08 19793 1062130
customer order: creation 185.63 464 22275
customer order: refuse 14.78 383 1773
order to supplier: creation 48.08 1323 5770
order to supplier: removal 3.30 153731 396
invoice (draft): creation 48.03 3026 5764
invoice (draft): removal 46.38 984 5565
invoice accept: apply 43.92 27111 5270
invoice accept: cancel 42.15 1307 5058
customer reserve: creation 4041.85 262 485022
customer reserve: removal 4074.49 99 488939
realization accept: apply 87.25 287 10470
realization accept: cancel 79.52 164 9542
payment from customer: creation 61.94 157 7433
payment from customer: removal 5.91 114 709
payment to supplier: creation 51.38 172 6166
payment to supplier: removal 3.41 132 409
service: total inventory turnovers 4.33 3614 520
service: total monetary turnovers 7.68 334 921
service: aggregate perf. data 1.03 14968 124
service: refresh index statistics 0.03 187219 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 16-10-2022 04:39:18 to 16-10-2022 04:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7309
TEST_TIME 2 7207
TEST_TIME 3 6958
TEST_TIME 4 6693
TEST_TIME 5 6833
TEST_TIME 6 6489
TEST_TIME 7 6349
TEST_TIME 8 6561
TEST_TIME 9 6542
TEST_TIME 10 6608
TEST_TIME 11 6780
TEST_TIME 12 6755
TEST_TIME 13 7074
TEST_TIME 14 7218
TEST_TIME 15 7642
TEST_TIME 16 7627
TEST_TIME 17 7711
TEST_TIME 18 8009
TEST_TIME 19 8484
TEST_TIME 20 8643
TEST_TIME 21 8832
TEST_TIME 22 9063
TEST_TIME 23 9057
TEST_TIME 24 9044
TEST_TIME 25 9242
TEST_TIME 26 9271
TEST_TIME 27 9090
TEST_TIME 28 9138
TEST_TIME 29 9471
TEST_TIME 30 10300
TEST_TIME 31 10367
TEST_TIME 32 10790
TEST_TIME 33 11201
TEST_TIME 34 11341
TEST_TIME 35 11279
TEST_TIME 36 11098
TEST_TIME 37 10980
TEST_TIME 38 10700
TEST_TIME 39 10559
TEST_TIME 40 10349
TEST_TIME 41 10316
TEST_TIME 42 10239
TEST_TIME 43 10244
TEST_TIME 44 10369
TEST_TIME 45 10225
TEST_TIME 46 10161
TEST_TIME 47 10131
TEST_TIME 48 10037
TEST_TIME 49 9942
TEST_TIME 50 9901
TEST_TIME 51 9872
TEST_TIME 52 9759
TEST_TIME 53 9855
TEST_TIME 54 9802
TEST_TIME 55 9738
TEST_TIME 56 9707
TEST_TIME 57 9814
TEST_TIME 58 9825
TEST_TIME 59 9800
TEST_TIME 60 9826
TEST_TIME 61 9843
TEST_TIME 62 9794
TEST_TIME 63 9873
TEST_TIME 64 9819
TEST_TIME 65 9783
TEST_TIME 66 9691
TEST_TIME 67 9701
TEST_TIME 68 9643
TEST_TIME 69 9538
TEST_TIME 70 9460
TEST_TIME 71 9428
TEST_TIME 72 9483
TEST_TIME 73 9425
TEST_TIME 74 9404
TEST_TIME 75 9416
TEST_TIME 76 9363
TEST_TIME 77 9389
TEST_TIME 78 9382
TEST_TIME 79 9413
TEST_TIME 80 9509
TEST_TIME 81 9588
TEST_TIME 82 9636
TEST_TIME 83 9614
TEST_TIME 84 9563
TEST_TIME 85 9478
TEST_TIME 86 9481
TEST_TIME 87 9456
TEST_TIME 88 9444
TEST_TIME 89 9395
TEST_TIME 90 9330
TEST_TIME 91 9277
TEST_TIME 92 9200
TEST_TIME 93 9167
TEST_TIME 94 9240
TEST_TIME 95 9236
TEST_TIME 96 9256
TEST_TIME 97 9223
TEST_TIME 98 9203
TEST_TIME 99 9233
TEST_TIME 100 9219
TEST_TIME 101 9221
TEST_TIME 102 9218
TEST_TIME 103 9216
TEST_TIME 104 9207
TEST_TIME 105 9279
TEST_TIME 106 9232
TEST_TIME 107 9162
TEST_TIME 108 9148
TEST_TIME 109 9135
TEST_TIME 110 9088
TEST_TIME 111 9041
TEST_TIME 112 9001
TEST_TIME 113 8985
TEST_TIME 114 8976
TEST_TIME 115 9010
TEST_TIME 116 8992
TEST_TIME 117 8968
TEST_TIME 118 8941
TEST_TIME 119 8916
TEST_TIME 120 8872

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 16-10-2022 04:39:22 to 16-10-2022 04:39:25.

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 1508910 1508910 0 0.00 0 38487 15 0 0
doc_list_biud 1508904 1508904 0 0.00 0 59673 38 0 0
sp_add_invoice_to_stock 5370 5270 100 1.86 67 234958 27112 0 100
sp_cancel_adding_invoice 5058 5058 0 0.00 8 93592 1308 0 0
sp_cancel_client_order 1773 1773 0 0.00 5 11559 384 0 0
sp_cancel_customer_reserve 488939 488939 0 0.00 1 52193 99 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 4 7748 114 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 4 2975 133 0 0
sp_cancel_supplier_invoice 5573 5565 8 0.14 3 59681 984 0 8
sp_cancel_supplier_order 396 396 0 0.00 5 1450782 153732 0 0
sp_cancel_write_off 9542 9542 0 0.00 2 27180 165 0 0
sp_client_order 22275 22275 0 0.00 6 37191 465 0 0
sp_customer_reserve 485022 485022 0 0.00 0 138673 263 0 0
sp_fill_shopping_cart 33806 33806 0 0.00 1 12358 77 0 0
sp_fill_shopping_cart_clo_res 484992 484992 0 0.00 0 14033 17 0 0
sp_get_clo_for_invoice 5219 5219 0 0.00 0 40093 249 0 0
sp_kill_cost_storno 15718 15718 0 0.00 0 6697 67 0 0
sp_kill_qstorno_ret_qs2qd 993381 993381 0 0.00 0 59671 43 0 0
sp_kill_qty_storno 526961 526961 0 0.00 0 59672 99 0 0
sp_lock_selected_doc 21666 21666 0 0.00 0 8302 9 0 0
sp_make_cost_storno 29287 29287 0 0.00 0 38445 78 0 0
sp_multiply_rows_for_pdistr 29287 29287 0 0.00 0 7523 34 0 0
sp_multiply_rows_for_qdistr 22275 22275 0 0.00 0 13745 179 0 0
sp_pay_from_customer 7433 7433 0 0.00 5 12427 157 0 0
sp_pay_to_supplier 6166 6166 0 0.00 7 15152 172 0 0
sp_payment_common 13599 13599 0 0.00 2 7230 43 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 11531 300 0 0
sp_qd_handle_on_invoice_upd_sts 10276 10276 0 0.00 0 28043 492 0 0
sp_qd_handle_on_reserve_upd_sts 10470 10470 0 0.00 0 37979 72 0 0
sp_reserve_write_off 10470 10470 0 0.00 8 38498 287 0 0
sp_supplier_invoice 5764 5764 0 0.00 7 123880 3027 0 0
sp_supplier_order 5770 5770 0 0.00 8 49215 1323 0 0
srv_aggregate_perf_data 124 124 0 0.00 2664 206581 14969 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 712 150274 34214 0 0
srv_find_qd_qs_mism 1010589 1010589 0 0.00 0 27162 13 0 0
srv_make_invnt_saldo 520 520 0 0.00 43 73191 3614 0 0
srv_make_money_saldo 921 921 0 0.00 1 6475 335 0 0
srv_recalc_idx_stat 4 4 0 0.00 53555 321312 187219 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 5271 5271 0 0.00 1 11848 909 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 1 12646 519 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 1726 24 0 0
t$perf-norm:sp_cancel_customer_reserve 707 707 0 0.00 0 1279 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 692 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 450 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 861 26 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 90994 6909 0 0
t$perf-norm:sp_cancel_write_off 1117 1117 0 0.00 0 3198 20 0 0
t$perf-norm:sp_client_order 22275 22275 0 0.00 0 14435 35 0 0
t$perf-norm:sp_customer_reserve 1037 1037 0 0.00 0 853 13 0 0
t$perf-norm:sp_pay_from_customer 7433 7433 0 0.00 0 11874 21 0 0
t$perf-norm:sp_pay_to_supplier 6166 6166 0 0.00 0 3294 18 0 0
t$perf-norm:sp_reserve_write_off 10470 10470 0 0.00 0 7126 21 0 0
t$perf-norm:sp_supplier_invoice 5764 5764 0 0.00 0 12114 31 0 0
t$perf-norm:sp_supplier_order 5770 5770 0 0.00 0 14215 29 0 0
t$perf-norm:srv_aggregate_perf_data 124 124 0 0.00 0 162 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 505 14 0 0
t$perf-norm:srv_make_invnt_saldo 520 520 0 0.00 0 530 11 0 0
t$perf-norm:srv_make_money_saldo 921 921 0 0.00 0 1914 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 10 360 124 0 0
v_add_invoice_to_stock 10632 10432 200 1.88 0 7222 27 0 200
v_all_customers 40522 40522 0 0.00 0 240 4 0 0
v_all_suppliers 32264 32264 0 0.00 0 95 4 0 0
v_all_wares 44550 44550 0 0.00 0 152 4 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 483 13 0 0
v_cancel_client_order 3546 3546 0 0.00 0 4807 12 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 1084 11 0 0
v_cancel_customer_reserve 1414 1414 0 0.00 0 5529 17 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 2290 19 0 0
v_cancel_supplier_invoice 850 834 16 1.88 0 1029 21 0 16
v_cancel_supplier_order 792 792 0 0.00 0 1219 10 0 0
v_cancel_write_off 2234 2234 0 0.00 0 2830 25 0 0
v_max_id_clo_ord 5769 5769 0 0.00 0 89 5 0 0
v_max_id_clo_res 1037 1037 0 0.00 0 2469 17 0 0
v_max_id_ord_sup 5762 5762 0 0.00 0 4286 7 0 0
v_max_non_paid_invoice 1565 1565 0 0.00 0 445 8 0 0
v_max_non_paid_realizn 3886 3886 0 0.00 0 746 7 0 0
v_min_id_clo_ord 5769 5769 0 0.00 0 1031 7 0 0
v_min_id_clo_res 1037 1037 0 0.00 0 2434 12 0 0
v_min_id_ord_sup 5762 5762 0 0.00 0 7411 9 0 0
v_min_non_paid_invoice 6166 6166 0 0.00 0 2946 12 0 0
v_min_non_paid_realizn 7433 7433 0 0.00 0 7223 17 0 0
v_our_firm 11122 11122 0 0.00 0 127 5 0 0
v_reserve_write_off 20940 20940 0 0.00 0 5286 12 0 0
x_lock_depdocs_on_canc_invoice 10206 10206 0 0.00 0 16255 119 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 15408 405 0 0
x_make_qstorno_1000_1200 5770 5770 0 0.00 2 49206 1168 0 0
x_make_qstorno_1200_2000 5764 5764 0 0.00 3 123866 2824 0 0
x_make_qstorno_2100_3300 485022 485022 0 0.00 0 138670 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-10-2022 04:39:26 to 16-10-2022 04:39:30.

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
02:07:26 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:26 16379 8192000000 9705411904 10003001344 790168976 8.797 100 89 178 0 820302320 8118464 22014880
02:09:49 20313 8192000000 9756253488 10059608064 823349872 9.132 100 85 170 0 857190160 12465184 21375104
02:11:12 17834 8192000000 9779826720 10092609536 842719936 9.327 100 85 168 0 884112112 21286976 20105200
02:12:51 6351 8192000000 9848610208 10178424832 902276448 9.921 100 84 166 0 961050848 40547664 18226736
02:14:00 5764 8192000000 9907281280 10249789440 940249344 10.295 100 82 164 0 1027391056 69923424 17218288
02:15:08 1994 8192000000 9965490912 10319478784 980659760 10.691 100 88 170 0 1082614704 85018256 16936688
02:16:14 1646 8192000000 9997763248 10358517760 1017383824 11.047 100 88 164 1 1110912496 77498064 16030608
02:17:17 870 8192000000 10011656688 10382594048 1041475872 11.279 100 95 177 1 1131354368 72531024 17347472
02:18:19 1019 8192000000 10012243024 10385575936 1055677584 11.415 100 99 192 3 1132919296 57569024 19672688
02:19:21 1018 8192000000 10020037216 10394030080 1063041920 11.486 100 95 189 3 1139555664 58181776 18331968
02:20:23 941 8192000000 10038803824 10423107584 1070192640 11.554 100 84 166 0 1160967136 74531104 16243392
02:21:25 1276 8192000000 10072358608 10461597696 1083578656 11.682 100 86 160 4 1191452240 92555024 15318560
02:22:27 1111 8192000000 10044236976 10431483904 1088225840 11.726 100 96 184 1 1169181840 61232064 19723936
02:23:29 3775 8192000000 10055185376 10443857920 1094408912 11.785 100 82 152 5 1186595408 77600272 14586224
02:24:34 887 8192000000 10059564000 10448621568 1094036528 11.781 100 87 164 1 1181512688 71024000 16452160
02:25:35 1208 8192000000 10073060928 10458931200 1100682576 11.844 100 97 185 1 1196084016 76804832 18596608
02:26:37 1174 8192000000 10090156896 10480103424 1101612992 11.853 100 99 186 5 1209964288 88517504 19833792
02:27:40 1013 8192000000 10062397536 10454876160 1099690992 11.835 100 93 178 0 1187120048 68799888 18629168
02:28:42 1307 8192000000 10049629488 10435792896 1080620416 11.653 99 93 177 2 1173877856 74277264 18980176
02:29:44 948 8192000000 10025665664 10400804864 1053914544 11.398 100 98 191 0 1147276432 75077968 18283920
02:30:47 1698 8192000000 10036386144 10418991104 1061950544 11.475 99 87 161 2 1161080176 82743904 16385728
02:31:50 1418 8192000000 10025953328 10406998016 1059926640 11.456 99 79 137 2 1145156144 72475200 12754304
02:32:52 893 8192000000 10011516128 10389180416 1050239040 11.363 100 91 162 1 1132903936 67079360 15585536
02:33:54 854 8192000000 10007429712 10387230720 1055517568 11.414 100 90 176 0 1125755408 52921616 17316224
02:34:56 1278 8192000000 10018637808 10399473664 1058264688 11.440 100 94 184 1 1139330080 62742192 18323200
02:35:59 1193 8192000000 10016682144 10392551424 1047312352 11.335 100 98 185 0 1139615920 73525136 18778432
02:37:01 2175 8192000000 9999665040 10381778944 1058966000 11.447 100 70 128 2 1123377104 51296224 13114880
02:38:10 2232 8192000000 10038892080 10417262592 1075001264 11.600 100 89 170 1 1153046768 61552976 16492528
02:39:19 9995 8192000000 10050784592 10435104768 1065065104 11.505 99 58 115 0 1170620176 94359632 11195440
02:40:37 8389 8192000000 10028097632 10426843136 1089515008 11.738 100 56 104 3 1158036320 57525104 10996208
02:41:50 1247 8192000000 10088495680 10480463872 1106965952 11.904 100 76 150 0 1205107728 83416208 14725568
02:42:56 1282 8192000000 10118632656 10511638528 1121085520 12.037 100 83 151 1 1236431056 100519648 14825888
02:44:01 2572 8192000000 10157277664 10555056128 1136164432 12.179 100 81 154 0 1275425888 122764448 16497008
02:45:07 803 8192000000 10172283824 10566508544 1138334800 12.200 100 93 183 1 1287927104 129360160 20232144
02:46:10 1333 8192000000 10212620416 10613882880 1154905616 12.356 100 89 172 1 1332958096 157957504 20094976
02:47:15 1018 8192000000 10257892816 10661605376 1157106128 12.376 99 92 181 0 1375052048 196349680 21596240
02:48:20 1803 8192000000 10287471296 10691072000 1176681840 12.559 100 87 170 1 1403174320 207889408 18603072
02:49:25 2511 8192000000 10322190048 10728914944 1176203696 12.555 100 85 162 0 1423817952 229106256 18508000
02:50:29 1216 8192000000 10347748224 10763005952 1179361984 12.584 100 93 179 0 1458341712 258037536 20942192
02:51:33 1915 8192000000 10342285040 10780102656 1177811552 12.570 100 94 181 1 1450709040 251884848 21012640
02:52:38 952 8192000000 10353751872 10759974912 1171957088 12.515 99 92 174 1 1462585824 270167936 20460800
02:53:41 882 8192000000 10408342832 10829484032 1191479808 12.697 100 97 190 1 1517739664 303962752 22297104
02:54:45 977 8192000000 10430349376 10848260096 1187730656 12.662 100 86 170 0 1537593552 329059440 20803456
02:55:48 699 8192000000 10378654000 10850963456 1178951296 12.580 99 94 179 0 1487178928 284515392 23712240
02:56:51 1530 8192000000 10389174480 10820362240 1189234752 12.676 100 82 158 0 1495461456 287715648 18511056
02:57:55 1060 8192000000 10394570688 10841157632 1192092816 12.703 100 94 182 0 1499431536 286605072 20733648
02:58:59 1321 8192000000 10373354272 10813075456 1174125584 12.535 99 94 183 1 1481840560 287086288 20628688
03:00:02 4905 8192000000 10428240064 10854883328 1186874976 12.654 100 71 141 0 1539910736 335841920 17193840
03:01:10 2966 8192000000 10442407008 10881613824 1181959296 12.608 100 79 159 1 1549601456 347963712 19678448
03:02:15 2739 8192000000 10443357136 10908012544 1170035792 12.497 100 75 149 1 1556635280 368653344 17946144
03:03:23 17383 8192000000 10451951376 10900828160 1155702096 12.363 99 45 87 0 1562234608 395433616 11098896
03:04:43 15484 8192000000 10497091408 10926510080 1158716336 12.391 100 44 88 0 1591626880 421223952 11686592
03:06:05 11264 8192000000 10436451920 10890608640 1157163424 12.377 100 38 73 0 1551101632 384317440 9620768
03:07:17 28787 8192000000 10286516032 10741456896 1154623504 12.353 100 23 44 0 1421463024 261422064 5417456
03:08:54 27270 8192000000 10284389328 10741071872 1154479376 12.352 100 25 50 0 1384836704 224184352 6172976
03:12:07 96672 8192000000 10075059952 10498347008 1090295056 11.745 95 33 64 1 1151375904 54438320 6642528
03:14:49 91860 8192000000 10118829840 10572238848 1143401696 12.248 100 30 55 1 1230141280 80279952 6459632
03:17:30 26123 8192000000 10144633232 10594107392 1152968400 12.337 100 42 79 0 1246644464 84553856 9122208
03:19:02 78691 8192000000 10168752176 10645114880 1132611616 12.146 99 16 32 0 1243772560 107462112 3698832
03:21:49 150274 8192000000 10107330624 10581905408 1120666528 12.033 98 9 18 0 1166848032 44104576 2076928
03:25:19 143865 8192000000 10063808416 10537607168 1093652640 11.777 96 22 38 1 1144837072 46848016 4336416
03:28:48 82282 8192000000 10111102432 10573615104 1134777536 12.166 100 11 22 0 1194836352 57893616 2165200
03:31:30 24149 8192000000 10083299312 10522886144 1129043568 12.112 99 34 68 0 1158424528 22624832 6756128
03:33:03 53473 8192000000 10148802592 10610995200 1140616336 12.221 100 33 64 1 1237879008 90774080 6488592
03:35:29 27696 8192000000 10083272112 10521513984 1123758336 12.062 99 40 78 0 1173168512 41906064 7504112
03:36:58 12112 8192000000 10084196720 10507739136 1119779184 12.025 99 45 85 1 1176278800 48482048 8017568
03:38:14 28331 8192000000 10073945936 10508410880 1112015200 11.951 98 23 46 0 1171555536 55193008 4347328
03:39:48 22083 8192000000 10153754592 10583441408 1140967408 12.225 100 50 98 0 1211141776 59559952 10614416
03:41:12 9543 8192000000 10074632560 10502979584 1123202704 12.057 99 49 95 0 1153604928 21268704 9133520
03:42:23 46891 8192000000 10110064336 10568519680 1109823824 11.931 98 37 74 0 1190249744 73050864 7375056
03:44:57 41659 8192000000 10061726144 10560688128 1110857552 11.941 99 14 28 0 1156207312 42589312 2760448
03:46:39 83748 8192000000 10094437040 10565730304 1119077024 12.018 99 11 22 0 1176011920 54623360 2311536
03:49:03 70089 8192000000 10101767392 10582237184 1140313872 12.218 100 19 35 1 1220757920 76154496 4289552
03:51:19 44383 8192000000 10133141392 10594271232 1136625104 12.184 99 30 58 0 1214781216 71476560 6679552
03:53:14 39263 8192000000 10149507120 10589671424 1111083536 11.943 98 29 58 0 1215350688 98119424 6147728
03:54:53 28796 8192000000 10150315392 10611187712 1132148000 12.142 99 26 52 0 1261619920 123593824 5878096
03:56:23 88776 8192000000 10112162576 10604417024 1079050880 11.638 96 26 52 0 1212594832 127818432 5725520
03:59:14 44355 8192000000 10074249888 10541060096 1097508496 11.814 98 32 60 1 1158427584 54835024 6084064
04:01:03 29560 8192000000 10107992528 10531119104 1099765408 11.835 99 52 102 0 1189332832 78764832 10802592
04:02:32 86724 8192000000 10048818416 10506981376 1113745152 11.968 100 42 76 0 1180053984 58375952 7932880
04:05:00 45586 8192000000 10082145888 10545831936 1114624320 11.976 99 31 61 0 1184379040 63944240 5810480
04:06:47 45796 8192000000 10051775440 10520895488 1102267520 11.859 98 13 26 1 1151052544 46022144 2762880
04:08:33 92243 8192000000 10095083616 10554142720 1122188464 12.048 99 14 28 0 1198793616 73643120 2962032
04:11:08 64645 8192000000 10098945216 10563571712 1141477248 12.229 100 14 28 0 1199128032 54900176 2750608
04:13:19 63445 8192000000 10070603696 10514210816 1121054464 12.037 98 20 41 0 1167369008 42074032 4240512
04:15:28 12998 8192000000 10127506256 10558586880 1142961568 12.243 100 56 106 0 1209255104 56118416 10175120
04:16:43 20928 8192000000 10109921920 10551873536 1135463584 12.173 99 29 57 1 1203578416 62471712 5643120
04:18:05 35434 8192000000 10078947040 10508275712 1097956816 11.818 97 40 78 2 1153907104 48201152 7749136
04:19:43 17972 8192000000 10097478672 10523680768 1106372048 11.898 98 54 108 0 1180964864 64176208 10416608
04:21:02 30058 8192000000 10115325040 10549747712 1118897168 12.017 99 48 96 0 1192597520 64146928 9553424
04:22:34 44838 8192000000 10070168016 10510045184 1133576992 12.155 100 21 40 1 1177765488 39855120 4333376
04:24:25 100240 8192000000 10074673760 10548314112 1103790144 11.874 97 32 64 0 1147875760 37348112 6737504
04:27:10 61154 8192000000 10065234784 10544140288 1127631264 12.099 100 7 14 0 1165892576 37106368 1154944
04:29:24 82871 8192000000 10081794064 10542444544 1081538496 11.662 95 9 18 0 1148999856 65422912 2038448
04:31:48 35257 8192000000 10100465008 10575118336 1121549072 12.042 99 27 52 0 1211097504 82445568 7102864
04:33:44 33134 8192000000 10158139248 10606305280 1154863984 12.355 100 30 56 1 1261068560 98683312 7521264
04:35:18 24354 8192000000 10181162064 10621177856 1132242320 12.142 98 26 52 0 1257971456 119255920 6473216
04:36:50 12110 8192000000 10223273760 10655358976 1127888464 12.101 98 51 103 1 1298179168 158700864 11589840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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 16-10-2022 04:39:50 to 16-10-2022 04:39:52.

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          218794
MON$OLDEST_ACTIVE               229619
MON$OLDEST_SNAPSHOT             229619
MON$NEXT_TRANSACTION            229619
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                       2064608
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             791
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2859 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2859 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2859 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2859 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 ---
:57: 
:58: mscs-test-161.budzdorov.ru    Sun Oct 16 04:37:33 2022
:59:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:60: 
:61: 
:62: mscs-test-161.budzdorov.ru    Sun Oct 16 04:37:34 2022
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Sun Oct 16 04:37:35 2022
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Sun Oct 16 04:37:40 2022
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
--- end of diff output ---

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

16.10.22 04:39:53. 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-10-16 04:39:55 No severe PSQL-related problems occured
16.10.22 04:39:57. End of report.