Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.09.2021 19: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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16268572 kB
MemAvailable 30795856 kB
Buffers 0 kB
Cached 15408428 kB
SwapCached 416 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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 2021-09-17 19:37:50
DTS_END                         2021-09-17 19:37:50.7080
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(57:17)
IP                              ::1/44266
TRN_ID                          239964
ATT_ID                          685
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: 9144.44 26648 1097333
customer order: creation 167.90 328 20148
customer order: refuse 14.48 356 1737
order to supplier: creation 45.43 1257 5452
order to supplier: removal 3.23 101891 388
invoice (draft): creation 45.48 3608 5458
invoice (draft): removal 53.78 722 6453
invoice accept: apply 40.28 17032 4834
invoice accept: cancel 48.43 818 5812
customer reserve: creation 3696.33 169 443560
customer reserve: removal 4736.14 55 568337
realization accept: apply 81.47 163 9777
realization accept: cancel 85.50 100 10260
payment from customer: creation 57.35 107 6882
payment from customer: removal 5.78 82 693
payment to supplier: creation 47.64 142 5717
payment to supplier: removal 3.54 81 425
service: total inventory turnovers 4.65 1171 558
service: total monetary turnovers 6.23 28 748
service: aggregate perf. data 0.75 26411 90
service: refresh index statistics 0.03 378436 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-09-2021 19:38:58 to 17-09-2021 19:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3964
TEST_TIME 2 4096
TEST_TIME 3 3679
TEST_TIME 4 4500
TEST_TIME 5 4289
TEST_TIME 6 4089
TEST_TIME 7 3994
TEST_TIME 8 4359
TEST_TIME 9 4620
TEST_TIME 10 4817
TEST_TIME 11 5132
TEST_TIME 12 5035
TEST_TIME 13 5297
TEST_TIME 14 5630
TEST_TIME 15 5910
TEST_TIME 16 6202
TEST_TIME 17 6178
TEST_TIME 18 7014
TEST_TIME 19 7635
TEST_TIME 20 7763
TEST_TIME 21 7918
TEST_TIME 22 7826
TEST_TIME 23 8260
TEST_TIME 24 9165
TEST_TIME 25 9490
TEST_TIME 26 9627
TEST_TIME 27 9722
TEST_TIME 28 9524
TEST_TIME 29 9572
TEST_TIME 30 9464
TEST_TIME 31 9679
TEST_TIME 32 9485
TEST_TIME 33 9352
TEST_TIME 34 9465
TEST_TIME 35 9426
TEST_TIME 36 9473
TEST_TIME 37 9392
TEST_TIME 38 9385
TEST_TIME 39 9245
TEST_TIME 40 9186
TEST_TIME 41 9145
TEST_TIME 42 9032
TEST_TIME 43 9154
TEST_TIME 44 9244
TEST_TIME 45 9217
TEST_TIME 46 9218
TEST_TIME 47 9111
TEST_TIME 48 9045
TEST_TIME 49 9263
TEST_TIME 50 9303
TEST_TIME 51 9349
TEST_TIME 52 9316
TEST_TIME 53 9253
TEST_TIME 54 9308
TEST_TIME 55 9351
TEST_TIME 56 9325
TEST_TIME 57 9421
TEST_TIME 58 9339
TEST_TIME 59 9273
TEST_TIME 60 9325
TEST_TIME 61 9259
TEST_TIME 62 9235
TEST_TIME 63 9320
TEST_TIME 64 9244
TEST_TIME 65 9178
TEST_TIME 66 9109
TEST_TIME 67 9120
TEST_TIME 68 9100
TEST_TIME 69 9025
TEST_TIME 70 9031
TEST_TIME 71 9079
TEST_TIME 72 9031
TEST_TIME 73 9009
TEST_TIME 74 9137
TEST_TIME 75 9168
TEST_TIME 76 9212
TEST_TIME 77 9246
TEST_TIME 78 9260
TEST_TIME 79 9280
TEST_TIME 80 9312
TEST_TIME 81 9310
TEST_TIME 82 9366
TEST_TIME 83 9370
TEST_TIME 84 9318
TEST_TIME 85 9252
TEST_TIME 86 9273
TEST_TIME 87 9222
TEST_TIME 88 9203
TEST_TIME 89 9141
TEST_TIME 90 9133
TEST_TIME 91 9079
TEST_TIME 92 9043
TEST_TIME 93 9069
TEST_TIME 94 9051
TEST_TIME 95 9067
TEST_TIME 96 9056
TEST_TIME 97 9119
TEST_TIME 98 9143
TEST_TIME 99 9129
TEST_TIME 100 9152
TEST_TIME 101 9214
TEST_TIME 102 9197
TEST_TIME 103 9238
TEST_TIME 104 9263
TEST_TIME 105 9276
TEST_TIME 106 9301
TEST_TIME 107 9305
TEST_TIME 108 9228
TEST_TIME 109 9225
TEST_TIME 110 9189
TEST_TIME 111 9173
TEST_TIME 112 9114
TEST_TIME 113 9130
TEST_TIME 114 9108
TEST_TIME 115 9136
TEST_TIME 116 9137
TEST_TIME 117 9119
TEST_TIME 118 9114
TEST_TIME 119 9092
TEST_TIME 120 9148

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-09-2021 19:39:01 to 17-09-2021 19:39:05.

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 1509951 1509951 0 0.00 0 46876 6 0 0
doc_list_biud 1509947 1509947 0 0.00 0 26852 26 0 0
sp_add_invoice_to_stock 4955 4834 121 2.44 31 195004 17033 0 121
sp_cancel_adding_invoice 5812 5812 0 0.00 2 79635 818 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 10154 356 0 0
sp_cancel_customer_reserve 568337 568337 0 0.00 1 16474 56 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 5599 82 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 3 3013 81 0 0
sp_cancel_supplier_invoice 6464 6453 11 0.17 1 22912 722 0 11
sp_cancel_supplier_order 388 388 0 0.00 8 996529 101891 0 0
sp_cancel_write_off 10260 10260 0 0.00 1 14046 100 0 0
sp_client_order 20148 20148 0 0.00 4 21918 329 0 0
sp_customer_reserve 443560 443560 0 0.00 0 34994 170 0 0
sp_fill_shopping_cart 31053 31053 0 0.00 1 19177 39 0 0
sp_fill_shopping_cart_clo_res 443554 443554 0 0.00 0 19265 12 0 0
sp_get_clo_for_invoice 4828 4828 0 0.00 0 30199 345 0 0
sp_kill_cost_storno 17190 17190 0 0.00 0 10772 54 0 0
sp_kill_qstorno_ret_qs2qd 1153775 1153775 0 0.00 0 22897 30 0 0
sp_kill_qty_storno 607592 607592 0 0.00 0 26851 65 0 0
sp_lock_selected_doc 20331 20331 0 0.00 0 6323 4 0 0
sp_make_cost_storno 27204 27204 0 0.00 0 46638 53 0 0
sp_multiply_rows_for_pdistr 27204 27204 0 0.00 0 8215 30 0 0
sp_multiply_rows_for_qdistr 20148 20148 0 0.00 0 21901 168 0 0
sp_pay_from_customer 6882 6882 0 0.00 3 10915 108 0 0
sp_pay_to_supplier 5717 5717 0 0.00 5 15057 142 0 0
sp_payment_common 12599 12599 0 0.00 1 10910 29 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 10117 327 0 0
sp_qd_handle_on_invoice_upd_sts 10640 10640 0 0.00 0 26850 326 0 0
sp_qd_handle_on_reserve_upd_sts 9777 9777 0 0.00 0 16290 53 0 0
sp_reserve_write_off 9777 9777 0 0.00 6 17842 163 0 0
sp_supplier_invoice 5458 5458 0 0.00 6 89226 3609 0 0
sp_supplier_order 5452 5452 0 0.00 5 42288 1257 0 0
srv_aggregate_perf_data 90 90 0 0.00 2750 620124 26412 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1936 153763 50505 0 0
srv_find_qd_qs_mism 1051916 1051916 0 0.00 0 12889 5 0 0
srv_make_invnt_saldo 558 558 0 0.00 36 57297 1171 0 0
srv_make_money_saldo 748 748 0 0.00 0 2616 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 94406 640772 378437 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4835 4835 0 0.00 0 13757 186 0 0
t$perf-norm:sp_cancel_adding_invoice 431 431 0 0.00 0 6663 79 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 7697 20 0 0
t$perf-norm:sp_cancel_customer_reserve 631 631 0 0.00 0 3087 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 3066 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 908 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 1395 16 0 0
t$perf-norm:sp_cancel_supplier_order 388 388 0 0.00 0 23116 1817 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 4478 13 0 0
t$perf-norm:sp_client_order 20148 20148 0 0.00 0 7170 18 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 8759 12 0 0
t$perf-norm:sp_pay_from_customer 6882 6882 0 0.00 0 11527 13 0 0
t$perf-norm:sp_pay_to_supplier 5717 5717 0 0.00 0 6548 13 0 0
t$perf-norm:sp_reserve_write_off 9777 9777 0 0.00 0 4558 10 0 0
t$perf-norm:sp_supplier_invoice 5458 5458 0 0.00 0 7580 23 0 0
t$perf-norm:sp_supplier_order 5452 5452 0 0.00 0 6954 18 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 81 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 374 15 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 707 15 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 822 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 9898 9656 242 2.44 0 6339 21 0 242
v_all_customers 36870 36870 0 0.00 0 16 1 0 0
v_all_suppliers 30014 30014 0 0.00 0 16 1 0 0
v_all_wares 40296 40296 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 862 862 0 0.00 0 932 7 0 0
v_cancel_client_order 3474 3474 0 0.00 0 5158 7 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 712 4 0 0
v_cancel_customer_reserve 1262 1262 0 0.00 0 264 3 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 1240 5 0 0
v_cancel_supplier_invoice 858 836 22 2.56 0 2750 21 0 22
v_cancel_supplier_order 776 776 0 0.00 0 452 3 0 0
v_cancel_write_off 2006 2006 0 0.00 0 6077 19 0 0
v_max_id_clo_ord 5451 5451 0 0.00 0 178 1 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 1727 16 0 0
v_max_id_ord_sup 5454 5454 0 0.00 0 4667 4 0 0
v_max_non_paid_invoice 1615 1615 0 0.00 0 1303 3 0 0
v_max_non_paid_realizn 3588 3588 0 0.00 0 3052 5 0 0
v_min_id_clo_ord 5451 5451 0 0.00 0 1694 4 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 2803 8 0 0
v_min_id_ord_sup 5454 5454 0 0.00 0 4087 5 0 0
v_min_non_paid_invoice 5717 5717 0 0.00 0 6646 20 0 0
v_min_non_paid_realizn 6882 6882 0 0.00 0 10907 21 0 0
v_our_firm 10014 10014 0 0.00 0 13 1 0 0
v_reserve_write_off 19553 19553 0 0.00 0 5963 7 0 0
x_lock_depdocs_on_canc_invoice 11847 11847 0 0.00 0 13112 108 0 0
x_lock_depdocs_on_canc_sup_ord 388 388 0 0.00 0 9642 353 0 0
x_make_qstorno_1000_1200 5452 5452 0 0.00 2 42150 1181 0 0
x_make_qstorno_1200_2000 5458 5458 0 0.00 2 87759 3467 0 0
x_make_qstorno_2100_3300 443560 443560 0 0.00 0 34993 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-09-2021 19:39:06 to 17-09-2021 19:39:10.

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
17:07:54 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:56 31208 8192000000 10355436512 10672128000 1403848032 14.629 100 85 170 0 1437954448 5836480 28269936
17:10:31 11294 8192000000 10422462640 10755506176 1485518768 15.350 100 87 175 0 1521670464 7807552 28344144
17:11:46 22502 8192000000 10491464448 10836860928 1548562848 15.898 100 82 164 0 1583269168 8633296 26073024
17:13:29 14188 8192000000 10588413568 10946609152 1641455776 16.692 100 77 154 0 1675063648 10319440 23288432
17:14:57 8753 8192000000 10675911920 11049529344 1732421424 17.456 100 71 142 0 1765235872 12710912 20103536
17:16:08 3269 8192000000 10731315280 11111178240 1786047264 17.899 100 83 166 3 1821851280 12573520 23230496
17:17:14 6981 8192000000 10754679952 11142107136 1813125664 18.121 100 77 154 1 1848155120 13317216 21712240
17:18:23 2081 8192000000 10797443568 11183140864 1852010352 18.438 100 88 175 1 1889626240 13371712 24244176
17:19:26 2069 8192000000 10809724384 11205054464 1872190288 18.602 100 88 177 0 1909969952 12977440 24802224
17:20:33 4706 8192000000 10787869328 11207905280 1873105680 18.609 100 39 78 0 1891854944 8597344 10151920
17:21:38 4244 8192000000 10798985728 11225317376 1888436384 18.733 100 29 58 2 1903423104 7042912 7943808
17:22:48 7850 8192000000 10798519408 11241025536 1903655264 18.856 100 23 46 1 1917121328 6709872 6756192
17:23:58 59360 8192000000 10843819200 11268243456 1909208480 18.900 100 25 49 1 1921853616 5725520 6919616
17:26:00 3762 8192000000 10838968688 11267469312 1914464576 18.942 100 26 52 0 1928979712 6838384 7676752
17:27:06 5056 8192000000 10802096432 11234353152 1873265248 18.611 98 20 38 1 1884742688 6222864 5254576
17:28:11 4223 8192000000 10814745056 11249201152 1898012384 18.810 100 29 58 3 1912067520 5871952 8183184
17:29:19 37878 8192000000 10716859504 11140366336 1648413872 16.751 88 33 65 1 1662739424 5438544 8887008
17:30:58 5182 8192000000 10695144240 11103846400 1753885920 17.634 98 22 44 0 1766201552 6126048 6189584
17:32:03 8630 8192000000 10706676704 11128246272 1798669744 18.003 99 27 53 0 1811801952 5515072 7617136
17:33:14 9666 8192000000 10765769904 11185348608 1842293392 18.359 100 23 46 2 1854783328 5022560 7467376
17:34:25 15007 8192000000 10751220464 11184144384 1832106208 18.277 99 35 70 0 1849716832 6211296 11399328
17:35:41 5720 8192000000 10797708080 11222913024 1877777488 18.647 100 34 67 0 1894025168 5866112 10381568
17:36:50 7718 8192000000 10807859248 11232243712 1883979280 18.697 100 17 34 0 1894157808 5405680 4772848
17:37:59 11567 8192000000 10854437152 11266637824 1919921392 18.986 100 47 93 1 1938395664 5132208 13342064
17:39:13 84901 8192000000 10857045648 11294363648 1936193536 19.116 100 36 72 1 1954043904 6656272 11194096
17:42:23 8686 8192000000 10909406304 11336761344 1972379440 19.404 100 49 98 1 1998836784 12004480 14452864
17:43:42 30319 8192000000 10929367792 11364032512 1988905136 19.535 100 39 77 3 2018191600 18518016 10768448
17:45:17 9802 8192000000 10939624048 11362435072 1957337328 19.285 98 57 112 1 1996404832 21794464 17273040
17:46:32 16743 8192000000 10946012144 11379318784 1988427696 19.531 100 55 109 1 2032209808 27210688 16571424
17:47:53 9829 8192000000 10956117104 11388112896 1988607968 19.533 99 49 95 0 2030568800 27386736 14574096
17:49:28 8195 8192000000 10972351104 11395366912 2007379872 19.681 100 65 130 0 2055783728 29012144 19391712
17:50:39 2166 8192000000 10992500832 11412713472 2020462448 19.784 100 79 157 2 2078835904 33293376 25080080
17:51:47 9705 8192000000 10982631056 11410685952 2012397584 19.720 100 63 126 0 2066564480 34796272 19370624
17:53:01 2709 8192000000 10942784192 11357007872 1936297088 19.117 97 85 167 3 1998278480 36735008 25246384
17:54:11 5164 8192000000 10962584640 11382013952 1975439952 19.429 100 71 139 0 2037488640 40259648 21789040
17:55:22 1934 8192000000 10957828624 11376283648 1967388992 19.365 100 67 130 0 2035915664 47670976 20855696
17:56:30 4087 8192000000 10953364272 11374063616 1975654816 19.430 100 77 153 0 2034086208 34717680 23713712
17:57:37 7392 8192000000 10924000944 11362881536 1951079824 19.235 99 34 68 0 1988619216 26529040 11010352
17:58:48 13156 8192000000 10928869936 11378458624 1978180528 19.450 100 33 66 3 2019638000 30721648 10735824
18:00:06 2281 8192000000 10930292464 11348021248 1942402192 19.166 99 71 140 1 2002526928 37868480 22256256
18:01:10 19344 8192000000 10907185072 11360677888 1945518176 19.191 100 25 50 0 1992882656 38196864 9167616
18:02:34 24371 8192000000 10878346480 11334553600 1935856112 19.114 100 9 18 0 1959396624 20491152 3049360
18:04:00 33488 8192000000 10855468448 11289817088 1884463472 18.701 98 28 54 1 1906266912 12834768 8968672
18:05:35 12368 8192000000 10898447616 11325739008 1947653008 19.208 100 69 135 0 1979578656 11929360 19996288
18:06:53 3748 8192000000 10893750896 11336921088 1954091632 19.259 100 42 86 3 1980569936 14159472 12318832
18:08:08 78731 8192000000 10897341040 11357601792 1965540592 19.350 100 21 41 1 1984657936 13268944 5848400
18:10:28 110178 8192000000 10904769056 11361734656 1950607408 19.231 99 14 28 0 1972512816 17890416 4014992
18:13:21 71889 8192000000 10907735728 11379838976 1970065168 19.386 100 19 36 1 1998595616 22793680 5736768
18:15:37 83361 8192000000 10914344752 11379265536 1981286688 19.475 100 21 42 0 2003738768 16018528 6433552
18:18:01 66364 8192000000 10919616816 11385561088 1971441040 19.397 99 15 30 1 1992490624 16400224 4649360
18:20:19 62744 8192000000 10891547488 11366924288 1950234064 19.228 98 18 36 0 1971612656 15797312 5581280
18:22:38 24592 8192000000 10893181472 11357904896 1958118144 19.291 99 34 68 1 1978966272 10752992 10095136
18:24:08 39884 8192000000 10923166128 11381878784 1985277584 19.506 100 21 42 1 2005220864 13817168 6126112
18:25:48 153746 8192000000 10917938448 11398782976 1946336352 19.197 98 13 26 0 1964317248 14330976 3649920
18:29:34 80875 8192000000 10912981840 11370557440 1945673280 19.192 98 29 58 2 1963409488 9379552 8356656
18:31:58 133939 8192000000 10907482064 11378724864 1903951392 18.858 96 15 28 0 1928161296 20245920 3963984
18:35:13 103547 8192000000 10897702640 11365847040 1937723872 19.129 98 21 41 0 1960365248 17156528 5484848
18:38:20 51835 8192000000 10913762832 11377213440 1971976304 19.401 99 29 56 2 1996282576 15424880 8881392
18:40:13 29116 8192000000 10857938672 11328159744 1872994160 18.608 95 26 48 1 1893009936 12069984 7945792
18:41:50 75970 8192000000 10848965792 11318583296 1908239632 18.893 97 12 25 0 1920806624 9290640 3276352
18:44:08 48228 8192000000 10862347200 11331522560 1931449312 19.078 99 17 32 1 1944826832 8966528 4410992
18:45:59 85644 8192000000 10867212928 11330469888 1931235152 19.077 99 13 26 0 1953114480 18513184 3366144
18:48:47 42955 8192000000 10851382288 11315752960 1891190656 18.755 97 22 44 3 1909860304 12605920 6063728
18:50:33 71432 8192000000 10878504160 11361697792 1950696160 19.232 100 12 24 0 1970450656 16209856 3544640
18:52:49 20496 8192000000 10902072640 11361603584 1961889168 19.321 100 31 62 0 1981581280 10687088 9005024
18:54:12 72498 8192000000 10849498816 11316133888 1808073520 18.080 93 12 22 2 1821363744 10231248 3058976
18:57:07 56833 8192000000 10844185296 11306545152 1874512976 18.621 98 23 46 0 1893842112 13200944 6128192
18:59:05 27522 8192000000 10856976240 11301003264 1864973008 18.544 97 41 82 1 1894511952 18319936 11219008
19:00:36 37612 8192000000 10858236544 11313201152 1887085920 18.722 98 24 48 0 1907713568 14504736 6122912
19:02:18 120464 8192000000 10830240768 11308384256 1867011872 18.560 97 20 40 1 1883211136 10210320 5988944
19:05:49 54461 8192000000 10844387888 11314700288 1914706256 18.944 99 10 20 0 1925464864 7827392 2931216
19:07:46 66073 8192000000 10868561744 11343073280 1919445536 18.982 98 10 19 1 1929544016 7316256 2782224
19:10:18 63226 8192000000 10871029424 11349196800 1942211632 19.164 99 10 20 1 1953423888 8508432 2703824
19:12:22 87443 8192000000 10913993280 11388899328 1978167488 19.450 100 14 28 0 1999891680 17725744 3998448
19:14:50 35793 8192000000 10927006336 11396833280 1995478688 19.587 100 18 36 0 2015966128 15642912 4844528
19:16:35 62490 8192000000 10913065808 11376336896 1932751792 19.089 97 12 24 0 1948163408 12187072 3224544
19:18:40 70052 8192000000 10921267376 11388751872 1953344544 19.253 98 18 36 0 1970236272 12121040 4770688
19:20:55 92143 8192000000 10936518816 11398430720 1884344928 18.700 94 12 24 1 1901992496 14118704 3528864
19:23:35 61695 8192000000 10884503664 11362648064 1878223120 18.651 95 10 20 0 1891375344 10403088 2749136
19:25:42 101189 8192000000 10873426064 11337551872 1852529328 18.443 94 20 41 0 1865884640 7882208 5473104
19:28:25 58051 8192000000 10890349808 11367211008 1968388800 19.373 100 15 28 1 1987468864 14780000 4300064
19:30:23 101649 8192000000 10891054128 11377639424 1943943392 19.178 98 11 22 0 1961513408 14224912 3345104
19:33:13 109429 8192000000 10901154336 11373858816 1876037488 18.633 94 13 26 2 1890504320 10896784 3570048
19:36:07 44573 8192000000 10907386736 11381280768 1950998912 19.234 98 18 36 2 1969499392 13129872 5370608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-09-2021 19:39:11 to 17-09-2021 19:39:26.

Exceptions occured during test was in run

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

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

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237932
MON$OLDEST_ACTIVE               240629
MON$OLDEST_SNAPSHOT             240629
MON$NEXT_TRANSACTION            240629
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-03 16:41:55.3470
MON$PAGES                       2008256
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33498 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33498 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33498 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Sep 17 19:38:04 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Sep 17 19:38:05 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
--- end of diff output ---

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

17.09.21 19:39:30. There are 3 logs in /var/tmp/logs-oltp30 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
2021-09-17 19:39:32 No severe PSQL-related problems occured
17.09.21 19:39:33. End of report.