Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.07.2022 13: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 15615516 kB
MemAvailable 31072296 kB
Buffers 0 kB
Cached 16115560 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-24 13:37:19
DTS_END                         2022-07-24 13:37:19.5460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(50:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53208
TRN_ID                          222237
ATT_ID                          593
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: 9134.14 26516 1096097
customer order: creation 176.53 470 21184
customer order: refuse 15.30 486 1836
order to supplier: creation 46.63 1437 5595
order to supplier: removal 3.40 189840 408
invoice (draft): creation 47.28 3179 5674
invoice (draft): removal 50.03 1166 6004
invoice accept: apply 42.38 25797 5085
invoice accept: cancel 44.75 1348 5370
customer reserve: creation 4008.06 238 480967
customer reserve: removal 4404.46 113 528535
realization accept: apply 85.03 298 10203
realization accept: cancel 77.90 193 9348
payment from customer: creation 61.53 173 7384
payment from customer: removal 5.72 122 686
payment to supplier: creation 49.25 198 5910
payment to supplier: removal 3.37 132 404
service: total inventory turnovers 3.70 4844 444
service: total monetary turnovers 7.76 359 931
service: aggregate perf. data 1.04 16059 125
service: refresh index statistics 0.03 283876 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 24-07-2022 13:38:55 to 24-07-2022 13:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8060
TEST_TIME 2 6191
TEST_TIME 3 5612
TEST_TIME 4 5367
TEST_TIME 5 5601
TEST_TIME 6 5753
TEST_TIME 7 5898
TEST_TIME 8 5881
TEST_TIME 9 5832
TEST_TIME 10 5801
TEST_TIME 11 6045
TEST_TIME 12 6767
TEST_TIME 13 6780
TEST_TIME 14 6657
TEST_TIME 15 6567
TEST_TIME 16 6838
TEST_TIME 17 6912
TEST_TIME 18 7049
TEST_TIME 19 7109
TEST_TIME 20 7233
TEST_TIME 21 7509
TEST_TIME 22 7600
TEST_TIME 23 7778
TEST_TIME 24 7771
TEST_TIME 25 7967
TEST_TIME 26 8025
TEST_TIME 27 7939
TEST_TIME 28 8168
TEST_TIME 29 8400
TEST_TIME 30 8328
TEST_TIME 31 8347
TEST_TIME 32 8685
TEST_TIME 33 8863
TEST_TIME 34 9428
TEST_TIME 35 9750
TEST_TIME 36 10163
TEST_TIME 37 10295
TEST_TIME 38 10396
TEST_TIME 39 10726
TEST_TIME 40 10586
TEST_TIME 41 10528
TEST_TIME 42 10543
TEST_TIME 43 10532
TEST_TIME 44 10380
TEST_TIME 45 10279
TEST_TIME 46 10291
TEST_TIME 47 10363
TEST_TIME 48 10286
TEST_TIME 49 10192
TEST_TIME 50 10039
TEST_TIME 51 9945
TEST_TIME 52 9825
TEST_TIME 53 10049
TEST_TIME 54 9897
TEST_TIME 55 9797
TEST_TIME 56 9782
TEST_TIME 57 9749
TEST_TIME 58 9670
TEST_TIME 59 9730
TEST_TIME 60 9799
TEST_TIME 61 9796
TEST_TIME 62 9756
TEST_TIME 63 9723
TEST_TIME 64 9610
TEST_TIME 65 9597
TEST_TIME 66 9537
TEST_TIME 67 9612
TEST_TIME 68 9622
TEST_TIME 69 9613
TEST_TIME 70 9634
TEST_TIME 71 9568
TEST_TIME 72 9512
TEST_TIME 73 9670
TEST_TIME 74 9785
TEST_TIME 75 9715
TEST_TIME 76 9785
TEST_TIME 77 9784
TEST_TIME 78 9714
TEST_TIME 79 9623
TEST_TIME 80 9665
TEST_TIME 81 9609
TEST_TIME 82 9539
TEST_TIME 83 9554
TEST_TIME 84 9530
TEST_TIME 85 9486
TEST_TIME 86 9460
TEST_TIME 87 9414
TEST_TIME 88 9396
TEST_TIME 89 9335
TEST_TIME 90 9322
TEST_TIME 91 9279
TEST_TIME 92 9249
TEST_TIME 93 9247
TEST_TIME 94 9215
TEST_TIME 95 9202
TEST_TIME 96 9207
TEST_TIME 97 9275
TEST_TIME 98 9260
TEST_TIME 99 9222
TEST_TIME 100 9303
TEST_TIME 101 9317
TEST_TIME 102 9328
TEST_TIME 103 9421
TEST_TIME 104 9484
TEST_TIME 105 9529
TEST_TIME 106 9489
TEST_TIME 107 9427
TEST_TIME 108 9361
TEST_TIME 109 9354
TEST_TIME 110 9365
TEST_TIME 111 9326
TEST_TIME 112 9298
TEST_TIME 113 9302
TEST_TIME 114 9267
TEST_TIME 115 9220
TEST_TIME 116 9239
TEST_TIME 117 9224
TEST_TIME 118 9196
TEST_TIME 119 9191
TEST_TIME 120 9158

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 24-07-2022 13:38:58 to 24-07-2022 13:39:02.

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 1539524 1539524 0 0.00 0 14071 14 0 0
doc_list_biud 1539518 1539518 0 0.00 0 24932 45 0 0
sp_add_invoice_to_stock 5211 5085 126 2.41 49 158705 25797 0 126
sp_cancel_adding_invoice 5370 5370 0 0.00 6 74246 1349 0 0
sp_cancel_client_order 1836 1836 0 0.00 6 15885 486 0 0
sp_cancel_customer_reserve 528535 528535 0 0.00 1 24966 114 0 0
sp_cancel_pay_from_customer 686 686 0 0.00 3 4549 123 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 4 4727 133 0 0
sp_cancel_supplier_invoice 6013 6004 9 0.14 2 23447 1167 0 9
sp_cancel_supplier_order 408 408 0 0.00 9 1540285 189840 0 0
sp_cancel_write_off 9348 9348 0 0.00 2 23747 194 0 0
sp_client_order 21184 21184 0 0.00 5 31328 470 0 0
sp_customer_reserve 480967 480967 0 0.00 0 100079 239 0 0
sp_fill_shopping_cart 32446 32446 0 0.00 1 15458 84 0 0
sp_fill_shopping_cart_clo_res 480935 480935 0 0.00 0 18669 17 0 0
sp_get_clo_for_invoice 5046 5046 0 0.00 0 18227 224 0 0
sp_kill_cost_storno 15808 15808 0 0.00 0 6750 86 0 0
sp_kill_qstorno_ret_qs2qd 1072830 1072830 0 0.00 0 24918 49 0 0
sp_kill_qty_storno 566750 566750 0 0.00 0 24922 113 0 0
sp_lock_selected_doc 21066 21066 0 0.00 0 10556 9 0 0
sp_make_cost_storno 28543 28543 0 0.00 0 13716 79 0 0
sp_multiply_rows_for_pdistr 28542 28542 0 0.00 0 5848 38 0 0
sp_multiply_rows_for_qdistr 21184 21184 0 0.00 0 10142 178 0 0
sp_pay_from_customer 7384 7384 0 0.00 5 13084 174 0 0
sp_pay_to_supplier 5910 5910 0 0.00 6 20724 198 0 0
sp_payment_common 13293 13293 0 0.00 1 20708 50 0 0
sp_qd_handle_on_cancel_clo 1836 1836 0 0.00 1 15876 410 0 0
sp_qd_handle_on_invoice_upd_sts 10416 10416 0 0.00 0 24101 577 0 0
sp_qd_handle_on_reserve_upd_sts 10203 10203 0 0.00 0 24611 74 0 0
sp_reserve_write_off 10203 10203 0 0.00 8 24658 299 0 0
sp_supplier_invoice 5674 5674 0 0.00 9 75003 3180 0 0
sp_supplier_order 5595 5595 0 0.00 7 36446 1438 0 0
srv_aggregate_perf_data 125 125 0 0.00 2356 199555 16059 0 0
srv_fill_mon_cache_memory 76 76 0 0.00 707 129993 29062 0 0
srv_find_qd_qs_mism 1045953 1045953 0 0.00 0 9266 11 0 0
srv_make_invnt_saldo 444 444 0 0.00 41 75687 4845 0 0
srv_make_money_saldo 931 931 0 0.00 1 13075 360 0 0
srv_recalc_idx_stat 4 4 0 0.00 99000 395073 283876 0 0
t$perf-abend:sp_add_invoice_to_stock 126 126 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5086 5086 0 0.00 0 41983 1070 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 2 6452 466 0 0
t$perf-norm:sp_cancel_client_order 1836 1836 0 0.00 0 4386 35 0 0
t$perf-norm:sp_cancel_customer_reserve 656 656 0 0.00 0 5497 43 0 0
t$perf-norm:sp_cancel_pay_from_customer 686 686 0 0.00 0 4805 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 413 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 399 399 0 0.00 0 4192 43 0 0
t$perf-norm:sp_cancel_supplier_order 408 408 0 0.00 0 107481 8311 0 0
t$perf-norm:sp_cancel_write_off 1014 1014 0 0.00 0 795 19 0 0
t$perf-norm:sp_client_order 21184 21184 0 0.00 0 12421 41 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 2674 19 0 0
t$perf-norm:sp_pay_from_customer 7384 7384 0 0.00 0 6960 21 0 0
t$perf-norm:sp_pay_to_supplier 5910 5910 0 0.00 0 8465 21 0 0
t$perf-norm:sp_reserve_write_off 10203 10203 0 0.00 0 18311 26 0 0
t$perf-norm:sp_supplier_invoice 5674 5674 0 0.00 0 5892 37 0 0
t$perf-norm:sp_supplier_order 5595 5595 0 0.00 0 11384 38 0 0
t$perf-norm:srv_aggregate_perf_data 125 125 0 0.00 0 1271 26 0 0
t$perf-norm:srv_fill_mon_cache_memory 76 76 0 0.00 0 573 12 0 0
t$perf-norm:srv_make_invnt_saldo 444 444 0 0.00 0 1524 15 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 1705 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 115 51 0 0
v_add_invoice_to_stock 10340 10088 252 2.43 0 5657 28 0 252
v_all_customers 38981 38981 0 0.00 0 165 4 0 0
v_all_suppliers 31476 31476 0 0.00 0 66 5 0 0
v_all_wares 42364 42364 0 0.00 0 100 4 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 950 16 0 0
v_cancel_client_order 3672 3672 0 0.00 0 5433 14 0 0
v_cancel_customer_prepayment 1372 1372 0 0.00 0 944 9 0 0
v_cancel_customer_reserve 1312 1312 0 0.00 0 3270 16 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 1767 14 0 0
v_cancel_supplier_invoice 816 798 18 2.20 0 2431 27 0 18
v_cancel_supplier_order 816 816 0 0.00 0 1002 14 0 0
v_cancel_write_off 2028 2028 0 0.00 0 2092 27 0 0
v_max_id_clo_ord 5592 5592 0 0.00 0 2218 6 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 1599 14 0 0
v_max_id_ord_sup 5669 5669 0 0.00 0 6865 8 0 0
v_max_non_paid_invoice 1431 1431 0 0.00 0 273 8 0 0
v_max_non_paid_realizn 3747 3747 0 0.00 0 1004 7 0 0
v_min_id_clo_ord 5592 5592 0 0.00 0 1264 8 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 5530 15 0 0
v_min_id_ord_sup 5669 5669 0 0.00 0 5072 10 0 0
v_min_non_paid_invoice 5909 5909 0 0.00 0 20703 20 0 0
v_min_non_paid_realizn 7384 7384 0 0.00 0 5218 16 0 0
v_our_firm 10656 10656 0 0.00 0 68 5 0 0
v_reserve_write_off 20406 20406 0 0.00 0 5435 12 0 0
x_lock_depdocs_on_canc_invoice 10975 10975 0 0.00 0 7934 120 0 0
x_lock_depdocs_on_canc_sup_ord 408 408 0 0.00 0 3461 337 0 0
x_make_qstorno_1000_1200 5595 5595 0 0.00 2 36435 1264 0 0
x_make_qstorno_1200_2000 5674 5674 0 0.00 4 74305 2955 0 0
x_make_qstorno_2100_3300 480967 480967 0 0.00 0 100078 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-07-2022 13:39:03 to 24-07-2022 13:39:07.

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
11:07:23 2 8192000000 8797168096 8807763968 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:24 20934 8192000000 9719126800 10012323840 792362928 8.819 100 92 185 0 822715296 7911360 22441008
11:09:51 21908 8192000000 9753652624 10059427840 829262224 9.192 100 85 170 0 861605072 11859728 20483120
11:11:23 14513 8192000000 9786985376 10105245696 863471776 9.535 100 85 170 0 904659296 21491664 19695856
11:12:42 11902 8192000000 9846673232 10178990080 903746688 9.935 100 82 164 0 952082848 30419248 17916912
11:13:57 6932 8192000000 9905073024 10248040448 951848816 10.409 100 85 170 0 1020387104 50326416 18211872
11:15:08 1450 8192000000 9963422848 10313728000 986034192 10.743 100 94 186 0 1077878720 72404752 19439776
11:16:13 1727 8192000000 9993745376 10350661632 1012343392 10.998 100 92 180 2 1106721360 76452208 17925760
11:17:17 2006 8192000000 10022599712 10388369408 1034525536 11.212 100 92 178 2 1139002480 85707712 18769232
11:18:22 862 8192000000 10032937232 10409041920 1053055312 11.390 100 90 171 1 1149887200 78830000 18001888
11:19:25 972 8192000000 10050613728 10428182528 1062100480 11.477 100 97 190 0 1173928496 92632704 19195312
11:20:28 1229 8192000000 10047038368 10421186560 1063111872 11.486 100 96 179 2 1166424528 85183520 18129136
11:21:32 1147 8192000000 10038093872 10419392512 1068804384 11.541 100 97 177 1 1158571696 71661424 18105888
11:22:36 1237 8192000000 10046413088 10427432960 1073927520 11.590 100 94 178 4 1169143808 77879968 17336320
11:23:39 1060 8192000000 10038984896 10424315904 1079667312 11.644 100 98 194 0 1161533168 63080656 18785200
11:24:41 1143 8192000000 10060526048 10450055168 1084275952 11.688 100 98 187 0 1184862992 82163200 18423840
11:25:43 4453 8192000000 10014885200 10404122624 1086606976 11.710 100 70 130 2 1150805312 52406976 11791360
11:26:49 6884 8192000000 10047663232 10447671296 1097143424 11.811 100 61 113 3 1183733568 75402032 11188112
11:27:58 2463 8192000000 10047861776 10434039808 1098615216 11.824 100 77 151 1 1166692848 53069456 15008176
11:29:03 1195 8192000000 10054173072 10441580544 1089296320 11.736 99 94 172 3 1175658512 68238960 18123232
11:30:05 1064 8192000000 10051071904 10437259264 1096604464 11.805 100 93 172 3 1169987984 55333104 18050416
11:31:07 3597 8192000000 10038141360 10423103488 1078122816 11.630 100 85 164 2 1157477696 63590224 15764656
11:32:13 943 8192000000 10030504944 10414325760 1073196976 11.583 100 95 182 0 1153876384 62969520 17709888
11:33:14 971 8192000000 9996575264 10371297280 1045672352 11.319 100 86 166 0 1119240128 56327568 17240208
11:34:17 1268 8192000000 10016914144 10398543872 1059015008 11.447 100 97 183 2 1136918208 57540368 20362832
11:35:19 3833 8192000000 9983862992 10361061376 1042297696 11.287 99 67 113 4 1103507504 47252784 13957024
11:36:25 6875 8192000000 9974345712 10359021568 1040345184 11.268 100 50 90 1 1101423056 51745328 9332544
11:37:35 2106 8192000000 9982642096 10361393152 1035891920 11.225 99 66 107 2 1103246864 57108784 10246160
11:38:44 2020 8192000000 10024126160 10399387648 1039338256 11.258 98 86 160 1 1126404288 70377328 16688704
11:39:52 3461 8192000000 10044005552 10427777024 1070706256 11.559 100 72 137 0 1166609008 81987808 13914944
11:41:04 7576 8192000000 10047660560 10442649600 1087818784 11.722 100 61 117 2 1167808864 67027248 12962832
11:42:22 4315 8192000000 10085744800 10473144320 1103599648 11.872 100 69 128 2 1210816656 94533024 12683984
11:43:28 704 8192000000 10106020144 10491072512 1109965984 11.932 100 98 192 0 1228302304 96501456 21834864
11:44:33 1479 8192000000 10150295968 10547748864 1120452192 12.031 100 92 172 1 1272901280 133075520 19373568
11:45:40 6305 8192000000 10164560752 10564292608 1138241904 12.199 100 68 130 1 1275032880 123087024 13703952
11:46:56 10839 8192000000 10221773888 10620358656 1155408208 12.360 100 76 152 0 1324742800 152698512 16636080
11:48:12 937 8192000000 10253290688 10651664384 1167426944 12.473 100 98 189 2 1370862256 182165856 21269456
11:49:16 2346 8192000000 10271329440 10693292032 1174820384 12.542 100 93 180 2 1384285456 189659536 19805536
11:50:23 836 8192000000 10304189440 10703355904 1186085888 12.647 100 96 190 0 1417220112 209467008 21667216
11:51:34 6854 8192000000 10333644640 10747527168 1193457664 12.716 100 76 146 5 1448941648 237706784 17777200
11:52:47 989 8192000000 10391704912 10808688640 1202511008 12.800 100 95 180 3 1495854672 273693920 19649744
11:53:52 1208 8192000000 10384411424 10812850176 1208981776 12.860 100 88 174 1 1495521584 265308448 21231360
11:54:57 1172 8192000000 10438090880 10846146560 1208186912 12.852 100 96 187 0 1544518096 314842432 21488752
11:56:01 1710 8192000000 10470129136 10886672384 1208784416 12.858 100 91 175 0 1575892144 346975504 20132224
11:57:05 706 8192000000 10474808368 10903281664 1194263824 12.723 100 97 191 0 1582962736 366755808 21943104
11:58:11 1062 8192000000 10471271248 10928566272 1191349456 12.696 100 96 188 0 1576469536 362393424 22726656
11:59:16 1495 8192000000 10453227648 10886750208 1196139504 12.740 100 87 169 1 1560039968 343415936 20484528
12:00:21 841 8192000000 10485512880 10924146688 1207641904 12.847 100 99 196 0 1589595952 359784224 22169824
12:01:24 1270 8192000000 10512662896 10957152256 1208358464 12.854 100 96 190 1 1610954880 378847984 23748432
12:02:31 1590 8192000000 10521798832 10963689472 1203145776 12.806 100 98 194 1 1630753808 404936064 22671968
12:03:36 1483 8192000000 10569999568 10986209280 1200900208 12.785 100 85 166 1 1672025584 450599280 20526096
12:04:42 1133 8192000000 10607343536 11065344000 1216822080 12.932 100 97 189 0 1709073872 469343280 22908512
12:05:45 1484 8192000000 10635637984 11088027648 1206149008 12.833 100 79 159 0 1735999984 510240208 19610768
12:06:52 14818 8192000000 10604995248 11057709056 1205330064 12.826 99 56 110 2 1694364848 475218000 13816784
12:08:08 8564 8192000000 10627057776 11079872512 1187293552 12.658 99 51 100 0 1718573920 518293424 12986944
12:09:37 8330 8192000000 10572121136 11077099520 1187418848 12.659 100 41 82 0 1691101472 492757744 10924880
12:11:25 8325 8192000000 10483754864 10978304000 1155993104 12.366 100 36 72 0 1578406080 412395056 10017920
12:12:37 50716 8192000000 10248258112 10783985664 1146571920 12.277 99 32 64 0 1451257824 296889376 7796528
12:14:37 33934 8192000000 10187836736 10669821952 1132431552 12.144 99 24 48 0 1277071888 138405216 6235120
12:16:17 32523 8192000000 10083616576 10632802304 1103920336 11.875 97 3 6 0 1179526704 74879712 726656
12:17:50 43772 8192000000 10071133600 10508853248 1111380608 11.945 98 38 69 0 1158156704 39539536 7236560
12:19:36 108936 8192000000 10057376240 10499346432 1123551072 12.061 99 16 31 1 1155761712 28955920 3254720
12:22:29 95463 8192000000 10106387680 10612191232 1126906928 12.092 98 20 40 0 1215158944 84047392 4204624
12:25:06 57261 8192000000 10061520976 10494599168 1111964752 11.951 97 13 25 1 1144347328 29815088 2567488
12:27:05 63365 8192000000 10081876016 10549895168 1138979552 12.206 100 21 42 0 1180276096 36396944 4899600
12:29:13 47354 8192000000 10172139072 10618490880 1133868032 12.158 99 38 72 0 1251133520 109208752 8056736
12:31:02 39283 8192000000 10084153056 10538848256 1154817440 12.355 100 26 46 1 1180248512 20465488 4965584
12:32:42 1429 8192000000 10098015920 10523717632 1143576016 12.249 100 94 177 2 1193139536 29725136 19838384
12:33:46 19052 8192000000 10118559392 10572898304 1144783440 12.261 100 36 70 1 1221071984 68894480 7394064
12:35:34 8808 8192000000 10127632448 10549989376 1114054672 11.971 97 62 121 1 1215288816 87779328 13454816
12:36:50 21410 8192000000 10113817424 10627473408 1116678352 11.996 99 15 30 0 1206683744 87073264 2932128
12:38:12 49811 8192000000 10059816528 10525708288 1127787792 12.101 99 12 22 0 1169360944 39797152 1776000
12:40:02 35876 8192000000 10073680256 10520363008 1115365184 11.983 98 33 66 0 1146444976 24413824 6665968
12:41:38 39235 8192000000 10095428768 10565148672 1140803360 12.223 100 16 32 0 1200476336 56519520 3153456
12:43:17 34579 8192000000 10131480528 10575421440 1147480816 12.286 100 23 45 2 1220057584 67815072 4761696
12:44:52 36141 8192000000 10106052512 10584064000 1142517840 12.239 100 20 40 0 1238968464 92075008 4375616
12:47:01 36332 8192000000 10140213360 10595995648 1130889904 12.130 98 24 48 2 1217810064 81977664 4942496
12:48:43 51224 8192000000 10206949488 10663727104 1141327840 12.228 99 22 43 0 1299219776 153133472 4758464
12:50:35 52043 8192000000 10113858288 10592833536 1151801024 12.326 100 18 36 0 1255871392 99957840 4112528
12:52:28 25075 8192000000 10137179952 10570964992 1145897632 12.271 100 41 82 0 1211832720 57391312 8543776
12:53:58 77293 8192000000 10086091568 10508165120 1092544576 11.767 96 21 42 0 1137794720 40976816 4273328
12:56:27 129993 8192000000 10061988864 10531938304 1133823360 12.157 100 28 56 0 1222518352 82767376 5927616
12:59:42 14086 8192000000 10098849232 10557542400 1111481872 11.946 98 36 69 1 1178315952 58333216 8500864
13:00:57 67695 8192000000 10028541056 10489765888 1075535072 11.605 96 16 32 0 1109502896 30428112 3539712
13:03:19 43454 8192000000 10057323040 10507673600 1110783952 11.940 100 19 37 0 1160149424 45558224 3807248
13:05:06 46906 8192000000 10047317936 10505535488 1068240000 11.535 97 26 52 0 1116663456 42806128 5617328
13:06:53 28691 8192000000 10053606176 10519326720 1099601296 11.834 100 11 22 0 1141570448 39875200 2093952
13:08:23 59486 8192000000 10064991488 10529853440 1113624080 11.967 100 9 18 0 1138480320 23342672 1513568
13:10:23 31407 8192000000 10073162448 10525253632 1108307216 11.916 99 19 38 0 1162886048 50633968 3944864
13:11:58 20631 8192000000 10142473184 10581737472 1136864512 12.186 100 61 114 0 1230291696 81225280 12201904
13:13:20 29965 8192000000 10178583280 10621689856 1144956464 12.262 100 37 74 1 1272341840 119554144 7831232
13:14:57 42632 8192000000 10165247824 10607968256 1144162560 12.255 100 31 62 0 1241510384 90230896 7116928
13:16:43 6495 8192000000 10232240096 10665099264 1147566416 12.287 100 57 114 1 1319491248 159380000 12544832
13:17:50 31070 8192000000 10205560896 10648567808 1149214560 12.302 100 38 75 0 1296214240 139064192 7935488
13:19:21 31559 8192000000 10178608720 10639114240 1099816848 11.836 96 27 52 0 1257781360 151850064 6114448
13:20:53 42648 8192000000 10133917200 10601246720 1149039056 12.300 100 39 76 0 1208489376 51536000 7914320
13:22:38 63553 8192000000 10072528688 10550824960 1112868080 11.960 98 20 40 0 1149544592 32652912 4023600
13:24:48 72560 8192000000 10114783040 10557935616 1098542224 11.824 96 36 72 0 1169290336 63257952 7490160
13:27:23 66996 8192000000 10084717264 10555764736 1109978640 11.932 98 8 16 0 1174379440 62670544 1730256
13:29:50 64517 8192000000 10073693520 10561265664 1110675168 11.939 98 24 47 0 1157750672 41982672 5092832
13:32:01 69153 8192000000 10085964864 10526322688 1087761104 11.721 97 16 30 0 1128390480 37018000 3611376
13:34:11 50263 8192000000 10116299312 10574897152 1107388736 11.908 98 22 44 0 1188829936 76736320 4704880
13:36:02 27819 8192000000 10155598688 10596413440 1118388912 12.012 98 42 78 0 1226037712 98338240 9310560

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 24-07-2022 13:39:08 to 24-07-2022 13:39:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 126 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-07-2022 13:39:26 to 24-07-2022 13:39:28.

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          217173
MON$OLDEST_ACTIVE               222357
MON$OLDEST_SNAPSHOT             222357
MON$NEXT_TRANSACTION            222357
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                       2083680
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2807 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2807 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2807 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2807 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 ---
:56: 
:57: mscs-test-161.budzdorov.ru    Sun Jul 24 13:37:21 2022
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
:60: 
:61: mscs-test-161.budzdorov.ru    Sun Jul 24 13:37:31 2022
:62:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:63: 
--- end of diff output ---

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

24.07.22 13:39:29. 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-24 13:39:31 No severe PSQL-related problems occured
24.07.22 13:39:33. End of report.