Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.10.2022 19:40
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 15566200 kB
MemAvailable 30590028 kB
Buffers 0 kB
Cached 16183004 kB
SwapCached 2120 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.11
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-10-04 19:38:29
DTS_END                         2022-10-04 19:38:29.4980
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/58498
TRN_ID                          248658
ATT_ID                          741
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: 9386.63 20216 1126395
customer order: creation 178.18 311 21381
customer order: refuse 15.36 325 1843
order to supplier: creation 48.39 1137 5807
order to supplier: removal 3.44 92590 413
invoice (draft): creation 48.05 3035 5766
invoice (draft): removal 50.89 755 6107
invoice accept: apply 44.28 18442 5313
invoice accept: cancel 45.89 786 5507
customer reserve: creation 4108.53 182 493024
customer reserve: removal 4544.02 55 545283
realization accept: apply 88.36 171 10603
realization accept: cancel 74.93 107 8992
payment from customer: creation 61.12 116 7334
payment from customer: removal 6.09 75 731
payment to supplier: creation 52.20 116 6264
payment to supplier: removal 3.54 56 425
service: total inventory turnovers 5.28 1147 633
service: total monetary turnovers 7.11 32 853
service: aggregate perf. data 0.93 18667 112
service: refresh index statistics 0.03 266222 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 04-10-2022 19:40:58 to 04-10-2022 19:41:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6090
TEST_TIME 2 4632
TEST_TIME 3 4985
TEST_TIME 4 5504
TEST_TIME 5 6298
TEST_TIME 6 6245
TEST_TIME 7 6196
TEST_TIME 8 6443
TEST_TIME 9 6649
TEST_TIME 10 6581
TEST_TIME 11 6985
TEST_TIME 12 7251
TEST_TIME 13 7723
TEST_TIME 14 7973
TEST_TIME 15 7977
TEST_TIME 16 8418
TEST_TIME 17 8463
TEST_TIME 18 8661
TEST_TIME 19 8818
TEST_TIME 20 9344
TEST_TIME 21 9247
TEST_TIME 22 9883
TEST_TIME 23 9908
TEST_TIME 24 9795
TEST_TIME 25 9868
TEST_TIME 26 9716
TEST_TIME 27 10018
TEST_TIME 28 11273
TEST_TIME 29 11226
TEST_TIME 30 11053
TEST_TIME 31 10995
TEST_TIME 32 10950
TEST_TIME 33 10950
TEST_TIME 34 10748
TEST_TIME 35 10667
TEST_TIME 36 10628
TEST_TIME 37 10602
TEST_TIME 38 10559
TEST_TIME 39 10537
TEST_TIME 40 10598
TEST_TIME 41 10455
TEST_TIME 42 10399
TEST_TIME 43 10335
TEST_TIME 44 10335
TEST_TIME 45 10215
TEST_TIME 46 10116
TEST_TIME 47 9935
TEST_TIME 48 10130
TEST_TIME 49 10022
TEST_TIME 50 9955
TEST_TIME 51 9904
TEST_TIME 52 9878
TEST_TIME 53 9832
TEST_TIME 54 9801
TEST_TIME 55 9773
TEST_TIME 56 9786
TEST_TIME 57 9823
TEST_TIME 58 9800
TEST_TIME 59 9836
TEST_TIME 60 9935
TEST_TIME 61 9925
TEST_TIME 62 10017
TEST_TIME 63 10058
TEST_TIME 64 10031
TEST_TIME 65 9991
TEST_TIME 66 9963
TEST_TIME 67 10069
TEST_TIME 68 10150
TEST_TIME 69 10053
TEST_TIME 70 9996
TEST_TIME 71 9987
TEST_TIME 72 9893
TEST_TIME 73 9858
TEST_TIME 74 9831
TEST_TIME 75 9838
TEST_TIME 76 9776
TEST_TIME 77 9703
TEST_TIME 78 9724
TEST_TIME 79 9700
TEST_TIME 80 9716
TEST_TIME 81 9722
TEST_TIME 82 9730
TEST_TIME 83 9689
TEST_TIME 84 9736
TEST_TIME 85 9807
TEST_TIME 86 9807
TEST_TIME 87 9785
TEST_TIME 88 9778
TEST_TIME 89 9752
TEST_TIME 90 9804
TEST_TIME 91 9824
TEST_TIME 92 9751
TEST_TIME 93 9716
TEST_TIME 94 9644
TEST_TIME 95 9643
TEST_TIME 96 9603
TEST_TIME 97 9585
TEST_TIME 98 9543
TEST_TIME 99 9530
TEST_TIME 100 9519
TEST_TIME 101 9535
TEST_TIME 102 9501
TEST_TIME 103 9506
TEST_TIME 104 9499
TEST_TIME 105 9532
TEST_TIME 106 9577
TEST_TIME 107 9566
TEST_TIME 108 9636
TEST_TIME 109 9601
TEST_TIME 110 9589
TEST_TIME 111 9636
TEST_TIME 112 9633
TEST_TIME 113 9618
TEST_TIME 114 9565
TEST_TIME 115 9537
TEST_TIME 116 9518
TEST_TIME 117 9486
TEST_TIME 118 9479
TEST_TIME 119 9451
TEST_TIME 120 9393

Performance per minute, chart


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

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1581361 1581361 0 0.00 0 22987 7 0 0
doc_list_biud 1581361 1581361 0 0.00 0 26667 24 0 0
sp_add_invoice_to_stock 5441 5313 128 2.35 24 156049 18443 0 128
sp_cancel_adding_invoice 5507 5507 0 0.00 5 64550 786 0 0
sp_cancel_client_order 1843 1843 0 0.00 4 11338 326 0 0
sp_cancel_customer_reserve 545283 545283 0 0.00 0 26668 55 0 0
sp_cancel_pay_from_customer 731 731 0 0.00 3 3187 75 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 2 3764 57 0 0
sp_cancel_supplier_invoice 6117 6107 10 0.16 2 25187 756 0 10
sp_cancel_supplier_order 413 413 0 0.00 7 1034947 92591 0 0
sp_cancel_write_off 8992 8992 0 0.00 1 6555 107 0 0
sp_client_order 21381 21381 0 0.00 3 18186 311 0 0
sp_customer_reserve 493024 493024 0 0.00 0 42367 183 0 0
sp_fill_shopping_cart 32954 32954 0 0.00 1 11424 37 0 0
sp_fill_shopping_cart_clo_res 493019 493019 0 0.00 0 19021 13 0 0
sp_get_clo_for_invoice 5300 5300 0 0.00 0 20036 265 0 0
sp_kill_cost_storno 15655 15655 0 0.00 0 10705 56 0 0
sp_kill_qstorno_ret_qs2qd 1106078 1106078 0 0.00 0 26664 30 0 0
sp_kill_qty_storno 584046 584046 0 0.00 0 26667 65 0 0
sp_lock_selected_doc 21965 21965 0 0.00 0 6015 6 0 0
sp_make_cost_storno 29499 29499 0 0.00 0 21392 48 0 0
sp_multiply_rows_for_pdistr 29499 29499 0 0.00 0 22966 31 0 0
sp_multiply_rows_for_qdistr 21381 21381 0 0.00 0 11672 133 0 0
sp_pay_from_customer 7334 7334 0 0.00 4 22993 116 0 0
sp_pay_to_supplier 6264 6264 0 0.00 4 11466 117 0 0
sp_payment_common 13598 13598 0 0.00 1 22824 26 0 0
sp_qd_handle_on_cancel_clo 1843 1843 0 0.00 0 11331 289 0 0
sp_qd_handle_on_invoice_upd_sts 10805 10805 0 0.00 0 18133 312 0 0
sp_qd_handle_on_reserve_upd_sts 10603 10603 0 0.00 0 22707 54 0 0
sp_reserve_write_off 10603 10603 0 0.00 6 22804 171 0 0
sp_supplier_invoice 5766 5766 0 0.00 6 106296 3036 0 0
sp_supplier_order 5807 5807 0 0.00 5 45490 1137 0 0
srv_aggregate_perf_data 112 112 0 0.00 2147 188059 18668 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 743 137635 44745 0 0
srv_find_qd_qs_mism 1075329 1075329 0 0.00 0 17935 6 0 0
srv_make_invnt_saldo 633 633 0 0.00 36 143744 1148 0 0
srv_make_money_saldo 853 853 0 0.00 0 3656 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 151843 457839 266222 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5313 5313 0 0.00 0 9908 161 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4631 107 0 0
t$perf-norm:sp_cancel_client_order 1843 1843 0 0.00 0 2898 16 0 0
t$perf-norm:sp_cancel_customer_reserve 744 744 0 0.00 0 4571 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 731 731 0 0.00 0 962 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 12890 38 0 0
t$perf-norm:sp_cancel_supplier_invoice 439 439 0 0.00 0 2929 22 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 21953 1453 0 0
t$perf-norm:sp_cancel_write_off 1041 1041 0 0.00 0 1074 5 0 0
t$perf-norm:sp_client_order 21381 21381 0 0.00 0 18456 17 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 1935 7 0 0
t$perf-norm:sp_pay_from_customer 7334 7334 0 0.00 0 6060 7 0 0
t$perf-norm:sp_pay_to_supplier 6264 6264 0 0.00 0 7817 10 0 0
t$perf-norm:sp_reserve_write_off 10603 10603 0 0.00 0 4753 7 0 0
t$perf-norm:sp_supplier_invoice 5766 5766 0 0.00 0 8183 20 0 0
t$perf-norm:sp_supplier_order 5807 5807 0 0.00 0 4899 15 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 207 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 27 1 0 0
t$perf-norm:srv_make_invnt_saldo 633 633 0 0.00 0 400 12 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 5741 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10852 10596 256 2.35 0 6759 19 0 256
v_all_customers 38562 38562 0 0.00 0 83 1 0 0
v_all_suppliers 32328 32328 0 0.00 0 19 1 0 0
v_all_wares 42762 42762 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1173 9 0 0
v_cancel_client_order 3686 3686 0 0.00 0 5355 8 0 0
v_cancel_customer_prepayment 1462 1462 0 0.00 0 3170 10 0 0
v_cancel_customer_reserve 1488 1488 0 0.00 0 742 5 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 3710 10 0 0
v_cancel_supplier_invoice 898 878 20 2.22 0 2244 17 0 20
v_cancel_supplier_order 826 826 0 0.00 0 3262 9 0 0
v_cancel_write_off 2082 2082 0 0.00 0 6543 17 0 0
v_max_id_clo_ord 5807 5807 0 0.00 0 165 1 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 3397 24 0 0
v_max_id_ord_sup 5766 5766 0 0.00 0 2686 3 0 0
v_max_non_paid_invoice 1673 1673 0 0.00 0 817 4 0 0
v_max_non_paid_realizn 4162 4162 0 0.00 0 3688 5 0 0
v_min_id_clo_ord 5807 5807 0 0.00 0 991 4 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 8118 12 0 0
v_min_id_ord_sup 5766 5766 0 0.00 0 9541 8 0 0
v_min_non_paid_invoice 6264 6264 0 0.00 0 7207 15 0 0
v_min_non_paid_realizn 7334 7334 0 0.00 0 22819 20 0 0
v_our_firm 10544 10544 0 0.00 0 23 1 0 0
v_reserve_write_off 21206 21206 0 0.00 0 4086 6 0 0
x_lock_depdocs_on_canc_invoice 11175 11175 0 0.00 0 17275 116 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 10860 367 0 0
x_make_qstorno_1000_1200 5807 5807 0 0.00 1 45438 1064 0 0
x_make_qstorno_1200_2000 5766 5766 0 0.00 2 96725 2903 0 0
x_make_qstorno_2100_3300 493024 493024 0 0.00 0 41490 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-10-2022 19:41:06 to 04-10-2022 19:41:10.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:33 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:34 15202 8192000000 9895967872 10207346688 982759984 10.711 100 85 168 0 1010419600 5578304 22081312
17:11:02 23698 8192000000 9953259584 10277216256 1036090528 11.227 100 90 180 1 1067352160 8509584 22752048
17:12:29 12717 8192000000 9991165776 10326904832 1078641680 11.635 100 88 176 0 1110007872 9523808 21842384
17:13:53 5750 8192000000 10029028112 10379280384 1118281104 12.011 100 82 164 1 1149726256 11772608 19672544
17:15:02 4854 8192000000 10078826720 10440114176 1164294976 12.443 100 73 146 0 1193411728 12400368 16716384
17:16:10 5185 8192000000 10115599248 10490232832 1211356624 12.882 100 70 137 0 1238964048 12485760 15121664
17:17:17 1783 8192000000 10169089072 10542362624 1254310640 13.278 100 86 170 0 1286497744 13318400 18868704
17:18:21 1785 8192000000 10190862496 10574573568 1284578048 13.555 100 79 158 0 1314251248 12390384 17282816
17:19:24 17668 8192000000 10199012128 10596704256 1296942016 13.667 100 58 116 1 1320161712 10259408 12960288
17:20:42 3780 8192000000 10194132176 10600542208 1307120976 13.760 100 35 70 1 1323341872 8194560 8026336
17:21:49 13601 8192000000 10198055424 10615877632 1316970512 13.849 100 18 36 0 1326891936 6201216 3720208
17:23:04 53199 8192000000 10210660176 10637766656 1321794576 13.893 100 18 36 0 1335558304 10047920 3715808
17:24:58 6525 8192000000 10217522240 10637877248 1326841776 13.939 100 22 44 0 1338169104 6758576 4568752
17:26:05 6594 8192000000 10227073008 10643001344 1329615824 13.964 100 25 50 0 1341136592 6208752 5312016
17:27:12 7593 8192000000 10201352768 10613706752 1288642928 13.592 98 20 40 1 1299126416 5748464 4735024
17:28:27 27170 8192000000 10208574976 10625794048 1305551888 13.746 99 19 38 0 1315320848 5858272 3910688
17:29:55 5979 8192000000 10165733008 10563878912 1265530176 13.381 99 36 72 0 1281972176 7913664 8528336
17:31:03 9635 8192000000 10134225808 10533867520 1203104768 12.805 96 23 46 0 1213809040 5407424 5296848
17:32:15 54883 8192000000 10149046240 10549678080 1237622096 13.124 98 31 62 0 1251370208 5499856 8248256
17:34:10 6259 8192000000 10169915328 10579304448 1278402768 13.498 100 30 60 2 1291261760 6140000 6718992
17:35:18 11471 8192000000 10177184432 10591207424 1289278128 13.598 100 15 30 0 1298202288 5553984 3370176
17:36:36 11967 8192000000 10195126752 10607345664 1296599616 13.664 100 13 26 0 1304953472 5305392 3048464
17:37:52 47494 8192000000 10214016512 10622533632 1310638080 13.792 100 40 80 0 1324860224 4681808 9540336
17:39:46 14263 8192000000 10251272560 10665304064 1340380816 14.061 100 43 85 0 1358757376 9221632 9154928
17:41:09 21849 8192000000 10246341952 10659094528 1335543808 14.017 99 48 93 1 1358851680 12563296 10744576
17:42:33 9877 8192000000 10275351696 10695036928 1361953680 14.255 100 44 84 2 1387554304 15107520 10493104
17:43:46 16368 8192000000 10282891344 10706231296 1373561200 14.359 100 34 68 0 1399242896 17577104 8104592
17:45:09 6533 8192000000 10279901968 10706165760 1361786352 14.253 99 33 64 0 1385334144 15700416 7847376
17:46:19 11080 8192000000 10294658240 10720948224 1369030608 14.318 99 36 72 1 1399746448 21310592 9405248
17:47:39 4279 8192000000 10313876208 10727362560 1382907632 14.443 100 71 139 1 1420526176 21463216 16155328
17:48:50 3378 8192000000 10314357840 10728386560 1382777808 14.441 100 68 135 0 1421899040 22806832 16314400
17:49:59 10740 8192000000 10313240080 10722938880 1354149872 14.185 98 69 135 1 1398536864 28406720 15980272
17:51:15 3087 8192000000 10350518704 10761830400 1401648512 14.610 100 72 144 0 1453307408 34457680 17201216
17:52:28 6900 8192000000 10344158416 10762952704 1398412656 14.581 100 61 122 0 1450377824 36132368 15832800
17:53:38 5051 8192000000 10343908896 10755051520 1385814128 14.469 99 73 144 0 1444308944 40289504 18205312
17:54:49 3167 8192000000 10352256688 10766938112 1396689216 14.566 99 73 146 2 1453015632 38679712 17646704
17:55:56 737 8192000000 10355574000 10758950912 1391230912 14.517 100 91 182 0 1455196544 41565408 22400224
17:57:00 9266 8192000000 10326657568 10743918592 1373009520 14.354 100 40 78 0 1429718752 45938304 10770928
17:58:13 8731 8192000000 10326022400 10751594496 1367992224 14.309 99 43 86 1 1423079984 43354144 11733616
17:59:26 10033 8192000000 10346831296 10766073856 1385477888 14.465 100 57 112 1 1448207008 48004384 14724736
18:00:45 15955 8192000000 10296157040 10726834176 1330170752 13.969 97 30 59 0 1375375184 37583456 7620976
18:02:20 13273 8192000000 10286227712 10717446144 1334340112 14.006 98 24 48 0 1377624336 36663360 6620864
18:03:41 18794 8192000000 10274963952 10706345984 1321323840 13.889 98 18 34 0 1366177456 39735728 5117888
18:05:04 35888 8192000000 10297801056 10729672704 1326089248 13.932 99 23 46 0 1385983216 53744320 6149648
18:06:42 62999 8192000000 10254361280 10691272704 1310062880 13.787 98 19 37 0 1327466688 13329040 4074768
18:08:45 70963 8192000000 10298244368 10734288896 1364848032 14.281 100 35 72 0 1395483744 22893328 7742384
18:11:37 137635 8192000000 10301712080 10739400704 1362240352 14.257 99 28 55 0 1380421808 11775728 6405728
18:15:19 41796 8192000000 10301452320 10743021568 1391654080 14.521 100 26 52 0 1412880192 14913968 6312144
18:18:08 40104 8192000000 10298758704 10757758976 1381748464 14.432 99 17 34 1 1402222000 16384320 4089216
18:20:10 52796 8192000000 10304394368 10768392192 1405152080 14.641 100 14 28 0 1420098992 11439808 3507104
18:22:07 66281 8192000000 10316283456 10777378816 1406338272 14.651 100 30 59 1 1427924432 14086304 7499856
18:24:21 53463 8192000000 10301814640 10768429056 1397409968 14.572 99 15 30 1 1413207712 12299552 3498192
18:26:24 53900 8192000000 10296531376 10762682368 1382301808 14.437 99 18 36 2 1406756880 20054928 4400144
18:28:22 32154 8192000000 10314687616 10775830528 1403172736 14.623 100 11 21 0 1426790096 21235744 2381616
18:29:54 45852 8192000000 10299362352 10769629184 1388268832 14.490 99 18 36 2 1408251456 16001648 3980976
18:31:57 75495 8192000000 10285173632 10751823872 1369670368 14.324 98 12 24 0 1383615232 11246960 2697904
18:34:13 44809 8192000000 10284295328 10749480960 1376857504 14.388 99 23 46 0 1398226480 15983168 5385808
18:36:00 43826 8192000000 10295243568 10751152128 1375122160 14.373 98 24 48 0 1389793072 9368544 5302368
18:37:45 44631 8192000000 10260900496 10720632832 1310388304 13.790 95 29 58 0 1327424848 11197440 5839104
18:39:33 70476 8192000000 10275641808 10732847104 1338975680 14.048 98 14 28 0 1358577584 16321936 3279968
18:41:44 30443 8192000000 10260031232 10695196672 1292550048 13.627 95 25 48 0 1306526528 8520256 5456224
18:43:16 13910 8192000000 10240380160 10664120320 1256179680 13.295 93 33 66 0 1273060160 9841920 7038560
18:44:33 7591 8192000000 10272578880 10699718656 1348586512 14.135 100 54 108 0 1373258384 13331424 11340448
18:45:44 66847 8192000000 10268605328 10733977600 1352027456 14.166 99 17 34 0 1373308192 17343056 3937680
18:47:51 123554 8192000000 10267384624 10721927168 1337383568 14.034 98 28 57 0 1352278416 8494736 6400112
18:50:58 49274 8192000000 10270889776 10713726976 1349442544 14.142 99 49 98 0 1370677504 10798240 10436720
18:52:55 30747 8192000000 10241581824 10700857344 1351125968 14.158 100 5 10 0 1361751856 9480976 1144912
18:55:05 74152 8192000000 10263110064 10712399872 1355930624 14.201 100 25 50 0 1376709936 15288112 5491200
18:57:20 84945 8192000000 10255993968 10705379328 1321074912 13.886 97 6 10 0 1337977024 15872624 1029488
18:59:45 108056 8192000000 10289512224 10745921536 1373260496 14.356 100 18 34 0 1386478688 9396384 3821808
19:02:41 101068 8192000000 10298746544 10766147584 1360498896 14.242 98 9 18 0 1376271584 13952592 1820096
19:05:22 136183 8192000000 10306611760 10759045120 1384432672 14.456 99 18 36 0 1402193184 13775008 3985504
19:08:40 39621 8192000000 10320088512 10774982656 1380140384 14.418 98 41 82 0 1402517184 13668736 8708064
19:10:28 103216 8192000000 10304484112 10768072704 1405558688 14.644 100 28 56 0 1421086528 9087856 6439984
19:13:27 63023 8192000000 10302458096 10772459520 1380558992 14.422 98 4 8 0 1391688800 10280768 849040
19:15:36 70994 8192000000 10297519120 10765721600 1393486736 14.537 99 10 20 0 1408840656 12695824 2658096
19:17:48 79628 8192000000 10297415584 10766831616 1388267216 14.490 99 8 16 0 1400465088 10544736 1653136
19:20:28 54526 8192000000 10293626064 10753417216 1351534848 14.161 97 28 56 0 1367626240 9411472 6679920
19:22:25 45474 8192000000 10299161200 10758361088 1381679520 14.432 99 28 56 0 1401694064 13659568 6354976
19:24:11 53886 8192000000 10291250336 10747572224 1358802560 14.227 98 14 28 0 1374338064 12349760 3185744
19:26:12 91950 8192000000 10270476544 10715656192 1301253216 13.707 95 10 20 0 1314174416 10861952 2059248
19:28:44 26238 8192000000 10269262512 10707574784 1307883024 13.767 96 50 101 1 1334554976 15741744 10930208
19:31:28 105253 8192000000 10263353488 10710831104 1342718944 14.082 98 35 69 0 1361509968 11374752 7416272
19:34:14 10184 8192000000 10253419360 10703597568 1353972720 14.183 100 27 54 0 1367188544 7591536 5624288
19:36:13 55544 8192000000 10265889216 10729648128 1369338896 14.321 100 5 10 0 1380546496 10183024 1024576
19:38:15 23364 8192000000 9307788320 9619775488 464501632 5.365 34 5 10 0 470250448 4562576 1186240

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-10-2022 19:41:12 to 04-10-2022 19:41:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-10-2022 19:41:28 to 04-10-2022 19:41:30.

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          249246
MON$OLDEST_ACTIVE               249247
MON$OLDEST_SNAPSHOT             249247
MON$NEXT_TRANSACTION            249247
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                       2030304
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33632 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33632 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33632 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33632 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 ---
:195: 
:196: mscs-test-161.budzdorov.ru    Tue Oct  4 19:38:41 2022
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Tue Oct  4 19:38:41 2022
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:202: 
--- end of diff output ---

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

04.10.22 19:41:31. 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-10-04 19:41:34 No severe PSQL-related problems occured
04.10.22 19:41:35. End of report.