Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.04.2022 19:39
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 16554252 kB
MemAvailable 31619428 kB
Buffers 0 kB
Cached 15184160 kB
SwapCached 2204 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.10
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 2022-04-02 19:38:08
DTS_END                         2022-04-02 19:38:08.2530
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/53892
TRN_ID                          246621
ATT_ID                          698
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: 9576.40 20043 1149168
customer order: creation 178.31 342 21397
customer order: refuse 15.56 319 1867
order to supplier: creation 45.77 1314 5492
order to supplier: removal 3.55 108096 426
invoice (draft): creation 48.22 3528 5786
invoice (draft): removal 55.16 762 6619
invoice accept: apply 43.48 17701 5218
invoice accept: cancel 49.87 833 5984
customer reserve: creation 3953.04 176 474365
customer reserve: removal 4876.75 62 585210
realization accept: apply 86.33 187 10359
realization accept: cancel 88.14 114 10577
payment from customer: creation 59.81 113 7177
payment from customer: removal 5.84 70 701
payment to supplier: creation 50.03 116 6004
payment to supplier: removal 3.77 84 452
service: total inventory turnovers 5.03 1027 603
service: total monetary turnovers 6.90 46 828
service: aggregate perf. data 0.83 23284 99
service: refresh index statistics 0.03 242677 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-04-2022 19:40:01 to 02-04-2022 19:40:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6171
TEST_TIME 2 4405
TEST_TIME 3 4222
TEST_TIME 4 4496
TEST_TIME 5 4955
TEST_TIME 6 5371
TEST_TIME 7 5522
TEST_TIME 8 5402
TEST_TIME 9 5435
TEST_TIME 10 5961
TEST_TIME 11 6031
TEST_TIME 12 6277
TEST_TIME 13 6325
TEST_TIME 14 6779
TEST_TIME 15 7057
TEST_TIME 16 7190
TEST_TIME 17 7290
TEST_TIME 18 7594
TEST_TIME 19 7578
TEST_TIME 20 8133
TEST_TIME 21 7931
TEST_TIME 22 8521
TEST_TIME 23 8934
TEST_TIME 24 8919
TEST_TIME 25 9223
TEST_TIME 26 9251
TEST_TIME 27 9629
TEST_TIME 28 10157
TEST_TIME 29 10794
TEST_TIME 30 10915
TEST_TIME 31 10860
TEST_TIME 32 10690
TEST_TIME 33 10610
TEST_TIME 34 10634
TEST_TIME 35 10438
TEST_TIME 36 10567
TEST_TIME 37 10638
TEST_TIME 38 10449
TEST_TIME 39 10543
TEST_TIME 40 10486
TEST_TIME 41 10362
TEST_TIME 42 10272
TEST_TIME 43 10254
TEST_TIME 44 10267
TEST_TIME 45 10427
TEST_TIME 46 10445
TEST_TIME 47 10375
TEST_TIME 48 10310
TEST_TIME 49 10190
TEST_TIME 50 10093
TEST_TIME 51 10048
TEST_TIME 52 9978
TEST_TIME 53 9914
TEST_TIME 54 9942
TEST_TIME 55 9887
TEST_TIME 56 9830
TEST_TIME 57 9761
TEST_TIME 58 9938
TEST_TIME 59 9978
TEST_TIME 60 9994
TEST_TIME 61 10067
TEST_TIME 62 10068
TEST_TIME 63 10077
TEST_TIME 64 10095
TEST_TIME 65 10070
TEST_TIME 66 10190
TEST_TIME 67 10210
TEST_TIME 68 10190
TEST_TIME 69 10092
TEST_TIME 70 10092
TEST_TIME 71 10023
TEST_TIME 72 9979
TEST_TIME 73 9984
TEST_TIME 74 9888
TEST_TIME 75 9811
TEST_TIME 76 9821
TEST_TIME 77 9823
TEST_TIME 78 9836
TEST_TIME 79 9819
TEST_TIME 80 9813
TEST_TIME 81 9826
TEST_TIME 82 9814
TEST_TIME 83 9895
TEST_TIME 84 9889
TEST_TIME 85 9907
TEST_TIME 86 9940
TEST_TIME 87 9909
TEST_TIME 88 9979
TEST_TIME 89 10017
TEST_TIME 90 10059
TEST_TIME 91 10010
TEST_TIME 92 9927
TEST_TIME 93 9892
TEST_TIME 94 9907
TEST_TIME 95 9860
TEST_TIME 96 9804
TEST_TIME 97 9799
TEST_TIME 98 9784
TEST_TIME 99 9743
TEST_TIME 100 9794
TEST_TIME 101 9751
TEST_TIME 102 9780
TEST_TIME 103 9748
TEST_TIME 104 9798
TEST_TIME 105 9807
TEST_TIME 106 9788
TEST_TIME 107 9799
TEST_TIME 108 9893
TEST_TIME 109 9858
TEST_TIME 110 9893
TEST_TIME 111 9835
TEST_TIME 112 9769
TEST_TIME 113 9751
TEST_TIME 114 9691
TEST_TIME 115 9653
TEST_TIME 116 9700
TEST_TIME 117 9692
TEST_TIME 118 9657
TEST_TIME 119 9661
TEST_TIME 120 9582

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-04-2022 19:40:05 to 02-04-2022 19:40:08.

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 1589714 1589714 0 0.00 0 19248 6 0 0
doc_list_biud 1589713 1589713 0 0.00 0 34777 29 0 0
sp_add_invoice_to_stock 5332 5218 114 2.13 43 182025 17701 0 114
sp_cancel_adding_invoice 5984 5984 0 0.00 4 68131 834 0 0
sp_cancel_client_order 1867 1867 0 0.00 4 14338 319 0 0
sp_cancel_customer_reserve 585210 585210 0 0.00 1 22249 62 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 2 4376 70 0 0
sp_cancel_pay_to_supplier 452 452 0 0.00 2 6339 84 0 0
sp_cancel_supplier_invoice 6625 6619 6 0.09 2 14337 762 0 6
sp_cancel_supplier_order 426 426 0 0.00 8 1069077 108097 0 0
sp_cancel_write_off 10577 10577 0 0.00 1 12202 115 0 0
sp_client_order 21397 21397 0 0.00 3 48307 343 0 0
sp_customer_reserve 474365 474365 0 0.00 0 75250 177 0 0
sp_fill_shopping_cart 32665 32665 0 0.00 1 14635 37 0 0
sp_fill_shopping_cart_clo_res 474355 474355 0 0.00 0 19336 13 0 0
sp_get_clo_for_invoice 5208 5208 0 0.00 0 42409 287 0 0
sp_kill_cost_storno 17714 17714 0 0.00 0 11060 62 0 0
sp_kill_qstorno_ret_qs2qd 1188042 1188042 0 0.00 0 20852 33 0 0
sp_kill_qty_storno 626250 626250 0 0.00 0 34773 72 0 0
sp_lock_selected_doc 21654 21654 0 0.00 0 6266 4 0 0
sp_make_cost_storno 28748 28748 0 0.00 0 19224 52 0 0
sp_multiply_rows_for_pdistr 28748 28748 0 0.00 0 9901 31 0 0
sp_multiply_rows_for_qdistr 21396 21396 0 0.00 0 48290 147 0 0
sp_pay_from_customer 7177 7177 0 0.00 4 17337 113 0 0
sp_pay_to_supplier 6004 6004 0 0.00 5 17476 117 0 0
sp_payment_common 13181 13181 0 0.00 2 13012 26 0 0
sp_qd_handle_on_cancel_clo 1867 1867 0 0.00 0 14331 299 0 0
sp_qd_handle_on_invoice_upd_sts 11192 11192 0 0.00 0 34768 354 0 0
sp_qd_handle_on_reserve_upd_sts 10359 10359 0 0.00 0 14287 69 0 0
sp_reserve_write_off 10359 10359 0 0.00 6 14302 187 0 0
sp_supplier_invoice 5786 5786 0 0.00 6 104973 3529 0 0
sp_supplier_order 5492 5492 0 0.00 5 35025 1314 0 0
srv_aggregate_perf_data 99 99 0 0.00 2775 479787 23284 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2447 170430 44259 0 0
srv_find_qd_qs_mism 1100825 1100825 0 0.00 0 13767 5 0 0
srv_make_invnt_saldo 603 603 0 0.00 35 52571 1028 0 0
srv_make_money_saldo 828 828 0 0.00 1 16869 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 122978 393114 242677 0 0
t$perf-abend:sp_add_invoice_to_stock 114 114 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5218 5218 0 0.00 0 12483 194 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 0 8132 90 0 0
t$perf-norm:sp_cancel_client_order 1867 1867 0 0.00 0 2073 13 0 0
t$perf-norm:sp_cancel_customer_reserve 726 726 0 0.00 0 2108 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 865 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 452 452 0 0.00 0 364 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 3550 23 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 33855 1641 0 0
t$perf-norm:sp_cancel_write_off 1051 1051 0 0.00 0 2665 13 0 0
t$perf-norm:sp_client_order 21397 21397 0 0.00 0 11285 21 0 0
t$perf-norm:sp_customer_reserve 1035 1035 0 0.00 0 1780 7 0 0
t$perf-norm:sp_pay_from_customer 7177 7177 0 0.00 0 6279 11 0 0
t$perf-norm:sp_pay_to_supplier 6004 6004 0 0.00 0 6335 13 0 0
t$perf-norm:sp_reserve_write_off 10359 10359 0 0.00 0 5987 13 0 0
t$perf-norm:sp_supplier_invoice 5786 5786 0 0.00 0 5954 20 0 0
t$perf-norm:sp_supplier_order 5492 5492 0 0.00 0 4320 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 235 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 174 3 0 0
t$perf-norm:srv_make_invnt_saldo 603 603 0 0.00 0 4168 36 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 1711 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 37 11 0 0
v_add_invoice_to_stock 10638 10410 228 2.14 0 6062 17 0 228
v_all_customers 38688 38688 0 0.00 0 136 1 0 0
v_all_suppliers 31054 31054 0 0.00 0 29 1 0 0
v_all_wares 42784 42784 0 0.00 0 30 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 371 6 0 0
v_cancel_client_order 3734 3734 0 0.00 0 1035 3 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 1214 4 0 0
v_cancel_customer_reserve 1452 1452 0 0.00 0 661 4 0 0
v_cancel_payment_to_supplier 904 904 0 0.00 0 748 4 0 0
v_cancel_supplier_invoice 884 872 12 1.35 0 8778 24 0 12
v_cancel_supplier_order 852 852 0 0.00 0 2165 9 0 0
v_cancel_write_off 2102 2102 0 0.00 0 1521 13 0 0
v_max_id_clo_ord 5489 5489 0 0.00 0 1410 2 0 0
v_max_id_clo_res 1035 1035 0 0.00 0 1211 8 0 0
v_max_id_ord_sup 5782 5782 0 0.00 0 7257 4 0 0
v_max_non_paid_invoice 1748 1748 0 0.00 0 494 3 0 0
v_max_non_paid_realizn 3828 3828 0 0.00 0 355 3 0 0
v_min_id_clo_ord 5489 5489 0 0.00 0 9608 7 0 0
v_min_id_clo_res 1035 1035 0 0.00 0 1372 8 0 0
v_min_id_ord_sup 5782 5782 0 0.00 0 9068 7 0 0
v_min_non_paid_invoice 6004 6004 0 0.00 0 12933 16 0 0
v_min_non_paid_realizn 7177 7177 0 0.00 0 9892 22 0 0
v_our_firm 10792 10792 0 0.00 0 16 1 0 0
v_reserve_write_off 20710 20710 0 0.00 0 2329 5 0 0
x_lock_depdocs_on_canc_invoice 12167 12167 0 0.00 0 19189 135 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 27108 574 0 0
x_make_qstorno_1000_1200 5492 5492 0 0.00 1 34639 1228 0 0
x_make_qstorno_1200_2000 5786 5786 0 0.00 3 104960 3405 0 0
x_make_qstorno_2100_3300 474365 474365 0 0.00 0 75248 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-04-2022 19:40:09 to 02-04-2022 19:40:13.

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:08:12 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:12 32507 8192000000 9899390048 10206441472 974021088 10.626 100 89 178 0 1003942768 6545872 23375808
17:10:48 25364 8192000000 9940978576 10260004864 1020792752 11.080 100 88 176 1 1052398432 9117664 22488016
17:12:18 18060 8192000000 9979391696 10311602176 1063604960 11.491 100 90 180 1 1096411024 10183248 22622816
17:13:44 11414 8192000000 10037231696 10385997824 1125068736 12.075 100 75 148 1 1152904560 9756736 18079088
17:14:59 8228 8192000000 10088047584 10444001280 1173365248 12.528 100 80 160 0 1203472816 12024832 18082736
17:16:09 6091 8192000000 10127740688 10496573440 1214795360 12.914 100 83 166 0 1246383248 13047920 18539968
17:17:19 4085 8192000000 10163767184 10540408832 1254601600 13.280 100 75 148 0 1283080640 12469408 16009632
17:18:24 2358 8192000000 10199255744 10581327872 1285405104 13.562 100 90 179 2 1318054816 12984048 19665664
17:19:29 1323 8192000000 10221486256 10604785664 1303950240 13.731 100 92 183 1 1337829760 13358688 20520832
17:20:32 22664 8192000000 10183098512 10604118016 1304657872 13.738 100 23 46 0 1316930592 7549936 4722784
17:21:58 4828 8192000000 10193929408 10610114560 1312937744 13.813 100 24 48 0 1325020880 6760208 5322928
17:23:03 16623 8192000000 10201219792 10621837312 1319822736 13.875 100 16 32 0 1328483760 5277872 3383152
17:24:22 12494 8192000000 10210216176 10631589888 1328649744 13.955 100 10 20 0 1336373040 5555376 2167920
17:25:39 35376 8192000000 10226833712 10648236032 1333477072 13.999 100 21 41 1 1344417120 5552144 5387904
17:27:18 8304 8192000000 10220625568 10641268736 1328149568 13.950 100 18 36 0 1337810784 5624784 4036432
17:28:27 8191 8192000000 10200605264 10617331712 1266546208 13.390 96 16 30 1 1275362640 5297008 3519424
17:29:36 52903 8192000000 10158902384 10572509184 1186642240 12.652 91 17 34 0 1195262640 4951712 3668688
17:31:35 7514 8192000000 10129788528 10522411008 1192345408 12.705 95 32 65 0 1205401184 5705616 7350160
17:32:43 6262 8192000000 10153586512 10551902208 1246708624 13.208 99 33 66 0 1260992592 6502320 7781648
17:33:52 61108 8192000000 10171074960 10582589440 1274405856 13.462 99 24 48 0 1285171552 5044144 5721552
17:35:56 4785 8192000000 10199223152 10607480832 1301123712 13.705 100 34 68 0 1315964816 7177456 7663648
17:37:03 8554 8192000000 10204392192 10620534784 1311623136 13.801 100 17 34 2 1320951552 5245696 4082720
17:38:15 12364 8192000000 10230767472 10642362368 1335023696 14.013 100 33 66 0 1347998064 5487408 7486960
17:39:35 10142 8192000000 10262758816 10676772864 1355780688 14.199 100 54 102 1 1377456848 9180640 12495520
17:40:50 67283 8192000000 10276875392 10692636672 1357675248 14.216 99 45 90 0 1382921696 15067072 10179376
17:43:02 14801 8192000000 10295028064 10718298112 1380124272 14.418 100 35 70 0 1404307904 16432672 7750960
17:44:21 13777 8192000000 10304783616 10724642816 1375969168 14.380 99 54 109 0 1405414944 16823456 12622320
17:45:38 9114 8192000000 10313412368 10735124480 1396221584 14.561 100 57 114 1 1429320528 20004448 13094496
17:46:52 7985 8192000000 10330725344 10750558208 1402643440 14.619 100 64 126 0 1444050720 26509952 14897328
17:48:08 6003 8192000000 10335131616 10758885376 1408263888 14.669 100 52 104 0 1444962880 23784816 12914176
17:49:21 6046 8192000000 10341810288 10765774848 1400913936 14.603 99 65 128 1 1444102352 27793488 15394928
17:50:34 2444 8192000000 10371705824 10780688384 1415456512 14.732 100 87 172 0 1469894624 33555280 20882832
17:51:43 6675 8192000000 10342699200 10756927488 1395918176 14.559 100 70 139 0 1450868864 37738816 17211872
17:52:53 6303 8192000000 10345024080 10756907008 1383199872 14.445 99 79 157 2 1443297280 40597264 19500144
17:54:09 2556 8192000000 10346544272 10753040384 1384392352 14.456 100 83 167 1 1447045856 41362208 21291296
17:55:17 6650 8192000000 10322192704 10731302912 1359363248 14.232 99 61 122 0 1425779712 50513472 15902992
17:56:29 3720 8192000000 10354754016 10758430720 1381984992 14.434 100 87 174 0 1454843824 51026672 21832160
17:57:38 3005 8192000000 10355852656 10766475264 1387949584 14.488 100 77 154 0 1460313408 52663872 19699952
17:58:46 6188 8192000000 10354629360 10771288064 1398803744 14.584 100 61 122 4 1461409632 46878800 15727088
18:00:11 3652 8192000000 10394286512 10802765824 1408982656 14.675 100 85 170 1 1493734496 62503664 22248176
18:01:18 7230 8192000000 10380690800 10805334016 1415242912 14.730 100 50 99 3 1480362688 51455600 13664176
18:02:29 19336 8192000000 10366219184 10799788032 1403032800 14.622 99 37 74 2 1463051216 50140592 9877824
18:03:51 16641 8192000000 10348078544 10782711808 1378986464 14.407 99 30 60 0 1444308096 56987696 8333936
18:05:18 26055 8192000000 10332618816 10776322048 1379119488 14.409 100 29 58 1 1441475520 53961920 8394112
18:06:47 33652 8192000000 10310242656 10762072064 1365765472 14.289 100 12 24 0 1409548272 40522912 3259888
18:08:46 42502 8192000000 10302957520 10745311232 1375133456 14.373 99 8 16 1 1386368256 9716720 1518080
18:10:35 24397 8192000000 10292217424 10736869376 1377356800 14.393 99 21 42 0 1390239728 7548352 5334576
18:12:01 67105 8192000000 10323192560 10764439552 1393085392 14.533 100 20 40 1 1411745056 14065776 4593888
18:14:09 170430 8192000000 10318878992 10762313728 1384258704 14.455 99 33 66 0 1411016544 19444144 7313696
18:18:02 52995 8192000000 10297328944 10765950976 1383928208 14.452 99 11 22 1 1400422704 13804704 2689792
18:20:03 35803 8192000000 10318523120 10770935808 1405015488 14.640 100 25 50 0 1421953616 10719312 6218816
18:21:40 61748 8192000000 10328801072 10785669120 1417477664 14.750 100 56 112 0 1445883264 14109408 14296192
18:23:57 58530 8192000000 10309921536 10776662016 1409975664 14.684 100 8 16 0 1423150080 11485920 1688496
18:25:59 95173 8192000000 10290982128 10752364544 1335590240 14.018 95 10 20 0 1347616544 9370480 2655824
18:28:44 62693 8192000000 10274245072 10736328704 1341668416 14.072 96 22 44 0 1359499904 12655024 5176464
18:30:47 5091 8192000000 10297087504 10745569280 1383163184 14.445 100 40 80 3 1402258768 10203568 8892016
18:31:53 33011 8192000000 10291210288 10759229440 1393419568 14.536 100 9 18 0 1407426320 11918448 2088304
18:33:33 38840 8192000000 10301304864 10763427840 1384846624 14.460 99 19 38 0 1398265488 9351248 4067616
18:35:20 26687 8192000000 10309075920 10758578176 1367854944 14.308 98 33 66 0 1390355296 14645648 7854704
18:36:47 49803 8192000000 10288470576 10749407232 1335899072 14.020 96 10 20 0 1349216656 10457696 2859888
18:38:39 10224 8192000000 10302832928 10748977152 1379209504 14.409 99 45 90 1 1401112448 11880272 10022672
18:39:51 44933 8192000000 10285873392 10735079424 1326601040 13.936 95 25 50 0 1341466176 9190272 5674864
18:41:37 42983 8192000000 10309029568 10763137024 1387002096 14.479 100 21 42 1 1401071152 9471552 4597504
18:43:22 72913 8192000000 10288780064 10758492160 1335850656 14.020 96 25 50 0 1355784736 14483088 5450992
18:45:39 60061 8192000000 10283037072 10732957696 1354856016 14.191 99 13 26 1 1370842464 13274672 2711776
18:47:40 111735 8192000000 10277836320 10732457984 1352881568 14.173 98 30 58 0 1372812592 12854384 7076640
18:50:33 24289 8192000000 10276617552 10728579072 1342148832 14.077 98 22 44 0 1356996048 9426800 5420416
18:52:09 53082 8192000000 10283804128 10746933248 1368288336 14.312 99 10 20 0 1385274640 14708896 2277408
18:54:07 60753 8192000000 10263697696 10723536896 1333058880 13.995 97 12 24 1 1352565056 16477136 3029040
18:56:55 86484 8192000000 10268816992 10732163072 1342288384 14.078 98 15 30 2 1356612144 10998976 3324784
18:59:22 50597 8192000000 10272802096 10723942400 1324563408 13.918 96 24 48 1 1339124080 9194176 5366496
19:01:19 51850 8192000000 10273636624 10739044352 1365817584 14.290 100 22 44 0 1386058640 15135840 5105216
19:03:16 66739 8192000000 10280172800 10735104000 1342525360 14.080 97 15 30 0 1359955824 14151440 3279024
19:05:26 35169 8192000000 10303413488 10752065536 1372520832 14.350 99 34 68 0 1390107296 10189936 7396528
19:07:04 76436 8192000000 10292537920 10764455936 1350132400 14.149 97 11 22 1 1366087200 13786352 2168448
19:09:21 111276 8192000000 10295146976 10749018112 1357428288 14.214 98 18 36 1 1372336176 10937584 3970304
19:12:13 34986 8192000000 10276917680 10748850176 1358827584 14.227 98 21 42 1 1375765392 12287440 4650368
19:14:01 53183 8192000000 10284143376 10736508928 1353697984 14.181 98 27 53 1 1365502272 5852592 5951696
19:15:57 81559 8192000000 10298115600 10749739008 1375288880 14.374 99 9 18 1 1385140016 7735808 2115328
19:18:19 50125 8192000000 10271751056 10725187584 1303948256 13.731 94 8 16 0 1313151600 7775456 1427888
19:20:12 87646 8192000000 10284881584 10743250944 1339070736 14.049 97 24 48 2 1358425488 13422640 5932112
19:23:01 57549 8192000000 10266426784 10716917760 1328373760 13.952 97 12 24 0 1341965504 10789104 2802640
19:25:05 46461 8192000000 10282705072 10735063040 1346385600 14.115 98 22 44 2 1364777728 13578368 4813760
19:26:56 147944 8192000000 10285282592 10725994496 1298994992 13.686 94 6 12 1 1312997360 12623104 1379264
19:30:34 86839 8192000000 10286323392 10748497920 1384360016 14.456 100 29 58 1 1405018960 13511504 7147440
19:33:01 74869 8192000000 10292313424 10756096000 1377663856 14.396 99 9 18 1 1396561600 15992000 2905744
19:35:16 73382 8192000000 10271990640 10733891584 1357120144 14.211 98 8 16 0 1370351936 11351280 1880512
19:37:31 50449 8192000000 9538868912 9882824704 671778896 7.578 50 4 8 0 683389584 10687776 922912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-04-2022 19:40:15 to 02-04-2022 19:40:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 114 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-04-2022 19:40:31 to 02-04-2022 19:40:33.

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          246620
MON$OLDEST_ACTIVE               246838
MON$OLDEST_SNAPSHOT             246838
MON$NEXT_TRANSACTION            246838
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                       2046944
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:895: 
:896: mscs-test-161.budzdorov.ru    Sat Apr  2 19:38:31 2022
:897:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:898: 
:899: 
:900: mscs-test-161.budzdorov.ru    Sat Apr  2 19:38:31 2022
:901:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:902: 
:903: 
:904: mscs-test-161.budzdorov.ru    Sat Apr  2 19:38:38 2022
:905:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:906: 
:907: 
:908: mscs-test-161.budzdorov.ru    Sat Apr  2 19:38:43 2022
:909:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:910: 
--- end of diff output ---

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

02.04.22 19:40:34. 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
2022-04-02 19:40:36 No severe PSQL-related problems occured
02.04.22 19:40:37. End of report.