Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.06.2022 01: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 16031472 kB
MemAvailable 31327052 kB
Buffers 0 kB
Cached 15696300 kB
SwapCached 2436 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-06-10 01:38:19
DTS_END                         2022-06-10 01:38:19.7300
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37678
TRN_ID                          237718
ATT_ID                          662
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: 9437.92 27999 1132550
customer order: creation 166.28 349 19953
customer order: refuse 14.58 393 1749
order to supplier: creation 44.49 1315 5339
order to supplier: removal 3.66 106560 439
invoice (draft): creation 45.19 3498 5423
invoice (draft): removal 56.27 742 6752
invoice accept: apply 40.38 18251 4846
invoice accept: cancel 50.86 889 6103
customer reserve: creation 3771.14 176 452537
customer reserve: removal 4954.76 62 594571
realization accept: apply 81.56 209 9787
realization accept: cancel 81.68 103 9801
payment from customer: creation 57.62 158 6914
payment from customer: removal 5.91 81 709
payment to supplier: creation 48.59 150 5831
payment to supplier: removal 3.43 76 412
service: total inventory turnovers 4.59 1144 551
service: total monetary turnovers 6.10 26 732
service: aggregate perf. data 0.81 18028 97
service: refresh index statistics 0.03 407777 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-06-2022 01:39:48 to 10-06-2022 01:39:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2665
TEST_TIME 2 3924
TEST_TIME 3 3202
TEST_TIME 4 3615
TEST_TIME 5 4004
TEST_TIME 6 4578
TEST_TIME 7 4681
TEST_TIME 8 5016
TEST_TIME 9 5446
TEST_TIME 10 6193
TEST_TIME 11 6603
TEST_TIME 12 6975
TEST_TIME 13 7469
TEST_TIME 14 7409
TEST_TIME 15 7820
TEST_TIME 16 7599
TEST_TIME 17 7796
TEST_TIME 18 7808
TEST_TIME 19 7958
TEST_TIME 20 7933
TEST_TIME 21 8144
TEST_TIME 22 8285
TEST_TIME 23 8667
TEST_TIME 24 8750
TEST_TIME 25 9093
TEST_TIME 26 8924
TEST_TIME 27 8866
TEST_TIME 28 9448
TEST_TIME 29 9717
TEST_TIME 30 9536
TEST_TIME 31 10153
TEST_TIME 32 10026
TEST_TIME 33 10745
TEST_TIME 34 10622
TEST_TIME 35 10529
TEST_TIME 36 10549
TEST_TIME 37 10529
TEST_TIME 38 10499
TEST_TIME 39 10351
TEST_TIME 40 10274
TEST_TIME 41 10136
TEST_TIME 42 10177
TEST_TIME 43 10142
TEST_TIME 44 10033
TEST_TIME 45 10152
TEST_TIME 46 10135
TEST_TIME 47 10257
TEST_TIME 48 10218
TEST_TIME 49 10067
TEST_TIME 50 9887
TEST_TIME 51 9929
TEST_TIME 52 9974
TEST_TIME 53 9809
TEST_TIME 54 9811
TEST_TIME 55 9743
TEST_TIME 56 9725
TEST_TIME 57 9720
TEST_TIME 58 9900
TEST_TIME 59 9814
TEST_TIME 60 9735
TEST_TIME 61 9678
TEST_TIME 62 9610
TEST_TIME 63 9669
TEST_TIME 64 9608
TEST_TIME 65 9548
TEST_TIME 66 9596
TEST_TIME 67 9605
TEST_TIME 68 9590
TEST_TIME 69 9617
TEST_TIME 70 9655
TEST_TIME 71 9733
TEST_TIME 72 9724
TEST_TIME 73 9766
TEST_TIME 74 9802
TEST_TIME 75 9792
TEST_TIME 76 9831
TEST_TIME 77 9772
TEST_TIME 78 9846
TEST_TIME 79 9844
TEST_TIME 80 9894
TEST_TIME 81 9795
TEST_TIME 82 9804
TEST_TIME 83 9752
TEST_TIME 84 9726
TEST_TIME 85 9660
TEST_TIME 86 9759
TEST_TIME 87 9754
TEST_TIME 88 9750
TEST_TIME 89 9711
TEST_TIME 90 9663
TEST_TIME 91 9630
TEST_TIME 92 9583
TEST_TIME 93 9571
TEST_TIME 94 9556
TEST_TIME 95 9585
TEST_TIME 96 9573
TEST_TIME 97 9537
TEST_TIME 98 9579
TEST_TIME 99 9585
TEST_TIME 100 9629
TEST_TIME 101 9626
TEST_TIME 102 9692
TEST_TIME 103 9702
TEST_TIME 104 9679
TEST_TIME 105 9708
TEST_TIME 106 9705
TEST_TIME 107 9713
TEST_TIME 108 9692
TEST_TIME 109 9637
TEST_TIME 110 9603
TEST_TIME 111 9588
TEST_TIME 112 9571
TEST_TIME 113 9536
TEST_TIME 114 9529
TEST_TIME 115 9536
TEST_TIME 116 9529
TEST_TIME 117 9488
TEST_TIME 118 9530
TEST_TIME 119 9510
TEST_TIME 120 9439

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 10-06-2022 01:39:51 to 10-06-2022 01:39: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 1553819 1553819 0 0.00 0 24844 6 0 0
doc_list_biud 1553819 1553819 0 0.00 0 71234 30 0 0
sp_add_invoice_to_stock 5009 4846 163 3.25 38 170697 18251 0 163
sp_cancel_adding_invoice 6103 6103 0 0.00 5 70546 889 0 0
sp_cancel_client_order 1749 1749 0 0.00 4 19664 393 0 0
sp_cancel_customer_reserve 594571 594571 0 0.00 1 71236 62 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 2 6661 82 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 2 3826 76 0 0
sp_cancel_supplier_invoice 6766 6752 14 0.20 1 18145 743 0 14
sp_cancel_supplier_order 439 439 0 0.00 5 967000 106561 0 0
sp_cancel_write_off 9801 9801 0 0.00 1 8495 104 0 0
sp_client_order 19953 19953 0 0.00 3 23603 349 0 0
sp_customer_reserve 452537 452537 0 0.00 0 36737 177 0 0
sp_fill_shopping_cart 30713 30713 0 0.00 1 8593 37 0 0
sp_fill_shopping_cart_clo_res 452537 452537 0 0.00 0 17427 13 0 0
sp_get_clo_for_invoice 4845 4845 0 0.00 0 31052 310 0 0
sp_kill_cost_storno 17025 17025 0 0.00 0 14707 63 0 0
sp_kill_qstorno_ret_qs2qd 1206134 1206134 0 0.00 0 71222 33 0 0
sp_kill_qty_storno 634047 634047 0 0.00 0 71234 72 0 0
sp_lock_selected_doc 20378 20378 0 0.00 0 8186 6 0 0
sp_make_cost_storno 27377 27377 0 0.00 0 24828 68 0 0
sp_multiply_rows_for_pdistr 27377 27377 0 0.00 0 10200 37 0 0
sp_multiply_rows_for_qdistr 19953 19953 0 0.00 0 9921 134 0 0
sp_pay_from_customer 6914 6914 0 0.00 4 21861 158 0 0
sp_pay_to_supplier 5831 5831 0 0.00 5 11313 150 0 0
sp_payment_common 12745 12745 0 0.00 1 21858 44 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 11218 327 0 0
sp_qd_handle_on_invoice_upd_sts 10948 10948 0 0.00 0 24856 380 0 0
sp_qd_handle_on_reserve_upd_sts 9787 9787 0 0.00 0 14922 64 0 0
sp_reserve_write_off 9787 9787 0 0.00 7 14949 210 0 0
sp_supplier_invoice 5423 5423 0 0.00 6 84400 3498 0 0
sp_supplier_order 5339 5339 0 0.00 5 70685 1316 0 0
srv_aggregate_perf_data 97 97 0 0.00 2181 141531 18029 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2674 135163 44421 0 0
srv_find_qd_qs_mism 1087001 1087001 0 0.00 0 19438 5 0 0
srv_make_invnt_saldo 551 551 0 0.00 51 125675 1144 0 0
srv_make_money_saldo 732 732 0 0.00 1 709 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 214582 523705 407777 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4846 4846 0 0.00 0 10541 180 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5493 122 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 2949 9 0 0
t$perf-norm:sp_cancel_customer_reserve 670 670 0 0.00 0 4404 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 650 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 2728 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 2283 27 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 26971 1723 0 0
t$perf-norm:sp_cancel_write_off 966 966 0 0.00 0 471 4 0 0
t$perf-norm:sp_client_order 19953 19953 0 0.00 0 7921 17 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 618 7 0 0
t$perf-norm:sp_pay_from_customer 6914 6914 0 0.00 0 5872 11 0 0
t$perf-norm:sp_pay_to_supplier 5831 5831 0 0.00 0 4729 9 0 0
t$perf-norm:sp_reserve_write_off 9787 9787 0 0.00 0 12468 13 0 0
t$perf-norm:sp_supplier_invoice 5423 5423 0 0.00 0 7642 23 0 0
t$perf-norm:sp_supplier_order 5339 5339 0 0.00 0 5851 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 186 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 8 0 0 0
t$perf-norm:srv_make_invnt_saldo 551 551 0 0.00 0 913 22 0 0
t$perf-norm:srv_make_money_saldo 732 732 0 0.00 0 4164 27 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 5 3 0 0
v_add_invoice_to_stock 10016 9690 326 3.25 0 4448 20 0 326
v_all_customers 36298 36298 0 0.00 0 46 1 0 0
v_all_suppliers 30104 30104 0 0.00 0 18 1 0 0
v_all_wares 39906 39906 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1956 11 0 0
v_cancel_client_order 3498 3498 0 0.00 0 5343 10 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 5926 11 0 0
v_cancel_customer_reserve 1340 1340 0 0.00 0 3544 7 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 1638 5 0 0
v_cancel_supplier_invoice 800 772 28 3.50 0 584 13 0 28
v_cancel_supplier_order 878 878 0 0.00 0 4273 13 0 0
v_cancel_write_off 1932 1932 0 0.00 0 1989 11 0 0
v_max_id_clo_ord 5339 5339 0 0.00 0 2121 2 0 0
v_max_id_clo_res 983 983 0 0.00 0 1091 9 0 0
v_max_id_ord_sup 5421 5421 0 0.00 0 6142 5 0 0
v_max_non_paid_invoice 1539 1539 0 0.00 0 982 4 0 0
v_max_non_paid_realizn 3789 3789 0 0.00 0 6649 6 0 0
v_min_id_clo_ord 5339 5339 0 0.00 0 2954 8 0 0
v_min_id_clo_res 983 983 0 0.00 0 5033 8 0 0
v_min_id_ord_sup 5421 5421 0 0.00 0 4328 6 0 0
v_min_non_paid_invoice 5831 5831 0 0.00 0 9876 29 0 0
v_min_non_paid_realizn 6914 6914 0 0.00 0 21854 39 0 0
v_our_firm 9858 9858 0 0.00 0 161 1 0 0
v_reserve_write_off 19574 19574 0 0.00 0 4137 8 0 0
x_lock_depdocs_on_canc_invoice 12469 12469 0 0.00 0 24231 123 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 9900 402 0 0
x_make_qstorno_1000_1200 5339 5339 0 0.00 2 70675 1229 0 0
x_make_qstorno_1200_2000 5423 5423 0 0.00 2 78376 3380 0 0
x_make_qstorno_2100_3300 452537 452537 0 0.00 0 32123 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-06-2022 01:39:56 to 10-06-2022 01:40: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
23:08:23 2 8192000000 8798273456 8809447424 4808080 0.058 2 1 2 0 4907680 56816 42784
23:09:24 37982 8192000000 9899375216 10209710080 976013216 10.645 100 88 175 2 1005389488 5495840 23880432
23:11:07 13943 8192000000 9956978064 10279940096 1040917632 11.273 100 90 180 0 1071953632 8078304 22957696
23:12:25 9268 8192000000 9988958576 10322096128 1071493216 11.566 100 85 170 0 1102207408 9473504 21240688
23:13:39 11014 8192000000 10028098720 10377404416 1119081424 12.018 100 74 148 0 1146851168 10491312 17278432
23:14:53 7943 8192000000 10083462768 10441031680 1169235120 12.490 100 83 166 0 1201040832 12289488 19516224
23:16:06 4716 8192000000 10120066080 10488836096 1209084864 12.861 100 79 159 1 1239341136 12441200 17815072
23:17:14 9546 8192000000 10149768464 10528866304 1248279344 13.222 100 77 155 0 1276666384 11885344 16501696
23:18:26 1424 8192000000 10187568080 10570186752 1278495056 13.499 100 90 181 1 1312505936 13752640 20258240
23:19:30 1462 8192000000 10218001520 10604011520 1299979088 13.695 100 92 184 0 1334799376 13617584 21202704
23:20:34 4477 8192000000 10215010240 10612281344 1309291408 13.780 100 67 133 5 1333233776 8994320 14948048
23:21:39 6021 8192000000 10203117536 10616664064 1315113184 13.832 100 45 90 1 1333787232 8204080 10469968
23:22:46 7901 8192000000 10214636656 10632409088 1326095616 13.932 100 37 74 0 1342789120 8834592 7858912
23:23:56 9036 8192000000 10224024112 10641416192 1335324336 14.015 100 32 64 0 1348979824 5724784 7930704
23:25:06 48992 8192000000 10246168416 10656641024 1340267328 14.060 100 46 90 0 1356660784 5235472 11157984
23:26:55 4962 8192000000 10239298368 10655670272 1339561472 14.053 100 37 75 0 1355038736 7338848 8138416
23:28:02 7976 8192000000 10208842560 10627186688 1280777968 13.520 96 22 45 1 1290943792 5074352 5091472
23:29:10 45701 8192000000 10184175440 10595291136 1267982256 13.403 97 24 49 0 1283830032 10632000 5215776
23:31:05 4343 8192000000 10142604816 10535784448 1242997376 13.174 100 40 79 1 1262717360 10807744 8912240
23:32:11 9272 8192000000 10144051696 10547159040 1245541360 13.197 99 28 56 0 1258204656 5617216 7046080
23:33:20 8992 8192000000 10163052272 10569052160 1261565824 13.344 99 22 44 0 1272914928 6101584 5247520
23:34:30 42625 8192000000 10165521296 10579791872 1278475184 13.499 100 35 70 1 1292437712 5293680 8668848
23:36:14 8978 8192000000 10186691712 10598604800 1293261344 13.634 100 24 46 1 1304151152 5685904 5203904
23:37:26 11316 8192000000 10192656656 10611605504 1305684208 13.747 100 16 32 0 1314805040 5551360 3569472
23:38:41 40417 8192000000 10235050864 10649575424 1330273184 13.970 100 36 70 1 1349084224 10426624 8384416
23:40:37 19606 8192000000 10264566656 10683555840 1363462480 14.268 100 34 68 0 1383195008 11298016 8434512
23:42:00 23951 8192000000 10283621408 10706124800 1370313504 14.330 100 38 76 1 1393572592 14226032 9033056
23:43:28 24996 8192000000 10281660704 10704781312 1361517792 14.251 99 41 82 1 1390793040 19523616 9751632
23:44:59 14905 8192000000 10296373280 10719944704 1383829856 14.451 100 33 66 1 1406078480 13468688 8779936
23:46:27 3035 8192000000 10320657232 10736234496 1392249504 14.526 100 70 140 1 1429964400 21078880 16636016
23:47:35 15418 8192000000 10328275872 10746351616 1396036384 14.560 100 62 124 1 1433777840 22817472 14923984
23:49:02 6330 8192000000 10319912576 10745671680 1387158224 14.481 99 53 103 1 1421099312 21227120 12713968
23:50:13 5381 8192000000 10327470384 10756759552 1407368672 14.661 100 54 108 0 1440064560 18764992 13930896
23:51:25 2674 8192000000 10342318544 10753265664 1394556496 14.547 100 84 164 2 1441240160 26108960 20574704
23:52:32 10487 8192000000 10304192256 10725101568 1372146336 14.346 99 58 114 0 1412912928 25798976 14967616
23:53:51 7644 8192000000 10308139680 10724786176 1359293696 14.231 98 51 103 1 1394591584 22568336 12729552
23:55:06 7053 8192000000 10322386320 10735075328 1372668464 14.351 99 52 102 1 1416435104 30511008 13255632
23:56:18 6007 8192000000 10341468128 10752671744 1393082336 14.533 100 76 152 0 1441813808 30086384 18645088
23:57:32 2779 8192000000 10349982464 10757251072 1394775104 14.548 100 88 176 1 1452511488 35511504 22224880
23:58:42 5115 8192000000 10355654720 10756194304 1387945264 14.488 100 90 180 1 1453814944 43675200 22194480
23:59:53 5392 8192000000 10346456704 10762240000 1387939792 14.487 100 58 115 1 1453458896 50719264 14799840
00:01:08 13384 8192000000 10328305664 10752778240 1358558768 14.224 98 41 78 2 1412752144 42972864 11220512
00:02:24 3183 8192000000 10348753792 10757771264 1377070080 14.390 99 79 157 1 1447364496 49401776 20892640
00:03:30 7632 8192000000 10359280080 10789830656 1401164992 14.605 100 34 68 0 1464626560 53363200 10098368
00:04:41 6021 8192000000 10367834848 10794336256 1400045664 14.595 99 43 86 1 1467428672 55218800 12164208
00:05:49 20733 8192000000 10387633632 10817232896 1406857136 14.656 100 37 74 2 1484029008 66741952 10429920
00:07:23 30798 8192000000 10350282912 10804125696 1385214288 14.463 99 26 52 1 1452289552 59491328 7583936
00:09:02 44622 8192000000 10322630928 10785701888 1385771200 14.468 100 24 48 1 1440859872 48944128 6144544
00:10:48 8680 8192000000 10317732448 10751225856 1366270352 14.294 100 38 75 2 1418656480 43111200 9274928
00:12:24 84378 8192000000 10277766672 10721349632 1336671088 14.027 98 18 36 0 1349139616 8790176 3678352
00:14:49 96827 8192000000 10306483568 10760609792 1373209968 14.356 99 7 14 1 1393097216 18554704 1332544
00:17:51 79151 8192000000 10326398032 10778824704 1414711216 14.726 100 38 80 0 1434600832 10808336 9081280
00:20:16 45927 8192000000 10318961904 10774024192 1397848944 14.576 99 12 24 0 1414997312 14419232 2729136
00:22:07 42768 8192000000 10332149536 10767249408 1389123616 14.498 99 31 62 0 1411144592 14272368 7748608
00:23:53 70238 8192000000 10339116976 10799943680 1395666976 14.556 99 14 28 1 1414896704 16180736 3048992
00:26:30 135162 8192000000 10349708160 10791321600 1422334608 14.793 100 28 56 0 1438832064 9997488 6499968
00:30:02 32932 8192000000 10321494576 10788859904 1409322336 14.678 99 45 90 2 1429927872 10770464 9835072
00:32:47 51220 8192000000 10316361792 10769760256 1384358544 14.456 97 18 36 0 1404632928 15944544 4329840
00:34:39 18756 8192000000 10324229440 10773721088 1387301152 14.482 99 44 88 1 1419548512 21930816 10316544
00:36:05 72694 8192000000 10313327744 10798911488 1409468320 14.679 100 11 22 0 1438890208 26972560 2449328
00:38:18 34445 8192000000 10291335664 10756317184 1382808480 14.442 99 8 16 1 1398623920 14229904 1585536
00:39:52 61302 8192000000 10302826320 10763927552 1383846064 14.451 99 20 40 0 1401449184 13676592 3926528
00:41:54 62497 8192000000 10294384160 10761089024 1380445280 14.421 99 17 34 0 1402253472 18703168 3105024
00:44:07 80496 8192000000 10282285664 10750668800 1336760816 14.028 96 13 25 0 1352583920 13092672 2730432
00:46:32 15190 8192000000 10281870688 10732552192 1346644448 14.117 97 34 68 0 1365849168 11473472 7731248
00:47:51 68765 8192000000 10270720640 10735710208 1289602336 13.601 93 17 34 1 1306480096 13292960 3584800
00:50:22 55937 8192000000 10275915984 10731458560 1329000528 13.958 97 13 26 1 1345289280 13278656 3010096
00:52:18 74181 8192000000 10257444896 10701377536 1303264896 13.725 96 21 40 3 1325742128 17719584 4757648
00:54:35 60088 8192000000 10276507280 10725150720 1349047408 14.139 99 18 36 1 1364751536 11515456 4188672
00:56:41 89283 8192000000 10279368048 10743037952 1327626832 13.946 97 13 26 1 1351096256 20391024 3078400
00:59:11 77332 8192000000 10251127264 10699182080 1332050272 13.986 98 26 52 0 1347149072 8792128 6306672
01:01:40 71917 8192000000 10283131344 10741882880 1370187600 14.329 100 23 46 1 1394564752 19010480 5366672
01:03:52 80234 8192000000 10266849600 10738626560 1359269152 14.231 99 24 49 1 1384356272 19333728 5753392
01:06:40 61089 8192000000 10256924640 10720829440 1355951808 14.201 100 13 25 2 1367883296 8576768 3354720
01:08:45 69625 8192000000 10284575744 10746933248 1369390448 14.322 99 17 34 0 1387575088 14802400 3382240
01:11:00 47816 8192000000 10285399920 10750488576 1389355392 14.500 100 15 30 0 1401707168 9190240 3161536
01:12:48 53635 8192000000 10275349440 10745753600 1364416512 14.277 99 11 22 0 1375656784 9020336 2219936
01:15:00 28076 8192000000 10306194976 10761347072 1382265440 14.437 99 26 52 2 1397792464 9719840 5807184
01:16:32 77070 8192000000 10315772480 10773704704 1387557376 14.484 99 14 28 0 1413047856 22144800 3345680
01:18:50 34795 8192000000 10307320928 10766905344 1381966784 14.434 99 30 60 0 1405647104 17167664 6512656
01:20:27 99206 8192000000 10284724976 10742833152 1321892208 13.894 95 7 14 0 1339415344 15612224 1910912
01:23:07 38388 8192000000 10293678656 10751991808 1356684528 14.208 98 32 64 0 1374422160 10717296 7020336
01:24:51 105599 8192000000 10288974960 10728546304 1325952064 13.931 96 29 56 2 1342761088 10041440 6767584
01:27:40 99803 8192000000 10256494096 10703048704 1329911264 13.966 97 14 29 0 1341642192 8456400 3274528
01:30:27 4992 8192000000 10278132944 10728144896 1355493792 14.197 99 34 67 1 1371675936 9432144 6750000
01:31:32 107421 8192000000 10270924768 10738266112 1350855024 14.155 98 20 40 1 1366931312 11771584 4304704
01:34:20 55072 8192000000 10279474080 10741972992 1363039168 14.265 98 18 36 0 1376476224 8572144 4864912
01:36:17 62269 8192000000 10266838320 10737455104 1339998976 14.057 97 11 22 0 1355458144 12666384 2792784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-06-2022 01:40:02 to 10-06-2022 01:40:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-06-2022 01:40:17 to 10-06-2022 01:40:19.

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          237717
MON$OLDEST_ACTIVE               238818
MON$OLDEST_SNAPSHOT             238818
MON$NEXT_TRANSACTION            238818
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                       2068896
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:293: 
:294: mscs-test-161.budzdorov.ru    Fri Jun 10 01:38:22 2022
:295:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:296: 
:297: 
:298: mscs-test-161.budzdorov.ru    Fri Jun 10 01:38:22 2022
:299:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:300: 
:301: 
:302: mscs-test-161.budzdorov.ru    Fri Jun 10 01:38:26 2022
:303:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:304: 
:305: 
:306: mscs-test-161.budzdorov.ru    Fri Jun 10 01:38:26 2022
:307:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:308: 
--- end of diff output ---

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

10.06.22 01:40:20. 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-06-10 01:40:23 No severe PSQL-related problems occured
10.06.22 01:40:24. End of report.