Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.10.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 15279656 kB
MemAvailable 30436248 kB
Buffers 0 kB
Cached 16406492 kB
SwapCached 2180 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-24 10:37:23
DTS_END                         2022-10-24 10:37:23.1910
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33384
TRN_ID                          214729
ATT_ID                          609
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: 9120.75 20759 1094490
customer order: creation 164.06 458 19687
customer order: refuse 13.91 377 1669
order to supplier: creation 43.33 1353 5200
order to supplier: removal 3.73 145815 448
invoice (draft): creation 44.07 2744 5288
invoice (draft): removal 52.65 1061 6318
invoice accept: apply 40.16 25532 4819
invoice accept: cancel 47.52 1103 5702
customer reserve: creation 3767.63 242 452116
customer reserve: removal 4662.54 90 559505
realization accept: apply 77.76 264 9331
realization accept: cancel 80.00 148 9600
payment from customer: creation 55.83 156 6699
payment from customer: removal 5.56 109 667
payment to supplier: creation 47.27 190 5672
payment to supplier: removal 3.13 151 375
service: total inventory turnovers 3.68 3755 441
service: total monetary turnovers 6.99 317 839
service: aggregate perf. data 0.91 14091 109
service: refresh index statistics 0.04 217225 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-10-2022 10:38:47 to 24-10-2022 10:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8098
TEST_TIME 2 6172
TEST_TIME 3 5331
TEST_TIME 4 5719
TEST_TIME 5 5507
TEST_TIME 6 5642
TEST_TIME 7 5624
TEST_TIME 8 5554
TEST_TIME 9 5613
TEST_TIME 10 5902
TEST_TIME 11 5980
TEST_TIME 12 6092
TEST_TIME 13 6131
TEST_TIME 14 6098
TEST_TIME 15 6218
TEST_TIME 16 6544
TEST_TIME 17 6745
TEST_TIME 18 6799
TEST_TIME 19 7442
TEST_TIME 20 7812
TEST_TIME 21 7697
TEST_TIME 22 7873
TEST_TIME 23 8170
TEST_TIME 24 8101
TEST_TIME 25 8567
TEST_TIME 26 9746
TEST_TIME 27 10112
TEST_TIME 28 10295
TEST_TIME 29 10454
TEST_TIME 30 10776
TEST_TIME 31 11116
TEST_TIME 32 11059
TEST_TIME 33 11201
TEST_TIME 34 11166
TEST_TIME 35 11082
TEST_TIME 36 11190
TEST_TIME 37 11049
TEST_TIME 38 10881
TEST_TIME 39 10817
TEST_TIME 40 10708
TEST_TIME 41 10464
TEST_TIME 42 10321
TEST_TIME 43 10367
TEST_TIME 44 10333
TEST_TIME 45 10292
TEST_TIME 46 10305
TEST_TIME 47 10153
TEST_TIME 48 10065
TEST_TIME 49 9965
TEST_TIME 50 9905
TEST_TIME 51 9905
TEST_TIME 52 9850
TEST_TIME 53 9942
TEST_TIME 54 9987
TEST_TIME 55 9886
TEST_TIME 56 9843
TEST_TIME 57 9855
TEST_TIME 58 10013
TEST_TIME 59 9982
TEST_TIME 60 9934
TEST_TIME 61 9965
TEST_TIME 62 10021
TEST_TIME 63 10083
TEST_TIME 64 10159
TEST_TIME 65 10107
TEST_TIME 66 10131
TEST_TIME 67 10008
TEST_TIME 68 9959
TEST_TIME 69 9897
TEST_TIME 70 9854
TEST_TIME 71 9745
TEST_TIME 72 9717
TEST_TIME 73 9787
TEST_TIME 74 9722
TEST_TIME 75 9664
TEST_TIME 76 9667
TEST_TIME 77 9667
TEST_TIME 78 9649
TEST_TIME 79 9669
TEST_TIME 80 9746
TEST_TIME 81 9734
TEST_TIME 82 9703
TEST_TIME 83 9700
TEST_TIME 84 9713
TEST_TIME 85 9747
TEST_TIME 86 9663
TEST_TIME 87 9582
TEST_TIME 88 9567
TEST_TIME 89 9543
TEST_TIME 90 9496
TEST_TIME 91 9433
TEST_TIME 92 9509
TEST_TIME 93 9464
TEST_TIME 94 9443
TEST_TIME 95 9435
TEST_TIME 96 9416
TEST_TIME 97 9395
TEST_TIME 98 9404
TEST_TIME 99 9455
TEST_TIME 100 9430
TEST_TIME 101 9427
TEST_TIME 102 9447
TEST_TIME 103 9446
TEST_TIME 104 9511
TEST_TIME 105 9533
TEST_TIME 106 9516
TEST_TIME 107 9506
TEST_TIME 108 9454
TEST_TIME 109 9409
TEST_TIME 110 9418
TEST_TIME 111 9380
TEST_TIME 112 9330
TEST_TIME 113 9309
TEST_TIME 114 9275
TEST_TIME 115 9258
TEST_TIME 116 9212
TEST_TIME 117 9198
TEST_TIME 118 9184
TEST_TIME 119 9217
TEST_TIME 120 9151

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-10-2022 10:38:51 to 24-10-2022 10:38:54.

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 1509702 1509702 0 0.00 0 35705 11 0 0
doc_list_biud 1509689 1509689 0 0.00 0 37373 39 0 0
sp_add_invoice_to_stock 4906 4819 87 1.77 45 232065 25532 0 87
sp_cancel_adding_invoice 5702 5702 0 0.00 6 103276 1103 0 0
sp_cancel_client_order 1669 1669 0 0.00 6 15471 378 0 0
sp_cancel_customer_reserve 559505 559505 0 0.00 1 33593 90 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 4 2675 110 0 0
sp_cancel_pay_to_supplier 375 375 0 0.00 4 15991 151 0 0
sp_cancel_supplier_invoice 6331 6318 13 0.20 3 32717 1061 0 13
sp_cancel_supplier_order 448 448 0 0.00 10 1433345 145816 0 0
sp_cancel_write_off 9600 9600 0 0.00 2 10967 149 0 0
sp_client_order 19687 19687 0 0.00 5 37404 458 0 0
sp_customer_reserve 452116 452116 0 0.00 0 67426 242 0 0
sp_fill_shopping_cart 30164 30164 0 0.00 1 9221 68 0 0
sp_fill_shopping_cart_clo_res 452082 452082 0 0.00 0 20533 20 0 0
sp_get_clo_for_invoice 4777 4777 0 0.00 0 29041 285 0 0
sp_kill_cost_storno 16344 16344 0 0.00 0 33899 72 0 0
sp_kill_qstorno_ret_qs2qd 1135376 1135376 0 0.00 0 33551 41 0 0
sp_kill_qty_storno 597349 597349 0 0.00 0 37372 93 0 0
sp_lock_selected_doc 19639 19639 0 0.00 0 9331 8 0 0
sp_make_cost_storno 26479 26479 0 0.00 0 35695 79 0 0
sp_multiply_rows_for_pdistr 26478 26478 0 0.00 0 34063 36 0 0
sp_multiply_rows_for_qdistr 19686 19686 0 0.00 0 20615 189 0 0
sp_pay_from_customer 6699 6699 0 0.00 5 11895 156 0 0
sp_pay_to_supplier 5672 5672 0 0.00 7 34127 190 0 0
sp_payment_common 12370 12370 0 0.00 2 6257 38 0 0
sp_qd_handle_on_cancel_clo 1669 1669 0 0.00 1 15455 303 0 0
sp_qd_handle_on_invoice_upd_sts 10478 10478 0 0.00 0 37372 483 0 0
sp_qd_handle_on_reserve_upd_sts 9331 9331 0 0.00 0 12601 55 0 0
sp_reserve_write_off 9331 9331 0 0.00 8 28584 265 0 0
sp_supplier_invoice 5288 5288 0 0.00 10 92365 2745 0 0
sp_supplier_order 5200 5200 0 0.00 7 71081 1354 0 0
srv_aggregate_perf_data 110 109 1 0.90 2405 125387 14092 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 957 102916 34678 0 0
srv_find_qd_qs_mism 1046829 1046829 0 0.00 0 17232 8 0 0
srv_make_invnt_saldo 441 441 0 0.00 91 79828 3756 0 0
srv_make_money_saldo 839 839 0 0.00 1 13467 317 0 0
srv_recalc_idx_stat 5 5 0 0.00 82953 305937 217226 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4827 4827 0 0.00 0 14333 772 0 0
t$perf-norm:sp_cancel_adding_invoice 373 373 0 0.00 1 6422 395 0 0
t$perf-norm:sp_cancel_client_order 1669 1669 0 0.00 0 2083 25 0 0
t$perf-norm:sp_cancel_customer_reserve 653 653 0 0.00 0 2193 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 501 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 375 375 0 0.00 0 1441 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 2627 34 0 0
t$perf-norm:sp_cancel_supplier_order 448 448 0 0.00 0 96375 5910 0 0
t$perf-norm:sp_cancel_write_off 948 948 0 0.00 0 6882 32 0 0
t$perf-norm:sp_client_order 19688 19688 0 0.00 0 9036 32 0 0
t$perf-norm:sp_customer_reserve 972 972 0 0.00 0 5384 15 0 0
t$perf-norm:sp_pay_from_customer 6699 6699 0 0.00 0 6459 18 0 0
t$perf-norm:sp_pay_to_supplier 5672 5672 0 0.00 0 3577 16 0 0
t$perf-norm:sp_reserve_write_off 9331 9331 0 0.00 0 12010 20 0 0
t$perf-norm:sp_supplier_invoice 5289 5289 0 0.00 0 10035 26 0 0
t$perf-norm:sp_supplier_order 5200 5200 0 0.00 0 1580 27 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 382 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 5177 98 0 0
t$perf-norm:srv_make_invnt_saldo 441 441 0 0.00 0 661 17 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 2378 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 201 84 0 0
v_add_invoice_to_stock 9720 9546 174 1.79 0 28364 32 0 174
v_all_customers 36275 36275 0 0.00 0 192 3 0 0
v_all_suppliers 29436 29436 0 0.00 0 194 3 0 0
v_all_wares 39367 39367 0 0.00 0 192 3 0 0
v_cancel_adding_invoice 746 746 0 0.00 0 3299 21 0 0
v_cancel_client_order 3338 3338 0 0.00 0 5553 15 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 1054 11 0 0
v_cancel_customer_reserve 1306 1306 0 0.00 0 26155 33 0 0
v_cancel_payment_to_supplier 750 750 0 0.00 0 4995 24 0 0
v_cancel_supplier_invoice 828 802 26 3.14 0 2024 25 0 26
v_cancel_supplier_order 896 896 0 0.00 0 4217 19 0 0
v_cancel_write_off 1896 1896 0 0.00 0 2381 27 0 0
v_max_id_clo_ord 5198 5198 0 0.00 0 434 4 0 0
v_max_id_clo_res 971 971 0 0.00 0 1448 10 0 0
v_max_id_ord_sup 5280 5280 0 0.00 0 9071 7 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 1256 7 0 0
v_max_non_paid_realizn 3288 3288 0 0.00 0 2574 7 0 0
v_min_id_clo_ord 5198 5198 0 0.00 0 2846 8 0 0
v_min_id_clo_res 971 971 0 0.00 0 6825 16 0 0
v_min_id_ord_sup 5280 5280 0 0.00 0 5803 7 0 0
v_min_non_paid_invoice 5671 5671 0 0.00 0 2781 12 0 0
v_min_non_paid_realizn 6699 6699 0 0.00 0 3865 16 0 0
v_our_firm 9910 9910 0 0.00 0 195 4 0 0
v_reserve_write_off 18662 18662 0 0.00 0 28321 18 0 0
x_lock_depdocs_on_canc_invoice 11619 11619 0 0.00 0 15816 113 0 0
x_lock_depdocs_on_canc_sup_ord 448 448 0 0.00 0 8907 402 0 0
x_make_qstorno_1000_1200 5200 5200 0 0.00 2 71063 1219 0 0
x_make_qstorno_1200_2000 5288 5288 0 0.00 5 92345 2556 0 0
x_make_qstorno_2100_3300 452115 452115 0 0.00 0 67424 212 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-10-2022 10:38:55 to 24-10-2022 10:38:59.

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:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
08:08:27 32221 8192000000 9725859216 10027642880 803894480 8.936 100 88 176 0 833133312 8043760 21195072
08:10:06 18669 8192000000 9762056864 10072854528 839412192 9.294 100 88 173 0 872050768 12419840 20218736
08:11:29 14556 8192000000 9805881040 10127327232 877480064 9.675 100 84 166 1 916117808 19399584 19238160
08:12:49 8106 8192000000 9862185616 10197655552 915848384 10.055 100 85 166 0 969688000 35994208 17845408
08:14:00 4367 8192000000 9924375536 10265698304 956051776 10.450 100 91 180 0 1046123024 71386592 18684656
08:15:08 2916 8192000000 9972003856 10330071040 990080976 10.782 100 91 179 1 1089181312 80250320 18850016
08:16:14 1717 8192000000 9981324464 10345721856 1017287936 11.046 100 93 178 1 1102681136 68341968 17051232
08:17:18 1147 8192000000 10005221504 10380136448 1040933408 11.274 100 95 182 0 1126795280 66823072 19038800
08:18:21 1188 8192000000 10035424544 10410086400 1054761072 11.406 100 90 179 0 1154783632 81678704 18343856
08:19:24 3395 8192000000 10016354880 10392715264 1065393104 11.508 100 94 174 2 1133400176 50604352 17402720
08:20:28 1377 8192000000 10047580368 10428698624 1075784176 11.607 100 97 182 6 1172439936 77107984 19547776
08:21:31 1067 8192000000 10044192672 10428571648 1084660944 11.692 100 87 165 1 1169567872 69383808 15523120
08:22:34 1222 8192000000 10051538832 10435686400 1085576720 11.701 100 88 165 0 1172705120 69873536 17254864
08:23:36 3712 8192000000 10036883104 10430652416 1089337392 11.736 100 67 126 1 1166475936 64897424 12241120
08:24:41 1095 8192000000 10052859216 10442633216 1092161392 11.763 100 93 183 1 1176877344 67011056 17704896
08:25:43 1203 8192000000 10067746912 10456567808 1096302992 11.803 100 89 165 1 1186298160 73149456 16845712
08:26:45 1242 8192000000 10076548624 10465587200 1098559424 11.824 100 90 172 0 1194507168 78959936 16987808
08:27:47 1132 8192000000 10050215280 10441158656 1091525056 11.757 100 96 182 2 1174482336 63821856 19135424
08:28:49 961 8192000000 10050629584 10442878976 1097743216 11.816 100 87 170 0 1173468448 58029888 17695344
08:29:51 1855 8192000000 10036619408 10425262080 1083307456 11.679 100 80 155 2 1151863072 52166624 16388992
08:30:54 999 8192000000 10031088400 10412322816 1078227504 11.631 100 91 177 3 1153930112 56270896 19431712
08:31:56 958 8192000000 10005121552 10392137728 1070791024 11.560 100 74 143 1 1133024432 47149056 15084352
08:32:57 1155 8192000000 10005645536 10378153984 1038095056 11.246 100 97 185 2 1128074000 70446704 19532240
08:33:59 1048 8192000000 9965358240 10333491200 1025125552 11.121 100 95 184 3 1089072176 46237264 17709360
08:35:01 2279 8192000000 9980903056 10349039616 1028581888 11.155 99 89 167 2 1106459984 60159952 17718144
08:36:04 1896 8192000000 9972749728 10356400128 1043476848 11.298 100 81 150 0 1093720336 34213744 16029744
08:37:07 6540 8192000000 10009978640 10387468288 1045296224 11.316 99 64 125 0 1118878128 61320064 12261840
08:38:21 2306 8192000000 10032372416 10415144960 1073028864 11.581 100 76 135 0 1151885664 65626864 13229936
08:39:31 5267 8192000000 10022638304 10414465024 1085145280 11.696 100 55 107 0 1144561360 48167488 11248592
08:40:47 7122 8192000000 10066639264 10446106624 1087181696 11.716 99 83 160 0 1182285344 77713360 17390288
08:42:00 3706 8192000000 10095533536 10488868864 1117349744 12.002 100 79 139 0 1222337936 90752240 14235952
08:43:07 6505 8192000000 10171845504 10565787648 1126418400 12.088 100 68 132 0 1287740160 147086432 14235328
08:44:22 2372 8192000000 10188410576 10586886144 1142080432 12.235 100 70 129 0 1312405664 155923152 14402080
08:45:30 4667 8192000000 10241058816 10637635584 1155460384 12.361 100 84 166 0 1347598976 173470928 18667664
08:46:39 7854 8192000000 10286304064 10685669376 1168664528 12.484 100 77 148 1 1400396720 215752032 15980160
08:47:51 1275 8192000000 10314123664 10712387584 1180858736 12.598 100 91 178 1 1426170720 226141600 19170384
08:48:57 1386 8192000000 10390174080 10790445056 1196482640 12.744 100 95 190 0 1498867152 281148256 21236256
08:50:03 1109 8192000000 10415081728 10819616768 1199584224 12.772 100 91 177 2 1525438096 305529456 20324416
08:51:06 1153 8192000000 10463368368 10868289536 1203595360 12.810 100 94 177 2 1573581696 348913008 21073328
08:52:10 949 8192000000 10515059584 10919010304 1203866528 12.812 100 98 196 0 1621019440 393990112 23162800
08:53:14 1006 8192000000 10539333856 10967425024 1204023856 12.814 100 97 192 1 1648548192 421331424 23192912
08:54:18 1183 8192000000 10567187536 10995511296 1213893328 12.905 100 96 187 0 1674675600 438797568 21984704
08:55:23 1996 8192000000 10575750656 11027533824 1219172416 12.954 100 89 175 0 1684715296 443598240 21944640
08:56:27 1534 8192000000 10589292496 11047804928 1215790496 12.923 100 95 189 1 1695972912 457888656 22293760
08:57:30 7030 8192000000 10562286736 10991718400 1209598704 12.865 100 51 99 1 1663222544 441085168 12538672
08:58:39 20348 8192000000 10590750448 11034017792 1206030528 12.832 100 44 86 0 1689710304 472023360 11656416
09:00:12 11027 8192000000 10579758272 11015643136 1182035440 12.609 100 48 93 1 1684861184 490010864 12814880
09:01:31 5031 8192000000 10569653536 11003858944 1170923696 12.505 100 54 106 0 1668438448 483308816 14205936
09:02:38 3014 8192000000 10500863072 11064598528 1174437056 12.538 99 56 112 0 1608810112 419393200 14979856
09:03:54 7729 8192000000 10338350224 10825117696 1183400496 12.622 100 48 96 0 1444724288 249779712 11544080
09:05:11 27375 8192000000 10292720320 10786942976 1166281568 12.462 100 22 44 0 1387543680 215934528 5327584
09:06:39 35320 8192000000 10268525136 10720862208 1142324032 12.237 99 15 30 0 1360461632 213988592 4149008
09:08:22 56971 8192000000 10122599376 10565361664 1127817536 12.101 98 32 63 0 1202439232 67344272 7277424
09:10:22 37985 8192000000 10043543184 10506305536 1082909872 11.675 95 15 30 0 1138014768 52345232 2759664
09:12:01 101428 8192000000 10091659264 10587111424 1137253296 12.190 99 10 20 0 1197097232 58126208 1717728
09:14:43 53362 8192000000 10108123632 10542104576 1135729584 12.175 99 36 69 0 1184667536 41759008 7178944
09:16:47 99480 8192000000 10128475424 10607337472 1140583216 12.221 99 27 54 0 1238088864 91736976 5768672
09:19:49 1923 8192000000 10128391568 10574589952 1140000160 12.216 100 61 117 0 1225357296 72772000 12585136
09:20:56 8413 8192000000 10082333440 10506162176 1122413232 12.050 99 69 119 0 1177388848 45038640 9936976
09:22:05 23390 8192000000 10083602784 10531799040 1129829440 12.120 99 50 96 1 1183414064 43743008 9841616
09:23:32 52872 8192000000 10099236336 10568069120 1150780096 12.317 100 16 32 0 1186265296 32137872 3347328
09:25:30 65365 8192000000 10117555088 10572709888 1097182896 11.811 94 20 37 1 1171664640 70696576 3785168
09:27:36 86915 8192000000 10131129104 10614902784 1125329632 12.077 98 22 44 0 1236043776 105851648 4862496
09:30:06 41755 8192000000 10094044560 10621116416 1137510624 12.192 100 17 34 0 1229846672 88494400 3841648
09:31:50 33792 8192000000 10061027872 10503725056 1130458960 12.126 99 21 42 0 1164960688 30416048 4085680
09:33:27 36819 8192000000 10161752416 10617872384 1158501952 12.389 100 34 68 0 1267373056 101804656 7066448
09:35:05 41884 8192000000 10080997072 10551275520 1111605280 11.948 97 23 46 0 1208504144 91644496 5254368
09:36:47 28150 8192000000 10105198160 10547490816 1136967440 12.187 99 48 89 0 1180111056 33766960 9376656
09:38:20 49188 8192000000 10123340176 10598932480 1121432528 12.041 98 25 50 0 1219619264 93096048 5090688
09:40:18 82329 8192000000 10074380976 10548469760 1116333584 11.992 97 14 28 0 1187949552 68922096 2693872
09:42:41 65441 8192000000 10110665408 10557067264 1128593696 12.108 99 17 34 0 1204930128 72754240 3582192
09:44:49 81013 8192000000 10101712528 10561384448 1140180880 12.217 100 51 100 1 1209896080 59769600 9945600
09:47:16 57365 8192000000 10108884592 10581733376 1131484672 12.135 99 12 24 0 1196527776 62768432 2274672
09:49:13 26080 8192000000 10132306224 10641866752 1147287904 12.284 100 12 26 0 1236778688 86981040 2509744
09:50:56 79011 8192000000 10077087648 10518847488 1117267824 12.001 98 13 26 0 1191129008 70900000 2961184
09:53:17 31369 8192000000 10109255248 10570002432 1145312512 12.265 99 35 60 1 1206246848 54529392 6404944
09:54:52 49526 8192000000 10120767968 10587770880 1118307680 12.011 97 22 42 0 1194126304 71159408 4659216
09:56:42 29795 8192000000 10122217472 10634928128 1129373280 12.115 99 16 32 0 1231416608 98544688 3498640
09:58:14 17427 8192000000 10092862512 10529439744 1133751728 12.157 99 46 92 0 1172882976 28487088 10644160
09:59:52 10204 8192000000 10135077456 10567196672 1158596752 12.390 100 58 113 1 1228806064 58950688 11258624
10:01:02 38334 8192000000 10133292528 10578333696 1137766016 12.195 99 29 56 0 1234948192 90965504 6216672
10:02:44 10657 8192000000 10119458128 10551988224 1137538432 12.192 100 41 82 0 1210768176 65245696 7984048
10:04:01 86152 8192000000 10111492160 10566434816 1136083456 12.179 100 45 88 2 1192448032 46636528 9728048
10:06:29 76246 8192000000 10079647152 10560040960 1119728528 12.024 98 7 14 0 1167708480 46452768 1527184
10:08:48 54545 8192000000 10108713264 10602303488 1140360912 12.219 100 16 30 0 1227552672 84029984 3161776
10:11:15 53660 8192000000 10069610656 10511519744 1089729008 11.740 95 31 62 0 1130674016 34901168 6043840
10:13:09 17674 8192000000 10099813824 10533412864 1136913968 12.186 99 54 105 0 1186365920 38590912 10861040
10:14:29 42000 8192000000 10139618608 10584494080 1136919360 12.187 99 27 54 0 1236210448 93555248 5735840
10:16:15 56072 8192000000 10098149248 10560065536 1124656048 12.071 98 24 48 1 1185160720 54577408 5927264
10:18:16 40331 8192000000 10136709808 10596372480 1104076992 11.876 96 32 64 0 1213966416 103185728 6703696
10:19:56 82024 8192000000 10106812176 10618736640 1102954176 11.866 97 26 52 0 1225630848 117359856 5316816
10:22:21 15070 8192000000 10100197440 10535092224 1119100352 12.018 99 47 94 0 1178406400 49281392 10024656
10:24:19 102915 8192000000 10085969264 10572050432 1121033040 12.037 100 30 58 1 1217822080 90448832 6340208
10:27:15 38024 8192000000 10055556416 10528456704 1126334864 12.087 100 14 27 0 1176832464 47745392 2752208
10:28:54 57943 8192000000 10053837264 10516705280 1112183600 11.953 99 8 16 0 1144653920 30764960 1705360
10:30:53 63120 8192000000 10054924448 10532728832 1112600304 11.957 99 12 24 0 1144491024 28935360 2955360
10:33:04 74008 8192000000 10076538528 10527219712 1089749280 11.740 97 15 28 0 1139995536 46977520 3268736
10:35:24 49840 8192000000 10134607008 10594365440 1111419680 11.946 99 24 48 0 1219906432 103158112 5328640
10:37:21 20473 8192000000 8964812720 9197563904 122545024 1.473 11 3 6 0 157865744 34725536 595184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 87 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-10-2022 10:39:19 to 24-10-2022 10:39:21.

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          215027
MON$OLDEST_ACTIVE               215028
MON$OLDEST_SNAPSHOT             215028
MON$NEXT_TRANSACTION            215028
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                       2044832
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2861 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2861 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2861 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

24.10.22 10:39:22. 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-24 10:39:25 No severe PSQL-related problems occured
24.10.22 10:39:26. End of report.