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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16247064 kB
MemAvailable 31102204 kB
Buffers 0 kB
Cached 15456624 kB
SwapCached 2516 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-07-12 19:38:29
DTS_END                         2022-07-12 19:38:29.4200
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55390
TRN_ID                          242392
ATT_ID                          686
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: 9256.09 21443 1110731
customer order: creation 173.99 378 20879
customer order: refuse 15.57 369 1868
order to supplier: creation 46.51 1253 5581
order to supplier: removal 3.28 100646 393
invoice (draft): creation 46.27 3092 5552
invoice (draft): removal 51.68 732 6202
invoice accept: apply 42.93 16676 5151
invoice accept: cancel 46.43 678 5572
customer reserve: creation 3958.65 166 475038
customer reserve: removal 4568.27 57 548192
realization accept: apply 85.67 165 10280
realization accept: cancel 84.13 103 10096
payment from customer: creation 61.19 109 7343
payment from customer: removal 5.53 56 664
payment to supplier: creation 50.34 118 6041
payment to supplier: removal 3.64 66 437
service: total inventory turnovers 4.68 1075 561
service: total monetary turnovers 6.39 22 767
service: aggregate perf. data 0.92 20858 110
service: refresh index statistics 0.03 282246 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-07-2022 19:39:33 to 12-07-2022 19:39:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3543
TEST_TIME 2 4241
TEST_TIME 3 4020
TEST_TIME 4 3824
TEST_TIME 5 3864
TEST_TIME 6 4440
TEST_TIME 7 4896
TEST_TIME 8 4939
TEST_TIME 9 5633
TEST_TIME 10 5781
TEST_TIME 11 5848
TEST_TIME 12 6407
TEST_TIME 13 6721
TEST_TIME 14 6880
TEST_TIME 15 6939
TEST_TIME 16 7210
TEST_TIME 17 7130
TEST_TIME 18 7433
TEST_TIME 19 8001
TEST_TIME 20 7930
TEST_TIME 21 8240
TEST_TIME 22 8350
TEST_TIME 23 8564
TEST_TIME 24 9059
TEST_TIME 25 9048
TEST_TIME 26 9325
TEST_TIME 27 9714
TEST_TIME 28 10043
TEST_TIME 29 10767
TEST_TIME 30 10691
TEST_TIME 31 10575
TEST_TIME 32 10347
TEST_TIME 33 10128
TEST_TIME 34 9992
TEST_TIME 35 9811
TEST_TIME 36 9809
TEST_TIME 37 9871
TEST_TIME 38 9776
TEST_TIME 39 9735
TEST_TIME 40 9619
TEST_TIME 41 9680
TEST_TIME 42 9580
TEST_TIME 43 9465
TEST_TIME 44 9399
TEST_TIME 45 9212
TEST_TIME 46 9460
TEST_TIME 47 9379
TEST_TIME 48 9353
TEST_TIME 49 9271
TEST_TIME 50 9381
TEST_TIME 51 9383
TEST_TIME 52 9318
TEST_TIME 53 9405
TEST_TIME 54 9490
TEST_TIME 55 9449
TEST_TIME 56 9540
TEST_TIME 57 9625
TEST_TIME 58 9640
TEST_TIME 59 9610
TEST_TIME 60 9647
TEST_TIME 61 9611
TEST_TIME 62 9516
TEST_TIME 63 9517
TEST_TIME 64 9486
TEST_TIME 65 9507
TEST_TIME 66 9424
TEST_TIME 67 9381
TEST_TIME 68 9300
TEST_TIME 69 9285
TEST_TIME 70 9282
TEST_TIME 71 9237
TEST_TIME 72 9315
TEST_TIME 73 9243
TEST_TIME 74 9270
TEST_TIME 75 9320
TEST_TIME 76 9277
TEST_TIME 77 9430
TEST_TIME 78 9418
TEST_TIME 79 9440
TEST_TIME 80 9504
TEST_TIME 81 9513
TEST_TIME 82 9579
TEST_TIME 83 9558
TEST_TIME 84 9466
TEST_TIME 85 9437
TEST_TIME 86 9358
TEST_TIME 87 9372
TEST_TIME 88 9335
TEST_TIME 89 9322
TEST_TIME 90 9296
TEST_TIME 91 9253
TEST_TIME 92 9274
TEST_TIME 93 9265
TEST_TIME 94 9248
TEST_TIME 95 9271
TEST_TIME 96 9306
TEST_TIME 97 9309
TEST_TIME 98 9308
TEST_TIME 99 9381
TEST_TIME 100 9384
TEST_TIME 101 9366
TEST_TIME 102 9367
TEST_TIME 103 9382
TEST_TIME 104 9441
TEST_TIME 105 9419
TEST_TIME 106 9483
TEST_TIME 107 9403
TEST_TIME 108 9338
TEST_TIME 109 9323
TEST_TIME 110 9282
TEST_TIME 111 9316
TEST_TIME 112 9289
TEST_TIME 113 9265
TEST_TIME 114 9256
TEST_TIME 115 9305
TEST_TIME 116 9289
TEST_TIME 117 9279
TEST_TIME 118 9286
TEST_TIME 119 9272
TEST_TIME 120 9260

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-07-2022 19:39:37 to 12-07-2022 19:39:40.

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 1550260 1550260 0 0.00 0 20880 6 0 0
doc_list_biud 1550256 1550256 0 0.00 0 59599 26 0 0
sp_add_invoice_to_stock 5252 5151 101 1.92 23 166724 16676 0 101
sp_cancel_adding_invoice 5572 5572 0 0.00 4 54554 678 0 0
sp_cancel_client_order 1868 1868 0 0.00 3 17726 369 0 0
sp_cancel_customer_reserve 548192 548192 0 0.00 0 59600 57 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 2 1385 57 0 0
sp_cancel_pay_to_supplier 437 437 0 0.00 2 3384 66 0 0
sp_cancel_supplier_invoice 6208 6202 6 0.09 2 31073 733 0 6
sp_cancel_supplier_order 393 393 0 0.00 11 979936 100646 0 0
sp_cancel_write_off 10096 10096 0 0.00 1 8710 104 0 0
sp_client_order 20879 20879 0 0.00 3 27939 379 0 0
sp_customer_reserve 475038 475038 0 0.00 0 70344 166 0 0
sp_fill_shopping_cart 32009 32009 0 0.00 1 21172 40 0 0
sp_fill_shopping_cart_clo_res 475035 475035 0 0.00 0 21216 14 0 0
sp_get_clo_for_invoice 5146 5146 0 0.00 0 21067 306 0 0
sp_kill_cost_storno 16769 16769 0 0.00 0 20876 50 0 0
sp_kill_qstorno_ret_qs2qd 1113075 1113075 0 0.00 0 59595 31 0 0
sp_kill_qty_storno 587745 587745 0 0.00 0 59598 67 0 0
sp_lock_selected_doc 21350 21350 0 0.00 0 8993 6 0 0
sp_make_cost_storno 28810 28810 0 0.00 0 11681 51 0 0
sp_multiply_rows_for_pdistr 28808 28808 0 0.00 0 18480 29 0 0
sp_multiply_rows_for_qdistr 20879 20879 0 0.00 0 25524 173 0 0
sp_pay_from_customer 7343 7343 0 0.00 4 11947 110 0 0
sp_pay_to_supplier 6041 6041 0 0.00 4 12595 119 0 0
sp_payment_common 13384 13384 0 0.00 1 9511 25 0 0
sp_qd_handle_on_cancel_clo 1868 1868 0 0.00 0 17718 336 0 0
sp_qd_handle_on_invoice_upd_sts 10718 10718 0 0.00 0 23704 296 0 0
sp_qd_handle_on_reserve_upd_sts 10276 10276 0 0.00 0 13415 55 0 0
sp_reserve_write_off 10280 10280 0 0.00 6 20290 166 0 0
sp_supplier_invoice 5552 5552 0 0.00 5 76791 3093 0 0
sp_supplier_order 5581 5581 0 0.00 5 60691 1253 0 0
srv_aggregate_perf_data 110 110 0 0.00 2594 451828 20859 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2635 121253 44323 0 0
srv_find_qd_qs_mism 1061761 1061761 0 0.00 0 14279 6 0 0
srv_make_invnt_saldo 561 561 0 0.00 32 123479 1075 0 0
srv_make_money_saldo 767 767 0 0.00 1 1799 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 183409 336999 282247 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 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5151 5151 0 0.00 0 21953 148 0 0
t$perf-norm:sp_cancel_adding_invoice 413 413 0 0.00 0 7395 96 0 0
t$perf-norm:sp_cancel_client_order 1868 1868 0 0.00 0 2198 11 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 6712 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 3485 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 437 437 0 0.00 0 77 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 427 427 0 0.00 0 1127 14 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 27571 1383 0 0
t$perf-norm:sp_cancel_write_off 1026 1026 0 0.00 0 5313 12 0 0
t$perf-norm:sp_client_order 20879 20879 0 0.00 0 25617 14 0 0
t$perf-norm:sp_customer_reserve 1086 1086 0 0.00 0 2248 10 0 0
t$perf-norm:sp_pay_from_customer 7344 7344 0 0.00 0 17785 12 0 0
t$perf-norm:sp_pay_to_supplier 6041 6041 0 0.00 0 6118 10 0 0
t$perf-norm:sp_reserve_write_off 10280 10280 0 0.00 0 19000 18 0 0
t$perf-norm:sp_supplier_invoice 5552 5552 0 0.00 0 4762 13 0 0
t$perf-norm:sp_supplier_order 5581 5581 0 0.00 0 2589 12 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 325 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 153 4 0 0
t$perf-norm:srv_make_invnt_saldo 561 561 0 0.00 0 884 21 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 5908 29 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 34 10 0 0
v_add_invoice_to_stock 10492 10290 202 1.92 0 8089 16 0 202
v_all_customers 38114 38114 0 0.00 0 23 0 0 0
v_all_suppliers 31060 31060 0 0.00 0 93 1 0 0
v_all_wares 41751 41751 0 0.00 0 36 1 0 0
v_cancel_adding_invoice 826 826 0 0.00 0 2968 10 0 0
v_cancel_client_order 3736 3736 0 0.00 0 2367 4 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 890 4 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 1517 6 0 0
v_cancel_payment_to_supplier 874 874 0 0.00 0 342 2 0 0
v_cancel_supplier_invoice 866 854 12 1.38 0 767 13 0 12
v_cancel_supplier_order 786 786 0 0.00 0 2363 7 0 0
v_cancel_write_off 2052 2052 0 0.00 0 8578 16 0 0
v_max_id_clo_ord 5581 5581 0 0.00 0 1755 1 0 0
v_max_id_clo_res 1086 1086 0 0.00 0 2107 9 0 0
v_max_id_ord_sup 5552 5552 0 0.00 0 5273 4 0 0
v_max_non_paid_invoice 1644 1644 0 0.00 0 2337 4 0 0
v_max_non_paid_realizn 4010 4010 0 0.00 0 1840 3 0 0
v_min_id_clo_ord 5581 5581 0 0.00 0 21149 14 0 0
v_min_id_clo_res 1086 1086 0 0.00 0 137 1 0 0
v_min_id_ord_sup 5552 5552 0 0.00 0 2536 4 0 0
v_min_non_paid_invoice 6040 6040 0 0.00 0 6747 15 0 0
v_min_non_paid_realizn 7343 7343 0 0.00 0 9506 20 0 0
v_our_firm 10302 10302 0 0.00 0 19 1 0 0
v_reserve_write_off 20552 20552 0 0.00 0 9703 7 0 0
x_lock_depdocs_on_canc_invoice 11347 11347 0 0.00 0 15688 105 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 12457 325 0 0
x_make_qstorno_1000_1200 5581 5581 0 0.00 2 60684 1180 0 0
x_make_qstorno_1200_2000 5552 5552 0 0.00 2 76769 2940 0 0
x_make_qstorno_2100_3300 475038 475038 0 0.00 0 70343 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-07-2022 19:39:41 to 12-07-2022 19:39:45.

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 18 8192000000 8823420624 8843407360 22706112 0.276 9 4 5 0 22940960 95568 139280
17:09:34 37706 8192000000 9926154256 10241679360 1006083200 10.937 100 95 190 0 1037155824 6300960 24771664
17:11:20 11808 8192000000 9972466592 10302717952 1057106032 11.429 100 87 174 0 1088033088 9092912 21834144
17:12:42 7812 8192000000 10006470752 10346029056 1092888736 11.770 100 84 167 0 1123541840 10045920 20607184
17:13:56 9951 8192000000 10045686208 10394177536 1129543856 12.117 100 85 170 0 1161904448 12220544 20140048
17:15:10 10600 8192000000 10079078960 10438909952 1160694784 12.410 100 74 146 1 1189279984 11513408 17071792
17:16:24 3010 8192000000 10118348304 10486747136 1208572304 12.856 100 78 155 0 1238364240 12170544 17621392
17:17:28 1980 8192000000 10155971088 10531360768 1245099376 13.193 100 88 171 1 1277954944 13980384 18875184
17:18:32 2232 8192000000 10185247792 10569601024 1272324832 13.443 100 83 164 2 1302421200 11957248 18139120
17:19:36 1491 8192000000 10206373952 10598416384 1297935440 13.676 100 74 148 0 1326672032 12341776 16394816
17:20:39 37998 8192000000 10201176464 10613104640 1308391344 13.771 100 33 66 1 1327502944 11769856 7341744
17:22:18 7397 8192000000 10206627440 10620219392 1316960560 13.849 100 32 65 0 1331576224 7205200 7410464
17:23:27 7825 8192000000 10205918336 10624634880 1320977040 13.886 100 22 43 0 1332352800 6800080 4575680
17:24:36 8759 8192000000 10215454368 10637230080 1328386352 13.953 100 22 45 0 1339536080 6289168 4860560
17:25:46 53189 8192000000 10221011504 10647838720 1334371440 14.007 100 31 58 1 1346608640 5472416 6764784
17:27:42 4335 8192000000 10231916336 10652971008 1336435632 14.025 100 29 58 0 1351124256 6782576 7906048
17:28:49 9967 8192000000 10219437040 10635186176 1306446560 13.754 98 18 36 0 1315477888 4887200 4144128
17:29:59 3500 8192000000 10182185856 10591952896 1288483552 13.590 99 28 56 1 1300691936 5937952 6270432
17:31:03 27873 8192000000 10137230544 10540716032 1158192192 12.386 90 37 74 1 1171836496 5390544 8253760
17:32:32 7825 8192000000 10129371216 10529124352 1224659264 13.005 98 16 32 0 1233372368 4873280 3839824
17:33:45 8520 8192000000 10144365648 10539184128 1245895440 13.200 99 40 79 0 1260499120 4921936 9681744
17:34:57 5074 8192000000 10179081728 10590851072 1288694032 13.592 100 29 55 3 1301560112 5874000 6992080
17:36:03 19891 8192000000 10194194832 10600751104 1293105408 13.633 100 53 107 0 1312000880 6500160 12395312
17:37:23 6534 8192000000 10193422800 10612088832 1305234304 13.743 100 21 43 1 1315290544 5617232 4439008
17:38:29 9531 8192000000 10235701840 10645843968 1332180176 13.987 100 45 92 0 1348997744 5003424 11814144
17:39:46 29631 8192000000 10247851680 10666070016 1345109152 14.103 100 33 67 0 1360365488 7985648 7270688
17:41:27 83495 8192000000 10284591520 10709856256 1365681456 14.288 100 36 70 1 1393097104 18782272 8633376
17:43:52 15643 8192000000 10283722912 10710249472 1366374208 14.295 99 37 73 0 1392087104 17322032 8390864
17:45:18 2803 8192000000 10311471904 10734219264 1392977792 14.532 100 50 100 0 1423330704 18595712 11757200
17:46:24 12703 8192000000 10326354304 10749571072 1404570688 14.636 100 65 126 0 1444742736 25436112 14735936
17:47:50 7143 8192000000 10330246880 10757402624 1401985408 14.613 100 63 124 0 1441931520 25250176 14695936
17:49:06 13281 8192000000 10326511200 10751004672 1390727168 14.512 99 51 102 0 1431177360 28311392 12138800
17:50:24 2634 8192000000 10353993696 10768236544 1405201152 14.641 100 77 153 0 1454419584 30776096 18442336
17:51:32 3523 8192000000 10339845568 10762821632 1404576368 14.636 100 60 117 2 1451150032 31906608 14667056
17:52:47 3106 8192000000 10361098032 10783698944 1406000784 14.648 99 70 140 2 1460728016 37437824 17289408
17:53:58 5132 8192000000 10381886576 10803699712 1426741264 14.832 100 66 130 1 1485123632 42492848 15889520
17:55:07 9065 8192000000 10372218656 10798166016 1428563776 14.849 100 48 96 0 1483207120 41953040 12690304
17:56:30 3718 8192000000 10373082240 10795700224 1413981152 14.719 100 59 115 0 1478177376 49487520 14708704
17:57:37 6780 8192000000 10340319264 10772025344 1388871968 14.496 99 38 70 2 1434885552 36194528 9819056
17:58:49 9095 8192000000 10340100352 10765623296 1392012064 14.524 100 43 84 0 1448342928 44606336 11724528
18:00:06 7839 8192000000 10341356864 10763866112 1384679856 14.458 100 49 98 0 1446065008 48532656 12852496
18:01:26 2828 8192000000 10332520592 10752540672 1367907648 14.308 99 53 106 1 1429093136 46876032 14309456
18:02:31 15192 8192000000 10312292784 10747817984 1358979728 14.228 98 29 58 0 1403914400 36495184 8439488
18:03:52 10311 8192000000 10315932352 10744090624 1369702448 14.324 100 31 62 0 1419146640 40381744 9062448
18:05:18 7935 8192000000 10325304256 10748338176 1374947152 14.371 100 48 93 0 1429933712 42818720 12167840
18:06:32 27660 8192000000 10291073680 10742108160 1348864752 14.137 100 15 30 0 1398547104 45159904 4522448
18:08:02 35437 8192000000 10264803072 10706350080 1337974624 14.039 98 12 22 0 1349367936 9137504 2255808
18:09:38 94642 8192000000 10280438512 10720505856 1362317504 14.258 99 30 59 0 1376739504 7990144 6431856
18:12:46 96380 8192000000 10284166288 10729291776 1356535856 14.206 98 37 72 0 1376434528 11610928 8287744
18:15:38 39043 8192000000 10292749152 10745724928 1384913280 14.460 100 30 60 2 1406045232 14263664 6868288
18:17:20 46535 8192000000 10276312528 10733236224 1377404288 14.393 100 27 56 1 1396590816 12599872 6586656
18:19:22 58012 8192000000 10259807520 10698481664 1342208224 14.077 97 19 35 0 1363548752 17364048 3976480
18:21:20 64419 8192000000 10275116512 10730131456 1368061488 14.310 99 10 20 0 1384109920 13676368 2372064
18:23:42 30760 8192000000 10288559984 10752372736 1379433584 14.411 100 14 28 0 1406930224 24016944 3479696
18:25:16 51789 8192000000 10286588560 10745810944 1388223296 14.490 100 28 57 0 1403553712 8368640 6961776
18:27:41 53094 8192000000 10300587360 10770374656 1394828512 14.549 100 7 14 0 1411726496 15172944 1725040
18:29:36 29908 8192000000 10317892048 10771218432 1392527024 14.528 99 49 98 0 1414697232 10352672 11817536
18:31:08 55484 8192000000 10303034448 10766790656 1402978224 14.622 100 12 24 1 1419315824 13583136 2754464
18:33:18 66269 8192000000 10297428064 10761490432 1385160432 14.463 99 19 36 0 1402080256 12547760 4372064
18:35:27 80721 8192000000 10294025472 10755059712 1366851264 14.299 98 14 28 0 1382987984 13267968 2868752
18:37:49 119215 8192000000 10304160144 10760310784 1368855008 14.317 98 24 45 1 1383338560 9555360 4928192
18:40:56 57040 8192000000 10298648944 10750107648 1368655360 14.315 98 26 52 0 1387337248 13162768 5519120
18:43:15 43470 8192000000 10288495792 10758955008 1399867248 14.594 100 9 18 0 1412143376 9447904 2828224
18:45:01 59675 8192000000 10285833744 10745597952 1376709392 14.387 99 13 26 0 1386747760 7023984 3014384
18:47:02 52264 8192000000 10279971680 10747527168 1374986144 14.372 99 15 30 0 1389347152 10329968 4031040
18:49:01 73602 8192000000 10280973376 10730475520 1305160368 13.742 94 14 28 1 1327821696 19388512 3272816
18:51:16 37597 8192000000 10245779584 10685526016 1286199744 13.570 94 24 48 0 1300903712 9253376 5450592
18:52:58 32811 8192000000 10237865168 10678484992 1293182192 13.633 97 43 86 2 1319507568 16303600 10021776
18:54:44 85528 8192000000 10255410384 10691989504 1281239520 13.524 96 11 22 0 1304923568 21440976 2243072
18:57:09 58339 8192000000 10227842464 10657734656 1243453680 13.178 93 26 52 0 1264356608 15086064 5816864
18:59:11 111811 8192000000 10262494912 10708459520 1337460016 14.035 99 17 34 0 1363460128 22641152 3358960
19:02:04 121253 8192000000 10250142672 10704183296 1355544224 14.197 100 23 46 0 1370047440 9382512 5120704
19:05:05 31714 8192000000 10261300960 10703478784 1348159136 14.131 99 34 68 0 1365938912 10219904 7559872
19:06:43 46473 8192000000 10258619792 10713767936 1354567008 14.189 99 12 24 0 1371253024 13873840 2812176
19:08:45 59216 8192000000 10266693968 10723086336 1362632320 14.261 99 18 36 0 1373526912 7018320 3876272
19:10:44 56259 8192000000 10277447488 10731044864 1336132416 14.023 97 26 51 0 1348418944 7022192 5264336
19:12:41 71995 8192000000 10280675936 10733953024 1373407040 14.358 99 11 22 0 1392449776 16607072 2435664
19:14:54 20042 8192000000 10303442160 10744283136 1387820656 14.486 100 42 83 0 1404552352 7682240 9049456
19:16:16 87331 8192000000 10305129488 10774085632 1387371472 14.482 99 24 48 0 1408740416 16031280 5337664
19:18:45 51883 8192000000 10304869264 10755956736 1384063552 14.453 99 18 36 0 1401815824 13770192 3982080
19:20:46 20430 8192000000 10312011680 10759958528 1395464256 14.555 100 30 60 1 1411533040 9286416 6782368
19:22:11 75978 8192000000 10291036256 10736484352 1354801504 14.191 97 17 34 1 1371560320 12717504 4041312
19:24:36 78168 8192000000 10295253328 10758299648 1382296848 14.437 99 16 32 0 1400218096 13976112 3945136
19:27:19 39972 8192000000 10282123984 10744823808 1375064336 14.372 99 16 32 0 1387698448 8814144 3819968
19:29:02 84555 8192000000 10275698464 10749054976 1360656672 14.243 98 11 22 0 1375802112 12483440 2662000
19:31:38 33261 8192000000 10258624880 10710192128 1314259776 13.825 96 27 54 0 1330440672 10148656 6032240
19:33:12 55160 8192000000 10277313792 10744307712 1364676368 14.279 99 18 36 1 1382594640 13878832 4039440
19:35:15 60379 8192000000 10255525232 10699304960 1258765328 13.319 91 21 40 0 1276745104 13332064 4647712
19:37:30 63921 8192000000 9408062736 9711915008 552902080 6.322 40 8 16 0 562445920 7759424 1784416

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-07-2022 19:39:47 to 12-07-2022 19:40:02.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-07-2022 19:40:03 to 12-07-2022 19:40:05.

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          239124
MON$OLDEST_ACTIVE               242629
MON$OLDEST_SNAPSHOT             242629
MON$NEXT_TRANSACTION            242629
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                       2011648
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:955: 
:956: mscs-test-161.budzdorov.ru    Tue Jul 12 19:38:37 2022
:957:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:958: 
:959: 
:960: mscs-test-161.budzdorov.ru    Tue Jul 12 19:38:42 2022
:961:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:962: 
:963: 
:964: mscs-test-161.budzdorov.ru    Tue Jul 12 19:38:48 2022
:965:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:966: 
--- end of diff output ---

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

12.07.22 19:40:05. 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-07-12 19:40:08 No severe PSQL-related problems occured
12.07.22 19:40:09. End of report.