Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.10.2021 13:37
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15980532 kB
MemAvailable 30843816 kB
Buffers 0 kB
Cached 15713320 kB
SwapCached 4792 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-29 13:37:07
DTS_END                         2021-10-29 13:37:07.6230
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/52666
TRN_ID                          232592
ATT_ID                          664
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: 9155.12 20400 1098614
customer order: creation 172.58 398 20709
customer order: refuse 14.21 318 1705
order to supplier: creation 44.67 1480 5360
order to supplier: removal 3.32 103133 398
invoice (draft): creation 44.77 3600 5372
invoice (draft): removal 52.27 844 6272
invoice accept: apply 41.93 19479 5032
invoice accept: cancel 46.47 828 5576
customer reserve: creation 3845.70 194 461484
customer reserve: removal 4600.88 58 552105
realization accept: apply 83.11 191 9973
realization accept: cancel 78.76 133 9451
payment from customer: creation 58.28 133 6993
payment from customer: removal 5.85 81 702
payment to supplier: creation 48.19 144 5783
payment to supplier: removal 3.52 87 422
service: total inventory turnovers 3.55 3549 426
service: total monetary turnovers 6.19 490 743
service: aggregate perf. data 0.87 15161 104
service: refresh index statistics 0.03 257694 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-10-2021 13:38:08 to 29-10-2021 13:38:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5666
TEST_TIME 2 3991
TEST_TIME 3 3290
TEST_TIME 4 4273
TEST_TIME 5 4307
TEST_TIME 6 4273
TEST_TIME 7 4493
TEST_TIME 8 5289
TEST_TIME 9 5452
TEST_TIME 10 5816
TEST_TIME 11 7003
TEST_TIME 12 7141
TEST_TIME 13 7262
TEST_TIME 14 7526
TEST_TIME 15 7654
TEST_TIME 16 7872
TEST_TIME 17 8041
TEST_TIME 18 8324
TEST_TIME 19 8899
TEST_TIME 20 8835
TEST_TIME 21 8792
TEST_TIME 22 8989
TEST_TIME 23 9174
TEST_TIME 24 9383
TEST_TIME 25 9297
TEST_TIME 26 9283
TEST_TIME 27 9873
TEST_TIME 28 10205
TEST_TIME 29 10568
TEST_TIME 30 10829
TEST_TIME 31 11022
TEST_TIME 32 10968
TEST_TIME 33 10874
TEST_TIME 34 10589
TEST_TIME 35 10394
TEST_TIME 36 10297
TEST_TIME 37 10229
TEST_TIME 38 10216
TEST_TIME 39 10175
TEST_TIME 40 10164
TEST_TIME 41 10208
TEST_TIME 42 10099
TEST_TIME 43 9998
TEST_TIME 44 9944
TEST_TIME 45 9817
TEST_TIME 46 9781
TEST_TIME 47 9676
TEST_TIME 48 9626
TEST_TIME 49 9688
TEST_TIME 50 9606
TEST_TIME 51 9689
TEST_TIME 52 9643
TEST_TIME 53 9694
TEST_TIME 54 9749
TEST_TIME 55 9760
TEST_TIME 56 9810
TEST_TIME 57 9764
TEST_TIME 58 9829
TEST_TIME 59 9793
TEST_TIME 60 9826
TEST_TIME 61 9820
TEST_TIME 62 9671
TEST_TIME 63 9693
TEST_TIME 64 9618
TEST_TIME 65 9608
TEST_TIME 66 9645
TEST_TIME 67 9613
TEST_TIME 68 9569
TEST_TIME 69 9597
TEST_TIME 70 9563
TEST_TIME 71 9609
TEST_TIME 72 9663
TEST_TIME 73 9575
TEST_TIME 74 9650
TEST_TIME 75 9659
TEST_TIME 76 9750
TEST_TIME 77 9753
TEST_TIME 78 9774
TEST_TIME 79 9693
TEST_TIME 80 9623
TEST_TIME 81 9633
TEST_TIME 82 9608
TEST_TIME 83 9544
TEST_TIME 84 9520
TEST_TIME 85 9483
TEST_TIME 86 9465
TEST_TIME 87 9427
TEST_TIME 88 9414
TEST_TIME 89 9399
TEST_TIME 90 9341
TEST_TIME 91 9403
TEST_TIME 92 9396
TEST_TIME 93 9428
TEST_TIME 94 9421
TEST_TIME 95 9460
TEST_TIME 96 9431
TEST_TIME 97 9473
TEST_TIME 98 9477
TEST_TIME 99 9536
TEST_TIME 100 9562
TEST_TIME 101 9517
TEST_TIME 102 9563
TEST_TIME 103 9484
TEST_TIME 104 9409
TEST_TIME 105 9373
TEST_TIME 106 9368
TEST_TIME 107 9304
TEST_TIME 108 9335
TEST_TIME 109 9328
TEST_TIME 110 9266
TEST_TIME 111 9246
TEST_TIME 112 9261
TEST_TIME 113 9265
TEST_TIME 114 9240
TEST_TIME 115 9231
TEST_TIME 116 9204
TEST_TIME 117 9220
TEST_TIME 118 9275
TEST_TIME 119 9231
TEST_TIME 120 9155

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-10-2021 13:38:12 to 29-10-2021 13:38:15.

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 1525883 1525883 0 0.00 0 24098 7 0 0
doc_list_biud 1525874 1525874 0 0.00 0 30618 27 0 0
sp_add_invoice_to_stock 5124 5032 92 1.79 60 208801 19479 0 92
sp_cancel_adding_invoice 5576 5576 0 0.00 6 65756 829 0 0
sp_cancel_client_order 1705 1705 0 0.00 5 17308 318 0 0
sp_cancel_customer_reserve 552105 552105 0 0.00 1 18786 58 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 4904 81 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 4239 88 0 0
sp_cancel_supplier_invoice 6284 6272 12 0.19 3 30624 845 0 12
sp_cancel_supplier_order 398 398 0 0.00 13 978596 103133 0 0
sp_cancel_write_off 9451 9451 0 0.00 1 22482 133 0 0
sp_client_order 20709 20709 0 0.00 4 38485 398 0 0
sp_customer_reserve 461484 461484 0 0.00 0 56844 195 0 0
sp_fill_shopping_cart 31437 31437 0 0.00 1 20323 42 0 0
sp_fill_shopping_cart_clo_res 461483 461483 0 0.00 0 20672 16 0 0
sp_get_clo_for_invoice 5031 5031 0 0.00 0 25819 373 0 0
sp_kill_cost_storno 16151 16151 0 0.00 0 12091 57 0 0
sp_kill_qstorno_ret_qs2qd 1120331 1120331 0 0.00 0 30616 32 0 0
sp_kill_qty_storno 590507 590507 0 0.00 0 30618 69 0 0
sp_lock_selected_doc 20670 20670 0 0.00 0 15714 5 0 0
sp_make_cost_storno 27780 27780 0 0.00 0 24069 62 0 0
sp_multiply_rows_for_pdistr 27779 27779 0 0.00 0 12102 36 0 0
sp_multiply_rows_for_qdistr 20709 20709 0 0.00 0 26368 187 0 0
sp_pay_from_customer 6993 6993 0 0.00 4 16749 133 0 0
sp_pay_to_supplier 5783 5783 0 0.00 4 19140 145 0 0
sp_payment_common 12771 12771 0 0.00 1 16149 30 0 0
sp_qd_handle_on_cancel_clo 1705 1705 0 0.00 0 17302 293 0 0
sp_qd_handle_on_invoice_upd_sts 10606 10606 0 0.00 0 30497 335 0 0
sp_qd_handle_on_reserve_upd_sts 9970 9970 0 0.00 0 12020 49 0 0
sp_reserve_write_off 9973 9973 0 0.00 7 19269 191 0 0
sp_supplier_invoice 5372 5372 0 0.00 6 84411 3600 0 0
sp_supplier_order 5360 5360 0 0.00 5 36593 1480 0 0
srv_aggregate_perf_data 105 104 1 0.95 3048 150004 15162 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1665 133412 44946 0 0
srv_find_qd_qs_mism 1051245 1051245 0 0.00 0 22453 6 0 0
srv_make_invnt_saldo 426 426 0 0.00 50 97234 3549 0 0
srv_make_money_saldo 743 743 0 0.00 1 20377 491 0 0
srv_recalc_idx_stat 4 4 0 0.00 148074 384418 257695 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5032 5032 0 0.00 0 13269 147 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 11516 137 0 0
t$perf-norm:sp_cancel_client_order 1705 1705 0 0.00 0 3089 14 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 1763 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 1010 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 3380 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 412 412 0 0.00 0 10673 38 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 15374 1532 0 0
t$perf-norm:sp_cancel_write_off 963 963 0 0.00 0 5289 10 0 0
t$perf-norm:sp_client_order 20709 20709 0 0.00 0 14590 18 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 576 4 0 0
t$perf-norm:sp_pay_from_customer 6993 6993 0 0.00 0 6888 10 0 0
t$perf-norm:sp_pay_to_supplier 5783 5783 0 0.00 0 3359 8 0 0
t$perf-norm:sp_reserve_write_off 9973 9973 0 0.00 0 4326 10 0 0
t$perf-norm:sp_supplier_invoice 5372 5372 0 0.00 0 9615 16 0 0
t$perf-norm:sp_supplier_order 5360 5360 0 0.00 0 6408 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 1329 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2465 44 0 0
t$perf-norm:srv_make_invnt_saldo 426 426 0 0.00 0 7064 28 0 0
t$perf-norm:srv_make_money_saldo 743 743 0 0.00 0 1529 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10244 10060 184 1.79 0 8209 16 0 184
v_all_customers 37584 37584 0 0.00 0 75 1 0 0
v_all_suppliers 29738 29738 0 0.00 0 17 1 0 0
v_all_wares 41418 41418 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 531 6 0 0
v_cancel_client_order 3410 3410 0 0.00 0 495 3 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1327 6 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 1460 4 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 1207 5 0 0
v_cancel_supplier_invoice 848 824 24 2.83 0 1343 17 0 24
v_cancel_supplier_order 796 796 0 0.00 0 4728 9 0 0
v_cancel_write_off 1926 1926 0 0.00 0 3849 15 0 0
v_max_id_clo_ord 5358 5358 0 0.00 0 924 2 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 3221 17 0 0
v_max_id_ord_sup 5370 5370 0 0.00 0 5025 8 0 0
v_max_non_paid_invoice 1639 1639 0 0.00 0 1293 4 0 0
v_max_non_paid_realizn 3743 3743 0 0.00 0 4040 3 0 0
v_min_id_clo_ord 5358 5358 0 0.00 0 4634 9 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 2940 7 0 0
v_min_id_ord_sup 5370 5370 0 0.00 0 6579 18 0 0
v_min_non_paid_invoice 5780 5780 0 0.00 0 10555 23 0 0
v_min_non_paid_realizn 6989 6989 0 0.00 0 12063 23 0 0
v_our_firm 10326 10326 0 0.00 0 78 1 0 0
v_reserve_write_off 19940 19940 0 0.00 0 7594 6 0 0
x_lock_depdocs_on_canc_invoice 11436 11436 0 0.00 0 13128 114 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 16889 374 0 0
x_make_qstorno_1000_1200 5360 5360 0 0.00 2 36580 1400 0 0
x_make_qstorno_1200_2000 5372 5372 0 0.00 3 84393 3442 0 0
x_make_qstorno_2100_3300 461484 461484 0 0.00 0 56842 177 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-10-2021 13:38:16 to 29-10-2021 13:38:20.

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:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 30190 8192000000 9716538432 10016546816 797231760 8.868 100 85 170 1 825694544 7500240 20962544
11:09:50 23976 8192000000 9764211248 10069692416 834233328 9.242 100 90 180 0 869442768 13083488 22125952
11:11:21 14876 8192000000 9804042240 10126147584 867389920 9.574 100 72 144 0 906464944 22521840 16553184
11:12:39 10443 8192000000 9874705840 10207035392 919543440 10.092 100 83 166 0 987162016 49991440 17627136
11:13:55 8845 8192000000 9927927296 10270724096 957982736 10.469 100 84 168 0 1034795456 59530288 17282432
11:15:07 5491 8192000000 9957486480 10310565888 990753200 10.789 100 77 152 1 1064599808 58304768 15541840
11:16:15 3785 8192000000 9994165056 10353471488 1022669264 11.098 100 84 167 2 1099900704 60651392 16580048
11:17:20 3051 8192000000 10035115104 10403450880 1047005248 11.332 100 93 186 1 1150344480 84223440 19115792
11:18:25 1450 8192000000 10020772464 10391523328 1057811120 11.436 100 91 179 2 1129148640 51836144 19501376
11:19:28 15090 8192000000 10000140544 10378067968 1069563392 11.548 100 63 127 0 1124663216 41732688 13367136
11:20:44 4386 8192000000 9995077920 10393600000 1073461872 11.585 100 55 109 1 1116369648 30970400 11937376
11:21:49 13685 8192000000 9994077392 10402250752 1075774448 11.607 100 36 71 3 1110567808 27557232 7236128
11:23:03 6134 8192000000 9995892816 10399006720 1080279312 11.650 100 37 72 1 1108766336 20760848 7726176
11:24:10 23627 8192000000 9999724128 10413649920 1083448816 11.680 100 30 60 1 1110893424 21505360 5939248
11:25:37 6954 8192000000 9998949536 10406096896 1088877216 11.732 100 32 63 0 1125064272 28802688 7384368
11:26:44 8016 8192000000 10013057552 10417319936 1080341216 11.651 99 31 62 4 1107499552 20259184 6899152
11:27:57 65972 8192000000 9978905248 10395803648 1032398096 11.192 95 33 64 1 1064690720 24847888 7444736
11:30:03 3766 8192000000 9964016288 10355404800 1037652704 11.242 98 33 64 3 1069828288 25578800 6596784
11:31:07 9618 8192000000 9931257920 10306146304 982225952 10.706 96 26 52 2 1007215424 19524864 5464608
11:32:18 7618 8192000000 9962503024 10343202816 1029206672 11.161 100 51 102 2 1056624848 16404064 11014112
11:33:27 36441 8192000000 9941699904 10345373696 1019335600 11.066 98 23 46 0 1051124736 26663632 5125504
11:35:06 6479 8192000000 9966101520 10360483840 1056805856 11.426 100 41 83 2 1078269584 12432160 9031568
11:36:13 8691 8192000000 9971436928 10364248064 1065841776 11.512 100 48 96 1 1086116912 9282320 10992816
11:37:25 10934 8192000000 10014950608 10404847616 1090931008 11.752 100 47 96 0 1119338096 18017712 10389376
11:38:42 79335 8192000000 10057990432 10458480640 1106845536 11.903 100 34 67 0 1161289648 47595216 6848896
11:41:11 25940 8192000000 10103199984 10513207296 1132032240 12.141 100 38 76 2 1209075120 69312816 7730064
11:42:43 18322 8192000000 10145016848 10569326592 1139092944 12.207 100 47 94 0 1260713744 110987344 10633456
11:44:08 21148 8192000000 10209316976 10628177920 1149846960 12.308 100 54 108 0 1318238608 156132192 12259456
11:45:46 2591 8192000000 10210143536 10638536704 1162755520 12.429 100 68 134 2 1318499456 140473888 15270048
11:46:51 10017 8192000000 10244663600 10658897920 1165834560 12.458 100 62 124 1 1349747088 169640816 14271712
11:48:05 2408 8192000000 10232971056 10675478528 1163361136 12.435 99 67 133 0 1343517424 165441104 14715184
11:49:12 5600 8192000000 10247352768 10650955776 1174529792 12.539 100 75 150 1 1340992384 150211456 16251136
11:50:21 2339 8192000000 10277869520 10684346368 1188791968 12.672 100 77 154 0 1372305872 166267328 17246576
11:51:26 6034 8192000000 10241717104 10669850624 1163985168 12.441 99 54 108 2 1366703616 190320288 12398160
11:52:40 1662 8192000000 10295846736 10700414976 1171919856 12.515 100 80 155 2 1391514288 202680944 16913488
11:53:45 8409 8192000000 10289486224 10722607104 1156756336 12.373 99 57 113 0 1398302832 227864832 13681664
11:54:58 3420 8192000000 10295942000 10702450688 1157746944 12.382 100 85 166 0 1395981856 219058304 19176608
11:56:06 11962 8192000000 10290726752 10717147136 1145074080 12.263 100 28 56 1 1393953472 241580976 7298416
11:57:27 9101 8192000000 10240442368 10653491200 1114264704 11.973 98 41 82 0 1327983744 204607728 9111312
11:58:49 7003 8192000000 10293493120 10706714624 1117867488 12.007 99 49 97 1 1391118352 261709088 11541776
12:00:06 14942 8192000000 10285638288 10721239040 1134529840 12.164 100 32 64 0 1390767312 248473632 7763840
12:01:27 23633 8192000000 10302187120 10755559424 1136577344 12.183 100 21 42 0 1413009824 271088256 5344224
12:03:09 15140 8192000000 10337079744 10757087232 1110250144 11.935 100 28 56 1 1433113776 315784144 7079488
12:04:41 26064 8192000000 10276823616 10728714240 1122274224 12.048 100 26 52 1 1393161728 264090656 6796848
12:06:10 32587 8192000000 10174538672 10671091712 1099269152 11.831 98 19 38 0 1298210896 194512576 4429168
12:07:56 39301 8192000000 10108767616 10591346688 1123483104 12.060 100 6 12 1 1208277344 83700512 1093728
12:09:36 25689 8192000000 10063478800 10469466112 1116596832 11.995 99 51 102 1 1149578256 21926400 11055024
12:11:07 103885 8192000000 10126913520 10551529472 1141277120 12.228 100 54 107 0 1192090464 39054448 11758896
12:13:58 49572 8192000000 10074132528 10498224128 1115324704 11.983 97 41 80 0 1157960608 33946336 8689568
12:15:48 42953 8192000000 10098457664 10524446720 1106794032 11.902 97 36 72 1 1181166240 66605392 7766816
12:17:38 16428 8192000000 10101666816 10566107136 1141212528 12.227 100 26 52 1 1199253056 52188064 5852464
12:18:55 99694 8192000000 10079005408 10539851776 1109067680 11.924 97 6 12 0 1137199168 27108800 1022688
12:21:39 59570 8192000000 10076941232 10538475520 1123479744 12.060 98 21 42 1 1161490192 33393824 4616624
12:23:45 49559 8192000000 10076235904 10528284672 1135255936 12.171 99 14 28 0 1181172224 43186944 2729344
12:25:34 77237 8192000000 10078929824 10604249088 1145182144 12.264 100 12 24 0 1215514656 67937568 2394944
12:27:53 66617 8192000000 10081196608 10531536896 1141975872 12.234 99 15 30 0 1198355552 53169664 3210016
12:30:04 35005 8192000000 10129202416 10579386368 1128880688 12.111 98 30 61 0 1214627040 79365984 6380368
12:31:39 7251 8192000000 10137436880 10560880640 1149736816 12.307 100 55 110 1 1216579184 55934224 10908144
12:32:48 61837 8192000000 10076607424 10542063616 1108180128 11.915 96 23 46 0 1145464720 32584768 4699824
12:34:54 57167 8192000000 10100259888 10535751680 1116583632 11.995 97 24 49 1 1180566832 58597056 5386144
12:37:49 91452 8192000000 10115050688 10575802368 1098632048 11.825 96 31 61 1 1167409040 62539584 6237408
12:40:40 78040 8192000000 10126511728 10599161856 1122655456 12.052 98 16 32 0 1218141088 92118512 3367120
12:43:01 70770 8192000000 10062199104 10549485568 1107790352 11.911 98 11 22 0 1197414928 86488272 3136304
12:45:17 41086 8192000000 10086523696 10516324352 1102186592 11.858 98 31 61 1 1164638000 56022112 6429296
12:47:01 69703 8192000000 10105795968 10560376832 1095747168 11.797 97 30 59 1 1176762704 74246496 6769040
12:49:15 62034 8192000000 10091807120 10528407552 1090145952 11.744 97 18 36 0 1145398656 51358240 3894464
12:51:17 38184 8192000000 10045717488 10460143616 1051660464 11.377 95 22 44 2 1089900784 33772704 4467616
12:52:57 61964 8192000000 10086351392 10592145408 1101517184 11.852 99 17 34 1 1209444576 103872144 4055248
12:54:59 63529 8192000000 10081368304 10531680256 1081668256 11.663 97 24 48 0 1132342800 45999280 4675264
12:57:11 114179 8192000000 10057180064 10541305856 1109730096 11.930 99 26 52 1 1177650080 63013184 4906800
13:00:06 70354 8192000000 10097983344 10549473280 1110123296 11.934 99 17 34 0 1171964224 58314640 3526288
13:02:18 67417 8192000000 10054624896 10545516544 1087055040 11.715 97 12 24 0 1149903312 59818640 3029632
13:04:40 52062 8192000000 10086670848 10551930880 1115812112 11.987 100 12 24 0 1179925488 61847488 2265888
13:07:06 61159 8192000000 10113020384 10562023424 1138231616 12.199 100 23 46 0 1169322528 26452432 4638480
13:09:12 30211 8192000000 10109462608 10567467008 1145133008 12.264 100 15 30 0 1191572320 43292016 3147296
13:10:43 71037 8192000000 10128538880 10594385920 1151647568 12.325 100 21 43 0 1227011168 70917616 4445984
13:12:57 35457 8192000000 10115460960 10553425920 1136070496 12.179 99 33 66 0 1192360304 49676688 6613120
13:14:36 14551 8192000000 10088651744 10523815936 1130697616 12.128 99 32 64 0 1179063504 41965760 6400128
13:15:55 40791 8192000000 10114718096 10636070912 1148395776 12.294 100 18 36 0 1226008656 74242976 3369904
13:17:37 80534 8192000000 10097532384 10533556224 1132737376 12.147 98 28 56 0 1182809424 43971408 6100640
13:20:01 133411 8192000000 10103785104 10562723840 1149068128 12.301 100 17 34 1 1184426656 32099328 3259200
13:23:15 67402 8192000000 10137682240 10631950336 1141868416 12.233 98 14 28 0 1222244240 77294816 3081008
13:25:23 53641 8192000000 10092323392 10595442688 1141272752 12.228 99 10 20 0 1190012560 46858960 1880848
13:27:33 93157 8192000000 10124259552 10605506560 1152038048 12.329 100 22 44 0 1193295616 36438160 4819408
13:30:06 56291 8192000000 10058166112 10535714816 1111660464 11.948 97 22 44 1 1173088384 56811936 4615984
13:32:07 40637 8192000000 10070819760 10515636224 1125639056 12.080 99 23 46 0 1164753552 34295984 4818512
13:33:59 91103 8192000000 10095359872 10586189824 1078967680 11.638 94 15 30 0 1178918464 96478896 3471888
13:36:44 40899 8192000000 9116875184 9399832576 256808512 3.039 23 10 19 0 312367840 53528688 2030640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-10-2021 13:38:22 to 29-10-2021 13:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 92 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 29-10-2021 13:38:38 to 29-10-2021 13:38:40.

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          232569
MON$OLDEST_ACTIVE               233043
MON$OLDEST_SNAPSHOT             233043
MON$NEXT_TRANSACTION            233043
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                       2045600
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             790
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2646 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2646 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2646 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

29.10.21 13:38:41. 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
2021-10-29 13:38:43 No severe PSQL-related problems occured
29.10.21 13:38:44. End of report.