Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.07.2022 07: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 15618224 kB
MemAvailable 31099032 kB
Buffers 0 kB
Cached 16038608 kB
SwapCached 2552 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 4.0.2
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-06 07:37:14
DTS_END                         2022-07-06 07:37:14.2730
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(68:17)
IP                              ::1/57954
TRN_ID                          216268
ATT_ID                          609
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: 9106.97 29111 1092836
customer order: creation 162.55 457 19506
customer order: refuse 14.50 455 1740
order to supplier: creation 43.48 1368 5217
order to supplier: removal 3.38 184476 406
invoice (draft): creation 43.45 3158 5214
invoice (draft): removal 52.39 1010 6287
invoice accept: apply 40.10 26393 4812
invoice accept: cancel 47.48 1283 5697
customer reserve: creation 3819.56 243 458347
customer reserve: removal 4581.63 106 549796
realization accept: apply 78.83 290 9460
realization accept: cancel 93.42 176 11210
payment from customer: creation 57.29 186 6875
payment from customer: removal 5.78 150 693
payment to supplier: creation 47.29 199 5675
payment to supplier: removal 3.33 122 400
service: total inventory turnovers 3.97 4458 476
service: total monetary turnovers 7.63 415 915
service: aggregate perf. data 0.88 16167 106
service: refresh index statistics 0.03 341110 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 06-07-2022 07:38:28 to 06-07-2022 07:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8886
TEST_TIME 2 6012
TEST_TIME 3 5653
TEST_TIME 4 5566
TEST_TIME 5 5857
TEST_TIME 6 5720
TEST_TIME 7 5794
TEST_TIME 8 5925
TEST_TIME 9 6004
TEST_TIME 10 6388
TEST_TIME 11 6407
TEST_TIME 12 6814
TEST_TIME 13 6646
TEST_TIME 14 6849
TEST_TIME 15 6839
TEST_TIME 16 6993
TEST_TIME 17 6959
TEST_TIME 18 6970
TEST_TIME 19 7015
TEST_TIME 20 7068
TEST_TIME 21 7135
TEST_TIME 22 7049
TEST_TIME 23 7057
TEST_TIME 24 7163
TEST_TIME 25 7466
TEST_TIME 26 7605
TEST_TIME 27 8102
TEST_TIME 28 8299
TEST_TIME 29 8417
TEST_TIME 30 8734
TEST_TIME 31 8724
TEST_TIME 32 8958
TEST_TIME 33 9317
TEST_TIME 34 10137
TEST_TIME 35 10259
TEST_TIME 36 10473
TEST_TIME 37 10258
TEST_TIME 38 10376
TEST_TIME 39 10753
TEST_TIME 40 10675
TEST_TIME 41 10594
TEST_TIME 42 10485
TEST_TIME 43 10486
TEST_TIME 44 10412
TEST_TIME 45 10345
TEST_TIME 46 10288
TEST_TIME 47 10381
TEST_TIME 48 10320
TEST_TIME 49 10265
TEST_TIME 50 10202
TEST_TIME 51 10273
TEST_TIME 52 10299
TEST_TIME 53 10228
TEST_TIME 54 10107
TEST_TIME 55 10014
TEST_TIME 56 9918
TEST_TIME 57 9881
TEST_TIME 58 9806
TEST_TIME 59 9720
TEST_TIME 60 9689
TEST_TIME 61 9622
TEST_TIME 62 9636
TEST_TIME 63 9688
TEST_TIME 64 9656
TEST_TIME 65 9694
TEST_TIME 66 9649
TEST_TIME 67 9658
TEST_TIME 68 9624
TEST_TIME 69 9620
TEST_TIME 70 9611
TEST_TIME 71 9577
TEST_TIME 72 9531
TEST_TIME 73 9602
TEST_TIME 74 9681
TEST_TIME 75 9667
TEST_TIME 76 9719
TEST_TIME 77 9694
TEST_TIME 78 9679
TEST_TIME 79 9692
TEST_TIME 80 9746
TEST_TIME 81 9688
TEST_TIME 82 9612
TEST_TIME 83 9607
TEST_TIME 84 9568
TEST_TIME 85 9500
TEST_TIME 86 9497
TEST_TIME 87 9518
TEST_TIME 88 9487
TEST_TIME 89 9494
TEST_TIME 90 9443
TEST_TIME 91 9385
TEST_TIME 92 9343
TEST_TIME 93 9368
TEST_TIME 94 9330
TEST_TIME 95 9357
TEST_TIME 96 9349
TEST_TIME 97 9362
TEST_TIME 98 9371
TEST_TIME 99 9364
TEST_TIME 100 9353
TEST_TIME 101 9412
TEST_TIME 102 9423
TEST_TIME 103 9422
TEST_TIME 104 9402
TEST_TIME 105 9445
TEST_TIME 106 9536
TEST_TIME 107 9465
TEST_TIME 108 9400
TEST_TIME 109 9366
TEST_TIME 110 9372
TEST_TIME 111 9340
TEST_TIME 112 9279
TEST_TIME 113 9264
TEST_TIME 114 9222
TEST_TIME 115 9181
TEST_TIME 116 9172
TEST_TIME 117 9239
TEST_TIME 118 9210
TEST_TIME 119 9190
TEST_TIME 120 9132

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-07-2022 07:38:32 to 06-07-2022 07:38:35.

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 1513547 1513547 0 0.00 0 24618 14 0 0
doc_list_biud 1513534 1513534 0 0.00 0 30548 44 0 0
sp_add_invoice_to_stock 4947 4812 135 2.72 60 184047 26393 0 135
sp_cancel_adding_invoice 5697 5697 0 0.00 5 91679 1283 0 0
sp_cancel_client_order 1740 1740 0 0.00 5 14270 456 0 0
sp_cancel_customer_reserve 549796 549796 0 0.00 1 30563 106 0 0
sp_cancel_pay_from_customer 693 693 0 0.00 3 5162 151 0 0
sp_cancel_pay_to_supplier 400 400 0 0.00 4 2016 123 0 0
sp_cancel_supplier_invoice 6299 6287 12 0.19 2 14409 1011 0 12
sp_cancel_supplier_order 406 406 0 0.00 21 1753398 184476 0 0
sp_cancel_write_off 11210 11210 0 0.00 2 9959 177 0 0
sp_client_order 19506 19506 0 0.00 6 30267 457 0 0
sp_customer_reserve 458347 458347 0 0.00 0 106321 243 0 0
sp_fill_shopping_cart 29923 29923 0 0.00 1 12548 80 0 0
sp_fill_shopping_cart_clo_res 458317 458317 0 0.00 0 14139 17 0 0
sp_get_clo_for_invoice 4765 4765 0 0.00 0 36437 300 0 0
sp_kill_cost_storno 18000 18000 0 0.00 0 24611 86 0 0
sp_kill_qstorno_ret_qs2qd 1117495 1117495 0 0.00 0 30542 46 0 0
sp_kill_qty_storno 589359 589359 0 0.00 0 30548 105 0 0
sp_lock_selected_doc 19847 19847 0 0.00 0 13765 8 0 0
sp_make_cost_storno 26775 26775 0 0.00 0 11181 80 0 0
sp_multiply_rows_for_pdistr 26773 26773 0 0.00 0 9290 41 0 0
sp_multiply_rows_for_qdistr 19505 19505 0 0.00 0 14729 165 0 0
sp_pay_from_customer 6875 6875 0 0.00 5 15703 186 0 0
sp_pay_to_supplier 5675 5675 0 0.00 6 7877 199 0 0
sp_payment_common 12549 12549 0 0.00 2 10319 49 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 1 12963 374 0 0
sp_qd_handle_on_invoice_upd_sts 10462 10462 0 0.00 0 12690 533 0 0
sp_qd_handle_on_reserve_upd_sts 9458 9458 0 0.00 0 18196 72 0 0
sp_reserve_write_off 9460 9460 0 0.00 7 20756 291 0 0
sp_supplier_invoice 5214 5214 0 0.00 11 97807 3159 0 0
sp_supplier_order 5217 5217 0 0.00 7 16919 1368 0 0
srv_aggregate_perf_data 106 106 0 0.00 2334 258059 16168 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 789 145656 36211 0 0
srv_find_qd_qs_mism 1044647 1044647 0 0.00 0 14704 10 0 0
srv_make_invnt_saldo 476 476 0 0.00 51 142729 4458 0 0
srv_make_money_saldo 915 915 0 0.00 1 21491 415 0 0
srv_recalc_idx_stat 4 4 0 0.00 221594 468396 341111 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4817 4817 0 0.00 1 19641 989 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 5933 517 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 3550 37 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 608 21 0 0
t$perf-norm:sp_cancel_pay_from_customer 693 693 0 0.00 0 7280 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 400 400 0 0.00 0 3530 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 360 360 0 0.00 0 1087 42 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 118259 7597 0 0
t$perf-norm:sp_cancel_write_off 936 936 0 0.00 0 22820 52 0 0
t$perf-norm:sp_client_order 19507 19507 0 0.00 0 14086 39 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 1723 19 0 0
t$perf-norm:sp_pay_from_customer 6875 6875 0 0.00 0 6822 20 0 0
t$perf-norm:sp_pay_to_supplier 5675 5675 0 0.00 0 9377 21 0 0
t$perf-norm:sp_reserve_write_off 9460 9460 0 0.00 0 9973 23 0 0
t$perf-norm:sp_supplier_invoice 5214 5214 0 0.00 0 4385 34 0 0
t$perf-norm:sp_supplier_order 5217 5217 0 0.00 0 3770 31 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 602 34 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 848 15 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 558 14 0 0
t$perf-norm:srv_make_money_saldo 915 915 0 0.00 0 4628 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 150 57 0 0
v_add_invoice_to_stock 9798 9528 270 2.75 0 6715 27 0 270
v_all_customers 35858 35858 0 0.00 0 73 4 0 0
v_all_suppliers 29627 29627 0 0.00 0 70 4 0 0
v_all_wares 38997 38997 0 0.00 0 69 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 1189 18 0 0
v_cancel_client_order 3480 3480 0 0.00 0 1847 11 0 0
v_cancel_customer_prepayment 1386 1386 0 0.00 0 1883 16 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 2812 19 0 0
v_cancel_payment_to_supplier 800 800 0 0.00 0 1920 16 0 0
v_cancel_supplier_invoice 744 720 24 3.22 0 1077 26 0 24
v_cancel_supplier_order 812 812 0 0.00 0 2265 17 0 0
v_cancel_write_off 1872 1872 0 0.00 0 3113 31 0 0
v_max_id_clo_ord 5216 5216 0 0.00 0 645 5 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 3876 16 0 0
v_max_id_ord_sup 5204 5204 0 0.00 0 4631 8 0 0
v_max_non_paid_invoice 1280 1280 0 0.00 0 375 9 0 0
v_max_non_paid_realizn 3494 3494 0 0.00 0 2677 8 0 0
v_min_id_clo_ord 5216 5216 0 0.00 0 1832 9 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 4710 21 0 0
v_min_id_ord_sup 5204 5204 0 0.00 0 5263 10 0 0
v_min_non_paid_invoice 5674 5674 0 0.00 0 5716 20 0 0
v_min_non_paid_realizn 6874 6874 0 0.00 0 7110 22 0 0
v_our_firm 9894 9894 0 0.00 0 61 4 0 0
v_reserve_write_off 18916 18916 0 0.00 0 2789 11 0 0
x_lock_depdocs_on_canc_invoice 11624 11624 0 0.00 0 17431 136 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 11013 465 0 0
x_make_qstorno_1000_1200 5217 5217 0 0.00 2 16899 1203 0 0
x_make_qstorno_1200_2000 5214 5214 0 0.00 5 97793 2934 0 0
x_make_qstorno_2100_3300 458345 458345 0 0.00 0 106318 213 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-07-2022 07:38:36 to 06-07-2022 07:38:40.

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
05:07:18 1 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:19 18227 8192000000 9726623232 10025488384 807640496 8.974 100 90 181 0 837492032 7782240 22069296
05:09:46 26152 8192000000 9766720384 10074812416 842865712 9.329 100 90 180 0 876201600 11619632 21716256
05:11:18 14914 8192000000 9807888560 10125279232 869839152 9.598 100 86 171 0 910973472 21147600 19986720
05:12:37 7776 8192000000 9852085856 10181644288 904466256 9.943 100 82 161 0 960381728 37846432 18069040
05:13:48 5174 8192000000 9902131280 10248892416 948085616 10.372 100 79 155 0 1014984032 50778144 16120272
05:14:57 2412 8192000000 9953623984 10303803392 991091280 10.792 100 94 182 1 1074691184 64873056 18726848
05:16:01 2633 8192000000 9991619584 10350882816 1014986160 11.024 100 89 172 0 1109776448 77757216 17033072
05:17:06 768 8192000000 10019258208 10383822848 1033580864 11.203 100 96 187 2 1137540112 84962816 18996432
05:18:08 1142 8192000000 10020409312 10395054080 1055950848 11.418 100 97 182 2 1139261744 66496992 16813904
05:19:11 1014 8192000000 10035755824 10414399488 1071599008 11.567 100 93 184 1 1158196224 68864160 17733056
05:20:13 1266 8192000000 10034810944 10414813184 1073829744 11.589 100 94 177 1 1152051456 60323440 17898272
05:21:15 4903 8192000000 10033591360 10418663424 1074655264 11.597 100 67 125 0 1164383904 78004256 11724384
05:22:21 1103 8192000000 10038886496 10433794048 1082521792 11.671 100 82 157 3 1162596976 63202224 16872960
05:23:23 1038 8192000000 10054173296 10441580544 1089512608 11.738 100 99 197 0 1177826720 68426640 19887472
05:24:25 2283 8192000000 10050240016 10439745536 1088128816 11.725 100 82 158 0 1166650432 62131280 16390336
05:25:29 1355 8192000000 10027358208 10411331584 1082145024 11.668 99 88 171 2 1150402672 49781424 18476224
05:26:32 2104 8192000000 10055668304 10445778944 1092892384 11.770 100 87 172 2 1178072288 66760640 18419264
05:27:36 1050 8192000000 10074548048 10466070528 1099601776 11.834 100 96 186 2 1194101280 74222800 20276704
05:28:37 951 8192000000 10080694336 10474700800 1095363056 11.794 100 94 181 0 1202423504 88857200 18203248
05:29:39 3151 8192000000 10036744032 10426830848 1086343392 11.708 100 72 138 2 1159442480 59063952 14035136
05:30:43 868 8192000000 10051175088 10441633792 1075494016 11.605 100 85 170 1 1174766976 81024512 18248448
05:31:45 2437 8192000000 10012350912 10398064640 1080185952 11.649 100 76 141 2 1136394240 40906736 15301552
05:32:53 7663 8192000000 9994713984 10382594048 1034475008 11.212 99 63 116 2 1112554112 65983312 12095792
05:34:02 989 8192000000 9977670592 10345119744 1030622848 11.174 100 90 168 2 1099063760 51589600 16851312
05:35:04 934 8192000000 10005781104 10382065664 1049246240 11.353 100 98 194 0 1126832192 56411376 21174576
05:36:06 1120 8192000000 10010206992 10384723968 1041992272 11.284 100 97 186 0 1130614448 70362064 18260112
05:37:08 761 8192000000 10003794768 10387800064 1047930768 11.341 100 84 162 1 1126372352 62400800 16040784
05:38:16 16991 8192000000 10017417472 10409127936 1054486576 11.404 100 64 124 0 1138081024 71382640 12211808
05:39:42 19206 8192000000 10028607664 10412576768 1063471296 11.490 100 67 122 3 1149051552 73048752 12531504
05:41:04 19711 8192000000 10074973888 10455810048 1075849344 11.608 100 75 139 2 1179067248 88948896 14269008
05:42:31 13400 8192000000 10119720224 10518884352 1099533520 11.833 100 64 129 0 1234519504 120710672 14275312
05:43:52 4100 8192000000 10165756800 10563317760 1115960640 11.989 100 82 155 0 1286699760 153473808 17265312
05:45:02 11471 8192000000 10182193792 10586951680 1126767376 12.091 100 73 143 0 1308058352 165859472 15431504
05:46:23 1076 8192000000 10211771536 10602291200 1125580672 12.080 100 95 181 3 1330869536 184565408 20723456
05:47:28 1518 8192000000 10218852848 10619207680 1138002288 12.197 100 79 151 1 1333834464 179828656 16003520
05:48:34 3200 8192000000 10253415312 10659414016 1157768416 12.382 100 88 167 1 1364760528 188428320 18563792
05:49:42 4569 8192000000 10282235072 10684833792 1173756544 12.532 100 86 166 0 1396397440 204229616 18411280
05:50:53 1788 8192000000 10356040976 10753691648 1180303840 12.593 100 95 183 0 1461599616 260441904 20853872
05:51:58 815 8192000000 10361225280 10761003008 1196105328 12.740 100 96 185 1 1467407280 251105824 20196128
05:53:03 793 8192000000 10410366192 10814697472 1197585744 12.754 100 91 179 1 1520209936 301660624 20963568
05:54:07 1192 8192000000 10426697632 10829873152 1178035920 12.572 100 98 190 1 1529844640 330514688 21294032
05:55:11 1015 8192000000 10478242672 10887241728 1199899200 12.775 100 93 180 1 1585534320 364499024 21136096
05:56:17 1874 8192000000 10528914336 10943676416 1206469568 12.836 100 92 182 0 1634174064 406453648 21250848
05:57:23 874 8192000000 10566492032 10987094016 1222579808 12.986 100 96 188 0 1671399328 426811168 22008352
05:58:27 777 8192000000 10570120928 10990100480 1207162928 12.843 100 97 192 0 1676368080 445610864 23594288
05:59:33 1901 8192000000 10621863312 11031953408 1215750256 12.922 100 91 182 1 1724063984 485853584 22460144
06:00:41 2053 8192000000 10685124544 11110002688 1221476736 12.975 100 97 192 3 1788569360 543503168 23589456
06:01:46 983 8192000000 10652811216 11107184640 1192067984 12.703 100 98 192 0 1759491632 541777296 25646352
06:02:51 1100 8192000000 10624639440 11096506368 1200242656 12.779 100 95 189 0 1730881920 506468768 24170496
06:03:57 1383 8192000000 10603309088 11102085120 1200241056 12.779 100 88 167 0 1708957856 488132640 20584160
06:05:01 2258 8192000000 10609219712 11066609664 1198404128 12.762 100 73 141 3 1711526416 495294512 17827776
06:06:09 7300 8192000000 10599467120 11079569408 1198564832 12.763 100 45 88 0 1709054816 498246848 12243136
06:07:27 36885 8192000000 10573976256 11070414848 1175663376 12.550 99 37 74 0 1680762944 495219072 9880496
06:09:23 6469 8192000000 10538419488 11022495744 1168844560 12.486 100 39 78 1 1655805232 476817824 10142848
06:10:32 19153 8192000000 10360831360 10904113152 1152258368 12.331 100 27 53 0 1481312544 322252608 6801568
06:12:43 18803 8192000000 10140913888 10645733376 1111266880 11.944 97 25 48 0 1231404384 114663824 5473680
06:14:17 15783 8192000000 10186649248 10618257408 1141282720 12.228 100 46 72 0 1276263280 126807920 8172640
06:16:49 47496 8192000000 10077447104 10534158336 1131983200 12.140 100 22 45 1 1174937904 38755424 4199280
06:18:41 79039 8192000000 10102483664 10540003328 1140997984 12.225 100 16 32 0 1202534016 58061360 3474672
06:21:09 102639 8192000000 10073864528 10541379584 1077380080 11.623 94 17 32 0 1121208160 41204912 2623168
06:23:53 74061 8192000000 10099445024 10609364992 1132370528 12.144 99 13 26 1 1214567184 79297008 2899648
06:26:20 77426 8192000000 10107487632 10558816256 1106888544 11.903 97 16 32 0 1154239696 44373008 2978144
06:28:40 84098 8192000000 10076038688 10551984128 1078033168 11.629 95 23 38 0 1145684912 63518192 4133552
06:31:06 117270 8192000000 10083740640 10535829504 1105124256 11.886 98 37 72 1 1149563488 36529952 7909280
06:34:05 68797 8192000000 10100097328 10538868736 1108724064 11.920 98 39 78 0 1196639408 79968096 7947248
06:36:15 5553 8192000000 10090703328 10526150656 1135784592 12.176 100 50 97 2 1184854304 39330144 9739568
06:37:22 43713 8192000000 10111538528 10548695040 1098164544 11.820 96 40 71 5 1190206608 84735968 7306096
06:39:06 15802 8192000000 10137410816 10580447232 1127983536 12.102 100 34 69 2 1243649536 107749264 7916736
06:40:26 43135 8192000000 10112638352 10593640448 1128652336 12.109 100 24 46 0 1223690560 90289904 4748320
06:42:13 44843 8192000000 10086537632 10553507840 1133004128 12.150 100 18 34 1 1188088608 51373568 3710912
06:44:04 103369 8192000000 10063555280 10538704896 1111133888 11.943 98 13 26 0 1172379232 58361200 2884144
06:46:51 94452 8192000000 10076361360 10527461376 1088387264 11.727 96 14 28 0 1132278336 41052960 2838112
06:49:28 20289 8192000000 10098692928 10545287168 1107929520 11.913 98 12 24 0 1172327344 62034080 2363744
06:50:56 79885 8192000000 10113746832 10598166528 1123182704 12.057 99 13 26 0 1210430320 84558624 2688992
06:53:33 45691 8192000000 10074671408 10510786560 1079322032 11.641 95 26 48 0 1116054160 31999104 4733024
06:55:20 53104 8192000000 10114820144 10580287488 1116550368 11.994 99 36 68 1 1175636896 52581488 6505040
06:57:14 55302 8192000000 10049185360 10506747904 1092893408 11.770 97 20 38 1 1142885728 46322928 3669392
06:59:10 145656 8192000000 10063674864 10515480576 1084890752 11.694 97 31 61 0 1153856736 62938688 6027296
07:02:41 44497 8192000000 10087672336 10564923392 1107846064 11.912 99 29 57 0 1182733680 68721024 6166592
07:04:26 59515 8192000000 10079314944 10555555840 1123519376 12.060 100 21 42 0 1188576400 60174816 4882208
07:06:26 42151 8192000000 10072369024 10538270720 1134344160 12.162 100 26 52 0 1181722672 41270096 6108416
07:08:08 82559 8192000000 10068661888 10544803840 1110008128 11.932 98 13 27 1 1163307120 49999632 3299360
07:10:40 46943 8192000000 10057614592 10506137600 1109540288 11.928 99 14 27 0 1170277872 58075552 2662032
07:12:28 31700 8192000000 10083672944 10549772288 1133736880 12.157 100 25 49 0 1177544320 38618208 5189232
07:14:07 57935 8192000000 10077274640 10513948672 1099026240 11.828 97 28 56 0 1157390544 52397424 5966880
07:16:13 9118 8192000000 10077427392 10505007104 1120595728 12.033 100 56 105 2 1165075952 34017952 10462272
07:17:24 7402 8192000000 10111087296 10535206912 1130351264 12.125 100 63 123 1 1206930816 64181808 12397744
07:18:32 52789 8192000000 10077388544 10527006720 1118668800 12.014 99 28 56 0 1188650640 64775360 5206480
07:20:28 29971 8192000000 10120935120 10588450816 1126243984 12.086 99 33 66 0 1214063616 81402144 6417488
07:22:01 46420 8192000000 10157135024 10617901056 1150582560 12.315 100 35 68 0 1248308800 90087344 7638896
07:23:49 99658 8192000000 10055701184 10507350016 1114286592 11.973 98 20 40 1 1146875712 28488320 4100800
07:27:08 54765 8192000000 10085798080 10575073280 1131660704 12.137 99 24 48 0 1215256496 79198928 4396864
07:30:36 42326 8192000000 10107529952 10552197120 1131154528 12.132 98 25 51 0 1195303600 58630784 5518288
07:32:19 96016 8192000000 10133792880 10617577472 1148556176 12.296 100 14 28 0 1234678256 83243808 2878272
07:35:08 44686 8192000000 10085612960 10535530496 1118835184 12.016 98 40 77 0 1144880640 18463024 7582432
07:36:56 29780 8192000000 8979304128 9206296576 138785488 1.665 13 3 6 0 159730048 20429648 514912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 06-07-2022 07:38:42 to 06-07-2022 07:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 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 06-07-2022 07:38:59 to 06-07-2022 07:39:01.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          216681
MON$OLDEST_ACTIVE               216682
MON$OLDEST_SNAPSHOT             216682
MON$NEXT_TRANSACTION            216682
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-30 07:30:08.6030 GMT
MON$PAGES                       2088864
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2796 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2796 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2796 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
--- end of diff output ---

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

06.07.22 07:39:02. There are 3 logs in /var/tmp/logs-oltp40 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-06 07:39:05 No severe PSQL-related problems occured
06.07.22 07:39:06. End of report.