Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.01.2022 10: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 16280352 kB
MemAvailable 31372692 kB
Buffers 0 kB
Cached 15438632 kB
SwapCached 1204 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.1
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-01-04 10:37:13
DTS_END                         2022-01-04 10:37:13.8820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49324
TRN_ID                          219793
ATT_ID                          603
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: 8886.31 26709 1066357
customer order: creation 169.68 464 20361
customer order: refuse 14.47 373 1736
order to supplier: creation 45.03 1336 5404
order to supplier: removal 3.30 147169 396
invoice (draft): creation 45.30 3147 5436
invoice (draft): removal 48.38 951 5806
invoice accept: apply 41.53 26724 4984
invoice accept: cancel 43.82 1152 5258
customer reserve: creation 3876.87 255 465224
customer reserve: removal 4314.39 88 517727
realization accept: apply 82.14 279 9857
realization accept: cancel 71.96 173 8635
payment from customer: creation 59.24 173 7109
payment from customer: removal 5.90 123 708
payment to supplier: creation 48.92 191 5870
payment to supplier: removal 3.43 130 411
service: total inventory turnovers 3.94 3929 473
service: total monetary turnovers 7.08 369 850
service: aggregate perf. data 0.90 14477 108
service: refresh index statistics 0.03 332667 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-01-2022 10:38:57 to 04-01-2022 10:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6106
TEST_TIME 2 5089
TEST_TIME 3 5510
TEST_TIME 4 5251
TEST_TIME 5 5313
TEST_TIME 6 5225
TEST_TIME 7 5324
TEST_TIME 8 5549
TEST_TIME 9 6061
TEST_TIME 10 6104
TEST_TIME 11 6397
TEST_TIME 12 6505
TEST_TIME 13 6653
TEST_TIME 14 6710
TEST_TIME 15 7032
TEST_TIME 16 7190
TEST_TIME 17 7508
TEST_TIME 18 7766
TEST_TIME 19 8337
TEST_TIME 20 8691
TEST_TIME 21 8817
TEST_TIME 22 9012
TEST_TIME 23 8907
TEST_TIME 24 8885
TEST_TIME 25 9111
TEST_TIME 26 9133
TEST_TIME 27 9349
TEST_TIME 28 9736
TEST_TIME 29 9698
TEST_TIME 30 10127
TEST_TIME 31 10472
TEST_TIME 32 10356
TEST_TIME 33 10752
TEST_TIME 34 10727
TEST_TIME 35 10547
TEST_TIME 36 10500
TEST_TIME 37 10542
TEST_TIME 38 10515
TEST_TIME 39 10603
TEST_TIME 40 10576
TEST_TIME 41 10513
TEST_TIME 42 10368
TEST_TIME 43 10215
TEST_TIME 44 10092
TEST_TIME 45 10156
TEST_TIME 46 10419
TEST_TIME 47 10253
TEST_TIME 48 10065
TEST_TIME 49 9994
TEST_TIME 50 10045
TEST_TIME 51 9894
TEST_TIME 52 9832
TEST_TIME 53 9783
TEST_TIME 54 9731
TEST_TIME 55 9697
TEST_TIME 56 9698
TEST_TIME 57 9627
TEST_TIME 58 9625
TEST_TIME 59 9634
TEST_TIME 60 9523
TEST_TIME 61 9515
TEST_TIME 62 9536
TEST_TIME 63 9542
TEST_TIME 64 9515
TEST_TIME 65 9491
TEST_TIME 66 9590
TEST_TIME 67 9676
TEST_TIME 68 9629
TEST_TIME 69 9669
TEST_TIME 70 9725
TEST_TIME 71 9703
TEST_TIME 72 9665
TEST_TIME 73 9594
TEST_TIME 74 9506
TEST_TIME 75 9422
TEST_TIME 76 9508
TEST_TIME 77 9452
TEST_TIME 78 9373
TEST_TIME 79 9353
TEST_TIME 80 9306
TEST_TIME 81 9228
TEST_TIME 82 9150
TEST_TIME 83 9100
TEST_TIME 84 9091
TEST_TIME 85 9103
TEST_TIME 86 9211
TEST_TIME 87 9189
TEST_TIME 88 9177
TEST_TIME 89 9200
TEST_TIME 90 9197
TEST_TIME 91 9229
TEST_TIME 92 9282
TEST_TIME 93 9287
TEST_TIME 94 9245
TEST_TIME 95 9306
TEST_TIME 96 9303
TEST_TIME 97 9284
TEST_TIME 98 9209
TEST_TIME 99 9173
TEST_TIME 100 9137
TEST_TIME 101 9117
TEST_TIME 102 9120
TEST_TIME 103 9054
TEST_TIME 104 9018
TEST_TIME 105 9027
TEST_TIME 106 9002
TEST_TIME 107 8978
TEST_TIME 108 8985
TEST_TIME 109 9018
TEST_TIME 110 8977
TEST_TIME 111 8963
TEST_TIME 112 8954
TEST_TIME 113 8987
TEST_TIME 114 9037
TEST_TIME 115 9028
TEST_TIME 116 9041
TEST_TIME 117 9069
TEST_TIME 118 9022
TEST_TIME 119 8978
TEST_TIME 120 8912

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-01-2022 10:39:01 to 04-01-2022 10:39:04.

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 1494875 1494875 0 0.00 0 25029 13 0 0
doc_list_biud 1494870 1494870 0 0.00 0 61233 37 0 0
sp_add_invoice_to_stock 5158 4984 174 3.37 40 241446 26725 0 174
sp_cancel_adding_invoice 5258 5258 0 0.00 6 91335 1152 0 0
sp_cancel_client_order 1736 1736 0 0.00 6 6424 374 0 0
sp_cancel_customer_reserve 517727 517727 0 0.00 1 61234 88 0 0
sp_cancel_pay_from_customer 708 708 0 0.00 4 5072 124 0 0
sp_cancel_pay_to_supplier 411 411 0 0.00 4 8844 130 0 0
sp_cancel_supplier_invoice 5818 5806 12 0.20 2 51989 951 0 12
sp_cancel_supplier_order 396 396 0 0.00 8 1151349 147169 0 0
sp_cancel_write_off 8635 8635 0 0.00 2 13082 174 0 0
sp_client_order 20361 20361 0 0.00 6 36181 464 0 0
sp_customer_reserve 465224 465224 0 0.00 1 93282 256 0 0
sp_fill_shopping_cart 31198 31198 0 0.00 1 21350 80 0 0
sp_fill_shopping_cart_clo_res 465206 465206 0 0.00 0 18717 19 0 0
sp_get_clo_for_invoice 4950 4950 0 0.00 0 27074 251 0 0
sp_kill_cost_storno 15012 15012 0 0.00 0 8612 72 0 0
sp_kill_qstorno_ret_qs2qd 1050291 1050291 0 0.00 0 61231 39 0 0
sp_kill_qty_storno 554363 554363 0 0.00 0 61233 91 0 0
sp_lock_selected_doc 20508 20508 0 0.00 0 8182 7 0 0
sp_make_cost_storno 27784 27784 0 0.00 0 25024 75 0 0
sp_multiply_rows_for_pdistr 27784 27784 0 0.00 0 14237 37 0 0
sp_multiply_rows_for_qdistr 20361 20361 0 0.00 0 19102 161 0 0
sp_pay_from_customer 7109 7109 0 0.00 5 14565 173 0 0
sp_pay_to_supplier 5870 5870 0 0.00 7 12151 192 0 0
sp_payment_common 12979 12979 0 0.00 2 13136 44 0 0
sp_qd_handle_on_cancel_clo 1736 1736 0 0.00 0 6345 302 0 0
sp_qd_handle_on_invoice_upd_sts 10206 10206 0 0.00 0 38282 484 0 0
sp_qd_handle_on_reserve_upd_sts 9857 9857 0 0.00 0 36605 77 0 0
sp_reserve_write_off 9857 9857 0 0.00 9 36764 279 0 0
sp_supplier_invoice 5436 5436 0 0.00 9 113829 3147 0 0
sp_supplier_order 5404 5404 0 0.00 7 43143 1337 0 0
srv_aggregate_perf_data 108 108 0 0.00 2342 158377 14478 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 742 166766 41747 0 0
srv_find_qd_qs_mism 1017578 1017578 0 0.00 0 17468 11 0 0
srv_make_invnt_saldo 473 473 0 0.00 55 72302 3929 0 0
srv_make_money_saldo 850 850 0 0.00 1 30502 370 0 0
srv_recalc_idx_stat 4 4 0 0.00 133492 610560 332667 0 0
t$perf-abend:sp_add_invoice_to_stock 174 174 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 4986 4986 0 0.00 1 13077 835 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 8063 429 0 0
t$perf-norm:sp_cancel_client_order 1736 1736 0 0.00 0 1760 24 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 3759 41 0 0
t$perf-norm:sp_cancel_pay_from_customer 708 708 0 0.00 0 521 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 411 411 0 0.00 0 6376 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 528 24 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 104040 6916 0 0
t$perf-norm:sp_cancel_write_off 962 962 0 0.00 0 844 15 0 0
t$perf-norm:sp_client_order 20361 20361 0 0.00 0 16252 32 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 2002 17 0 0
t$perf-norm:sp_pay_from_customer 7109 7109 0 0.00 0 7625 18 0 0
t$perf-norm:sp_pay_to_supplier 5870 5870 0 0.00 0 2174 17 0 0
t$perf-norm:sp_reserve_write_off 9857 9857 0 0.00 0 4126 22 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 10209 30 0 0
t$perf-norm:sp_supplier_order 5404 5404 0 0.00 0 2295 28 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 331 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1384 43 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 627 15 0 0
t$perf-norm:srv_make_money_saldo 850 850 0 0.00 0 3662 10 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 867 284 0 0
v_add_invoice_to_stock 10244 9896 348 3.39 0 10696 29 0 348
v_all_customers 37402 37402 0 0.00 0 156 3 0 0
v_all_suppliers 30572 30572 0 0.00 0 91 4 0 0
v_all_wares 40722 40722 0 0.00 0 146 3 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 1041 14 0 0
v_cancel_client_order 3472 3472 0 0.00 0 5219 14 0 0
v_cancel_customer_prepayment 1416 1416 0 0.00 0 4715 16 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 1594 11 0 0
v_cancel_payment_to_supplier 822 822 0 0.00 0 3032 20 0 0
v_cancel_supplier_invoice 818 794 24 2.93 0 3872 28 0 24
v_cancel_supplier_order 792 792 0 0.00 0 4019 17 0 0
v_cancel_write_off 1924 1924 0 0.00 0 7222 26 0 0
v_max_id_clo_ord 5404 5404 0 0.00 0 1931 5 0 0
v_max_id_clo_res 989 989 0 0.00 0 1200 10 0 0
v_max_id_ord_sup 5433 5433 0 0.00 0 2318 5 0 0
v_max_non_paid_invoice 1421 1421 0 0.00 0 870 7 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 6608 8 0 0
v_min_id_clo_ord 5404 5404 0 0.00 0 7186 9 0 0
v_min_id_clo_res 989 989 0 0.00 0 5208 12 0 0
v_min_id_ord_sup 5433 5433 0 0.00 0 4302 9 0 0
v_min_non_paid_invoice 5870 5870 0 0.00 0 6599 15 0 0
v_min_non_paid_realizn 7109 7109 0 0.00 0 12082 20 0 0
v_our_firm 10104 10104 0 0.00 0 59 4 0 0
v_reserve_write_off 19714 19714 0 0.00 0 6107 12 0 0
x_lock_depdocs_on_canc_invoice 10667 10667 0 0.00 0 13859 120 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 6096 313 0 0
x_make_qstorno_1000_1200 5404 5404 0 0.00 2 43128 1176 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 4 113814 2926 0 0
x_make_qstorno_2100_3300 465224 465224 0 0.00 0 93272 226 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 04-01-2022 10:39:05 to 04-01-2022 10:39:10.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:17 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:18 21207 8192000000 9730792832 10030981120 809880512 8.996 100 90 180 0 839515840 7714128 21921200
08:09:43 32113 8192000000 9777563744 10088157184 849493456 9.395 100 90 180 0 883152048 12425920 21232672
08:11:20 18234 8192000000 9815868512 10135764992 878368880 9.683 100 87 174 0 922835952 24822656 19644416
08:12:41 14076 8192000000 9862539120 10195791872 914478000 10.042 100 78 156 1 973570640 41770448 17322192
08:14:06 7319 8192000000 9924789856 10268262400 958145664 10.471 100 86 171 1 1038035568 62742432 17147472
08:15:15 2587 8192000000 9982412432 10335477760 991581488 10.797 100 94 185 0 1097930224 87353344 18995392
08:16:20 1321 8192000000 10008845328 10369314816 1029965888 11.168 100 93 180 1 1124094960 76255232 17873840
08:17:24 1381 8192000000 10007465232 10380505088 1049801440 11.359 100 96 187 2 1130750000 61426544 19522016
08:18:27 1142 8192000000 10039954736 10412687360 1055669920 11.415 100 100 194 1 1160999616 85652176 19677520
08:19:30 1232 8192000000 10064522944 10438406144 1063508992 11.490 100 97 194 2 1184636048 101905808 19221248
08:20:34 1340 8192000000 10074602016 10448007168 1071566848 11.567 100 97 185 1 1195414896 105681904 18166144
08:21:37 5306 8192000000 10031330432 10417762304 1077643552 11.625 100 83 163 0 1159785760 66175424 15966784
08:22:43 904 8192000000 10071052928 10453979136 1081524192 11.662 100 96 187 2 1193336288 92878032 18934064
08:23:45 1294 8192000000 10051360576 10439143424 1086711776 11.711 100 98 188 0 1176923376 71423552 18788048
08:24:48 1025 8192000000 10069765904 10460168192 1091466336 11.757 100 95 184 2 1193058480 83129232 18462912
08:25:51 1190 8192000000 10059947744 10448740352 1100147616 11.839 100 88 167 1 1179831072 62450016 17233440
08:26:53 3989 8192000000 10052035120 10443763712 1096383392 11.803 100 60 118 2 1169106224 60923328 11799504
08:28:00 941 8192000000 10063397808 10453987328 1097038720 11.810 100 93 187 2 1187529408 70888448 19602240
08:29:02 936 8192000000 10049006416 10443022336 1098618288 11.825 100 68 132 0 1176224048 64125024 13480736
08:30:05 1228 8192000000 10024665136 10405343232 1056545760 11.423 99 98 184 2 1148068368 71800416 19722192
08:31:07 1434 8192000000 10028664288 10404417536 1065585584 11.510 100 92 177 0 1151992832 68142560 18264688
08:32:10 1299 8192000000 10009007856 10382114816 1049167232 11.353 100 95 185 0 1135366496 66812016 19387248
08:33:13 1189 8192000000 10004017392 10373992448 1033203872 11.199 100 97 181 0 1124777200 73709744 17863584
08:34:16 1303 8192000000 9982095872 10350370816 1015181072 11.025 100 99 186 1 1106509472 71158912 20169488
08:35:18 1271 8192000000 9964017312 10325893120 1018387024 11.056 100 92 168 3 1087806112 50775664 18643424
08:36:21 976 8192000000 9977778128 10346250240 1024527088 11.116 100 93 180 0 1101897984 58895856 18475040
08:37:25 738 8192000000 10014119808 10386239488 1063595328 11.491 100 89 165 2 1133608576 53476976 16536272
08:38:33 28968 8192000000 10007101424 10388475904 1064706224 11.501 99 70 132 1 1125721552 47137104 13878224
08:40:07 1429 8192000000 10033985744 10407411712 1083251888 11.678 100 88 168 0 1155510912 55088064 17170960
08:41:14 15150 8192000000 10102143152 10490658816 1107409664 11.908 100 61 119 1 1219403792 99524496 12469632
08:42:54 12385 8192000000 10155164576 10550808576 1122103136 12.047 100 56 108 2 1266399392 132417280 11878976
08:44:09 1037 8192000000 10189932768 10580045824 1131467456 12.135 100 93 173 1 1305602480 154341808 19793216
08:45:17 2998 8192000000 10209658400 10602135552 1136285888 12.181 100 81 159 0 1323748656 170838976 16623792
08:46:25 1577 8192000000 10240090048 10631868416 1149286848 12.303 100 94 182 0 1355658784 185659280 20712656
08:47:29 1039 8192000000 10285830640 10684833792 1158363952 12.388 100 97 190 1 1400587744 221160960 21062832
08:48:33 997 8192000000 10294349888 10714501120 1167095568 12.470 100 93 178 1 1407380096 220149008 20135520
08:49:36 930 8192000000 10319884208 10719170560 1175936896 12.552 100 98 183 0 1429237568 233657792 19642880
08:50:40 1228 8192000000 10354082544 10755018752 1179787808 12.588 100 97 189 3 1461047232 259625680 21633744
08:51:44 969 8192000000 10381297664 10804076544 1181324336 12.603 100 96 187 1 1494331552 291172512 21834704
08:52:46 761 8192000000 10416173616 10822496256 1187415840 12.659 100 96 193 1 1526062096 316791488 21854768
08:53:51 1811 8192000000 10398565488 10826477568 1178248368 12.574 99 89 169 3 1512844656 314030688 20565600
08:54:56 769 8192000000 10411829504 10847105024 1192814416 12.710 100 98 195 1 1519270528 304047328 22408784
08:55:59 1169 8192000000 10391989712 10851553280 1187850688 12.663 100 95 186 2 1498310896 289822000 20638208
08:57:02 3901 8192000000 10390797264 10848280576 1195829040 12.738 100 67 127 0 1501889680 290440352 15620288
08:58:12 19310 8192000000 10342405968 10766278656 1191986272 12.702 100 46 89 1 1452596544 250201456 10408816
08:59:38 6482 8192000000 10370108672 10808811520 1201068272 12.786 100 50 98 1 1487821440 275138368 11614800
09:00:48 17878 8192000000 10392125584 10832871424 1187671520 12.662 100 47 92 0 1495317936 296348144 11298272
09:02:18 11380 8192000000 10395306464 10846285824 1188682544 12.671 100 49 98 0 1518231008 316931152 12617312
09:03:39 1041 8192000000 10401197408 10840002560 1180864960 12.598 100 64 123 0 1505355664 309313920 15176784
09:04:53 2529 8192000000 10330702992 10804346880 1170292176 12.500 100 51 100 1 1436201680 254308848 11600656
09:06:01 26860 8192000000 10338809968 10782838784 1133749552 12.157 98 32 62 1 1425546128 283196864 8599712
09:07:53 26465 8192000000 10240625520 10691215360 1160715776 12.410 100 31 60 1 1362514784 194878000 6921008
09:09:24 61767 8192000000 10217329856 10676232192 1150876976 12.318 100 32 64 1 1311476480 153716448 6883056
09:11:35 88949 8192000000 10118308896 10589675520 1102197440 11.858 97 18 34 2 1162226784 56582256 3447088
09:14:06 115102 8192000000 10100417168 10547486720 1079856784 11.646 95 11 21 0 1148585456 66947360 1781312
09:17:03 73686 8192000000 10075730960 10544189440 1140607280 12.221 100 14 29 0 1179315904 35596592 3112032
09:19:18 48590 8192000000 10126061104 10564636672 1119465760 12.022 98 37 71 1 1183528256 57224464 6838032
09:21:20 28543 8192000000 10147076192 10563592192 1130824736 12.129 100 47 91 0 1234570832 93957792 9788304
09:23:00 28013 8192000000 10112099248 10631147520 1134638304 12.165 100 7 14 0 1216811104 80757152 1415648
09:24:29 166765 8192000000 10105925312 10549170176 1105929824 11.894 97 27 54 0 1159124128 47229712 5964592
09:28:26 51832 8192000000 10089726912 10522210304 1133047056 12.150 100 64 120 0 1177428128 32288880 12092192
09:30:20 61346 8192000000 10116053248 10556514304 1131298384 12.134 99 31 62 1 1199859952 61244864 7316704
09:32:34 128052 8192000000 10078904224 10543206400 1096440848 11.804 96 28 50 1 1201911648 100242288 5228512
09:35:43 46336 8192000000 10062512480 10536415232 1091792576 11.760 97 13 25 1 1160507856 65479184 3236096
09:37:30 40188 8192000000 10078136832 10545680384 1112888800 11.960 99 19 39 1 1193569472 76681664 3999008
09:39:14 42504 8192000000 10110065952 10564972544 1124001200 12.065 99 20 40 1 1206740960 78416384 4323376
09:40:57 74183 8192000000 10151670320 10598141952 1110744240 11.939 97 16 32 0 1235710064 121614432 3351392
09:43:11 39710 8192000000 10129176528 10595373056 1128417600 12.106 99 28 56 2 1221414096 86791136 6205360
09:45:01 10329 8192000000 10106484624 10525597696 1131387360 12.134 99 50 98 0 1188026576 46598688 10040528
09:46:12 21725 8192000000 10154013024 10601832448 1144202656 12.255 100 51 102 1 1249255312 95303680 9748976
09:48:36 34660 8192000000 10088687728 10501320704 1112451040 11.956 97 64 122 3 1156946800 32194608 12301152
09:50:11 130608 8192000000 10116771024 10560012288 1080443696 11.652 95 28 56 1 1168681712 82215600 6022416
09:53:24 16569 8192000000 10091826064 10601410560 1126442240 12.088 100 23 45 2 1184169936 53014320 4713376
09:54:43 52147 8192000000 10050596304 10514100224 1106310384 11.897 99 8 16 0 1139225104 31642784 1271936
09:56:36 28191 8192000000 10038249824 10483290112 1103685936 11.873 98 21 37 0 1127398960 19805728 3907296
09:58:04 96203 8192000000 10103248032 10556403712 1126372736 12.087 99 25 50 0 1189506240 57782864 5350640
10:01:31 96362 8192000000 10111555088 10638671872 1130651392 12.128 99 16 32 0 1262155200 127699536 3804272
10:05:06 49614 8192000000 10086549904 10533875712 1098328608 11.822 98 40 80 0 1165480560 59090608 8061344
10:07:00 93369 8192000000 10131759120 10578235392 1072346448 11.574 95 44 86 2 1162929472 81711568 8871456
10:09:37 82812 8192000000 10067889728 10502803456 1100963168 11.847 98 29 54 0 1142064160 35911568 5189424
10:12:13 104850 8192000000 10078919456 10540548096 1098014784 11.819 97 33 66 3 1176371856 71682448 6674624
10:15:02 96514 8192000000 10086757808 10522615808 1132313872 12.143 100 43 78 2 1197078736 56106960 8657904
10:17:40 45681 8192000000 10094388400 10536202240 1130776016 12.129 99 24 48 1 1190558416 54037600 5744800
10:19:30 55898 8192000000 10056942240 10547073024 1117779936 12.006 99 5 10 0 1148816672 30292256 744480
10:21:26 51706 8192000000 10072334896 10533281792 1107723952 11.911 98 10 20 1 1159197008 49466096 2006960
10:23:35 27855 8192000000 10060989136 10509430784 1104923568 11.884 97 14 28 0 1133431584 24742416 3765600
10:25:04 96768 8192000000 10075528752 10564640768 1110352208 11.936 97 24 48 1 1161730224 45356352 6021664
10:27:42 28730 8192000000 10130675408 10570817536 1154397872 12.351 100 38 76 4 1219124944 56826464 7900608
10:29:16 38257 8192000000 10184841424 10631606272 1153591888 12.343 100 38 76 0 1272079728 110485632 8002208
10:31:01 52040 8192000000 10114008528 10582872064 1135725216 12.175 99 20 40 1 1209341360 69369536 4246608
10:32:53 29530 8192000000 10099474224 10529648640 1110939440 11.941 98 33 66 0 1164497056 46377536 7180080
10:34:41 129257 8192000000 10046130592 10500804608 1081821072 11.665 95 31 62 1 1155648960 67771104 6056784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 04-01-2022 10:39:11 to 04-01-2022 10:39:27.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 174 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 04-01-2022 10:39:28 to 04-01-2022 10:39:30.

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          218520
MON$OLDEST_ACTIVE               220255
MON$OLDEST_SNAPSHOT             220255
MON$NEXT_TRANSACTION            220255
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                       2067744
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2701 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2701 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2701 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2701 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'

04.01.22 10:39:31. 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-01-04 10:39:33 No severe PSQL-related problems occured
04.01.22 10:39:35. End of report.