Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 16.11.2021 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 16489360 kB
MemAvailable 31659528 kB
Buffers 0 kB
Cached 15225700 kB
SwapCached 0 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.1
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 2021-11-16 10:37:06
DTS_END                         2021-11-16 10:37:06.5110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/44458
TRN_ID                          235170
ATT_ID                          651
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: 9113.29 25918 1093595
customer order: creation 159.81 387 19177
customer order: refuse 14.12 357 1694
order to supplier: creation 44.07 1401 5288
order to supplier: removal 3.57 112064 428
invoice (draft): creation 43.53 3605 5224
invoice (draft): removal 53.08 938 6369
invoice accept: apply 40.10 18368 4812
invoice accept: cancel 48.49 776 5819
customer reserve: creation 3688.91 180 442669
customer reserve: removal 4734.48 66 568138
realization accept: apply 80.43 200 9651
realization accept: cancel 79.73 118 9568
payment from customer: creation 56.27 99 6752
payment from customer: removal 6.09 94 731
payment to supplier: creation 46.84 141 5621
payment to supplier: removal 3.35 86 402
service: total inventory turnovers 3.53 3877 423
service: total monetary turnovers 5.97 530 716
service: aggregate perf. data 0.91 13344 109
service: refresh index statistics 0.03 361724 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-11-2021 10:38:48 to 16-11-2021 10:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1713
TEST_TIME 2 4374
TEST_TIME 3 5051
TEST_TIME 4 5384
TEST_TIME 5 5842
TEST_TIME 6 5725
TEST_TIME 7 5908
TEST_TIME 8 5698
TEST_TIME 9 5832
TEST_TIME 10 6360
TEST_TIME 11 6528
TEST_TIME 12 6469
TEST_TIME 13 6522
TEST_TIME 14 6809
TEST_TIME 15 7272
TEST_TIME 16 7721
TEST_TIME 17 8141
TEST_TIME 18 8474
TEST_TIME 19 8682
TEST_TIME 20 8650
TEST_TIME 21 8704
TEST_TIME 22 9126
TEST_TIME 23 9509
TEST_TIME 24 9503
TEST_TIME 25 9488
TEST_TIME 26 9408
TEST_TIME 27 10022
TEST_TIME 28 11206
TEST_TIME 29 11217
TEST_TIME 30 11256
TEST_TIME 31 11111
TEST_TIME 32 11005
TEST_TIME 33 10910
TEST_TIME 34 10663
TEST_TIME 35 10647
TEST_TIME 36 10550
TEST_TIME 37 10411
TEST_TIME 38 10285
TEST_TIME 39 10200
TEST_TIME 40 10144
TEST_TIME 41 10022
TEST_TIME 42 10053
TEST_TIME 43 10338
TEST_TIME 44 10300
TEST_TIME 45 10383
TEST_TIME 46 10378
TEST_TIME 47 10312
TEST_TIME 48 10356
TEST_TIME 49 10195
TEST_TIME 50 10013
TEST_TIME 51 9969
TEST_TIME 52 9836
TEST_TIME 53 9840
TEST_TIME 54 9798
TEST_TIME 55 9731
TEST_TIME 56 9804
TEST_TIME 57 9885
TEST_TIME 58 9817
TEST_TIME 59 9721
TEST_TIME 60 9785
TEST_TIME 61 9678
TEST_TIME 62 9597
TEST_TIME 63 9567
TEST_TIME 64 9464
TEST_TIME 65 9436
TEST_TIME 66 9376
TEST_TIME 67 9390
TEST_TIME 68 9344
TEST_TIME 69 9371
TEST_TIME 70 9392
TEST_TIME 71 9433
TEST_TIME 72 9534
TEST_TIME 73 9560
TEST_TIME 74 9537
TEST_TIME 75 9499
TEST_TIME 76 9640
TEST_TIME 77 9681
TEST_TIME 78 9664
TEST_TIME 79 9677
TEST_TIME 80 9660
TEST_TIME 81 9746
TEST_TIME 82 9651
TEST_TIME 83 9585
TEST_TIME 84 9571
TEST_TIME 85 9575
TEST_TIME 86 9553
TEST_TIME 87 9502
TEST_TIME 88 9461
TEST_TIME 89 9408
TEST_TIME 90 9356
TEST_TIME 91 9352
TEST_TIME 92 9317
TEST_TIME 93 9244
TEST_TIME 94 9223
TEST_TIME 95 9227
TEST_TIME 96 9265
TEST_TIME 97 9256
TEST_TIME 98 9224
TEST_TIME 99 9225
TEST_TIME 100 9270
TEST_TIME 101 9342
TEST_TIME 102 9378
TEST_TIME 103 9372
TEST_TIME 104 9407
TEST_TIME 105 9421
TEST_TIME 106 9445
TEST_TIME 107 9443
TEST_TIME 108 9494
TEST_TIME 109 9419
TEST_TIME 110 9389
TEST_TIME 111 9345
TEST_TIME 112 9318
TEST_TIME 113 9284
TEST_TIME 114 9291
TEST_TIME 115 9260
TEST_TIME 116 9241
TEST_TIME 117 9232
TEST_TIME 118 9213
TEST_TIME 119 9189
TEST_TIME 120 9113

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-11-2021 10:38:51 to 16-11-2021 10:38:55.

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 1505174 1505174 0 0.00 0 17812 6 0 0
doc_list_biud 1505174 1505174 0 0.00 0 64273 32 0 0
sp_add_invoice_to_stock 4931 4812 119 2.41 47 171531 18369 0 119
sp_cancel_adding_invoice 5819 5819 0 0.00 5 71359 777 0 0
sp_cancel_client_order 1694 1694 0 0.00 4 10136 357 0 0
sp_cancel_customer_reserve 568138 568138 0 0.00 1 22686 66 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 8330 94 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 5520 87 0 0
sp_cancel_supplier_invoice 6385 6369 16 0.25 2 74905 939 0 16
sp_cancel_supplier_order 428 428 0 0.00 12 1102845 112065 0 0
sp_cancel_write_off 9568 9568 0 0.00 1 11994 118 0 0
sp_client_order 19177 19177 0 0.00 4 43279 387 0 0
sp_customer_reserve 442669 442669 0 0.00 0 70032 181 0 0
sp_fill_shopping_cart 29685 29685 0 0.00 1 30454 49 0 0
sp_fill_shopping_cart_clo_res 442668 442668 0 0.00 0 36400 16 0 0
sp_get_clo_for_invoice 4810 4810 0 0.00 0 38277 417 0 0
sp_kill_cost_storno 16520 16520 0 0.00 0 8325 61 0 0
sp_kill_qstorno_ret_qs2qd 1152641 1152641 0 0.00 0 64271 36 0 0
sp_kill_qty_storno 606475 606475 0 0.00 0 64272 77 0 0
sp_lock_selected_doc 20091 20091 0 0.00 0 8300 7 0 0
sp_make_cost_storno 26832 26832 0 0.00 0 16041 64 0 0
sp_multiply_rows_for_pdistr 26832 26832 0 0.00 0 8289 32 0 0
sp_multiply_rows_for_qdistr 19177 19177 0 0.00 0 43191 190 0 0
sp_pay_from_customer 6752 6752 0 0.00 4 12285 99 0 0
sp_pay_to_supplier 5621 5621 0 0.00 5 18092 141 0 0
sp_payment_common 12373 12373 0 0.00 1 6703 18 0 0
sp_qd_handle_on_cancel_clo 1694 1694 0 0.00 0 10123 323 0 0
sp_qd_handle_on_invoice_upd_sts 10627 10627 0 0.00 0 43993 382 0 0
sp_qd_handle_on_reserve_upd_sts 9651 9651 0 0.00 0 30367 67 0 0
sp_reserve_write_off 9651 9651 0 0.00 7 30381 201 0 0
sp_supplier_invoice 5224 5224 0 0.00 6 88717 3605 0 0
sp_supplier_order 5288 5288 0 0.00 5 56005 1402 0 0
srv_aggregate_perf_data 109 109 0 0.00 2937 174574 13345 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1494 185729 51123 0 0
srv_find_qd_qs_mism 1049073 1049073 0 0.00 0 17773 5 0 0
srv_make_invnt_saldo 423 423 0 0.00 34 98591 3877 0 0
srv_make_money_saldo 716 716 0 0.00 0 16456 530 0 0
srv_recalc_idx_stat 4 4 0 0.00 262132 512642 361724 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4812 4812 0 0.00 0 19626 169 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 0 1930 79 0 0
t$perf-norm:sp_cancel_client_order 1694 1694 0 0.00 0 1363 11 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 2919 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 3753 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 1461 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 380 380 0 0.00 0 598 17 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 24047 1628 0 0
t$perf-norm:sp_cancel_write_off 955 955 0 0.00 0 16755 29 0 0
t$perf-norm:sp_client_order 19177 19177 0 0.00 0 17002 14 0 0
t$perf-norm:sp_customer_reserve 927 927 0 0.00 0 5243 11 0 0
t$perf-norm:sp_pay_from_customer 6752 6752 0 0.00 0 3299 7 0 0
t$perf-norm:sp_pay_to_supplier 5621 5621 0 0.00 0 15953 11 0 0
t$perf-norm:sp_reserve_write_off 9651 9651 0 0.00 0 7721 10 0 0
t$perf-norm:sp_supplier_invoice 5224 5224 0 0.00 0 4709 16 0 0
t$perf-norm:sp_supplier_order 5288 5288 0 0.00 0 4868 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 1288 30 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 44 1 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 257 5 0 0
t$perf-norm:srv_make_money_saldo 716 716 0 0.00 0 648 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 59 18 0 0
v_add_invoice_to_stock 9852 9614 238 2.41 0 5458 20 0 238
v_all_customers 34822 34822 0 0.00 0 34 1 0 0
v_all_suppliers 28964 28964 0 0.00 0 53 1 0 0
v_all_wares 38352 38352 0 0.00 0 120 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 917 6 0 0
v_cancel_client_order 3388 3388 0 0.00 0 3815 8 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 5055 8 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1999 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 553 5 0 0
v_cancel_supplier_invoice 792 760 32 4.04 0 1096 16 0 32
v_cancel_supplier_order 856 856 0 0.00 0 2455 12 0 0
v_cancel_write_off 1910 1910 0 0.00 0 6904 19 0 0
v_max_id_clo_ord 5288 5288 0 0.00 0 2280 2 0 0
v_max_id_clo_res 927 927 0 0.00 0 3904 21 0 0
v_max_id_ord_sup 5221 5221 0 0.00 0 2103 4 0 0
v_max_non_paid_invoice 1648 1648 0 0.00 0 1361 3 0 0
v_max_non_paid_realizn 3778 3778 0 0.00 0 1411 3 0 0
v_min_id_clo_ord 5288 5288 0 0.00 0 1889 6 0 0
v_min_id_clo_res 927 927 0 0.00 0 8026 38 0 0
v_min_id_ord_sup 5221 5221 0 0.00 0 4902 10 0 0
v_min_non_paid_invoice 5621 5621 0 0.00 0 6699 14 0 0
v_min_non_paid_realizn 6752 6752 0 0.00 0 6634 10 0 0
v_our_firm 9478 9478 0 0.00 0 20 1 0 0
v_reserve_write_off 19302 19302 0 0.00 0 6156 7 0 0
x_lock_depdocs_on_canc_invoice 11808 11808 0 0.00 0 20867 111 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 5743 344 0 0
x_make_qstorno_1000_1200 5288 5288 0 0.00 1 55995 1311 0 0
x_make_qstorno_1200_2000 5224 5224 0 0.00 3 72627 3418 0 0
x_make_qstorno_2100_3300 442669 442669 0 0.00 0 70030 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-11-2021 10:38:56 to 16-11-2021 10:39:00.

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:10 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:10 20926 8192000000 9722754400 10022727680 812726800 9.025 100 86 173 0 840756704 7474384 20555520
08:09:41 22846 8192000000 9769349424 10077265920 843442528 9.334 100 85 170 1 876369216 12903776 20022912
08:11:12 13261 8192000000 9801545888 10117947392 866287952 9.563 100 85 170 0 909400432 23405824 19706656
08:12:35 11891 8192000000 9873203392 10202316800 904129248 9.939 100 85 164 1 974743136 52759344 17854544
08:13:49 9672 8192000000 9917429264 10260729856 944305744 10.335 100 76 150 2 1026794560 66295664 16193152
08:15:05 3224 8192000000 9954360896 10303979520 991838896 10.799 100 86 170 0 1059972176 50641824 17491456
08:16:11 2688 8192000000 9996114112 10357149696 1021348528 11.085 100 89 179 0 1108621184 68534160 18738496
08:17:15 1229 8192000000 10006414208 10376904704 1051062192 11.371 100 81 163 2 1120914000 54287184 15564624
08:18:17 1541 8192000000 10072716640 10453184512 1073619920 11.587 100 83 164 0 1187427072 96907248 16899904
08:19:19 3514 8192000000 10004911840 10404954112 1074043424 11.591 100 28 56 1 1130862864 50928576 5890864
08:20:24 8157 8192000000 9999778272 10399879168 1083436336 11.680 100 34 68 0 1112844016 22116976 7290704
08:21:33 6432 8192000000 10014142592 10401521664 1091672944 11.759 100 43 83 3 1123524992 22557264 9294784
08:22:40 69945 8192000000 9999463904 10412122112 1101843728 11.855 100 41 81 2 1126691328 15838512 9009088
08:24:50 3177 8192000000 10037114496 10442289152 1102470416 11.861 100 58 114 2 1155112688 39914752 12727520
08:25:57 7013 8192000000 10017218624 10413424640 1095280048 11.793 100 39 76 1 1127643888 24299072 8064768
08:27:07 11535 8192000000 10006033936 10421121024 1075009392 11.600 98 32 65 1 1111285312 30172544 6103376
08:28:19 26736 8192000000 9984334672 10386628608 1018144736 11.054 94 36 70 1 1054664272 29010064 7509472
08:29:48 5477 8192000000 9964027616 10344341504 1029055344 11.159 98 48 97 3 1061075120 20976288 11043488
08:30:56 5916 8192000000 9919923024 10297020416 989008928 10.772 98 25 50 0 1022094544 27307840 5777776
08:32:06 8860 8192000000 9946488448 10333696000 1011428048 10.989 98 35 68 2 1030028848 10906176 7694624
08:33:15 26333 8192000000 9955325216 10356658176 1041058736 11.275 100 23 46 1 1071947520 26353296 4535488
08:34:47 6983 8192000000 9968774464 10359844864 1058657008 11.444 100 38 76 1 1075316944 8304080 8355856
08:35:54 6851 8192000000 9982561344 10377547776 1066925776 11.523 100 38 75 3 1094109232 18977600 8205856
08:37:06 109192 8192000000 10027895360 10423779328 1085846032 11.703 100 40 80 1 1111622816 17309584 8467200
08:40:02 23495 8192000000 10063577072 10472632320 1103844160 11.874 100 38 76 0 1184834848 72919792 8070896
08:41:29 7567 8192000000 10134301360 10538762240 1112643760 11.957 100 52 104 1 1235181504 111582144 10955600
08:42:41 22970 8192000000 10127708608 10532986880 1125221680 12.076 100 47 93 0 1217073024 81700928 10150416
08:44:07 20562 8192000000 10157403168 10586750976 1128864224 12.111 99 41 82 2 1272329040 134225120 9239696
08:45:31 21590 8192000000 10185927872 10617901056 1146660400 12.278 100 46 92 0 1313010608 155444848 10905360
08:46:59 26210 8192000000 10221631408 10680864768 1162007552 12.422 100 44 88 0 1345261808 173206736 10047520
08:48:31 10858 8192000000 10228811056 10638303232 1179947040 12.590 100 61 119 2 1326543360 134008032 12588288
08:49:46 4732 8192000000 10269548688 10669289472 1166394016 12.463 100 78 156 0 1373282208 189399424 17488768
08:50:55 3317 8192000000 10311998592 10716532736 1153681472 12.344 98 85 170 2 1408950320 235881008 19387840
08:52:03 2639 8192000000 10353111488 10753339392 1172782576 12.523 99 87 173 0 1444999536 251906960 20310000
08:53:07 12636 8192000000 10322510048 10743554048 1167111712 12.470 99 42 85 0 1426757104 249023344 10622048
08:54:24 1494 8192000000 10369496800 10769768448 1159027392 12.394 100 84 167 0 1461495232 283079632 19388208
08:55:30 2592 8192000000 10349967952 10769616896 1155524512 12.361 98 75 149 0 1431376512 258451904 17400096
08:56:35 1945 8192000000 10346655040 10746040320 1159501520 12.399 100 79 156 2 1440739520 263176704 18061296
08:57:41 9257 8192000000 10382821328 10793844736 1143131904 12.245 100 52 104 0 1474165536 318088512 12945120
08:58:53 8129 8192000000 10458701008 10882306048 1147732096 12.288 100 42 84 0 1557945424 399091936 11121392
09:00:06 8381 8192000000 10444168992 10865860608 1141348416 12.228 99 48 95 0 1526981648 373513472 12119760
09:01:22 14939 8192000000 10404538864 10849660928 1149276896 12.303 100 28 54 3 1517940208 361595760 7067552
09:02:41 11654 8192000000 10425904704 10854432768 1136978352 12.187 100 38 76 1 1525849568 378662640 10208576
09:03:58 12767 8192000000 10477259392 10925711360 1134696112 12.166 100 30 58 2 1581339120 438875024 7767984
09:05:12 21349 8192000000 10218885920 10738704384 1104663936 11.882 98 15 30 0 1389358096 280556992 4137168
09:06:34 35526 8192000000 10106900896 10554425344 1129850448 12.120 100 44 88 1 1216847424 76113488 10883488
09:08:11 102894 8192000000 10066681200 10529202176 1089609984 11.739 97 29 59 0 1153785104 57816224 6358896
09:10:54 120470 8192000000 10070129280 10546257920 1081029152 11.657 97 12 24 2 1149534400 65239952 3265296
09:13:55 41031 8192000000 10042252464 10476933120 1105091488 11.886 99 24 46 0 1141147168 31038320 5017360
09:15:37 25382 8192000000 10065079808 10491289600 1118995840 12.018 100 40 79 2 1163301712 36632240 7673632
09:17:11 37864 8192000000 10069313424 10508447744 1110131792 11.934 99 26 52 2 1150991296 35851184 5008320
09:19:09 185729 8192000000 10078517536 10559819776 1126696464 12.090 99 9 18 0 1202490320 74146592 1647264
09:23:18 106766 8192000000 10112692240 10586095616 1135310128 12.171 99 18 36 1 1176557040 38182256 3064656
09:26:05 115626 8192000000 10084850736 10527981568 1135280096 12.171 99 30 60 1 1173695184 32455344 5959744
09:29:21 15837 8192000000 10140731168 10577031168 1162011264 12.422 100 59 118 2 1235950480 61245152 12694064
09:30:39 50545 8192000000 10133081664 10591678464 1136177248 12.180 99 16 32 0 1224661648 84925648 3558752
09:32:31 34518 8192000000 10158073648 10615324672 1150722688 12.316 100 25 50 0 1266303120 109931680 5648752
09:34:08 19446 8192000000 10091746048 10515451904 1129578896 12.117 99 49 100 2 1183791264 44382016 9830352
09:35:28 111032 8192000000 10115207968 10579582976 1126228512 12.086 99 15 31 0 1174556144 45161296 3166336
09:38:51 65124 8192000000 10051006160 10516934656 1093486336 11.776 97 10 20 0 1138501792 43073488 1941968
09:41:44 64715 8192000000 10039237648 10509197312 1103583104 11.872 99 23 46 0 1132943440 24979408 4380928
09:43:49 64193 8192000000 10046076992 10507280384 1095371504 11.794 98 11 22 0 1141815888 44208160 2236224
09:46:02 39864 8192000000 10118853728 10596671488 1120486208 12.032 99 31 61 1 1220982400 93901888 6594304
09:47:42 72352 8192000000 10097203440 10529406976 1059548720 11.452 94 20 40 0 1184327648 120470992 4307936
09:49:54 44003 8192000000 10068305296 10504486912 1059463664 11.451 95 16 32 0 1111133248 48331392 3338192
09:51:43 58309 8192000000 10133863616 10574643200 1108859280 11.922 98 31 62 0 1199894128 84491536 6543312
09:53:41 95143 8192000000 10058231440 10532384768 1097833328 11.817 97 11 22 0 1160809408 60378128 2597952
09:56:42 41681 8192000000 10106084912 10567757824 1104288352 11.878 98 27 54 0 1164908240 55292480 5327408
09:58:45 120471 8192000000 10091266704 10555023360 1122864416 12.054 99 35 71 1 1174066400 43189376 8012608
10:01:46 39835 8192000000 10082467424 10539110400 1126232256 12.086 100 34 68 1 1181508784 48223856 7052672
10:03:27 65106 8192000000 10026389696 10475008000 1090843040 11.751 97 18 36 1 1123712512 28693856 4175616
10:05:33 80709 8192000000 10070142320 10519977984 1104834176 11.883 98 13 26 1 1136612784 28969040 2809568
10:07:55 60661 8192000000 10040382096 10535964672 1121775120 12.044 100 14 28 0 1176508928 51366608 3367200
10:09:56 92903 8192000000 10068149568 10548477952 1131504528 12.136 99 12 24 1 1193808800 59721744 2582528
10:13:10 70423 8192000000 10074380496 10515947520 1139230064 12.208 99 15 30 0 1202001696 59430752 3340880
10:15:21 38191 8192000000 10163287424 10594316288 1154680240 12.353 100 44 88 1 1235737616 71928016 9129360
10:17:02 89363 8192000000 10101489792 10592178176 1132832720 12.148 98 19 38 1 1226908976 89620048 4456208
10:19:31 43708 8192000000 10090466976 10561576960 1142626256 12.240 99 27 53 2 1206081744 58277088 5178400
10:21:15 133535 8192000000 10101905392 10588749824 1083136080 11.677 94 32 64 2 1129168400 40101520 5930800
10:24:30 94190 8192000000 10093504896 10590879744 1127188800 12.095 98 21 42 0 1166021936 34457696 4375440
10:27:13 56345 8192000000 10072978208 10528628736 1118920800 12.017 97 21 42 1 1152800368 29690336 4189232
10:29:20 46132 8192000000 10114205168 10582228992 1124988976 12.074 99 12 24 0 1200312960 72888672 2435312
10:31:06 68669 8192000000 10090815232 10579070976 1116699168 11.996 98 13 26 0 1208598800 88850080 3049552
10:33:27 60380 8192000000 10112303744 10584494080 1115353232 11.983 98 13 26 2 1204397600 86010256 3034112
10:35:28 102654 8192000000 9830439680 10235789312 822121472 9.120 72 26 52 0 926884768 98937680 5825616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-11-2021 10:39:01 to 16-11-2021 10:39:16.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-11-2021 10:39:17 to 16-11-2021 10:39:19.

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          231695
MON$OLDEST_ACTIVE               235471
MON$OLDEST_SNAPSHOT             235471
MON$NEXT_TRANSACTION            235471
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                       2079520
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2666 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2666 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2666 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2666 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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue Nov 16 10:37:17 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
--- end of diff output ---

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

16.11.21 10:39:20. 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
2021-11-16 10:39:22 No severe PSQL-related problems occured
16.11.21 10:39:23. End of report.