Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.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 16125268 kB
MemAvailable 31148648 kB
Buffers 0 kB
Cached 15620948 kB
SwapCached 2520 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-11 13:37:14
DTS_END                         2022-07-11 13:37:14.9640
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/50752
TRN_ID                          209360
ATT_ID                          582
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: 8717.17 26882 1046060
customer order: creation 156.89 546 18827
customer order: refuse 12.99 394 1559
order to supplier: creation 42.32 1372 5078
order to supplier: removal 3.23 172209 387
invoice (draft): creation 41.53 3424 4984
invoice (draft): removal 51.18 907 6141
invoice accept: apply 38.72 26308 4646
invoice accept: cancel 46.03 1094 5524
customer reserve: creation 3577.10 250 429252
customer reserve: removal 4473.01 99 536761
realization accept: apply 76.00 295 9120
realization accept: cancel 78.59 151 9431
payment from customer: creation 54.95 192 6594
payment from customer: removal 5.24 134 629
payment to supplier: creation 44.32 216 5318
payment to supplier: removal 3.24 147 389
service: total inventory turnovers 3.87 3976 464
service: total monetary turnovers 7.01 375 841
service: aggregate perf. data 0.93 13469 111
service: refresh index statistics 0.03 312088 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-07-2022 13:39:12 to 11-07-2022 13:39:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 11704
TEST_TIME 2 9161
TEST_TIME 3 7756
TEST_TIME 4 7492
TEST_TIME 5 6812
TEST_TIME 6 6622
TEST_TIME 7 6411
TEST_TIME 8 6807
TEST_TIME 9 6832
TEST_TIME 10 7085
TEST_TIME 11 7208
TEST_TIME 12 7242
TEST_TIME 13 7274
TEST_TIME 14 7449
TEST_TIME 15 7468
TEST_TIME 16 7524
TEST_TIME 17 7369
TEST_TIME 18 7479
TEST_TIME 19 7497
TEST_TIME 20 7556
TEST_TIME 21 7597
TEST_TIME 22 7555
TEST_TIME 23 8156
TEST_TIME 24 8371
TEST_TIME 25 8400
TEST_TIME 26 8530
TEST_TIME 27 8737
TEST_TIME 28 9412
TEST_TIME 29 9614
TEST_TIME 30 10203
TEST_TIME 31 10900
TEST_TIME 32 10693
TEST_TIME 33 10405
TEST_TIME 34 10436
TEST_TIME 35 10478
TEST_TIME 36 10387
TEST_TIME 37 10252
TEST_TIME 38 10103
TEST_TIME 39 10033
TEST_TIME 40 9993
TEST_TIME 41 9939
TEST_TIME 42 9905
TEST_TIME 43 9812
TEST_TIME 44 9742
TEST_TIME 45 9543
TEST_TIME 46 9447
TEST_TIME 47 9357
TEST_TIME 48 9246
TEST_TIME 49 9178
TEST_TIME 50 9081
TEST_TIME 51 8983
TEST_TIME 52 8955
TEST_TIME 53 8978
TEST_TIME 54 9190
TEST_TIME 55 9229
TEST_TIME 56 9190
TEST_TIME 57 9166
TEST_TIME 58 9150
TEST_TIME 59 9140
TEST_TIME 60 9155
TEST_TIME 61 9172
TEST_TIME 62 9104
TEST_TIME 63 9201
TEST_TIME 64 9282
TEST_TIME 65 9212
TEST_TIME 66 9214
TEST_TIME 67 9309
TEST_TIME 68 9289
TEST_TIME 69 9324
TEST_TIME 70 9278
TEST_TIME 71 9180
TEST_TIME 72 9171
TEST_TIME 73 9236
TEST_TIME 74 9139
TEST_TIME 75 9130
TEST_TIME 76 9050
TEST_TIME 77 9030
TEST_TIME 78 8996
TEST_TIME 79 8925
TEST_TIME 80 8881
TEST_TIME 81 8840
TEST_TIME 82 8844
TEST_TIME 83 8823
TEST_TIME 84 8919
TEST_TIME 85 8989
TEST_TIME 86 9118
TEST_TIME 87 9132
TEST_TIME 88 9096
TEST_TIME 89 9089
TEST_TIME 90 9164
TEST_TIME 91 9198
TEST_TIME 92 9173
TEST_TIME 93 9110
TEST_TIME 94 9040
TEST_TIME 95 9042
TEST_TIME 96 9025
TEST_TIME 97 9016
TEST_TIME 98 8962
TEST_TIME 99 8920
TEST_TIME 100 8983
TEST_TIME 101 8950
TEST_TIME 102 8940
TEST_TIME 103 8925
TEST_TIME 104 8959
TEST_TIME 105 9035
TEST_TIME 106 9015
TEST_TIME 107 9044
TEST_TIME 108 9149
TEST_TIME 109 9107
TEST_TIME 110 9065
TEST_TIME 111 9003
TEST_TIME 112 8983
TEST_TIME 113 8994
TEST_TIME 114 8964
TEST_TIME 115 8929
TEST_TIME 116 8894
TEST_TIME 117 8867
TEST_TIME 118 8802
TEST_TIME 119 8790
TEST_TIME 120 8747

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-07-2022 13:39:16 to 11-07-2022 13:39:19.

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 1441953 1441953 0 0.00 0 21479 13 0 0
doc_list_biud 1441944 1441944 0 0.00 0 39351 42 0 0
sp_add_invoice_to_stock 4710 4646 64 1.35 66 198216 26309 0 64
sp_cancel_adding_invoice 5524 5524 0 0.00 6 81977 1095 0 0
sp_cancel_client_order 1559 1559 0 0.00 5 9340 394 0 0
sp_cancel_customer_reserve 536761 536761 0 0.00 1 39353 99 0 0
sp_cancel_pay_from_customer 629 629 0 0.00 4 4805 134 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 4 4862 148 0 0
sp_cancel_supplier_invoice 6150 6141 9 0.14 3 16164 908 0 9
sp_cancel_supplier_order 387 387 0 0.00 17 1234568 172209 0 0
sp_cancel_write_off 9431 9431 0 0.00 2 9659 151 0 0
sp_client_order 18827 18827 0 0.00 5 43690 547 0 0
sp_customer_reserve 429252 429252 0 0.00 1 66873 250 0 0
sp_fill_shopping_cart 28887 28887 0 0.00 1 23250 85 0 0
sp_fill_shopping_cart_clo_res 429210 429210 0 0.00 0 15260 22 0 0
sp_get_clo_for_invoice 4592 4592 0 0.00 0 37021 370 0 0
sp_kill_cost_storno 15973 15973 0 0.00 0 19031 71 0 0
sp_kill_qstorno_ret_qs2qd 1089481 1089481 0 0.00 0 39290 43 0 0
sp_kill_qty_storno 573513 573513 0 0.00 0 39351 98 0 0
sp_lock_selected_doc 18975 18975 0 0.00 0 7665 6 0 0
sp_make_cost_storno 25624 25624 0 0.00 0 21477 89 0 0
sp_multiply_rows_for_pdistr 25622 25622 0 0.00 0 6783 42 0 0
sp_multiply_rows_for_qdistr 18827 18827 0 0.00 0 41445 211 0 0
sp_pay_from_customer 6594 6594 0 0.00 5 21789 192 0 0
sp_pay_to_supplier 5318 5318 0 0.00 6 21484 217 0 0
sp_payment_common 11912 11912 0 0.00 2 21753 54 0 0
sp_qd_handle_on_cancel_clo 1559 1559 0 0.00 0 9330 328 0 0
sp_qd_handle_on_invoice_upd_sts 10114 10114 0 0.00 0 28645 488 0 0
sp_qd_handle_on_reserve_upd_sts 9120 9120 0 0.00 0 21251 72 0 0
sp_reserve_write_off 9120 9120 0 0.00 8 21337 295 0 0
sp_supplier_invoice 4984 4984 0 0.00 10 101694 3425 0 0
sp_supplier_order 5078 5078 0 0.00 7 31827 1373 0 0
srv_aggregate_perf_data 111 111 0 0.00 2485 121664 13470 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 913 152166 40736 0 0
srv_find_qd_qs_mism 1001275 1001275 0 0.00 0 12866 9 0 0
srv_make_invnt_saldo 464 464 0 0.00 61 63653 3976 0 0
srv_make_money_saldo 841 841 0 0.00 1 30103 375 0 0
srv_recalc_idx_stat 4 4 0 0.00 41426 519366 312089 0 0
t$perf-abend:sp_add_invoice_to_stock 64 64 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 4652 4652 0 0.00 1 15759 943 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 6526 468 0 0
t$perf-norm:sp_cancel_client_order 1559 1559 0 0.00 0 1075 24 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 2077 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 629 629 0 0.00 0 998 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 1756 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 341 341 0 0.00 0 4043 44 0 0
t$perf-norm:sp_cancel_supplier_order 387 387 0 0.00 1 122478 5807 0 0
t$perf-norm:sp_cancel_write_off 904 904 0 0.00 0 906 15 0 0
t$perf-norm:sp_client_order 18827 18827 0 0.00 0 16577 39 0 0
t$perf-norm:sp_customer_reserve 904 904 0 0.00 0 1686 16 0 0
t$perf-norm:sp_pay_from_customer 6594 6594 0 0.00 0 19888 25 0 0
t$perf-norm:sp_pay_to_supplier 5318 5318 0 0.00 0 6234 22 0 0
t$perf-norm:sp_reserve_write_off 9120 9120 0 0.00 0 7501 26 0 0
t$perf-norm:sp_supplier_invoice 4984 4984 0 0.00 0 3649 34 0 0
t$perf-norm:sp_supplier_order 5078 5078 0 0.00 0 8196 36 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 2377 36 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 2737 47 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 2558 22 0 0
t$perf-norm:srv_make_money_saldo 841 841 0 0.00 0 2830 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 223 70 0 0
v_add_invoice_to_stock 9308 9180 128 1.37 0 6540 24 0 128
v_all_customers 34818 34818 0 0.00 0 264 4 0 0
v_all_suppliers 27984 27984 0 0.00 0 75 4 0 0
v_all_wares 37652 37652 0 0.00 0 131 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 874 16 0 0
v_cancel_client_order 3118 3118 0 0.00 0 3207 10 0 0
v_cancel_customer_prepayment 1258 1258 0 0.00 0 4780 18 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 724 12 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 2229 15 0 0
v_cancel_supplier_invoice 700 682 18 2.57 0 1834 26 0 18
v_cancel_supplier_order 774 774 0 0.00 0 2109 15 0 0
v_cancel_write_off 1808 1808 0 0.00 0 1508 22 0 0
v_max_id_clo_ord 5078 5078 0 0.00 0 10070 7 0 0
v_max_id_clo_res 904 904 0 0.00 0 2026 16 0 0
v_max_id_ord_sup 4983 4983 0 0.00 0 4752 8 0 0
v_max_non_paid_invoice 1386 1386 0 0.00 0 722 8 0 0
v_max_non_paid_realizn 3387 3387 0 0.00 0 3472 10 0 0
v_min_id_clo_ord 5077 5077 0 0.00 0 9875 9 0 0
v_min_id_clo_res 904 904 0 0.00 0 1341 9 0 0
v_min_id_ord_sup 4983 4983 0 0.00 0 4223 11 0 0
v_min_non_paid_invoice 5318 5318 0 0.00 0 7981 20 0 0
v_min_non_paid_realizn 6593 6593 0 0.00 0 21747 27 0 0
v_our_firm 9244 9244 0 0.00 0 71 5 0 0
v_reserve_write_off 18238 18238 0 0.00 0 5975 11 0 0
x_lock_depdocs_on_canc_invoice 11324 11324 0 0.00 0 23271 126 0 0
x_lock_depdocs_on_canc_sup_ord 387 387 0 0.00 0 3181 353 0 0
x_make_qstorno_1000_1200 5078 5078 0 0.00 2 31811 1198 0 0
x_make_qstorno_1200_2000 4984 4984 0 0.00 4 100854 3199 0 0
x_make_qstorno_2100_3300 429249 429249 0 0.00 0 66866 216 0 0

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

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:18 4 8192000000 8799382976 8810975232 5201376 0.063 3 1 2 0 5302544 65392 35776
11:08:19 28815 8192000000 9715450160 10013024256 791799008 8.813 100 86 172 0 820943712 7531184 21613520
11:09:52 9224 8192000000 9766585520 10074537984 840502672 9.305 100 91 182 0 874100944 12061200 21537072
11:11:06 13996 8192000000 9797386736 10111078400 861932304 9.519 100 91 182 0 904330768 20846080 21552384
11:12:31 7088 8192000000 9849406384 10184335360 904987296 9.948 100 74 148 0 962808944 41149792 16671856
11:13:40 3068 8192000000 9915100128 10258362368 953840848 10.429 100 87 173 0 1029696144 57729824 18125472
11:14:47 1485 8192000000 9958421824 10311888896 995227520 10.832 100 92 173 0 1074135296 61008608 17899168
11:15:52 1167 8192000000 10007558848 10371530752 1027870096 11.148 100 94 180 0 1127053456 81639264 17544096
11:16:55 1187 8192000000 10012843920 10383376384 1042180080 11.286 100 93 180 0 1131546256 71445904 17920272
11:17:58 1360 8192000000 10041198832 10419412992 1061419472 11.470 100 96 189 0 1163352944 82176544 19756928
11:19:00 4858 8192000000 10031176352 10407890944 1066163216 11.515 100 88 161 3 1152016352 69640592 16212544
11:20:06 2150 8192000000 10020075216 10401370112 1066197904 11.516 100 74 132 0 1159406240 81180864 12027472
11:21:09 1035 8192000000 10045652000 10426617856 1074578096 11.596 100 97 187 1 1167082848 73990512 18514240
11:22:11 4476 8192000000 10028278656 10414764032 1082589296 11.672 100 78 145 0 1162052704 65837952 13625456
11:23:17 4706 8192000000 10041205824 10431963136 1083227568 11.678 100 80 158 2 1161646448 62805568 15613312
11:24:22 1460 8192000000 10049033760 10437578752 1086942160 11.714 100 75 141 3 1167390496 67106240 13342096
11:25:24 895 8192000000 10056439312 10449162240 1081391536 11.661 99 96 180 1 1183161152 83330128 18439488
11:26:27 1998 8192000000 10045321344 10437083136 1091830800 11.760 100 70 137 0 1171104544 66144912 13128832
11:27:32 2403 8192000000 10044061888 10436235264 1091125360 11.753 100 74 144 1 1172639760 67937168 13577232
11:28:36 1122 8192000000 10027919792 10419482624 1087204272 11.716 100 88 168 2 1152505520 47505648 17795600
11:29:40 1260 8192000000 10051687696 10439258112 1083623744 11.682 100 96 180 4 1175366560 74099952 17642864
11:30:43 1168 8192000000 10008271440 10382548992 1043396640 11.297 99 95 186 0 1128185776 65164512 19624624
11:31:44 1137 8192000000 9980960032 10357575680 1025946560 11.129 98 82 153 1 1104589152 63673152 14969440
11:32:47 1060 8192000000 10013240560 10391584768 1046321056 11.325 100 83 163 1 1137975088 75600912 16053120
11:33:49 983 8192000000 9985367664 10356396032 1015303248 11.027 98 93 180 2 1108150128 73903568 18943312
11:34:50 1198 8192000000 9962887360 10335322112 1025174272 11.122 99 94 167 1 1084604112 42132640 17297200
11:35:53 1093 8192000000 10033891040 10410659840 1050798080 11.368 100 97 185 1 1155885600 85839664 19247856
11:36:54 1456 8192000000 10005930160 10390888448 1055870576 11.417 100 82 159 0 1129032352 55990656 17171120
11:38:01 10966 8192000000 10020099968 10407940096 1066838096 11.522 100 64 114 3 1139377008 61879728 10659184
11:39:15 9317 8192000000 10027746544 10418180096 1083821472 11.684 100 80 154 2 1154884384 54503584 16559328
11:40:31 4376 8192000000 10063187824 10454388736 1100957744 11.847 100 75 147 0 1173183680 56582592 15643344
11:41:43 1854 8192000000 10082400752 10478010368 1112460432 11.956 100 72 135 2 1201869536 74478864 14930240
11:42:50 7645 8192000000 10113894256 10511912960 1116875136 11.997 99 77 145 1 1225333488 93661616 14796736
11:44:04 12460 8192000000 10148218864 10545098752 1127086768 12.094 100 69 129 0 1261022320 120232672 13702880
11:45:20 2087 8192000000 10167013136 10578313216 1139447184 12.210 100 88 164 2 1282267472 125258960 17561328
11:46:25 1662 8192000000 10194418800 10594271232 1146691824 12.278 100 85 167 0 1307989776 143966496 17331456
11:47:28 1036 8192000000 10212428688 10611027968 1155716112 12.363 100 91 175 0 1327971520 152541984 19713424
11:48:32 908 8192000000 10247744592 10651197440 1170465504 12.501 100 94 178 0 1357668448 167969360 19233584
11:49:36 1095 8192000000 10293914144 10698145792 1181129168 12.601 100 96 191 0 1404114768 202022784 20962816
11:50:41 1029 8192000000 10335857792 10744926208 1188540784 12.670 100 90 164 2 1443478320 235968064 18969472
11:51:46 911 8192000000 10325830720 10733215744 1187253632 12.658 100 94 180 2 1437501008 228534528 21712848
11:52:54 1189 8192000000 10357696592 10771169280 1194856544 12.729 100 95 183 0 1467130800 250813248 21461008
11:53:57 1109 8192000000 10395199584 10816749568 1201716704 12.792 100 93 183 1 1500261472 276910864 21633904
11:55:04 5109 8192000000 10440753648 10852093952 1192784160 12.709 100 89 176 0 1542203104 328535888 20883056
11:56:13 1175 8192000000 10495247872 10908835840 1197785264 12.756 100 90 179 0 1598079264 378737632 21556368
11:57:18 1217 8192000000 10529882864 10943750144 1192502288 12.707 100 97 191 0 1636772784 421311408 22959088
11:58:22 1541 8192000000 10548470096 10989223936 1201134400 12.787 100 97 191 0 1656422240 431603648 23684192
11:59:26 1444 8192000000 10567570400 11034546176 1199523568 12.772 100 94 187 0 1668767328 446307584 22936176
12:00:30 1981 8192000000 10573047552 11002138624 1203315984 12.807 100 84 166 1 1680522576 456118640 21087952
12:01:34 14957 8192000000 10564562624 11053608960 1185234480 12.639 99 41 81 0 1657427328 460747584 11445264
12:03:05 29670 8192000000 10544763408 11007238144 1170401552 12.501 99 38 74 0 1663008768 482566720 10040496
12:04:46 18321 8192000000 10517372800 11044028416 1164739600 12.448 100 29 56 0 1624683328 452074864 7868864
12:06:08 36152 8192000000 10348178672 10878017536 1125446464 12.078 98 30 58 0 1493618896 361228944 6943488
12:08:21 17688 8192000000 10115253904 10575556608 1151186608 12.321 100 34 67 2 1241946624 82197648 8562368
12:09:39 73112 8192000000 10142026752 10571456512 1128378592 12.106 98 48 90 0 1227891360 89220592 10292176
12:11:54 118758 8192000000 10049311328 10524512256 1110958624 11.941 97 13 25 0 1162378704 48836416 2583664
12:14:56 57038 8192000000 10095183728 10543226880 1110605088 11.938 98 24 44 0 1169949120 54866576 4477456
12:16:57 152166 8192000000 10075382304 10535501824 1100195472 11.839 97 16 34 0 1146483408 43183312 3104624
12:20:34 29491 8192000000 10078736320 10502684672 1118921536 12.017 99 66 117 1 1169196992 38125808 12149648
12:22:10 51557 8192000000 10080975200 10503401472 1127602048 12.099 99 62 106 2 1163883408 25020880 11260480
12:24:08 52127 8192000000 10103270272 10575044608 1128730864 12.109 99 16 32 0 1211659136 79583072 3345200
12:26:02 3870 8192000000 10111438752 10548711424 1133149984 12.151 99 55 99 5 1207656352 63050608 11455760
12:27:08 47075 8192000000 10180723040 10603130880 1147082784 12.282 100 35 68 0 1264973008 109339520 8550704
12:29:03 73153 8192000000 10247907792 10730524672 1144574048 12.259 99 24 48 0 1339232400 189091744 5566608
12:32:17 81811 8192000000 10062698208 10562785280 1107924528 11.913 98 7 14 0 1189682576 80430352 1327696
12:34:52 91033 8192000000 10113242208 10554613760 1123305904 12.058 99 17 34 0 1169244448 42478624 3459920
12:37:28 50570 8192000000 10087593648 10576240640 1141689168 12.231 100 25 50 0 1206381792 59528592 5164032
12:39:47 88912 8192000000 10092687344 10593259520 1116595872 11.995 97 15 30 0 1195424352 75913536 2914944
12:42:16 42325 8192000000 10081769584 10512584704 1096832032 11.808 96 25 46 0 1146776640 45808448 4136160
12:44:06 88998 8192000000 10100423728 10554953728 1134582208 12.165 100 15 30 0 1225290928 87509232 3199488
12:46:41 23636 8192000000 10098829216 10531037184 1140646576 12.222 100 43 86 0 1181607792 32179488 8781728
12:48:09 105644 8192000000 10133558448 10609733632 1134698112 12.166 99 42 84 0 1246457360 102994432 8764816
12:51:09 43743 8192000000 10089201552 10558447616 1139132512 12.207 100 32 62 1 1184578944 38743152 6703280
12:52:54 73405 8192000000 10132270336 10591649792 1127692048 12.100 98 13 26 0 1211833616 81175120 2966448
12:55:13 61755 8192000000 10145591728 10621931520 1147900912 12.290 100 19 38 0 1257643504 105473376 4269216
12:57:56 75368 8192000000 10191860848 10633080832 1136529808 12.183 99 25 50 0 1260259632 117787120 5942704
13:00:12 32814 8192000000 10224362560 10670804992 1126687824 12.090 98 25 49 0 1301639984 168893072 6059088
13:01:45 40739 8192000000 10156832800 10628366336 1120220368 12.029 98 23 45 0 1264252144 138329008 5702768
13:03:28 36976 8192000000 10076774432 10528014336 1126910784 12.092 100 31 58 0 1180192256 47062224 6219248
13:05:07 30814 8192000000 10132775808 10560806912 1128459408 12.107 99 57 110 0 1200226048 60727808 11038832
13:06:40 40578 8192000000 10159335904 10628890624 1135665712 12.175 99 37 74 0 1239288192 96219408 7403072
13:08:22 26637 8192000000 10102708496 10566115328 1111900960 11.950 98 33 66 0 1169013584 51070784 6041840
13:09:58 95085 8192000000 10096447280 10541088768 1129065056 12.113 99 43 81 1 1187131712 50020032 8046624
13:12:34 64325 8192000000 10107891776 10573008896 1140877968 12.224 100 11 22 0 1201521424 58566480 2076976
13:14:53 81524 8192000000 10105884240 10574319616 1121528352 12.041 98 12 24 0 1177640176 53520112 2591712
13:17:15 54887 8192000000 10074039232 10511339520 1067746944 11.531 93 29 50 1 1143510128 69173840 6589344
13:19:10 12377 8192000000 10113615376 10531184640 1131575568 12.136 100 62 117 0 1202909200 59557552 11776080
13:20:24 60684 8192000000 10151207872 10605600768 1121656720 12.043 98 25 47 1 1232934304 106383024 4894560
13:22:25 71281 8192000000 10111444880 10572791808 1103990720 11.875 96 33 63 0 1153968880 43431968 6546192
13:24:37 78528 8192000000 10099310048 10594705408 1107650864 11.910 98 17 34 0 1180086592 69108512 3327216
13:26:57 35701 8192000000 10092736816 10511765504 1098653408 11.825 98 39 78 1 1143100976 36637840 7809728
13:28:37 106210 8192000000 10084637088 10549022720 1096972768 11.809 98 20 40 0 1179444400 78452336 4019296
13:31:26 72891 8192000000 10033831296 10512171008 1116152064 11.991 99 4 8 0 1148782816 32081344 549408
13:33:39 58990 8192000000 10059491984 10512244736 1088862720 11.732 97 16 32 1 1121573392 29525440 3185232
13:35:49 55627 8192000000 10109152544 10569715712 1131611024 12.137 100 29 58 0 1233515776 95538288 6366464

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 11-07-2022 13:39:26 to 11-07-2022 13:39:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 64 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 11-07-2022 13:39:43 to 11-07-2022 13:39:45.

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          209359
MON$OLDEST_ACTIVE               212403
MON$OLDEST_SNAPSHOT             212403
MON$NEXT_TRANSACTION            212403
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                       2036448
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             765
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2798 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2798 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2798 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2798 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Mon Jul 11 13:37:18 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Mon Jul 11 13:37:23 2022
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Mon Jul 11 13:37:29 2022
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Mon Jul 11 13:37:30 2022
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
--- end of diff output ---

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

11.07.22 13:39:46. 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-11 13:39:48 No severe PSQL-related problems occured
11.07.22 13:39:49. End of report.