Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2022 16:44
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 15396876 kB
MemAvailable 30599812 kB
Buffers 0 kB
Cached 16342884 kB
SwapCached 2064 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-01 16:42:23
DTS_END                         2022-10-01 16:42:23.8690
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/38036
TRN_ID                          239354
ATT_ID                          600
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: 9427.42 18784 1131290
customer order: creation 171.03 355 20523
customer order: refuse 14.68 322 1762
order to supplier: creation 44.83 1264 5379
order to supplier: removal 3.57 90074 428
invoice (draft): creation 45.92 3459 5510
invoice (draft): removal 55.22 693 6626
invoice accept: apply 41.46 18038 4975
invoice accept: cancel 49.73 788 5968
customer reserve: creation 3813.88 178 457665
customer reserve: removal 4892.82 51 587138
realization accept: apply 82.88 184 9945
realization accept: cancel 83.05 110 9966
payment from customer: creation 58.45 130 7014
payment from customer: removal 5.88 105 705
payment to supplier: creation 48.20 133 5784
payment to supplier: removal 3.35 73 402
service: total inventory turnovers 5.04 1086 605
service: total monetary turnovers 6.69 40 803
service: aggregate perf. data 0.73 30156 88
service: refresh index statistics 0.03 228438 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-10-2022 16:45:05 to 01-10-2022 16:45:08.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4720
TEST_TIME 2 4178
TEST_TIME 3 4772
TEST_TIME 4 5072
TEST_TIME 5 5385
TEST_TIME 6 5382
TEST_TIME 7 5879
TEST_TIME 8 6287
TEST_TIME 9 6293
TEST_TIME 10 6385
TEST_TIME 11 6703
TEST_TIME 12 6614
TEST_TIME 13 6497
TEST_TIME 14 6648
TEST_TIME 15 6746
TEST_TIME 16 6887
TEST_TIME 17 7273
TEST_TIME 18 7410
TEST_TIME 19 7576
TEST_TIME 20 7711
TEST_TIME 21 7916
TEST_TIME 22 8243
TEST_TIME 23 9053
TEST_TIME 24 8921
TEST_TIME 25 8892
TEST_TIME 26 8869
TEST_TIME 27 8996
TEST_TIME 28 9243
TEST_TIME 29 9996
TEST_TIME 30 10353
TEST_TIME 31 10218
TEST_TIME 32 10468
TEST_TIME 33 10424
TEST_TIME 34 10424
TEST_TIME 35 10530
TEST_TIME 36 10410
TEST_TIME 37 10316
TEST_TIME 38 10387
TEST_TIME 39 10132
TEST_TIME 40 10076
TEST_TIME 41 10133
TEST_TIME 42 10064
TEST_TIME 43 10018
TEST_TIME 44 9873
TEST_TIME 45 9802
TEST_TIME 46 9808
TEST_TIME 47 9791
TEST_TIME 48 9762
TEST_TIME 49 9633
TEST_TIME 50 9673
TEST_TIME 51 9672
TEST_TIME 52 9532
TEST_TIME 53 9466
TEST_TIME 54 9570
TEST_TIME 55 9535
TEST_TIME 56 9482
TEST_TIME 57 9504
TEST_TIME 58 9416
TEST_TIME 59 9558
TEST_TIME 60 9574
TEST_TIME 61 9639
TEST_TIME 62 9615
TEST_TIME 63 9669
TEST_TIME 64 9674
TEST_TIME 65 9783
TEST_TIME 66 9837
TEST_TIME 67 9839
TEST_TIME 68 9783
TEST_TIME 69 9800
TEST_TIME 70 9845
TEST_TIME 71 9723
TEST_TIME 72 9670
TEST_TIME 73 9676
TEST_TIME 74 9607
TEST_TIME 75 9592
TEST_TIME 76 9597
TEST_TIME 77 9521
TEST_TIME 78 9446
TEST_TIME 79 9478
TEST_TIME 80 9398
TEST_TIME 81 9421
TEST_TIME 82 9392
TEST_TIME 83 9343
TEST_TIME 84 9392
TEST_TIME 85 9423
TEST_TIME 86 9523
TEST_TIME 87 9509
TEST_TIME 88 9528
TEST_TIME 89 9591
TEST_TIME 90 9553
TEST_TIME 91 9614
TEST_TIME 92 9654
TEST_TIME 93 9742
TEST_TIME 94 9738
TEST_TIME 95 9641
TEST_TIME 96 9595
TEST_TIME 97 9627
TEST_TIME 98 9601
TEST_TIME 99 9540
TEST_TIME 100 9499
TEST_TIME 101 9473
TEST_TIME 102 9447
TEST_TIME 103 9429
TEST_TIME 104 9453
TEST_TIME 105 9472
TEST_TIME 106 9453
TEST_TIME 107 9425
TEST_TIME 108 9420
TEST_TIME 109 9469
TEST_TIME 110 9470
TEST_TIME 111 9493
TEST_TIME 112 9519
TEST_TIME 113 9558
TEST_TIME 114 9541
TEST_TIME 115 9569
TEST_TIME 116 9629
TEST_TIME 117 9569
TEST_TIME 118 9511
TEST_TIME 119 9455
TEST_TIME 120 9430

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 01-10-2022 16:45:09 to 01-10-2022 16:45:12.

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 1555845 1555845 0 0.00 0 19963 6 0 0
doc_list_biud 1555845 1555845 0 0.00 0 53864 25 0 0
sp_add_invoice_to_stock 5076 4975 101 1.98 42 154732 18038 0 101
sp_cancel_adding_invoice 5968 5968 0 0.00 5 61543 789 0 0
sp_cancel_client_order 1762 1762 0 0.00 4 9899 322 0 0
sp_cancel_customer_reserve 587138 587138 0 0.00 0 21949 51 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 2 9383 105 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 6900 73 0 0
sp_cancel_supplier_invoice 6636 6626 10 0.15 2 53875 694 0 10
sp_cancel_supplier_order 428 428 0 0.00 5 1035842 90074 0 0
sp_cancel_write_off 9966 9966 0 0.00 1 6787 111 0 0
sp_client_order 20523 20523 0 0.00 3 27403 355 0 0
sp_customer_reserve 457665 457665 0 0.00 0 117751 178 0 0
sp_fill_shopping_cart 31401 31401 0 0.00 1 18192 39 0 0
sp_fill_shopping_cart_clo_res 457656 457656 0 0.00 0 20463 12 0 0
sp_get_clo_for_invoice 4966 4966 0 0.00 0 23603 301 0 0
sp_kill_cost_storno 17041 17041 0 0.00 0 9356 52 0 0
sp_kill_qstorno_ret_qs2qd 1191296 1191296 0 0.00 0 53861 28 0 0
sp_kill_qty_storno 626799 626799 0 0.00 0 53863 61 0 0
sp_lock_selected_doc 20700 20700 0 0.00 0 6905 6 0 0
sp_make_cost_storno 27709 27709 0 0.00 0 19900 58 0 0
sp_multiply_rows_for_pdistr 27709 27709 0 0.00 0 10418 29 0 0
sp_multiply_rows_for_qdistr 20523 20523 0 0.00 0 20328 144 0 0
sp_pay_from_customer 7014 7014 0 0.00 4 17221 131 0 0
sp_pay_to_supplier 5784 5784 0 0.00 4 15943 133 0 0
sp_payment_common 12798 12798 0 0.00 2 16689 41 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 9886 299 0 0
sp_qd_handle_on_invoice_upd_sts 10934 10934 0 0.00 0 21318 316 0 0
sp_qd_handle_on_reserve_upd_sts 9945 9945 0 0.00 0 12421 56 0 0
sp_reserve_write_off 9945 9945 0 0.00 6 17852 184 0 0
sp_supplier_invoice 5510 5510 0 0.00 6 72382 3460 0 0
sp_supplier_order 5379 5379 0 0.00 4 50631 1264 0 0
srv_aggregate_perf_data 88 88 0 0.00 3000 985525 30157 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1328 163205 46055 0 0
srv_find_qd_qs_mism 1084378 1084378 0 0.00 0 19962 5 0 0
srv_make_invnt_saldo 605 605 0 0.00 44 111561 1086 0 0
srv_make_money_saldo 803 803 0 0.00 1 6991 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 146047 377731 228439 0 0
t$perf-abend:sp_add_invoice_to_stock 101 101 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 4976 4976 0 0.00 0 18486 201 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 2565 90 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 1552 13 0 0
t$perf-norm:sp_cancel_customer_reserve 695 695 0 0.00 0 6001 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 1522 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 891 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 3912 28 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 29635 1606 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 3918 8 0 0
t$perf-norm:sp_client_order 20523 20523 0 0.00 0 10313 19 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 2391 8 0 0
t$perf-norm:sp_pay_from_customer 7014 7014 0 0.00 0 3366 8 0 0
t$perf-norm:sp_pay_to_supplier 5784 5784 0 0.00 0 3521 11 0 0
t$perf-norm:sp_reserve_write_off 9945 9945 0 0.00 0 7901 14 0 0
t$perf-norm:sp_supplier_invoice 5510 5510 0 0.00 0 7157 19 0 0
t$perf-norm:sp_supplier_order 5379 5379 0 0.00 0 10357 24 0 0
t$perf-norm:srv_aggregate_perf_data 88 88 0 0.00 0 657 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 16 1 0 0
t$perf-norm:srv_make_invnt_saldo 605 605 0 0.00 0 3532 33 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 4002 28 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10134 9932 202 1.99 0 8670 22 0 202
v_all_customers 36984 36984 0 0.00 0 29 1 0 0
v_all_suppliers 30280 30280 0 0.00 0 81 1 0 0
v_all_wares 41046 41046 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 1440 9 0 0
v_cancel_client_order 3524 3524 0 0.00 0 2333 4 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 1137 5 0 0
v_cancel_customer_reserve 1390 1390 0 0.00 0 1035 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 6893 11 0 0
v_cancel_supplier_invoice 776 756 20 2.57 0 976 14 0 20
v_cancel_supplier_order 856 856 0 0.00 0 526 5 0 0
v_cancel_write_off 1990 1990 0 0.00 0 5379 23 0 0
v_max_id_clo_ord 5373 5373 0 0.00 0 317 1 0 0
v_max_id_clo_res 995 995 0 0.00 0 1633 15 0 0
v_max_id_ord_sup 5505 5505 0 0.00 0 7007 3 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 10029 12 0 0
v_max_non_paid_realizn 3834 3834 0 0.00 0 3188 5 0 0
v_min_id_clo_ord 5373 5373 0 0.00 0 7817 9 0 0
v_min_id_clo_res 995 995 0 0.00 0 1612 7 0 0
v_min_id_ord_sup 5505 5505 0 0.00 0 18186 10 0 0
v_min_non_paid_invoice 5784 5784 0 0.00 0 15302 27 0 0
v_min_non_paid_realizn 7014 7014 0 0.00 0 12539 33 0 0
v_our_firm 10422 10422 0 0.00 0 22 1 0 0
v_reserve_write_off 19890 19890 0 0.00 0 7586 7 0 0
x_lock_depdocs_on_canc_invoice 12216 12216 0 0.00 0 13146 109 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 14031 380 0 0
x_make_qstorno_1000_1200 5379 5379 0 0.00 1 50607 1181 0 0
x_make_qstorno_1200_2000 5510 5510 0 0.00 3 72356 3315 0 0
x_make_qstorno_2100_3300 457665 457665 0 0.00 0 117749 163 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 01-10-2022 16:45:13 to 01-10-2022 16:45:18.

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
14:12:27 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:13:28 16285 8192000000 9890554688 10199609344 971264160 10.599 100 84 168 0 999451312 5789456 22397696
14:14:52 20593 8192000000 9943329440 10265563136 1030870752 11.177 100 87 174 0 1064735456 12159280 21705424
14:16:19 16320 8192000000 9983393808 10313383936 1062271696 11.478 100 87 174 0 1093467280 9567040 21628544
14:17:42 9936 8192000000 10026192144 10368905216 1109068320 11.924 100 77 152 1 1138173488 10637120 18468048
14:18:54 7292 8192000000 10064654512 10415104000 1148851152 12.299 100 82 164 0 1179493968 11048304 19594512
14:20:02 4442 8192000000 10112853840 10478063616 1200956688 12.785 100 74 146 1 1228748448 11394496 16397264
14:21:11 1982 8192000000 10149639680 10526097408 1241627632 13.161 100 83 168 2 1274407728 13120768 19659328
14:22:15 1588 8192000000 10168218768 10550706176 1261040368 13.340 100 74 144 0 1288563360 12424864 15098128
14:23:19 2660 8192000000 10194087456 10575925248 1283390176 13.544 100 80 158 0 1314072144 12879360 17802608
14:24:23 1760 8192000000 10199041504 10590208000 1294574032 13.646 100 67 133 1 1319152368 10359440 14218896
14:25:25 11453 8192000000 10208356480 10601299968 1297742480 13.675 100 63 124 1 1321306464 9636976 13927008
14:26:37 7791 8192000000 10187808768 10601684992 1303528432 13.727 100 28 56 0 1315930320 6285888 6116000
14:27:49 7781 8192000000 10200000640 10615832576 1314382864 13.826 100 16 32 0 1324046672 6287536 3376272
14:28:57 17069 8192000000 10216646240 10632953856 1322661872 13.901 100 25 46 2 1338325552 9370304 6293376
14:30:57 5471 8192000000 10223778432 10636378112 1328554624 13.954 100 40 79 0 1344822240 7725936 8541680
14:32:04 12542 8192000000 10205058240 10618818560 1288884080 13.594 97 19 39 1 1298314960 5272608 4158272
14:33:17 10865 8192000000 10181862576 10589769728 1260500752 13.335 97 16 32 0 1269959072 5168896 4289424
14:34:28 18789 8192000000 10151516816 10560479232 1219230064 12.955 94 18 36 0 1229021392 5538224 4253104
14:35:51 5732 8192000000 10142830800 10540056576 1243024256 13.174 99 35 70 0 1258120976 6981984 8114736
14:36:59 7503 8192000000 10151770576 10546233344 1248227168 13.222 99 28 54 0 1263452032 10080032 5144832
14:38:08 65482 8192000000 10164561184 10577092608 1274548368 13.463 100 17 34 0 1283329840 5000576 3780896
14:40:14 5830 8192000000 10187294992 10597720064 1297451568 13.672 100 24 49 0 1308299648 5740128 5107952
14:41:21 5327 8192000000 10189888000 10604916736 1299882272 13.694 100 27 55 0 1312584304 6696768 6005264
14:42:29 22055 8192000000 10223528144 10634518528 1326800352 13.938 100 36 68 1 1339733920 4467280 8466288
14:43:57 55602 8192000000 10246313936 10663546880 1343572448 14.090 100 45 90 2 1362440336 8249728 10618160
14:45:59 14408 8192000000 10270464096 10688765952 1360987536 14.246 100 60 120 0 1387285664 12598512 13699616
14:47:22 38026 8192000000 10276539584 10697564160 1369387680 14.322 100 28 56 1 1386305520 10494704 6423136
14:49:04 2747 8192000000 10293248736 10715103232 1380449360 14.421 100 51 100 2 1406007744 14465456 11092928
14:50:09 7994 8192000000 10298805360 10722062336 1377456560 14.394 99 58 116 0 1406334784 15614288 13263936
14:51:22 23016 8192000000 10280820960 10716000256 1365755456 14.289 99 24 48 0 1386078368 14508528 5814384
14:52:48 4654 8192000000 10297924528 10720051200 1378133344 14.400 100 49 98 0 1410418768 20828032 11457392
14:53:58 17812 8192000000 10327664176 10747473920 1396171344 14.561 100 57 113 5 1430821392 20408544 14241504
14:55:23 14727 8192000000 10306692896 10731384832 1359426864 14.232 97 53 104 0 1395330416 23376208 12527344
14:56:43 4021 8192000000 10310846240 10719092736 1376480528 14.385 100 60 116 0 1417848720 27732592 13635600
14:57:55 1322 8192000000 10335449216 10739605504 1388451648 14.492 100 79 157 0 1436725984 29276224 18998112
14:59:04 2417 8192000000 10345703376 10755096576 1397114960 14.569 100 72 143 1 1448759424 34541024 17103440
15:00:12 1792 8192000000 10308313904 10717011968 1364729328 14.280 99 72 142 1 1415648800 32707760 18211712
15:01:18 1385 8192000000 10338983680 10739761152 1371971440 14.345 99 80 158 4 1427985728 37255568 18758720
15:02:22 3638 8192000000 10332467616 10746970112 1378080528 14.399 100 70 139 0 1435957744 39469792 18407424
15:03:33 1848 8192000000 10342898176 10744463360 1375480208 14.376 100 88 174 1 1445963328 47879440 22603680
15:04:41 2000 8192000000 10355226208 10765250560 1385701984 14.468 100 81 160 1 1457558896 50819024 21037888
15:05:48 14784 8192000000 10332310048 10768920576 1398668640 14.583 100 31 62 0 1450237600 43524832 8044128
15:07:10 25395 8192000000 10345310608 10771468288 1401670096 14.610 100 41 81 0 1447442576 34145488 11626992
15:08:38 25773 8192000000 10328430128 10769559552 1384325712 14.455 99 29 58 0 1438920224 46154480 8440032
15:10:23 56330 8192000000 10279728096 10734133248 1350996416 14.156 99 25 50 0 1406910496 48932832 6981248
15:12:22 49860 8192000000 10281067472 10717523968 1367913280 14.308 100 19 38 0 1394631328 22406960 4311088
15:14:15 66007 8192000000 10286353872 10744881152 1349902192 14.147 98 9 19 0 1373275024 21520464 1852368
15:16:27 104803 8192000000 10276569712 10725941248 1313462448 13.817 95 8 16 0 1328201744 13188144 1551152
15:19:12 92050 8192000000 10277439280 10733408256 1350252832 14.150 98 15 30 0 1365593728 11456160 3884736
15:22:14 80801 8192000000 10295521696 10743042048 1370777664 14.334 99 15 30 0 1384952464 11090864 3083936
15:25:09 163205 8192000000 10311897216 10755153920 1355646784 14.198 97 12 24 0 1369079312 10425376 3007152
15:28:53 28426 8192000000 10314731712 10763808768 1403184832 14.623 100 35 65 0 1424185840 13616016 7384992
15:31:45 24777 8192000000 10301952944 10753646592 1366937488 14.300 97 28 54 1 1385656608 12846560 5872560
15:33:28 60938 8192000000 10284358912 10743402496 1364124608 14.274 98 17 34 0 1381706496 13374512 4207376
15:36:05 11356 8192000000 10289231920 10736820224 1374837488 14.370 99 33 64 0 1394751008 12743888 7169632
15:37:17 61344 8192000000 10277591360 10746806272 1356023456 14.202 98 10 20 0 1369883632 11822448 2037728
15:39:42 55394 8192000000 10294067024 10748141568 1360915232 14.246 98 10 20 0 1374957232 11671552 2370448
15:41:50 48579 8192000000 10309257632 10760929280 1396516048 14.564 100 25 48 0 1409918672 8162224 5240400
15:43:39 61911 8192000000 10313971280 10758909952 1372588480 14.350 98 32 61 1 1387090624 7448512 7053632
15:45:45 41975 8192000000 10299440080 10751340544 1362237952 14.257 98 24 46 1 1381007872 12552176 6217744
15:47:27 95371 8192000000 10282026416 10741555200 1354598624 14.189 98 13 26 0 1372487248 14791616 3097008
15:50:12 25018 8192000000 10291178576 10727747584 1318925136 13.867 95 22 43 1 1330215056 6813792 4476128
15:51:40 80738 8192000000 10279966608 10741776384 1342565472 14.081 97 24 46 0 1363384384 15987808 4831104
15:54:01 40631 8192000000 10287203616 10733682688 1370619376 14.333 99 39 76 0 1387599888 7579728 9400784
15:56:02 36540 8192000000 10236921664 10684301312 1322956640 13.903 98 20 40 0 1337627744 9977456 4693648
15:57:40 75434 8192000000 10270494752 10730459136 1329634560 13.964 97 12 22 1 1346671264 14528080 2508624
16:00:00 66545 8192000000 10276822912 10736656384 1372826640 14.352 100 19 38 0 1387633984 10139264 4668080
16:02:16 42944 8192000000 10250454640 10702299136 1322640832 13.901 96 12 24 0 1335268256 9572208 3055216
16:03:59 50395 8192000000 10264456960 10722787328 1334037552 14.004 97 11 22 0 1344183552 7566864 2579136
16:05:51 41244 8192000000 10276745072 10739396608 1364545712 14.278 99 17 34 0 1382118288 13475344 4097232
16:07:50 59058 8192000000 10272476704 10737319936 1370646256 14.333 100 14 28 0 1389337328 15648256 3042816
16:09:55 59222 8192000000 10298646816 10748526592 1382670368 14.440 100 23 46 0 1403074384 14970656 5433360
16:12:05 26746 8192000000 10299445664 10741899264 1381088752 14.426 100 40 78 1 1402427216 13332320 8006144
16:13:32 103763 8192000000 10302873984 10766073856 1380800512 14.424 99 17 34 0 1400530608 15735840 3994256
16:16:20 115797 8192000000 10291924624 10743394304 1360492112 14.242 98 20 40 0 1379487792 13731808 5263872
16:21:15 68039 8192000000 10294012592 10760744960 1383030096 14.444 99 3 6 0 1393220000 9640176 549728
16:25:11 58219 8192000000 10268462960 10730696704 1326035264 13.931 95 10 20 0 1341854976 13518288 2301424
16:27:09 69022 8192000000 10276580288 10736717824 1357642656 14.216 98 16 32 0 1370806416 8910240 4253520
16:29:20 41944 8192000000 10284177936 10733420544 1330646000 13.973 96 20 40 0 1341424912 6705280 4073632
16:31:03 82740 8192000000 10268167072 10734358528 1311918672 13.803 95 13 26 1 1332351840 17506768 2926400
16:33:26 102331 8192000000 10285449952 10737606656 1334865728 14.011 97 13 26 0 1351713328 14209264 2638336
16:36:11 62323 8192000000 10312696096 10760355840 1362839952 14.263 98 19 38 0 1377318928 10480656 3998320
16:38:21 39136 8192000000 10295101984 10762887168 1372252368 14.347 99 6 12 0 1387271808 14064368 955072
16:40:02 72916 8192000000 10283947792 10747682816 1360807904 14.245 98 17 34 0 1376569216 11800416 3960896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 01-10-2022 16:45:20 to 01-10-2022 16:45:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 101 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 01-10-2022 16:45:35 to 01-10-2022 16:45:37.

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          238647
MON$OLDEST_ACTIVE               239493
MON$OLDEST_SNAPSHOT             239493
MON$NEXT_TRANSACTION            239493
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                       2049248
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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Sat Oct  1 16:42:29 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Sat Oct  1 16:42:32 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Sat Oct  1 16:42:48 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:17: 
:18: 
:19: mscs-test-161.budzdorov.ru    Sat Oct  1 16:42:56 2022
:20:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:21: 
--- end of diff output ---

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

01.10.22 16:45:38. 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-01 16:45:41 No severe PSQL-related problems occured
01.10.22 16:45:42. End of report.