Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.08.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 16216344 kB
MemAvailable 30909536 kB
Buffers 0 kB
Cached 15448040 kB
SwapCached 1324 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-08-28 19:37:47
DTS_END                         2021-08-28 19:37:47.6650
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(57:17)
IP                              ::1/40916
TRN_ID                          244814
ATT_ID                          694
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: 9247.19 18564 1109663
customer order: creation 178.40 368 21408
customer order: refuse 15.59 389 1871
order to supplier: creation 47.80 1320 5736
order to supplier: removal 3.28 100435 394
invoice (draft): creation 47.22 3436 5666
invoice (draft): removal 52.42 805 6290
invoice accept: apply 43.14 18371 5177
invoice accept: cancel 46.88 741 5626
customer reserve: creation 3936.08 185 472329
customer reserve: removal 4572.08 56 548649
realization accept: apply 88.84 189 10661
realization accept: cancel 80.22 96 9627
payment from customer: creation 61.99 125 7439
payment from customer: removal 5.79 106 695
payment to supplier: creation 51.61 139 6193
payment to supplier: removal 3.58 119 430
service: total inventory turnovers 4.65 1072 558
service: total monetary turnovers 6.81 34 817
service: aggregate perf. data 0.78 21927 93
service: refresh index statistics 0.03 221376 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-08-2021 19:38:55 to 28-08-2021 19:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4252
TEST_TIME 2 4109
TEST_TIME 3 3982
TEST_TIME 4 3772
TEST_TIME 5 5559
TEST_TIME 6 5112
TEST_TIME 7 5011
TEST_TIME 8 5425
TEST_TIME 9 5562
TEST_TIME 10 5659
TEST_TIME 11 5607
TEST_TIME 12 5952
TEST_TIME 13 6078
TEST_TIME 14 6141
TEST_TIME 15 6443
TEST_TIME 16 6412
TEST_TIME 17 6417
TEST_TIME 18 6567
TEST_TIME 19 7000
TEST_TIME 20 7915
TEST_TIME 21 8128
TEST_TIME 22 8213
TEST_TIME 23 8362
TEST_TIME 24 8875
TEST_TIME 25 9057
TEST_TIME 26 9387
TEST_TIME 27 9717
TEST_TIME 28 10352
TEST_TIME 29 10513
TEST_TIME 30 10293
TEST_TIME 31 10278
TEST_TIME 32 10208
TEST_TIME 33 9990
TEST_TIME 34 10026
TEST_TIME 35 9991
TEST_TIME 36 9959
TEST_TIME 37 9841
TEST_TIME 38 9869
TEST_TIME 39 9824
TEST_TIME 40 9836
TEST_TIME 41 9822
TEST_TIME 42 9723
TEST_TIME 43 9606
TEST_TIME 44 9471
TEST_TIME 45 9541
TEST_TIME 46 9508
TEST_TIME 47 9494
TEST_TIME 48 9465
TEST_TIME 49 9497
TEST_TIME 50 9536
TEST_TIME 51 9500
TEST_TIME 52 9565
TEST_TIME 53 9642
TEST_TIME 54 9738
TEST_TIME 55 9800
TEST_TIME 56 9820
TEST_TIME 57 9820
TEST_TIME 58 9805
TEST_TIME 59 9818
TEST_TIME 60 9740
TEST_TIME 61 9689
TEST_TIME 62 9620
TEST_TIME 63 9561
TEST_TIME 64 9521
TEST_TIME 65 9482
TEST_TIME 66 9423
TEST_TIME 67 9368
TEST_TIME 68 9355
TEST_TIME 69 9288
TEST_TIME 70 9427
TEST_TIME 71 9416
TEST_TIME 72 9381
TEST_TIME 73 9332
TEST_TIME 74 9385
TEST_TIME 75 9560
TEST_TIME 76 9561
TEST_TIME 77 9512
TEST_TIME 78 9550
TEST_TIME 79 9548
TEST_TIME 80 9623
TEST_TIME 81 9602
TEST_TIME 82 9592
TEST_TIME 83 9511
TEST_TIME 84 9499
TEST_TIME 85 9490
TEST_TIME 86 9456
TEST_TIME 87 9465
TEST_TIME 88 9391
TEST_TIME 89 9350
TEST_TIME 90 9305
TEST_TIME 91 9358
TEST_TIME 92 9345
TEST_TIME 93 9319
TEST_TIME 94 9391
TEST_TIME 95 9353
TEST_TIME 96 9376
TEST_TIME 97 9406
TEST_TIME 98 9381
TEST_TIME 99 9408
TEST_TIME 100 9459
TEST_TIME 101 9448
TEST_TIME 102 9452
TEST_TIME 103 9461
TEST_TIME 104 9468
TEST_TIME 105 9505
TEST_TIME 106 9465
TEST_TIME 107 9398
TEST_TIME 108 9381
TEST_TIME 109 9332
TEST_TIME 110 9409
TEST_TIME 111 9338
TEST_TIME 112 9313
TEST_TIME 113 9266
TEST_TIME 114 9263
TEST_TIME 115 9295
TEST_TIME 116 9296
TEST_TIME 117 9280
TEST_TIME 118 9292
TEST_TIME 119 9265
TEST_TIME 120 9247

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-08-2021 19:38:58 to 28-08-2021 19:39:02.

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 1548926 1548926 0 0.00 0 31683 7 0 0
doc_list_biud 1548925 1548925 0 0.00 0 25935 25 0 0
sp_add_invoice_to_stock 5280 5177 103 1.95 55 218592 18372 0 103
sp_cancel_adding_invoice 5626 5626 0 0.00 4 49840 742 0 0
sp_cancel_client_order 1871 1871 0 0.00 4 13342 389 0 0
sp_cancel_customer_reserve 548649 548649 0 0.00 1 25937 56 0 0
sp_cancel_pay_from_customer 695 695 0 0.00 3 19281 107 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 5403 120 0 0
sp_cancel_supplier_invoice 6297 6290 7 0.11 2 19150 805 0 7
sp_cancel_supplier_order 394 394 0 0.00 8 1025356 100436 0 0
sp_cancel_write_off 9627 9627 0 0.00 1 17339 96 0 0
sp_client_order 21408 21408 0 0.00 3 18783 369 0 0
sp_customer_reserve 472329 472329 0 0.00 0 45311 185 0 0
sp_fill_shopping_cart 32804 32804 0 0.00 1 19114 36 0 0
sp_fill_shopping_cart_clo_res 472327 472327 0 0.00 0 13480 14 0 0
sp_get_clo_for_invoice 5175 5175 0 0.00 0 28756 260 0 0
sp_kill_cost_storno 16378 16378 0 0.00 0 26638 55 0 0
sp_kill_qstorno_ret_qs2qd 1113609 1113609 0 0.00 0 25923 31 0 0
sp_kill_qty_storno 588292 588292 0 0.00 0 25935 66 0 0
sp_lock_selected_doc 21834 21834 0 0.00 0 6582 7 0 0
sp_make_cost_storno 29468 29468 0 0.00 0 31417 55 0 0
sp_multiply_rows_for_pdistr 29467 29467 0 0.00 0 25821 35 0 0
sp_multiply_rows_for_qdistr 21408 21408 0 0.00 0 11603 159 0 0
sp_pay_from_customer 7439 7439 0 0.00 4 26536 125 0 0
sp_pay_to_supplier 6193 6193 0 0.00 4 19664 140 0 0
sp_payment_common 13632 13632 0 0.00 1 26514 32 0 0
sp_qd_handle_on_cancel_clo 1871 1871 0 0.00 0 13282 358 0 0
sp_qd_handle_on_invoice_upd_sts 10801 10801 0 0.00 0 16127 314 0 0
sp_qd_handle_on_reserve_upd_sts 10660 10660 0 0.00 0 16720 55 0 0
sp_reserve_write_off 10661 10661 0 0.00 6 26281 190 0 0
sp_supplier_invoice 5666 5666 0 0.00 7 102966 3437 0 0
sp_supplier_order 5736 5736 0 0.00 7 37681 1321 0 0
srv_aggregate_perf_data 93 93 0 0.00 2408 400176 21928 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1489 119359 45046 0 0
srv_find_qd_qs_mism 1061233 1061233 0 0.00 0 17297 6 0 0
srv_make_invnt_saldo 558 558 0 0.00 49 62889 1073 0 0
srv_make_money_saldo 817 817 0 0.00 1 5892 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 80856 287588 221377 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5177 5177 0 0.00 0 14605 164 0 0
t$perf-norm:sp_cancel_adding_invoice 426 426 0 0.00 0 2671 91 0 0
t$perf-norm:sp_cancel_client_order 1871 1871 0 0.00 0 8772 19 0 0
t$perf-norm:sp_cancel_customer_reserve 750 750 0 0.00 0 3646 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 695 695 0 0.00 0 710 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 3363 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2305 21 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 22637 1737 0 0
t$perf-norm:sp_cancel_write_off 1024 1024 0 0.00 0 3272 14 0 0
t$perf-norm:sp_client_order 21408 21408 0 0.00 0 7785 17 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 9901 16 0 0
t$perf-norm:sp_pay_from_customer 7439 7439 0 0.00 0 11557 9 0 0
t$perf-norm:sp_pay_to_supplier 6193 6193 0 0.00 0 5709 9 0 0
t$perf-norm:sp_reserve_write_off 10661 10661 0 0.00 0 11005 10 0 0
t$perf-norm:sp_supplier_invoice 5666 5666 0 0.00 0 7706 18 0 0
t$perf-norm:sp_supplier_order 5736 5736 0 0.00 0 5659 18 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 665 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 19 1 0 0
t$perf-norm:srv_make_invnt_saldo 558 558 0 0.00 0 759 19 0 0
t$perf-norm:srv_make_money_saldo 817 817 0 0.00 0 7141 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 12 5 0 0
v_add_invoice_to_stock 10556 10350 206 1.95 0 6043 21 0 206
v_all_customers 39192 39192 0 0.00 0 14 1 0 0
v_all_suppliers 31624 31624 0 0.00 0 15 1 0 0
v_all_wares 42816 42816 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 852 852 0 0.00 0 2016 14 0 0
v_cancel_client_order 3742 3742 0 0.00 0 3434 7 0 0
v_cancel_customer_prepayment 1390 1390 0 0.00 0 3295 6 0 0
v_cancel_customer_reserve 1500 1500 0 0.00 0 2585 5 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 1906 5 0 0
v_cancel_supplier_invoice 834 820 14 1.67 0 4149 25 0 14
v_cancel_supplier_order 788 788 0 0.00 0 2553 7 0 0
v_cancel_write_off 2048 2048 0 0.00 0 3060 13 0 0
v_max_id_clo_ord 5734 5734 0 0.00 0 1945 2 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 1388 10 0 0
v_max_id_ord_sup 5662 5662 0 0.00 0 5971 4 0 0
v_max_non_paid_invoice 1777 1777 0 0.00 0 2294 4 0 0
v_max_non_paid_realizn 4036 4036 0 0.00 0 8341 5 0 0
v_min_id_clo_ord 5734 5734 0 0.00 0 4779 8 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 6369 16 0 0
v_min_id_ord_sup 5662 5662 0 0.00 0 6190 5 0 0
v_min_non_paid_invoice 6193 6193 0 0.00 0 11272 16 0 0
v_min_non_paid_realizn 7439 7439 0 0.00 0 15692 18 0 0
v_our_firm 10430 10430 0 0.00 0 14 1 0 0
v_reserve_write_off 21318 21318 0 0.00 0 5339 6 0 0
x_lock_depdocs_on_canc_invoice 11506 11506 0 0.00 0 20907 118 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 21341 410 0 0
x_make_qstorno_1000_1200 5736 5736 0 0.00 1 37669 1240 0 0
x_make_qstorno_1200_2000 5666 5666 0 0.00 3 102894 3310 0 0
x_make_qstorno_2100_3300 472329 472329 0 0.00 0 45309 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-08-2021 19:39:03 to 28-08-2021 19:39:07.

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:51 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:52 26870 8192000000 10363194096 10680057856 1411799264 14.700 100 91 182 0 1447915216 6310672 29805280
17:10:25 12323 8192000000 10410711968 10736312320 1461249040 15.137 100 87 174 0 1497746528 8160912 28336576
17:11:45 16748 8192000000 10460912880 10800689152 1517692256 15.630 100 80 158 2 1551850240 9184464 24973520
17:13:07 22521 8192000000 10560004448 10915946496 1612104080 16.443 100 83 166 0 1648208512 11103568 25000864
17:14:35 8325 8192000000 10622862448 10995462144 1689855184 17.100 100 80 160 0 1726728064 13872144 23000736
17:15:48 5446 8192000000 10677119584 11052224512 1739373712 17.513 100 80 160 2 1776186496 13814192 22998592
17:16:56 5285 8192000000 10722508448 11109765120 1783894736 17.882 100 75 150 1 1817717072 12766544 21055792
17:18:03 1263 8192000000 10785035200 11171065856 1845482480 18.385 100 89 175 2 1883454592 12754464 25217648
17:19:06 31767 8192000000 10810863216 11206930432 1871164128 18.594 100 62 124 4 1899734608 10639392 17931088
17:20:39 3671 8192000000 10794075888 11213238272 1876539376 18.637 100 41 82 4 1895455536 8016720 10899440
17:21:43 6679 8192000000 10791116528 11219738624 1884023840 18.698 100 24 49 0 1896586624 5687120 6875664
17:22:49 9296 8192000000 10789388144 11229360128 1888878480 18.737 100 14 28 1 1898989616 6366816 3744320
17:24:04 50988 8192000000 10828170336 11256340480 1900694032 18.832 100 34 68 0 1916193456 5907008 9592416
17:25:58 7399 8192000000 10821801600 11261710336 1912587424 18.927 100 27 54 3 1926311424 5995536 7728464
17:27:06 9121 8192000000 10831826752 11257417728 1889995088 18.746 99 29 58 1 1908261568 9714160 8552320
17:28:15 11620 8192000000 10802546944 11231477760 1873535952 18.613 99 30 61 2 1887959984 6156336 8267696
17:29:29 20870 8192000000 10766406544 11204612096 1801279232 18.024 96 18 36 0 1815635696 9309216 5047248
17:30:50 4179 8192000000 10701859744 11118518272 1741266688 17.529 96 28 57 3 1754860640 5343440 8250512
17:31:55 14785 8192000000 10704139040 11116859392 1751347520 17.613 97 28 56 1 1765882768 6238112 8297136
17:33:14 12259 8192000000 10721682896 11145093120 1796124624 17.982 99 22 44 1 1808269008 5983488 6160896
17:34:30 19910 8192000000 10751176864 11178004480 1838288624 18.327 100 16 32 1 1847731936 5342416 4100896
17:35:51 10553 8192000000 10764069248 11196510208 1855433232 18.466 100 24 48 0 1868066640 5360368 7273040
17:37:02 12865 8192000000 10806533360 11230490624 1877797264 18.647 100 15 30 0 1886972032 4506304 4668464
17:38:21 48434 8192000000 10847836656 11276967936 1924770688 19.025 100 43 86 0 1945399744 8475296 12153760
17:40:12 69035 8192000000 10867394400 11303620608 1941997472 19.163 100 39 78 2 1964985008 11415328 11572208
17:42:31 16652 8192000000 10892918640 11328016384 1959898736 19.305 100 53 106 0 1988008992 12409296 15700960
17:43:51 17778 8192000000 10922959664 11346542592 1973540752 19.414 100 53 103 2 2006879104 18883360 14454992
17:45:13 7506 8192000000 10936629824 11368574976 1987583744 19.525 100 45 89 1 2021650080 20906944 13159392
17:46:26 3733 8192000000 10961462368 11390668800 2006937328 19.677 100 75 148 0 2053208528 25081120 21190080
17:47:33 15307 8192000000 10958995552 11400359936 2010510752 19.706 100 48 94 2 2050688352 26399136 13778464
17:48:55 4468 8192000000 10965878832 11405406208 2013473792 19.729 100 55 110 1 2060833264 30643440 16716032
17:50:05 9857 8192000000 10981997408 11410583552 2014619968 19.738 100 62 124 0 2069703232 36373584 18709680
17:51:20 1489 8192000000 10986357168 11397410816 1984224528 19.498 98 92 184 2 2049926304 37750288 27951488
17:52:25 2945 8192000000 10971378800 11397582848 1985034208 19.505 99 62 125 1 2040916960 36750768 19131984
17:53:40 2730 8192000000 10983025600 11404349440 1997974928 19.607 100 80 159 1 2066969024 43994912 24999184
17:54:50 3862 8192000000 10961306384 11382042624 1959697520 19.304 99 72 144 0 2033074736 50180624 23196592
17:56:00 2401 8192000000 10952862768 11371716608 1950740976 19.232 99 69 137 1 2033990304 61475344 21773984
17:57:08 4716 8192000000 10962480432 11388702720 1981327344 19.475 100 80 160 0 2055423808 48344144 25752320
17:58:16 8303 8192000000 10908448976 11343323136 1908143072 18.892 98 38 76 1 1964260112 42851808 13265232
17:59:28 11918 8192000000 10906180880 11346989056 1938987936 19.139 100 41 82 0 1996852592 43420928 14443728
18:00:50 5303 8192000000 10927302832 11355746304 1947492672 19.207 100 63 124 2 2013077136 45210496 20373968
18:02:04 20204 8192000000 10893613024 11339735040 1934645168 19.104 100 24 48 3 1978351008 36280608 7425232
18:03:37 52743 8192000000 10878615888 11331248128 1901508048 18.838 99 22 44 1 1950969632 42255696 7205888
18:05:44 43740 8192000000 10833141408 11271262208 1827671392 18.240 95 14 28 0 1858529664 26523504 4334768
18:07:28 85854 8192000000 10873606400 11319111680 1906772240 18.881 98 37 75 4 1928838080 11923104 10142736
18:10:00 43233 8192000000 10878099136 11318034432 1927511936 19.047 99 41 83 1 1945464224 5589440 12362848
18:11:50 105676 8192000000 10884022704 11358212096 1959938096 19.306 100 22 45 0 1984599392 18383072 6278224
18:15:09 27047 8192000000 10906428144 11361554432 1974576688 19.422 100 29 57 1 1996121744 13082640 8462416
18:16:39 57684 8192000000 10902237648 11375648768 1979303920 19.459 100 25 50 1 2001275536 15111264 6860352
18:18:39 44618 8192000000 10909462160 11381776384 1985690416 19.510 100 7 14 1 1996351536 8699984 1961136
18:20:24 29998 8192000000 10912263920 11376660480 1968949952 19.377 99 26 52 1 1987445056 10940112 7554992
18:21:56 57767 8192000000 10908887040 11377971200 1976668704 19.438 100 15 30 0 1998708928 17315232 4724992
18:23:57 49255 8192000000 10898299248 11390238720 1980450336 19.468 100 19 38 0 2003841152 18273216 5117600
18:25:51 30845 8192000000 10915233616 11382341632 1989299840 19.538 100 27 53 4 2007037744 9782288 7955616
18:27:24 66472 8192000000 10922009136 11390509056 1954856928 19.265 98 18 36 2 1978463232 18736288 4870016
18:29:41 116886 8192000000 10915420528 11382403072 1913972272 18.939 96 15 30 0 1940411840 22535168 3904400
18:32:39 61659 8192000000 10905435808 11382464512 1965202176 19.347 99 16 32 0 1988629120 19317648 4109296
18:34:41 45250 8192000000 10872775536 11344392192 1857551936 18.483 94 11 22 2 1873857504 13175952 3129616
18:36:28 61329 8192000000 10882927632 11337179136 1865383936 18.547 95 23 46 1 1882879632 10219968 7275728
18:38:30 56398 8192000000 10872566288 11327250432 1911371440 18.918 97 54 105 1 1933084256 6720544 14992272
18:40:37 5040 8192000000 10886370080 11338039296 1932489280 19.087 99 41 82 1 1953492080 9863600 11139200
18:41:46 35542 8192000000 10851487824 11322834944 1897995728 18.810 97 29 58 3 1914968608 8870752 8102128
18:43:23 50991 8192000000 10847703152 11307720704 1882518208 18.685 97 9 18 0 1897788720 12486128 2784384
18:45:59 52152 8192000000 10858340432 11327737856 1928536192 19.055 100 18 36 0 1948459184 14888416 5034576
18:48:07 69918 8192000000 10855989648 11324301312 1868278000 18.570 96 17 34 0 1888196864 14845184 5073680
18:50:23 60011 8192000000 10851272304 11324432384 1895929920 18.794 98 28 56 0 1918958192 14444944 8583328
18:52:31 100672 8192000000 10871564496 11330289664 1882523232 18.685 97 9 18 1 1904937440 19870176 2544032
18:55:12 48355 8192000000 10885387616 11331256320 1920821184 18.993 99 28 56 0 1937408720 8257296 8330240
18:57:01 67304 8192000000 10868302032 11352985600 1920107184 18.988 99 13 26 0 1943840224 20140640 3592400
18:59:09 31391 8192000000 10887091040 11344191488 1905126016 18.868 97 36 72 0 1924879936 9622976 10130944
19:00:50 95001 8192000000 10883914912 11363831808 1928745488 19.057 98 23 46 1 1953665760 17825376 7094896
19:03:35 20073 8192000000 10902114576 11368046592 1972772224 19.407 100 18 36 0 1986040160 8478976 4788960
19:05:00 45043 8192000000 10884417296 11373027328 1969774384 19.384 100 5 11 1 1979002560 7984848 1243328
19:06:45 82803 8192000000 10897449472 11384504320 1943046960 19.171 98 6 12 0 1960997072 16177520 1772592
19:09:09 89890 8192000000 10908454384 11373060096 1909869744 18.906 96 15 30 2 1928924288 14928128 4126416
19:11:51 46876 8192000000 10912729712 11380023296 1962081168 19.323 99 17 34 0 1978160720 11740496 4339056
19:13:41 84495 8192000000 10915771104 11403751424 1952903712 19.250 98 12 23 0 1970918880 14578496 3436672
19:16:06 90612 8192000000 10902388560 11390128128 1934543792 19.103 97 23 44 0 1956639840 16141472 5954576
19:18:56 33877 8192000000 10926941504 11386126336 1980785632 19.471 100 28 56 0 2004496864 15862544 7848688
19:20:33 32628 8192000000 10920395504 11377897472 1961148352 19.315 99 40 80 1 1982002000 9336672 11516976
19:22:07 119349 8192000000 10906520256 11381624832 1947296016 19.205 98 17 33 1 1964740736 11862432 5582288
19:25:24 43265 8192000000 10876524832 11364458496 1959708608 19.304 100 9 18 1 1972213664 9665216 2839840
19:27:07 64983 8192000000 10873775856 11369394176 1933810976 19.097 98 13 26 1 1955956368 18201152 3944240
19:29:14 99238 8192000000 10876253856 11356295168 1896703456 18.800 96 11 21 0 1915909824 15858416 3347952
19:31:55 53465 8192000000 10847549520 11327385600 1863686240 18.533 96 12 22 0 1883589120 16724848 3178032
19:33:54 34967 8192000000 10857406240 11319349248 1869553968 18.581 96 21 42 1 1882014032 6661328 5798736
19:35:31 59965 8192000000 10880877888 11343761408 1928766736 19.057 99 30 61 2 1945629456 7830624 9032096
19:37:37 16921 8192000000 9432576656 9738256384 581984592 6.633 30 5 9 0 591829408 8547360 1297456

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-08-2021 19:39:08 to 28-08-2021 19:39:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-08-2021 19:39:24 to 28-08-2021 19:39:26.

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          244354
MON$OLDEST_ACTIVE               245198
MON$OLDEST_SNAPSHOT             245198
MON$NEXT_TRANSACTION            245198
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                       2027232
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:647: 
:648: mscs-test-161.budzdorov.ru    Sat Aug 28 19:38:06 2021
:649:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:650: 
:651: 
:652: mscs-test-161.budzdorov.ru    Sat Aug 28 19:38:08 2021
:653:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:654: 
:655: 
:656: mscs-test-161.budzdorov.ru    Sat Aug 28 19:38:11 2021
:657:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:658: 
--- end of diff output ---

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

28.08.21 19:39:27. 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-08-28 19:39:30 No severe PSQL-related problems occured
28.08.21 19:39:31. End of report.