Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.05.2022 13:39
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 15992912 kB
MemAvailable 31465160 kB
Buffers 0 kB
Cached 15737980 kB
SwapCached 2644 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-05-17 13:37:18
DTS_END                         2022-05-17 13:37:18.9500
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58838
TRN_ID                          229553
ATT_ID                          692
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: 9203.13 24122 1104375
customer order: creation 184.79 469 22175
customer order: refuse 16.02 398 1922
order to supplier: creation 48.78 1392 5854
order to supplier: removal 3.41 165217 409
invoice (draft): creation 47.37 3102 5684
invoice (draft): removal 48.94 1076 5873
invoice accept: apply 44.00 28758 5280
invoice accept: cancel 44.00 1288 5280
customer reserve: creation 4109.38 273 493125
customer reserve: removal 4354.41 100 522529
realization accept: apply 89.53 283 10743
realization accept: cancel 73.16 181 8779
payment from customer: creation 63.08 194 7569
payment from customer: removal 6.25 139 750
payment to supplier: creation 52.33 198 6279
payment to supplier: removal 3.34 171 401
service: total inventory turnovers 4.51 3896 541
service: total monetary turnovers 8.85 372 1062
service: aggregate perf. data 0.97 18560 116
service: refresh index statistics 0.03 256374 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-05-2022 13:40:17 to 17-05-2022 13:40:19.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6585
TEST_TIME 2 5485
TEST_TIME 3 5627
TEST_TIME 4 5466
TEST_TIME 5 5531
TEST_TIME 6 5779
TEST_TIME 7 5848
TEST_TIME 8 6079
TEST_TIME 9 6383
TEST_TIME 10 6938
TEST_TIME 11 7407
TEST_TIME 12 7513
TEST_TIME 13 8035
TEST_TIME 14 7745
TEST_TIME 15 7831
TEST_TIME 16 7795
TEST_TIME 17 8066
TEST_TIME 18 8171
TEST_TIME 19 8157
TEST_TIME 20 8198
TEST_TIME 21 8216
TEST_TIME 22 8338
TEST_TIME 23 8352
TEST_TIME 24 8396
TEST_TIME 25 8517
TEST_TIME 26 8499
TEST_TIME 27 8665
TEST_TIME 28 8761
TEST_TIME 29 8792
TEST_TIME 30 8933
TEST_TIME 31 9450
TEST_TIME 32 9390
TEST_TIME 33 9948
TEST_TIME 34 10184
TEST_TIME 35 10595
TEST_TIME 36 10895
TEST_TIME 37 11329
TEST_TIME 38 11151
TEST_TIME 39 11012
TEST_TIME 40 11003
TEST_TIME 41 10873
TEST_TIME 42 10747
TEST_TIME 43 10729
TEST_TIME 44 10693
TEST_TIME 45 10473
TEST_TIME 46 10400
TEST_TIME 47 10458
TEST_TIME 48 10322
TEST_TIME 49 10237
TEST_TIME 50 10205
TEST_TIME 51 10146
TEST_TIME 52 10161
TEST_TIME 53 10186
TEST_TIME 54 10094
TEST_TIME 55 10077
TEST_TIME 56 10018
TEST_TIME 57 9931
TEST_TIME 58 9902
TEST_TIME 59 9825
TEST_TIME 60 9714
TEST_TIME 61 9720
TEST_TIME 62 9758
TEST_TIME 63 9775
TEST_TIME 64 9738
TEST_TIME 65 9737
TEST_TIME 66 9731
TEST_TIME 67 9863
TEST_TIME 68 9850
TEST_TIME 69 9921
TEST_TIME 70 9922
TEST_TIME 71 9954
TEST_TIME 72 9989
TEST_TIME 73 9952
TEST_TIME 74 9913
TEST_TIME 75 9949
TEST_TIME 76 9882
TEST_TIME 77 9812
TEST_TIME 78 9750
TEST_TIME 79 9758
TEST_TIME 80 9700
TEST_TIME 81 9651
TEST_TIME 82 9579
TEST_TIME 83 9503
TEST_TIME 84 9483
TEST_TIME 85 9468
TEST_TIME 86 9445
TEST_TIME 87 9439
TEST_TIME 88 9486
TEST_TIME 89 9463
TEST_TIME 90 9457
TEST_TIME 91 9451
TEST_TIME 92 9534
TEST_TIME 93 9505
TEST_TIME 94 9536
TEST_TIME 95 9546
TEST_TIME 96 9579
TEST_TIME 97 9591
TEST_TIME 98 9619
TEST_TIME 99 9614
TEST_TIME 100 9556
TEST_TIME 101 9472
TEST_TIME 102 9418
TEST_TIME 103 9382
TEST_TIME 104 9336
TEST_TIME 105 9351
TEST_TIME 106 9332
TEST_TIME 107 9324
TEST_TIME 108 9305
TEST_TIME 109 9309
TEST_TIME 110 9268
TEST_TIME 111 9266
TEST_TIME 112 9287
TEST_TIME 113 9315
TEST_TIME 114 9310
TEST_TIME 115 9365
TEST_TIME 116 9343
TEST_TIME 117 9346
TEST_TIME 118 9329
TEST_TIME 119 9276
TEST_TIME 120 9224

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-05-2022 13:40:21 to 17-05-2022 13:40:24.

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 1557662 1557662 0 0.00 0 26879 16 0 0
doc_list_biud 1557656 1557656 0 0.00 0 42790 40 0 0
sp_add_invoice_to_stock 5397 5280 117 2.16 40 310105 28758 0 117
sp_cancel_adding_invoice 5280 5280 0 0.00 7 84863 1288 0 0
sp_cancel_client_order 1922 1922 0 0.00 6 24693 398 0 0
sp_cancel_customer_reserve 522529 522529 0 0.00 1 26172 101 0 0
sp_cancel_pay_from_customer 750 750 0 0.00 3 4605 139 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 3 9082 171 0 0
sp_cancel_supplier_invoice 5884 5873 11 0.18 4 42810 1077 0 11
sp_cancel_supplier_order 409 409 0 0.00 10 1466409 165218 0 0
sp_cancel_write_off 8779 8779 0 0.00 2 10993 181 0 0
sp_client_order 22175 22175 0 0.00 5 25793 470 0 0
sp_customer_reserve 493125 493125 0 0.00 1 52182 274 0 0
sp_fill_shopping_cart 33702 33702 0 0.00 1 27132 85 0 0
sp_fill_shopping_cart_clo_res 493106 493106 0 0.00 0 17840 18 0 0
sp_get_clo_for_invoice 5252 5252 0 0.00 0 14985 263 0 0
sp_kill_cost_storno 15210 15210 0 0.00 0 9103 74 0 0
sp_kill_qstorno_ret_qs2qd 1060119 1060119 0 0.00 0 42786 43 0 0
sp_kill_qty_storno 560785 560785 0 0.00 0 42789 100 0 0
sp_lock_selected_doc 22116 22116 0 0.00 0 9056 8 0 0
sp_make_cost_storno 29841 29841 0 0.00 0 16445 81 0 0
sp_multiply_rows_for_pdistr 29841 29841 0 0.00 0 11797 41 0 0
sp_multiply_rows_for_qdistr 22175 22175 0 0.00 0 12663 159 0 0
sp_pay_from_customer 7569 7569 0 0.00 6 25545 194 0 0
sp_pay_to_supplier 6279 6279 0 0.00 7 25525 199 0 0
sp_payment_common 13848 13848 0 0.00 2 10044 50 0 0
sp_qd_handle_on_cancel_clo 1922 1922 0 0.00 0 24686 313 0 0
sp_qd_handle_on_invoice_upd_sts 10530 10530 0 0.00 0 23766 553 0 0
sp_qd_handle_on_reserve_upd_sts 10743 10743 0 0.00 0 13840 61 0 0
sp_reserve_write_off 10743 10743 0 0.00 7 20285 284 0 0
sp_supplier_invoice 5684 5684 0 0.00 10 75500 3103 0 0
sp_supplier_order 5854 5854 0 0.00 8 28018 1393 0 0
srv_aggregate_perf_data 117 116 1 0.85 3503 151904 18560 0 1
srv_fill_mon_cache_memory 77 77 0 0.00 898 115049 28701 0 0
srv_find_qd_qs_mism 1051739 1051739 0 0.00 0 12946 13 0 0
srv_make_invnt_saldo 541 541 0 0.00 90 56467 3897 0 0
srv_make_money_saldo 1062 1062 0 0.00 1 11927 373 0 0
srv_recalc_idx_stat 4 4 0 0.00 88467 488496 256374 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5284 5284 0 0.00 1 9693 1055 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 2 6862 505 0 0
t$perf-norm:sp_cancel_client_order 1922 1922 0 0.00 0 4955 32 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 1495 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 750 750 0 0.00 0 609 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 2684 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 892 39 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 103540 7191 0 0
t$perf-norm:sp_cancel_write_off 1089 1089 0 0.00 0 1572 22 0 0
t$perf-norm:sp_client_order 22175 22175 0 0.00 0 7418 38 0 0
t$perf-norm:sp_customer_reserve 1048 1048 0 0.00 0 1880 20 0 0
t$perf-norm:sp_pay_from_customer 7569 7569 0 0.00 0 4304 20 0 0
t$perf-norm:sp_pay_to_supplier 6279 6279 0 0.00 0 3731 19 0 0
t$perf-norm:sp_reserve_write_off 10743 10743 0 0.00 0 3612 24 0 0
t$perf-norm:sp_supplier_invoice 5684 5684 0 0.00 0 3692 35 0 0
t$perf-norm:sp_supplier_order 5854 5854 0 0.00 0 2787 33 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 884 35 0 0
t$perf-norm:srv_fill_mon_cache_memory 77 77 0 0.00 0 399 14 0 0
t$perf-norm:srv_make_invnt_saldo 541 541 0 0.00 0 360 13 0 0
t$perf-norm:srv_make_money_saldo 1062 1062 0 0.00 0 620 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 186 59 0 0
v_add_invoice_to_stock 10734 10500 234 2.17 0 10890 26 0 234
v_all_customers 40380 40380 0 0.00 0 103 4 0 0
v_all_suppliers 32448 32448 0 0.00 0 241 5 0 0
v_all_wares 44350 44350 0 0.00 0 106 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 371 15 0 0
v_cancel_client_order 3844 3844 0 0.00 0 3917 13 0 0
v_cancel_customer_prepayment 1500 1500 0 0.00 0 1339 12 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 1016 11 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 3268 17 0 0
v_cancel_supplier_invoice 828 806 22 2.65 0 3616 32 0 22
v_cancel_supplier_order 818 818 0 0.00 0 3419 24 0 0
v_cancel_write_off 2178 2178 0 0.00 0 3448 25 0 0
v_max_id_clo_ord 5854 5854 0 0.00 0 946 5 0 0
v_max_id_clo_res 1048 1048 0 0.00 0 836 9 0 0
v_max_id_ord_sup 5672 5672 0 0.00 0 4706 8 0 0
v_max_non_paid_invoice 1581 1581 0 0.00 0 101 8 0 0
v_max_non_paid_realizn 4111 4111 0 0.00 0 1395 8 0 0
v_min_id_clo_ord 5854 5854 0 0.00 0 1306 8 0 0
v_min_id_clo_res 1048 1048 0 0.00 0 3081 10 0 0
v_min_id_ord_sup 5672 5672 0 0.00 0 5023 9 0 0
v_min_non_paid_invoice 6279 6279 0 0.00 0 9246 17 0 0
v_min_non_paid_realizn 7569 7569 0 0.00 0 10039 20 0 0
v_our_firm 10886 10886 0 0.00 0 72 5 0 0
v_reserve_write_off 21486 21486 0 0.00 0 3448 11 0 0
x_lock_depdocs_on_canc_invoice 10750 10750 0 0.00 0 13838 124 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 9607 382 0 0
x_make_qstorno_1000_1200 5854 5854 0 0.00 3 27814 1211 0 0
x_make_qstorno_1200_2000 5684 5684 0 0.00 6 75484 2881 0 0
x_make_qstorno_2100_3300 493125 493125 0 0.00 0 44125 242 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-05-2022 13:40:25 to 17-05-2022 13:40:29.

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:21 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:24 30533 8192000000 9730013056 10034905088 806928896 8.966 100 88 175 0 835448256 7782896 20736464
11:10:02 24964 8192000000 9776686288 10088419328 848317584 9.383 100 91 182 1 883066848 13013120 21736144
11:11:39 16184 8192000000 9825548960 10153828352 880176976 9.701 100 82 163 0 931465968 32374688 18914304
11:12:59 9379 8192000000 9869959776 10207662080 933087584 10.225 100 83 165 1 981035536 29304848 18643104
11:14:16 3876 8192000000 9951552400 10302697472 971144864 10.598 100 85 168 0 1062145040 73096352 17903824
11:15:24 1656 8192000000 9980700608 10341675008 1001049392 10.889 100 92 177 0 1097540272 78290128 18200752
11:16:28 1056 8192000000 10001767872 10367332352 1031231296 11.180 100 92 175 1 1118470944 69153152 18086496
11:17:30 1159 8192000000 10027296336 10400559104 1055245456 11.411 100 96 188 0 1142516800 67837456 19433888
11:18:34 1335 8192000000 10050532784 10429751296 1072433504 11.575 100 95 186 2 1173627184 83083648 18110032
11:19:38 1292 8192000000 10050425056 10429644800 1079629696 11.644 100 93 176 1 1173274672 74912864 18732112
11:20:41 1007 8192000000 10066803152 10446708736 1085575696 11.701 100 96 187 1 1187647552 83344224 18727632
11:21:43 968 8192000000 10070913376 10457673728 1087238784 11.716 100 90 178 0 1196054656 89772528 19043344
11:22:45 1968 8192000000 10032843200 10425663488 1089130800 11.734 100 79 144 2 1154764912 51371056 14263056
11:23:48 4675 8192000000 10035759760 10421850112 1095341168 11.793 100 89 163 2 1155363392 44240704 15781520
11:24:53 1305 8192000000 10072145056 10463440896 1097000976 11.809 100 96 187 2 1191670992 74763184 19906832
11:25:55 1290 8192000000 10085907040 10478252032 1096823312 11.807 100 99 190 2 1208817632 92217664 19776656
11:26:58 1011 8192000000 10051452800 10445504512 1090048656 11.743 100 84 165 2 1174879648 68333024 16497968
11:28:00 1248 8192000000 10058750576 10449887232 1099539040 11.833 100 96 180 0 1182351872 63494480 19318352
11:29:02 1225 8192000000 10035696576 10430545920 1094545056 11.786 100 79 151 2 1153021456 42256832 16219568
11:30:05 899 8192000000 10053213696 10440056832 1081520912 11.662 99 91 173 3 1173489136 73612384 18355840
11:31:06 1242 8192000000 10043733344 10433114112 1087026240 11.714 100 92 171 1 1162542192 59380048 16135904
11:32:08 1166 8192000000 10016136992 10395906048 1057526080 11.433 100 96 181 3 1132587888 56417312 18644496
11:33:11 1477 8192000000 10009254112 10389524480 1053459984 11.394 100 93 171 1 1137551936 65915056 18176896
11:34:13 1767 8192000000 10002039392 10376663040 1024027504 11.111 98 96 185 2 1124238640 81357392 18853744
11:35:15 1265 8192000000 10009047888 10382196736 1037529744 11.241 100 96 188 0 1129924176 73379104 19015328
11:36:18 1284 8192000000 9989120512 10361417728 1029675184 11.165 100 92 172 3 1106724400 58444160 18605056
11:37:19 3824 8192000000 9988983664 10358517760 1043331040 11.297 100 75 134 3 1102664832 46387648 12946144
11:38:37 1916 8192000000 10001057568 10378182656 1070078864 11.553 100 75 136 0 1119692896 36008432 13605600
11:39:52 14583 8192000000 10070628384 10455875584 1089710304 11.740 100 71 131 2 1193624944 89572704 14341936
11:41:14 1204 8192000000 10080430208 10465435648 1097616816 11.815 100 79 151 0 1195618576 82166400 15835360
11:42:19 2412 8192000000 10108005168 10495750144 1110024832 11.933 100 79 153 1 1227112576 101320208 15767536
11:43:28 3729 8192000000 10146073696 10540748800 1116207072 11.991 100 77 148 1 1267927696 135761808 15958816
11:44:35 6797 8192000000 10167954160 10570731520 1130639424 12.127 100 80 151 0 1286382256 139384720 16358112
11:45:44 1632 8192000000 10188169168 10581155840 1137202736 12.189 100 78 151 0 1296614704 143349856 16062112
11:46:50 1456 8192000000 10197556384 10610049024 1149645344 12.306 100 89 171 1 1315863552 146457920 19760288
11:47:53 1520 8192000000 10232567104 10643603456 1154931968 12.356 100 94 182 2 1345920768 170674976 20313824
11:48:57 1065 8192000000 10238660768 10639282176 1154256464 12.349 100 96 185 1 1355776832 180623808 20896560
11:50:02 893 8192000000 10245428096 10670686208 1164670896 12.447 100 95 188 0 1356189216 170521968 20996352
11:51:06 3509 8192000000 10244514224 10655649792 1169877088 12.496 100 80 152 0 1357221536 170279904 17064544
11:52:12 1106 8192000000 10324899872 10735841280 1183368528 12.622 100 98 190 1 1435851168 229969552 22513088
11:53:15 1763 8192000000 10336962144 10742140928 1178572480 12.577 100 90 177 1 1447743552 248249712 20921360
11:54:21 2357 8192000000 10324035072 10753720320 1190382256 12.687 100 91 179 0 1430674576 220187984 20104336
11:55:26 1295 8192000000 10366275696 10784440320 1185342624 12.640 100 91 177 0 1475235712 269034480 20858608
11:56:30 955 8192000000 10405831056 10810617856 1182395472 12.613 100 95 182 2 1513357504 309776624 21185408
11:57:35 1016 8192000000 10427725984 10842091520 1189454416 12.678 100 97 183 3 1529557760 318835232 21268112
11:58:39 1702 8192000000 10425943808 10844921856 1174401008 12.538 100 88 175 3 1534561152 339509344 20650800
11:59:44 1086 8192000000 10478152768 10892992512 1187853712 12.663 100 98 191 1 1583114688 372422816 22838160
12:00:47 2188 8192000000 10466812496 10894524416 1170297136 12.500 99 88 176 0 1571535936 379962272 21276528
12:01:51 1849 8192000000 10521842432 10947952640 1184937552 12.636 100 99 192 1 1623371584 415244448 23189584
12:02:57 1096 8192000000 10518763392 10939129856 1178160864 12.573 99 92 184 0 1618272432 417347152 22764416
12:04:02 947 8192000000 10568310496 11012603904 1194949344 12.729 100 92 183 1 1668005152 450988448 22067360
12:05:05 907 8192000000 10557843712 10998177792 1190941824 12.692 100 83 163 0 1659326592 448449168 19935600
12:06:09 2220 8192000000 10591107312 11033022464 1193527968 12.716 100 71 139 0 1689253120 478024416 17700736
12:07:15 5339 8192000000 10623836016 11097464832 1181847760 12.607 99 66 132 2 1726473728 527340160 17285808
12:08:33 12729 8192000000 10535233456 11047464960 1180621568 12.596 100 46 92 1 1652233184 459068432 12543184
12:09:59 25304 8192000000 10441317664 10932486144 1130589808 12.127 96 38 74 0 1552139424 411033296 10516320
12:11:26 38521 8192000000 10313606832 10870673408 1142229408 12.236 99 24 48 1 1493517344 344731696 6556240
12:13:26 88466 8192000000 10105561712 10683498496 1103649696 11.872 97 7 14 0 1225537216 120390016 1497504
12:16:01 4575 8192000000 10069232208 10497417216 1129832896 12.120 100 55 106 1 1155998096 14704816 11460384
12:17:06 7430 8192000000 10097577504 10526236672 1137375392 12.191 100 55 106 2 1195996400 46869296 11751712
12:18:17 37860 8192000000 10081314912 10515857408 1136888144 12.186 100 31 59 0 1173950736 31245504 5817088
12:20:00 66960 8192000000 10068620320 10534416384 1090816992 11.750 96 13 24 2 1162397664 68797024 2783648
12:23:10 27105 8192000000 10103932736 10535096320 1135195152 12.170 100 45 77 2 1195107520 52480320 7432048
12:24:52 21536 8192000000 10086172816 10544930816 1127525056 12.098 100 31 61 0 1193021088 59585472 5910560
12:26:15 17763 8192000000 10079470848 10522783744 1141479888 12.229 100 38 69 1 1179389440 31483472 6426080
12:28:22 16473 8192000000 10101024320 10544308224 1132284064 12.143 100 45 89 1 1196349152 53715824 10349264
12:29:41 31462 8192000000 10138139264 10612789248 1140018240 12.216 99 33 64 1 1256315888 109738208 6559440
12:31:13 19056 8192000000 10078875904 10568409088 1139871344 12.214 100 8 16 0 1201357792 59959472 1526976
12:32:33 26451 8192000000 10064138016 10516344832 1122457280 12.050 99 21 40 1 1167769632 41084608 4227744
12:34:00 71534 8192000000 10080881088 10554662912 1144695616 12.260 100 21 42 0 1188432096 39127712 4608768
12:36:11 32068 8192000000 10062826432 10512715776 1115066688 11.980 98 19 38 0 1146973872 28245456 3661728
12:37:59 92901 8192000000 10105067424 10669305856 1115496512 11.984 98 10 20 0 1234363040 116562400 2304128
12:40:32 28835 8192000000 10114985920 10561159168 1139362592 12.210 99 38 74 0 1200834976 53214208 8258176
12:42:02 19192 8192000000 10147509504 10596790272 1125932480 12.083 99 31 62 1 1242634624 110316976 6385168
12:43:22 30684 8192000000 10183116992 10637262848 1140459568 12.220 100 24 48 1 1284962320 139094304 5408448
12:44:54 53625 8192000000 10120618352 10596573184 1139610496 12.212 100 16 32 0 1239669952 96244400 3815056
12:46:49 16206 8192000000 10124754272 10566393856 1140611568 12.221 100 45 90 1 1206493728 56825280 9056880
12:48:11 39915 8192000000 10111935808 10574909440 1083129792 11.677 95 39 78 0 1169480624 78435216 7915616
12:50:26 15548 8192000000 10129753920 10561896448 1141287712 12.228 100 45 90 3 1213429792 63087600 9054480
12:51:45 115048 8192000000 10078877984 10596372480 1102765312 11.864 97 26 52 1 1193730304 84732560 6232432
12:54:41 56746 8192000000 10094039984 10538913792 1135023280 12.169 100 35 70 3 1202303344 59923136 7356928
12:56:38 78634 8192000000 10069489696 10561667072 1106710640 11.901 97 13 26 0 1164362736 55056048 2596048
12:58:58 71702 8192000000 10059054112 10511245312 1133757344 12.157 100 19 38 0 1173987392 36206048 4024000
13:01:11 91891 8192000000 10054755408 10550878208 1105943536 11.894 97 16 31 0 1165273104 56202080 3127488
13:03:43 26351 8192000000 10097331104 10546561024 1093064336 11.772 96 29 56 2 1168635984 69288848 6282800
13:05:10 32826 8192000000 10065755568 10517626880 1120631472 12.033 100 45 85 0 1169514640 39338048 9545120
13:06:43 37731 8192000000 10100572640 10551332864 1130650160 12.127 100 40 80 0 1210332048 71608528 8073360
13:08:21 47765 8192000000 10143385584 10592014336 1101348416 11.850 97 37 74 2 1202371536 93627136 7395984
13:10:14 14860 8192000000 10065744928 10494803968 1127828832 12.101 100 41 80 1 1163806016 27928560 8048624
13:11:30 30064 8192000000 10094825504 10559823872 1112685776 11.958 98 34 68 1 1201069472 81716944 6666752
13:13:02 108815 8192000000 10094546336 10565361664 1073132720 11.582 95 31 62 1 1125815904 46498176 6185008
13:15:51 57311 8192000000 10076716880 10562138112 1127719600 12.100 100 16 32 3 1186864480 55916080 3228800
13:17:49 71923 8192000000 10056536368 10485346304 1090636720 11.749 96 40 78 1 1116867568 17839968 8390880
13:20:02 105937 8192000000 10149787904 10616000512 1122844512 12.054 99 18 36 1 1242868608 115678576 4345520
13:22:48 60675 8192000000 10060820320 10551373824 1114995840 11.980 99 11 22 0 1176907808 58940528 2971440
13:24:58 42437 8192000000 10061484864 10512064512 1110195024 11.934 98 24 48 0 1141755552 26682336 4878192
13:27:02 24017 8192000000 10094341488 10522120192 1125600352 12.080 99 49 91 1 1174695424 39618528 9476544
13:28:27 38728 8192000000 10137902208 10595201024 1129109776 12.113 99 43 86 0 1237062944 99135888 8817280
13:30:15 54268 8192000000 10105014144 10544226304 1102437184 11.861 97 25 50 2 1149085456 41355136 5293136
13:32:12 38036 8192000000 10091138976 10559172608 1093865584 11.779 97 40 80 1 1208134672 106069728 8199360
13:33:58 26216 8192000000 10093507840 10533928960 1128030976 12.103 100 51 101 0 1188502800 50225552 10246272
13:35:29 28279 8192000000 10117591184 10552311808 1119025216 12.018 99 61 123 0 1192873968 61942512 11906240
13:36:58 36846 8192000000 9301821728 9627643904 420542592 4.882 38 10 15 2 456723872 34526160 1655120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 17-05-2022 13:40:31 to 17-05-2022 13:40:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 117 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-05-2022 13:40:49 to 17-05-2022 13:40:51.

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          229515
MON$OLDEST_ACTIVE               229787
MON$OLDEST_SNAPSHOT             229787
MON$NEXT_TRANSACTION            229787
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                       2092288
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2764 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2764 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2764 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2764 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 ---
:264: 
:265: mscs-test-161.budzdorov.ru    Tue May 17 13:38:03 2022
:266:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:267: 
--- end of diff output ---

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

17.05.22 13:40:52. 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-05-17 13:40:54 No severe PSQL-related problems occured
17.05.22 13:40:56. End of report.