Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 21.06.2022 07:47
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 15653348 kB
MemAvailable 31200444 kB
Buffers 0 kB
Cached 16005760 kB
SwapCached 2476 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-06-21 07:46:58
DTS_END                         2022-06-21 07:46:58.1680
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/56334
TRN_ID                          236082
ATT_ID                          699
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: 9436.91 29562 1132429
customer order: creation 185.59 440 22271
customer order: refuse 15.80 410 1896
order to supplier: creation 50.03 1316 6004
order to supplier: removal 3.38 178920 405
invoice (draft): creation 48.63 2863 5835
invoice (draft): removal 51.54 1022 6185
invoice accept: apply 44.97 25310 5396
invoice accept: cancel 46.83 1175 5619
customer reserve: creation 4123.85 244 494862
customer reserve: removal 4547.28 104 545674
realization accept: apply 90.43 263 10851
realization accept: cancel 86.94 180 10433
payment from customer: creation 64.68 165 7762
payment from customer: removal 6.50 112 780
payment to supplier: creation 52.71 184 6325
payment to supplier: removal 3.80 139 456
service: total inventory turnovers 4.28 4143 513
service: total monetary turnovers 8.66 333 1039
service: aggregate perf. data 0.98 18125 118
service: refresh index statistics 0.04 355784 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 21-06-2022 07:47:51 to 21-06-2022 07:47:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3577
TEST_TIME 2 4043
TEST_TIME 3 4894
TEST_TIME 4 5924
TEST_TIME 5 6070
TEST_TIME 6 5724
TEST_TIME 7 5619
TEST_TIME 8 5699
TEST_TIME 9 6302
TEST_TIME 10 6693
TEST_TIME 11 6696
TEST_TIME 12 6590
TEST_TIME 13 6894
TEST_TIME 14 7242
TEST_TIME 15 7173
TEST_TIME 16 7277
TEST_TIME 17 7674
TEST_TIME 18 7747
TEST_TIME 19 7894
TEST_TIME 20 8045
TEST_TIME 21 8189
TEST_TIME 22 8267
TEST_TIME 23 8330
TEST_TIME 24 8353
TEST_TIME 25 8368
TEST_TIME 26 8468
TEST_TIME 27 8545
TEST_TIME 28 8683
TEST_TIME 29 9185
TEST_TIME 30 9346
TEST_TIME 31 9411
TEST_TIME 32 10002
TEST_TIME 33 10465
TEST_TIME 34 11257
TEST_TIME 35 11145
TEST_TIME 36 11189
TEST_TIME 37 10955
TEST_TIME 38 10822
TEST_TIME 39 10979
TEST_TIME 40 10902
TEST_TIME 41 10970
TEST_TIME 42 10969
TEST_TIME 43 10996
TEST_TIME 44 10842
TEST_TIME 45 10640
TEST_TIME 46 10465
TEST_TIME 47 10375
TEST_TIME 48 10255
TEST_TIME 49 10145
TEST_TIME 50 10095
TEST_TIME 51 10097
TEST_TIME 52 9988
TEST_TIME 53 10010
TEST_TIME 54 9949
TEST_TIME 55 10027
TEST_TIME 56 10090
TEST_TIME 57 9942
TEST_TIME 58 9950
TEST_TIME 59 10001
TEST_TIME 60 10104
TEST_TIME 61 10036
TEST_TIME 62 10048
TEST_TIME 63 10098
TEST_TIME 64 10084
TEST_TIME 65 10106
TEST_TIME 66 10060
TEST_TIME 67 10097
TEST_TIME 68 10108
TEST_TIME 69 10073
TEST_TIME 70 10098
TEST_TIME 71 9987
TEST_TIME 72 9910
TEST_TIME 73 9877
TEST_TIME 74 9832
TEST_TIME 75 9824
TEST_TIME 76 9838
TEST_TIME 77 9765
TEST_TIME 78 9685
TEST_TIME 79 9696
TEST_TIME 80 9683
TEST_TIME 81 9647
TEST_TIME 82 9634
TEST_TIME 83 9625
TEST_TIME 84 9598
TEST_TIME 85 9591
TEST_TIME 86 9549
TEST_TIME 87 9525
TEST_TIME 88 9516
TEST_TIME 89 9509
TEST_TIME 90 9495
TEST_TIME 91 9642
TEST_TIME 92 9868
TEST_TIME 93 9855
TEST_TIME 94 9870
TEST_TIME 95 9847
TEST_TIME 96 9804
TEST_TIME 97 9728
TEST_TIME 98 9702
TEST_TIME 99 9649
TEST_TIME 100 9608
TEST_TIME 101 9647
TEST_TIME 102 9626
TEST_TIME 103 9626
TEST_TIME 104 9578
TEST_TIME 105 9529
TEST_TIME 106 9474
TEST_TIME 107 9471
TEST_TIME 108 9411
TEST_TIME 109 9392
TEST_TIME 110 9392
TEST_TIME 111 9379
TEST_TIME 112 9413
TEST_TIME 113 9409
TEST_TIME 114 9409
TEST_TIME 115 9434
TEST_TIME 116 9440
TEST_TIME 117 9452
TEST_TIME 118 9421
TEST_TIME 119 9428
TEST_TIME 120 9460

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 21-06-2022 07:47:54 to 21-06-2022 07:47:58.

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 1587940 1587940 0 0.00 0 16414 14 0 0
doc_list_biud 1587927 1587927 0 0.00 0 40823 42 0 0
sp_add_invoice_to_stock 5622 5396 226 4.01 58 206394 25311 0 226
sp_cancel_adding_invoice 5619 5619 0 0.00 7 107127 1175 0 0
sp_cancel_client_order 1896 1896 0 0.00 5 9270 410 0 0
sp_cancel_customer_reserve 545674 545674 0 0.00 1 40831 105 0 0
sp_cancel_pay_from_customer 780 780 0 0.00 3 5929 112 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 4 7256 140 0 0
sp_cancel_supplier_invoice 6201 6185 16 0.25 3 18130 1022 0 16
sp_cancel_supplier_order 405 405 0 0.00 9 1484863 178921 0 0
sp_cancel_write_off 10433 10433 0 0.00 2 13277 181 0 0
sp_client_order 22271 22271 0 0.00 6 26193 441 0 0
sp_customer_reserve 494862 494862 0 0.00 0 76699 245 0 0
sp_fill_shopping_cart 34089 34089 0 0.00 2 6833 81 0 0
sp_fill_shopping_cart_clo_res 494830 494830 0 0.00 0 15211 17 0 0
sp_get_clo_for_invoice 5354 5354 0 0.00 0 34530 219 0 0
sp_kill_cost_storno 17288 17288 0 0.00 0 10875 72 0 0
sp_kill_qstorno_ret_qs2qd 1108371 1108371 0 0.00 0 40816 45 0 0
sp_kill_qty_storno 586414 586414 0 0.00 0 40822 104 0 0
sp_lock_selected_doc 22482 22482 0 0.00 0 8728 7 0 0
sp_make_cost_storno 30292 30292 0 0.00 0 16385 74 0 0
sp_multiply_rows_for_pdistr 30292 30292 0 0.00 0 8914 34 0 0
sp_multiply_rows_for_qdistr 22270 22270 0 0.00 0 10105 159 0 0
sp_pay_from_customer 7762 7762 0 0.00 5 8948 166 0 0
sp_pay_to_supplier 6325 6325 0 0.00 7 16467 185 0 0
sp_payment_common 14086 14086 0 0.00 2 5964 46 0 0
sp_qd_handle_on_cancel_clo 1896 1896 0 0.00 1 9262 327 0 0
sp_qd_handle_on_invoice_upd_sts 10973 10973 0 0.00 0 9442 535 0 0
sp_qd_handle_on_reserve_upd_sts 10848 10848 0 0.00 0 26279 66 0 0
sp_reserve_write_off 10851 10851 0 0.00 9 26575 264 0 0
sp_supplier_invoice 5835 5835 0 0.00 10 83847 2863 0 0
sp_supplier_order 6004 6004 0 0.00 6 17855 1317 0 0
srv_aggregate_perf_data 118 118 0 0.00 2783 285363 18126 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 819 151332 31722 0 0
srv_find_qd_qs_mism 1079532 1079532 0 0.00 0 15632 12 0 0
srv_make_invnt_saldo 513 513 0 0.00 73 94202 4143 0 0
srv_make_money_saldo 1039 1039 0 0.00 1 11639 333 0 0
srv_recalc_idx_stat 5 5 0 0.00 286955 428759 355784 0 0
t$perf-abend:sp_add_invoice_to_stock 226 226 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5399 5399 0 0.00 1 14983 953 0 0
t$perf-norm:sp_cancel_adding_invoice 439 439 0 0.00 1 12701 410 0 0
t$perf-norm:sp_cancel_client_order 1896 1896 0 0.00 0 2004 24 0 0
t$perf-norm:sp_cancel_customer_reserve 768 768 0 0.00 0 937 22 0 0
t$perf-norm:sp_cancel_pay_from_customer 780 780 0 0.00 0 4413 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 5284 23 0 0
t$perf-norm:sp_cancel_supplier_invoice 461 461 0 0.00 0 578 29 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 1 88616 7343 0 0
t$perf-norm:sp_cancel_write_off 1080 1080 0 0.00 0 7143 30 0 0
t$perf-norm:sp_client_order 22272 22272 0 0.00 0 6220 33 0 0
t$perf-norm:sp_customer_reserve 1086 1086 0 0.00 0 1148 15 0 0
t$perf-norm:sp_pay_from_customer 7762 7762 0 0.00 0 4669 18 0 0
t$perf-norm:sp_pay_to_supplier 6325 6325 0 0.00 0 7303 19 0 0
t$perf-norm:sp_reserve_write_off 10851 10851 0 0.00 0 8653 23 0 0
t$perf-norm:sp_supplier_invoice 5835 5835 0 0.00 0 12971 31 0 0
t$perf-norm:sp_supplier_order 6004 6004 0 0.00 0 9843 30 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 113 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 86 5 0 0
t$perf-norm:srv_make_invnt_saldo 513 513 0 0.00 0 655 18 0 0
t$perf-norm:srv_make_money_saldo 1039 1039 0 0.00 0 7778 13 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 244 65 0 0
v_add_invoice_to_stock 11149 10697 452 4.05 0 7187 29 0 452
v_all_customers 40458 40458 0 0.00 0 84 4 0 0
v_all_suppliers 33044 33044 0 0.00 0 94 4 0 0
v_all_wares 44522 44522 0 0.00 0 92 4 0 0
v_cancel_adding_invoice 878 878 0 0.00 0 176 12 0 0
v_cancel_client_order 3792 3792 0 0.00 0 2560 12 0 0
v_cancel_customer_prepayment 1560 1560 0 0.00 0 2130 11 0 0
v_cancel_customer_reserve 1536 1536 0 0.00 0 538 10 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 7203 26 0 0
v_cancel_supplier_invoice 954 922 32 3.35 0 612 25 0 32
v_cancel_supplier_order 810 810 0 0.00 0 6552 26 0 0
v_cancel_write_off 2160 2160 0 0.00 0 4563 29 0 0
v_max_id_clo_ord 5998 5998 0 0.00 0 3088 6 0 0
v_max_id_clo_res 1086 1086 0 0.00 0 4180 19 0 0
v_max_id_ord_sup 5825 5825 0 0.00 0 3309 6 0 0
v_max_non_paid_invoice 1624 1624 0 0.00 0 127 8 0 0
v_max_non_paid_realizn 4059 4059 0 0.00 0 880 8 0 0
v_min_id_clo_ord 5998 5998 0 0.00 0 2849 8 0 0
v_min_id_clo_res 1086 1086 0 0.00 0 6230 19 0 0
v_min_id_ord_sup 5825 5825 0 0.00 0 6038 11 0 0
v_min_non_paid_invoice 6324 6324 0 0.00 0 5608 17 0 0
v_min_non_paid_realizn 7760 7760 0 0.00 0 5957 18 0 0
v_our_firm 11468 11468 0 0.00 0 85 4 0 0
v_reserve_write_off 21693 21693 0 0.00 0 3537 9 0 0
x_lock_depdocs_on_canc_invoice 11343 11343 0 0.00 0 31439 118 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 7974 357 0 0
x_make_qstorno_1000_1200 6004 6004 0 0.00 2 17833 1127 0 0
x_make_qstorno_1200_2000 5835 5835 0 0.00 4 81066 2678 0 0
x_make_qstorno_2100_3300 494861 494861 0 0.00 0 76698 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 21-06-2022 07:47:59 to 21-06-2022 07:48:03.

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:17:02 3 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:18:02 16981 8192000000 9714888656 10013851648 797034048 8.866 100 86 172 1 825064688 7268112 20762528
05:19:23 16995 8192000000 9750909824 10055659520 825391968 9.153 100 90 180 0 859272288 11882000 21998320
05:20:46 14245 8192000000 9787602880 10103099392 855324496 9.453 100 88 176 0 894590896 18661920 20604480
05:22:09 6551 8192000000 9869485408 10199269376 915886784 10.055 100 83 165 1 980879392 46680512 18312096
05:23:19 7307 8192000000 9925539584 10265640960 950606032 10.397 100 90 181 0 1037033376 67560896 18866448
05:24:31 2447 8192000000 9956243392 10308698112 988245600 10.764 100 91 178 0 1067760000 60875200 18639200
05:25:35 1699 8192000000 10003783712 10364960768 1017071136 11.044 100 94 178 1 1124475488 90124656 17279696
05:26:39 1643 8192000000 10024619664 10393116672 1043775088 11.301 100 92 181 1 1139296384 77089600 18431696
05:27:42 1006 8192000000 10011253296 10386804736 1053672160 11.396 100 94 181 1 1135040192 62488832 18879200
05:28:46 1315 8192000000 10012603136 10386702336 1060804320 11.464 100 87 157 3 1135386368 58079648 16502400
05:29:50 1826 8192000000 10055121552 10436911104 1068811280 11.541 100 90 172 0 1176766704 91398768 16556656
05:30:54 1252 8192000000 10052886848 10431791104 1069355616 11.546 100 98 190 0 1175232064 86922976 18953472
05:31:58 1100 8192000000 10048556048 10435325952 1077988176 11.628 100 96 196 0 1173148784 75641344 19519264
05:33:01 1094 8192000000 10061673360 10447175680 1083548528 11.681 100 97 184 0 1179267888 77369648 18349712
05:34:04 1275 8192000000 10077847840 10464243712 1081760096 11.664 100 96 185 1 1199697472 99874176 18063200
05:35:06 1189 8192000000 10059754560 10443849728 1085690688 11.702 100 95 182 2 1179627504 75671376 18265440
05:36:08 1262 8192000000 10080603664 10469470208 1095068080 11.791 100 99 179 2 1200204560 86582448 18554032
05:37:10 1259 8192000000 10066439584 10455117824 1093279104 11.774 100 96 188 3 1183169312 70272112 19618096
05:38:12 1110 8192000000 10046191984 10433679360 1082318288 11.670 100 94 181 1 1171503648 71240656 17944704
05:39:14 1271 8192000000 10068165664 10455285760 1089662192 11.739 100 96 186 1 1190373632 81876096 18835344
05:40:16 1129 8192000000 10044680176 10426703872 1072531792 11.576 99 97 188 2 1165601200 73241616 19827792
05:41:20 1409 8192000000 10036984416 10418257920 1071697280 11.568 100 97 187 2 1157543216 67293472 18552464
05:42:22 1789 8192000000 10007894256 10384355328 1049032224 11.351 100 86 163 1 1123145968 57249216 16864528
05:43:25 1628 8192000000 9994560864 10366652416 1047781056 11.339 100 96 186 2 1115204832 47899664 19524112
05:44:28 1149 8192000000 9993908320 10370301952 1035293856 11.219 100 87 154 2 1110777024 60194128 15289040
05:45:30 1355 8192000000 10005252032 10388434944 1046811328 11.330 100 93 173 4 1132084992 66931152 18342512
05:46:32 933 8192000000 10001544208 10376200192 1034187728 11.209 100 88 169 3 1126618112 73769440 18660944
05:47:39 5100 8192000000 9992373824 10367586304 1045224928 11.315 100 79 146 2 1115834432 55986368 14623136
05:48:48 1626 8192000000 10018270944 10393575424 1065525680 11.509 100 82 151 4 1135030912 53098272 16406960
05:49:53 1296 8192000000 10056960016 10437169152 1084725472 11.692 100 98 189 2 1176200528 71813312 19661744
05:50:56 1358 8192000000 10088042176 10471182336 1097920672 11.818 100 79 154 0 1212319376 98674112 15724592
05:52:12 20464 8192000000 10117115728 10510901248 1099986592 11.838 99 62 124 0 1229862112 117166032 12709488
05:53:40 17663 8192000000 10166877504 10573586432 1122682912 12.052 100 75 145 1 1272856528 135162096 15011520
05:55:27 1282 8192000000 10183365568 10580807680 1140874192 12.224 100 82 149 3 1297999168 140971408 16153568
05:56:30 924 8192000000 10227449360 10626383872 1146110512 12.273 100 92 176 0 1337181600 171679536 19391552
05:57:35 3614 8192000000 10248153824 10654420992 1153542448 12.343 100 73 129 3 1364434096 196239296 14652352
05:58:47 2238 8192000000 10294401072 10704007168 1165879248 12.458 100 86 172 1 1415228032 230520992 18827792
05:59:54 2392 8192000000 10311151216 10725429248 1180917184 12.599 100 89 175 0 1419466320 219560912 18988224
06:01:00 1669 8192000000 10334282592 10733473792 1190809568 12.691 100 88 168 2 1434088672 224210896 19068208
06:02:04 810 8192000000 10370972864 10790436864 1189488080 12.679 100 95 184 0 1477053504 266482496 21082928
06:03:08 2298 8192000000 10397954768 10841436160 1197553136 12.754 100 95 190 1 1504096144 284240480 22302528
06:04:13 2091 8192000000 10424563808 10835787776 1182534384 12.614 100 93 179 0 1530327104 327111120 20681600
06:05:19 1386 8192000000 10446499968 10858696704 1193896272 12.720 100 96 185 0 1559029344 342087776 23045296
06:06:23 1288 8192000000 10441293216 10881916928 1206747216 12.839 100 94 186 3 1550806720 322610672 21448832
06:07:27 915 8192000000 10451645136 10865995776 1180143920 12.592 100 97 189 0 1555428896 353232080 22052896
06:08:29 3454 8192000000 10502841456 10922000384 1200694608 12.783 100 74 145 1 1615409920 397701584 17013728
06:09:35 1688 8192000000 10547468736 10975223808 1197085264 12.749 100 97 192 0 1649536336 429032288 23418784
06:10:39 1200 8192000000 10533737728 10962812928 1189085952 12.675 100 86 172 1 1642058096 429459168 23512976
06:11:44 1300 8192000000 10594413488 11012329472 1195135296 12.731 100 86 170 0 1695096448 479683648 20277504
06:12:55 3220 8192000000 10590403568 11044356096 1193400176 12.715 100 65 130 0 1692543104 482364272 16778656
06:14:04 3275 8192000000 10591482368 11066626048 1191650192 12.699 99 73 146 0 1694459200 483917456 18891552
06:15:10 8881 8192000000 10572090416 11046293504 1184520160 12.632 99 50 98 1 1669474368 471925808 13028400
06:16:51 38418 8192000000 10534789472 11038285824 1153243056 12.340 98 33 66 1 1623933328 461992672 8697600
06:18:49 19004 8192000000 10401140928 10905837568 1144772432 12.260 100 29 58 0 1519039296 366344464 7922400
06:20:10 15638 8192000000 10143091200 10598981632 1124845728 12.073 99 24 44 3 1247415728 117360320 5209680
06:21:55 29564 8192000000 10095399360 10552082432 1136825472 12.186 99 17 34 0 1217609872 76860080 3924320
06:23:54 33837 8192000000 10133756048 10567733248 1131810432 12.138 99 43 85 1 1228291424 86847680 9633312
06:25:29 65508 8192000000 10131751312 10581139456 1133595840 12.155 99 13 26 1 1203565184 67253936 2715408
06:27:35 151332 8192000000 10068976672 10579808256 1070248224 11.554 93 19 34 1 1138022608 64849616 2924768
06:31:07 75631 8192000000 10060652432 10497867776 1117165824 12.000 98 28 56 0 1151317536 27292240 6859472
06:33:34 29069 8192000000 10134906400 10572349440 1138632704 12.203 99 49 96 1 1220267424 71557776 10076944
06:35:48 1840 8192000000 10133662736 10553704448 1133674816 12.156 98 86 168 2 1229296992 76004352 19617824
06:36:51 34007 8192000000 10167838832 10617622528 1143103488 12.245 99 37 72 1 1278696048 127088496 8504064
06:38:29 25416 8192000000 10224010032 10656153600 1153452784 12.342 100 61 119 1 1315014400 147735968 13825648
06:40:20 15575 8192000000 10170420656 10663268352 1117818624 12.006 99 20 39 0 1281368480 158520608 5029248
06:42:00 118123 8192000000 10153664000 10617290752 1116384240 11.993 97 14 28 1 1192871632 73936448 2550944
06:44:58 53142 8192000000 10142322512 10644008960 1133240880 12.152 99 17 34 1 1257744032 119997280 4505872
06:46:52 38842 8192000000 10123790704 10566148096 1124995872 12.074 98 18 36 0 1189254400 60446448 3812080
06:48:32 44232 8192000000 10083181216 10564616192 1106202112 11.896 97 42 82 1 1189001264 73955296 8843856
06:50:20 55929 8192000000 10127447344 10592174080 1127038800 12.093 99 26 52 0 1176172624 44156176 4977648
06:52:46 61543 8192000000 10064518832 10519425024 1104318096 11.879 97 14 28 0 1150030080 42726768 2985216
06:54:49 61898 8192000000 10107017072 10549493760 1122698560 12.052 98 27 54 0 1185888208 57315248 5874400
06:56:52 109360 8192000000 10111304688 10550190080 1111348176 11.945 97 26 52 0 1161547216 44817424 5381616
07:00:17 64915 8192000000 10091783840 10536976384 1113810816 11.968 97 22 44 0 1193316976 75076864 4429296
07:02:23 59906 8192000000 10088613984 10570596352 1117634096 12.005 98 16 32 0 1200534160 79966880 2933184
07:04:31 68004 8192000000 10082358496 10585894912 1139950944 12.215 100 12 25 1 1221047360 78013728 3082688
07:06:39 50638 8192000000 10084754224 10546470912 1109545984 11.928 97 26 52 0 1156221664 41146544 5529136
07:08:31 17847 8192000000 10112255968 10552840192 1146608176 12.278 100 33 67 2 1215719824 61831376 7280272
07:09:51 45088 8192000000 10166970064 10622607360 1158515584 12.389 100 26 52 1 1258367248 93758352 6093312
07:11:37 37751 8192000000 10200202192 10651930624 1146016048 12.272 98 26 52 0 1285312288 133063280 6232960
07:13:15 19773 8192000000 10272764192 10714542080 1156206608 12.368 99 40 80 1 1351941616 186432736 9302272
07:14:35 28970 8192000000 10275922240 10722893824 1146653872 12.278 98 30 60 1 1361763136 207717712 7391552
07:16:10 6997 8192000000 10347472288 10780299264 1164618496 12.447 100 55 101 4 1430803808 254677856 11507456
07:17:22 47890 8192000000 10232392848 10720518144 1128204784 12.104 99 21 42 0 1361470304 227783760 5481760
07:19:23 41403 8192000000 10093338512 10515582976 1084340480 11.689 96 37 73 0 1122435216 30904976 7189760
07:21:10 27360 8192000000 10061027056 10486460416 1122769872 12.053 100 36 70 2 1160857952 31032784 7055296
07:22:42 82902 8192000000 10082377632 10500571136 1096900736 11.808 97 47 90 0 1134883776 27702192 10280848
07:25:30 33624 8192000000 10094912112 10537512960 1124229776 12.067 100 33 64 0 1191984688 61421792 6333120
07:27:05 53323 8192000000 10072481648 10554580992 1120285520 12.030 99 39 79 2 1209337568 80873264 8178784
07:29:00 45300 8192000000 10055391632 10506371072 1096745040 11.807 97 15 30 0 1133312080 33570048 2996992
07:30:45 67266 8192000000 10057780096 10506878976 1118052240 12.009 99 16 32 0 1144422880 23172512 3198128
07:33:13 66810 8192000000 10035745056 10519986176 1113063440 11.961 98 9 18 0 1156960704 42174512 1722752
07:35:20 18164 8192000000 10080121824 10518700032 1131285280 12.133 99 42 83 2 1170550912 31196624 8069008
07:36:40 52872 8192000000 10112382064 10564194304 1111536992 11.947 97 28 56 0 1174505216 57050416 5917808
07:38:33 41177 8192000000 10090728176 10559066112 1120229728 12.029 98 23 45 1 1216306240 91149008 4927504
07:40:20 32551 8192000000 10116883360 10564186112 1122955952 12.055 99 42 84 1 1205079840 73598752 8525136
07:42:21 43908 8192000000 10113306192 10596610048 1139804992 12.214 100 31 62 0 1210581584 64788256 5988336
07:44:11 11839 8192000000 10095519168 10527707136 1129999120 12.121 99 47 90 1 1171375760 32339312 9037328
07:45:31 48129 8192000000 10154713632 10618892288 1132528048 12.145 99 40 78 3 1240567504 99879520 8159936

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 21-06-2022 07:48:05 to 21-06-2022 07:48:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 226 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 21-06-2022 07:48:22 to 21-06-2022 07:48:24.

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          235957
MON$OLDEST_ACTIVE               237346
MON$OLDEST_SNAPSHOT             237346
MON$NEXT_TRANSACTION            237346
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                       2094848
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2782 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2782 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2782 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2782 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Tue Jun 21 07:47:09 2022
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Tue Jun 21 07:47:09 2022
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Tue Jun 21 07:47:09 2022
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

21.06.22 07:48:25. 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-06-21 07:48:28 No severe PSQL-related problems occured
21.06.22 07:48:29. End of report.