Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.06.2022 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16357456 kB
MemAvailable 31231812 kB
Buffers 0 kB
Cached 15328240 kB
SwapCached 2460 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-15 22:38:19
DTS_END                         2022-06-15 22:38:19.5350
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/43258
TRN_ID                          246511
ATT_ID                          646
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: 9397.89 22912 1127747
customer order: creation 177.43 381 21291
customer order: refuse 14.58 325 1749
order to supplier: creation 47.13 1255 5656
order to supplier: removal 3.31 98870 397
invoice (draft): creation 46.63 3641 5596
invoice (draft): removal 53.12 778 6374
invoice accept: apply 42.60 17626 5112
invoice accept: cancel 48.08 869 5769
customer reserve: creation 4002.70 172 480324
customer reserve: removal 4664.08 55 559690
realization accept: apply 84.84 168 10181
realization accept: cancel 80.97 101 9716
payment from customer: creation 60.43 99 7251
payment from customer: removal 5.88 73 705
payment to supplier: creation 50.44 106 6053
payment to supplier: removal 3.66 74 439
service: total inventory turnovers 4.71 1019 565
service: total monetary turnovers 6.40 23 768
service: aggregate perf. data 0.89 16177 107
service: refresh index statistics 0.03 316432 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-06-2022 22:39:20 to 15-06-2022 22:39:22.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1294
TEST_TIME 2 1364
TEST_TIME 3 2838
TEST_TIME 4 3132
TEST_TIME 5 3414
TEST_TIME 6 4157
TEST_TIME 7 4142
TEST_TIME 8 4156
TEST_TIME 9 4286
TEST_TIME 10 4358
TEST_TIME 11 4691
TEST_TIME 12 4882
TEST_TIME 13 5498
TEST_TIME 14 5847
TEST_TIME 15 6452
TEST_TIME 16 6974
TEST_TIME 17 7841
TEST_TIME 18 7987
TEST_TIME 19 8975
TEST_TIME 20 9212
TEST_TIME 21 9454
TEST_TIME 22 9560
TEST_TIME 23 9751
TEST_TIME 24 9903
TEST_TIME 25 10070
TEST_TIME 26 10235
TEST_TIME 27 10036
TEST_TIME 28 10116
TEST_TIME 29 10395
TEST_TIME 30 10261
TEST_TIME 31 10182
TEST_TIME 32 9891
TEST_TIME 33 10066
TEST_TIME 34 9941
TEST_TIME 35 9930
TEST_TIME 36 9791
TEST_TIME 37 9815
TEST_TIME 38 9740
TEST_TIME 39 9616
TEST_TIME 40 9508
TEST_TIME 41 9471
TEST_TIME 42 9484
TEST_TIME 43 9466
TEST_TIME 44 9453
TEST_TIME 45 9406
TEST_TIME 46 9347
TEST_TIME 47 9367
TEST_TIME 48 9348
TEST_TIME 49 9453
TEST_TIME 50 9515
TEST_TIME 51 9514
TEST_TIME 52 9481
TEST_TIME 53 9503
TEST_TIME 54 9505
TEST_TIME 55 9566
TEST_TIME 56 9478
TEST_TIME 57 9588
TEST_TIME 58 9650
TEST_TIME 59 9611
TEST_TIME 60 9577
TEST_TIME 61 9498
TEST_TIME 62 9553
TEST_TIME 63 9454
TEST_TIME 64 9460
TEST_TIME 65 9403
TEST_TIME 66 9400
TEST_TIME 67 9334
TEST_TIME 68 9372
TEST_TIME 69 9354
TEST_TIME 70 9338
TEST_TIME 71 9459
TEST_TIME 72 9452
TEST_TIME 73 9474
TEST_TIME 74 9454
TEST_TIME 75 9487
TEST_TIME 76 9604
TEST_TIME 77 9567
TEST_TIME 78 9517
TEST_TIME 79 9576
TEST_TIME 80 9501
TEST_TIME 81 9417
TEST_TIME 82 9389
TEST_TIME 83 9414
TEST_TIME 84 9411
TEST_TIME 85 9380
TEST_TIME 86 9350
TEST_TIME 87 9368
TEST_TIME 88 9298
TEST_TIME 89 9341
TEST_TIME 90 9330
TEST_TIME 91 9308
TEST_TIME 92 9315
TEST_TIME 93 9415
TEST_TIME 94 9430
TEST_TIME 95 9435
TEST_TIME 96 9465
TEST_TIME 97 9454
TEST_TIME 98 9483
TEST_TIME 99 9483
TEST_TIME 100 9611
TEST_TIME 101 9591
TEST_TIME 102 9568
TEST_TIME 103 9577
TEST_TIME 104 9497
TEST_TIME 105 9469
TEST_TIME 106 9473
TEST_TIME 107 9463
TEST_TIME 108 9444
TEST_TIME 109 9431
TEST_TIME 110 9405
TEST_TIME 111 9364
TEST_TIME 112 9345
TEST_TIME 113 9378
TEST_TIME 114 9385
TEST_TIME 115 9419
TEST_TIME 116 9419
TEST_TIME 117 9413
TEST_TIME 118 9426
TEST_TIME 119 9448
TEST_TIME 120 9398

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-06-2022 22:39:24 to 15-06-2022 22:39:27.

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 1570982 1570982 0 0.00 0 26867 6 0 0
doc_list_biud 1570981 1570981 0 0.00 0 54022 25 0 0
sp_add_invoice_to_stock 5204 5112 92 1.76 30 216777 17626 0 92
sp_cancel_adding_invoice 5769 5769 0 0.00 6 94336 870 0 0
sp_cancel_client_order 1749 1749 0 0.00 5 10305 326 0 0
sp_cancel_customer_reserve 559690 559690 0 0.00 1 52853 55 0 0
sp_cancel_pay_from_customer 705 705 0 0.00 2 3795 73 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 5062 75 0 0
sp_cancel_supplier_invoice 6386 6374 12 0.18 1 53940 779 0 12
sp_cancel_supplier_order 397 397 0 0.00 8 1027061 98871 0 0
sp_cancel_write_off 9716 9716 0 0.00 1 16848 102 0 0
sp_client_order 21291 21291 0 0.00 3 37019 381 0 0
sp_customer_reserve 480324 480324 0 0.00 0 122217 172 0 0
sp_fill_shopping_cart 32529 32529 0 0.00 1 29180 39 0 0
sp_fill_shopping_cart_clo_res 480324 480324 0 0.00 0 21354 14 0 0
sp_get_clo_for_invoice 5112 5112 0 0.00 0 47687 335 0 0
sp_kill_cost_storno 16629 16629 0 0.00 0 6602 52 0 0
sp_kill_qstorno_ret_qs2qd 1135867 1135867 0 0.00 0 53936 30 0 0
sp_kill_qty_storno 598987 598987 0 0.00 0 54018 66 0 0
sp_lock_selected_doc 21203 21203 0 0.00 0 8542 5 0 0
sp_make_cost_storno 28597 28597 0 0.00 0 26858 49 0 0
sp_multiply_rows_for_pdistr 28596 28596 0 0.00 0 7807 23 0 0
sp_multiply_rows_for_qdistr 21291 21291 0 0.00 0 27833 180 0 0
sp_pay_from_customer 7251 7251 0 0.00 4 18091 99 0 0
sp_pay_to_supplier 6053 6053 0 0.00 4 21187 107 0 0
sp_payment_common 13304 13304 0 0.00 1 8792 20 0 0
sp_qd_handle_on_cancel_clo 1749 1749 0 0.00 0 10300 300 0 0
sp_qd_handle_on_invoice_upd_sts 10881 10881 0 0.00 0 54011 352 0 0
sp_qd_handle_on_reserve_upd_sts 10180 10180 0 0.00 0 20108 62 0 0
sp_reserve_write_off 10181 10181 0 0.00 6 31043 169 0 0
sp_supplier_invoice 5596 5596 0 0.00 9 121063 3641 0 0
sp_supplier_order 5656 5656 0 0.00 5 37515 1256 0 0
srv_aggregate_perf_data 107 107 0 0.00 2656 83933 16178 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1514 152719 46142 0 0
srv_find_qd_qs_mism 1077764 1077764 0 0.00 0 16485 6 0 0
srv_make_invnt_saldo 565 565 0 0.00 31 37729 1019 0 0
srv_make_money_saldo 768 768 0 0.00 0 1501 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 218710 440524 316432 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5112 5112 0 0.00 0 18312 150 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 4806 105 0 0
t$perf-norm:sp_cancel_client_order 1749 1749 0 0.00 0 3029 12 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2811 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 705 705 0 0.00 0 7056 24 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 760 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 856 15 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 26670 1734 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 5623 11 0 0
t$perf-norm:sp_client_order 21291 21291 0 0.00 0 9356 16 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 2041 5 0 0
t$perf-norm:sp_pay_from_customer 7251 7251 0 0.00 0 5216 10 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 10759 16 0 0
t$perf-norm:sp_reserve_write_off 10181 10181 0 0.00 0 23225 16 0 0
t$perf-norm:sp_supplier_invoice 5596 5596 0 0.00 0 21366 29 0 0
t$perf-norm:sp_supplier_order 5656 5656 0 0.00 0 9387 16 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 494 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 37 2 0 0
t$perf-norm:srv_make_invnt_saldo 565 565 0 0.00 0 654 17 0 0
t$perf-norm:srv_make_money_saldo 768 768 0 0.00 0 2221 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10408 10224 184 1.76 0 5157 19 0 184
v_all_customers 38950 38950 0 0.00 0 16 0 0 0
v_all_suppliers 31300 31300 0 0.00 0 100 1 0 0
v_all_wares 42582 42582 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 5204 13 0 0
v_cancel_client_order 3498 3498 0 0.00 0 4617 6 0 0
v_cancel_customer_prepayment 1410 1410 0 0.00 0 2205 7 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 2511 6 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 5038 14 0 0
v_cancel_supplier_invoice 912 888 24 2.63 0 7377 36 0 24
v_cancel_supplier_order 794 794 0 0.00 0 3602 14 0 0
v_cancel_write_off 2112 2112 0 0.00 0 5446 16 0 0
v_max_id_clo_ord 5653 5653 0 0.00 0 323 1 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 5756 27 0 0
v_max_id_ord_sup 5581 5581 0 0.00 0 3686 4 0 0
v_max_non_paid_invoice 1636 1636 0 0.00 0 748 2 0 0
v_max_non_paid_realizn 3782 3782 0 0.00 0 1651 4 0 0
v_min_id_clo_ord 5653 5653 0 0.00 0 1331 4 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 5865 14 0 0
v_min_id_ord_sup 5581 5581 0 0.00 0 18934 11 0 0
v_min_non_paid_invoice 6053 6053 0 0.00 0 6118 14 0 0
v_min_non_paid_realizn 7251 7251 0 0.00 0 8788 16 0 0
v_our_firm 10570 10570 0 0.00 0 15 1 0 0
v_reserve_write_off 20360 20360 0 0.00 0 6395 8 0 0
x_lock_depdocs_on_canc_invoice 11699 11699 0 0.00 0 13098 115 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 5001 303 0 0
x_make_qstorno_1000_1200 5656 5656 0 0.00 1 37509 1190 0 0
x_make_qstorno_1200_2000 5596 5596 0 0.00 3 121049 3483 0 0
x_make_qstorno_2100_3300 480324 480324 0 0.00 0 122215 156 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-06-2022 22:39:28 to 15-06-2022 22:39:32.

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
20:08:23 2 8192000000 8798205344 8809119744 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:24 29372 8192000000 9912845632 10225754112 993203568 10.813 100 89 178 0 1022599632 6121312 23274752
20:11:01 24108 8192000000 9944391232 10269523968 1025903440 11.129 100 86 172 0 1056672032 8437120 22331472
20:12:31 14243 8192000000 9996454704 10331516928 1076706432 11.616 100 85 170 0 1107775184 10259056 20809696
20:13:47 10847 8192000000 10052241600 10403442688 1136764016 12.185 100 82 162 1 1167105920 11694960 18646944
20:15:06 5958 8192000000 10095423120 10461372416 1184175456 12.629 100 76 152 1 1214865440 13242384 17447600
20:16:16 5659 8192000000 10137080480 10506723328 1224131136 13.000 100 83 165 2 1256600992 13901184 18568672
20:17:24 2291 8192000000 10176863552 10553491456 1260334160 13.333 100 87 175 1 1293225952 13140304 19751488
20:18:28 2017 8192000000 10207614944 10590507008 1291848816 13.621 100 93 186 1 1324828944 12511904 20468224
20:19:32 21243 8192000000 10208254224 10604744704 1303721680 13.729 100 58 115 1 1325848464 9496992 12629792
20:20:55 7979 8192000000 10196075024 10607198208 1310022608 13.786 100 34 68 0 1325534640 8113776 7398256
20:22:08 4902 8192000000 10209002848 10620698624 1321307696 13.889 100 36 70 2 1337150848 7756960 8086192
20:23:16 4108 8192000000 10212919248 10628718592 1327849328 13.948 100 28 54 1 1340150064 6219776 6080960
20:24:24 43962 8192000000 10217227104 10643890176 1332990752 13.994 100 24 48 1 1344663728 5635712 6037264
20:26:09 7525 8192000000 10221511472 10644615168 1333932288 14.003 100 20 41 0 1344063696 5831552 4299856
20:27:18 9923 8192000000 10208124480 10629459968 1313019008 13.813 99 22 45 0 1324639888 6560624 5060256
20:28:28 10440 8192000000 10192854448 10598367232 1266009472 13.385 97 19 35 1 1276168064 5824128 4334464
20:29:42 9191 8192000000 10179534656 10590015488 1275022432 13.468 98 19 39 0 1284995344 5978528 3994384
20:30:53 8444 8192000000 10139178160 10535997440 1207092240 12.842 96 21 41 0 1217444736 5460592 4891904
20:32:04 10113 8192000000 10138953712 10539114496 1206531216 12.837 96 29 58 2 1219307664 4859184 7917264
20:33:14 12703 8192000000 10167315600 10572652544 1275024576 13.468 100 32 65 0 1287705568 5583904 7097088
20:34:27 5481 8192000000 10168987056 10581168128 1280730352 13.520 100 37 74 0 1297217024 8568704 7917968
20:35:34 6931 8192000000 10193255328 10602483712 1300805536 13.703 100 33 66 0 1314324336 5538880 7979920
20:36:44 8819 8192000000 10195343488 10611101696 1306443872 13.754 100 34 68 3 1320315008 6270288 7600848
20:37:58 152719 8192000000 10234407280 10654236672 1324861296 13.921 100 32 65 0 1338079472 4785936 8432240
20:41:36 26021 8192000000 10263205984 10682212352 1351939808 14.165 100 55 109 3 1383200304 18173952 13086544
20:43:16 5372 8192000000 10285360352 10706735104 1373615024 14.359 100 37 74 0 1402128592 19275536 9238032
20:44:26 12590 8192000000 10305694320 10723663872 1386841072 14.478 100 66 132 2 1426756224 23904160 16010992
20:45:42 14577 8192000000 10319784496 10740453376 1393126496 14.534 100 60 120 2 1431578336 24426544 14025296
20:47:04 4697 8192000000 10331647520 10749566976 1400143520 14.596 100 54 108 1 1439397312 26100768 13153024
20:48:20 1513 8192000000 10344514080 10758836224 1407555552 14.662 100 62 121 2 1448507088 26587888 14363648
20:49:25 11825 8192000000 10337130320 10755883008 1392187312 14.525 99 59 113 2 1438351328 32552384 13611632
20:50:41 2518 8192000000 10368146016 10787540992 1417821328 14.753 100 67 133 2 1473257408 38845472 16590608
20:51:53 3117 8192000000 10366371360 10777985024 1410133056 14.685 100 83 166 1 1469914192 39307072 20474064
20:52:59 3225 8192000000 10348063440 10759831552 1382694416 14.441 99 75 147 0 1445752848 44142256 18916176
20:54:07 2697 8192000000 10326276224 10740142080 1362573616 14.260 99 64 128 1 1423932576 43391584 17967376
20:55:14 5722 8192000000 10314329344 10733879296 1350293824 14.150 99 54 108 2 1407710864 42888256 14528784
20:56:25 4609 8192000000 10310786304 10727141376 1354501584 14.188 99 53 106 1 1408685120 39635360 14548176
20:57:37 6004 8192000000 10284603264 10710503424 1349725728 14.145 99 31 62 0 1382774352 24851936 8196688
20:58:54 19360 8192000000 10271330496 10714910720 1333262368 13.997 98 19 38 0 1363503104 25351472 4889264
21:00:22 16962 8192000000 10265544464 10705383424 1337266448 14.033 99 22 44 1 1370784064 27571008 5946608
21:01:44 28360 8192000000 10272141712 10706010112 1341318576 14.069 99 24 48 0 1372924160 25111360 6494224
21:03:28 25067 8192000000 10280520784 10725576704 1363211680 14.266 100 15 30 0 1390156672 23001792 3943200
21:05:16 38630 8192000000 10294307360 10724880384 1362117472 14.256 100 47 91 2 1396037424 22708912 11211040
21:06:57 25162 8192000000 10298830272 10733142016 1359109872 14.229 99 36 72 0 1395900400 27975104 8815424
21:08:25 33757 8192000000 10300046736 10732851200 1367551584 14.305 100 42 86 2 1396497984 18866304 10080096
21:10:00 79480 8192000000 10299703040 10734972928 1380951440 14.425 100 55 105 1 1407482960 13304368 13227152
21:12:21 33552 8192000000 10270064272 10706079744 1355726912 14.199 99 42 82 0 1374820256 9885728 9207616
21:13:55 117397 8192000000 10258129296 10704191488 1298138128 13.678 94 14 28 0 1308653536 6909744 3605664
21:16:53 49647 8192000000 10292845344 10744004608 1385102288 14.462 100 18 36 0 1401257600 11499952 4655360
21:18:48 72267 8192000000 10287981856 10738429952 1357890992 14.218 98 17 33 1 1375449504 13707520 3850992
21:21:52 90068 8192000000 10290230176 10757578752 1375112608 14.373 99 26 51 2 1396759696 15530000 6117088
21:25:00 30561 8192000000 10305978352 10758279168 1398525648 14.582 100 35 70 1 1417354912 10211312 8617952
21:26:31 92153 8192000000 10305986048 10767220736 1373013936 14.354 98 22 44 0 1388342320 10362720 4965664
21:29:04 49980 8192000000 10302466480 10752462848 1356810688 14.209 97 22 44 0 1371315408 9277792 5226928
21:30:55 29366 8192000000 10300741408 10755809280 1364781840 14.280 98 30 62 0 1383460736 11885168 6793728
21:32:30 30605 8192000000 10294797120 10740678656 1345295472 14.105 97 24 48 1 1364638496 14033536 5309488
21:34:03 31523 8192000000 10293339744 10733395968 1350206064 14.149 98 32 64 0 1368107056 10918096 6982896
21:35:36 33528 8192000000 10294796000 10749988864 1380226192 14.419 100 21 42 1 1397485904 12394544 4865168
21:37:14 51589 8192000000 10282778896 10726645760 1327597648 13.945 96 23 46 2 1349230512 16437648 5195216
21:39:06 103851 8192000000 10253926992 10713423872 1327722288 13.947 97 25 50 0 1345105184 11070144 6312752
21:41:52 52111 8192000000 10267009952 10721001472 1350446272 14.151 99 40 80 0 1373582800 14367488 8769040
21:43:48 62425 8192000000 10253966960 10710228992 1346051936 14.112 98 14 28 1 1360662992 11474464 3136592
21:46:00 55843 8192000000 10279210320 10738380800 1371935744 14.344 100 14 28 1 1391097584 15475776 3686064
21:47:57 65074 8192000000 10273665920 10725924864 1315974640 13.840 95 21 42 1 1333833840 12687920 5171280
21:50:02 65981 8192000000 10270276112 10716307456 1302125872 13.715 95 17 34 0 1321872176 15750864 3995440
21:52:19 30413 8192000000 10274807680 10715414528 1327208752 13.942 97 24 48 0 1341581856 8897776 5475328
21:53:52 78277 8192000000 10284631104 10741460992 1324949360 13.921 97 10 20 0 1356521696 29228336 2344000
21:56:11 45690 8192000000 10297586784 10734706688 1357548224 14.215 99 41 83 0 1379388784 12165232 9675328
21:57:59 18380 8192000000 10303207376 10738487296 1364726960 14.280 99 37 72 1 1389654592 16318128 8609504
21:59:21 97967 8192000000 10285772880 10738737152 1369724176 14.325 100 35 70 1 1395339184 17690720 7924288
22:02:02 57958 8192000000 10286422896 10752548864 1381719056 14.432 100 16 32 1 1404644080 18930896 3994128
22:05:24 49332 8192000000 10277302288 10738479104 1372616096 14.350 99 18 36 0 1389484592 12491088 4377408
22:07:16 57546 8192000000 10279083344 10732613632 1362972688 14.264 99 10 21 1 1371346528 6571312 1802528
22:09:14 78210 8192000000 10268093648 10723508224 1294648992 13.647 94 13 26 0 1311492112 14203344 2639776
22:11:37 75506 8192000000 10289011792 10736967680 1365468864 14.286 99 23 47 0 1386168384 15210576 5488944
22:13:58 39105 8192000000 10286435680 10728189952 1322093696 13.896 96 28 54 1 1342475728 14439776 5942256
22:15:38 25425 8192000000 10294282240 10741256192 1381526304 14.430 100 28 56 1 1400031648 11893472 6611872
22:17:09 70074 8192000000 10301865984 10770092032 1376651280 14.387 99 17 34 0 1403087536 22395360 4040896
22:19:24 78231 8192000000 10263191536 10708160512 1309202816 13.779 95 10 20 0 1325402640 14224560 1975264
22:21:46 134988 8192000000 10282491152 10724327424 1338249424 14.042 98 21 42 0 1351481056 8999040 4232592
22:25:05 13337 8192000000 10274107984 10718785536 1342784592 14.083 98 33 66 1 1364379376 14658464 6936320
22:26:19 58996 8192000000 10258685504 10719010816 1360592720 14.243 100 8 16 0 1372120160 9788256 1739184
22:28:19 83703 8192000000 10270195440 10728255488 1365672336 14.288 100 12 24 0 1380981072 12465616 2843120
22:30:44 63598 8192000000 10273188992 10735792128 1365120880 14.283 100 14 29 0 1385617824 17073088 3423856
22:32:57 96841 8192000000 10264387520 10718887936 1309281280 13.780 96 20 40 1 1326403232 12796832 4325120
22:35:34 20605 8192000000 10255212624 10694205440 1331579712 13.981 98 31 63 0 1346488080 8154304 6754064
22:36:57 37349 8192000000 10268978496 10716450816 1347861936 14.128 99 29 58 0 1366892752 12220752 6810064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-06-2022 22:39:34 to 15-06-2022 22:39:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-06-2022 22:39:50 to 15-06-2022 22:39:52.

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          245898
MON$OLDEST_ACTIVE               246839
MON$OLDEST_SNAPSHOT             246839
MON$NEXT_TRANSACTION            246839
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                       2019136
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 ---
:676: 
:677: mscs-test-161.budzdorov.ru    Wed Jun 15 22:38:29 2022
:678:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:679: 
:680: 
:681: mscs-test-161.budzdorov.ru    Wed Jun 15 22:38:29 2022
:682:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:683: 
:684: 
:685: mscs-test-161.budzdorov.ru    Wed Jun 15 22:38:30 2022
:686:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:687: 
:688: 
:689: mscs-test-161.budzdorov.ru    Wed Jun 15 22:38:35 2022
:690:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:691: 
:692: 
:693: mscs-test-161.budzdorov.ru    Wed Jun 15 22:38:35 2022
:694:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:695: 
--- end of diff output ---

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

15.06.22 22:39:53. 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-15 22:39:55 No severe PSQL-related problems occured
15.06.22 22:39:56. End of report.