Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.04.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 16408792 kB
MemAvailable 31508696 kB
Buffers 0 kB
Cached 15267776 kB
SwapCached 2364 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-04-09 13:37:14
DTS_END                         2022-04-09 13:37:14.0350
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/45790
TRN_ID                          205867
ATT_ID                          619
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: 8575.92 20840 1029110
customer order: creation 160.07 533 19208
customer order: refuse 13.75 474 1650
order to supplier: creation 42.06 1478 5047
order to supplier: removal 3.26 186960 391
invoice (draft): creation 41.65 3276 4998
invoice (draft): removal 50.31 1092 6037
invoice accept: apply 37.63 27388 4516
invoice accept: cancel 45.53 1221 5464
customer reserve: creation 3472.33 259 416679
customer reserve: removal 4437.14 107 532457
realization accept: apply 76.82 302 9218
realization accept: cancel 75.47 182 9057
payment from customer: creation 55.26 181 6631
payment from customer: removal 5.31 151 637
payment to supplier: creation 44.33 194 5320
payment to supplier: removal 3.34 140 401
service: total inventory turnovers 3.53 4660 424
service: total monetary turnovers 7.33 384 879
service: aggregate perf. data 0.77 19626 92
service: refresh index statistics 0.03 168199 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-04-2022 13:38:25 to 09-04-2022 13:38:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6679
TEST_TIME 2 5197
TEST_TIME 3 5247
TEST_TIME 4 5197
TEST_TIME 5 5427
TEST_TIME 6 5092
TEST_TIME 7 5236
TEST_TIME 8 5000
TEST_TIME 9 5081
TEST_TIME 10 5273
TEST_TIME 11 5439
TEST_TIME 12 5721
TEST_TIME 13 5885
TEST_TIME 14 5897
TEST_TIME 15 6407
TEST_TIME 16 6770
TEST_TIME 17 7154
TEST_TIME 18 7111
TEST_TIME 19 7090
TEST_TIME 20 7083
TEST_TIME 21 7267
TEST_TIME 22 7576
TEST_TIME 23 8015
TEST_TIME 24 8105
TEST_TIME 25 8272
TEST_TIME 26 8600
TEST_TIME 27 8615
TEST_TIME 28 9118
TEST_TIME 29 9240
TEST_TIME 30 9807
TEST_TIME 31 10008
TEST_TIME 32 10146
TEST_TIME 33 10113
TEST_TIME 34 10227
TEST_TIME 35 10365
TEST_TIME 36 10189
TEST_TIME 37 9953
TEST_TIME 38 9827
TEST_TIME 39 9743
TEST_TIME 40 9742
TEST_TIME 41 9704
TEST_TIME 42 9790
TEST_TIME 43 9827
TEST_TIME 44 9694
TEST_TIME 45 9737
TEST_TIME 46 9635
TEST_TIME 47 9515
TEST_TIME 48 9446
TEST_TIME 49 9330
TEST_TIME 50 9372
TEST_TIME 51 9312
TEST_TIME 52 9150
TEST_TIME 53 9177
TEST_TIME 54 9091
TEST_TIME 55 9058
TEST_TIME 56 8996
TEST_TIME 57 8901
TEST_TIME 58 8857
TEST_TIME 59 8754
TEST_TIME 60 8756
TEST_TIME 61 8831
TEST_TIME 62 8850
TEST_TIME 63 8784
TEST_TIME 64 8746
TEST_TIME 65 8829
TEST_TIME 66 8855
TEST_TIME 67 8821
TEST_TIME 68 8844
TEST_TIME 69 8883
TEST_TIME 70 8868
TEST_TIME 71 8935
TEST_TIME 72 8969
TEST_TIME 73 9028
TEST_TIME 74 9012
TEST_TIME 75 9003
TEST_TIME 76 8923
TEST_TIME 77 8840
TEST_TIME 78 8872
TEST_TIME 79 8832
TEST_TIME 80 8805
TEST_TIME 81 8745
TEST_TIME 82 8687
TEST_TIME 83 8650
TEST_TIME 84 8629
TEST_TIME 85 8667
TEST_TIME 86 8681
TEST_TIME 87 8685
TEST_TIME 88 8687
TEST_TIME 89 8668
TEST_TIME 90 8639
TEST_TIME 91 8653
TEST_TIME 92 8639
TEST_TIME 93 8737
TEST_TIME 94 8791
TEST_TIME 95 8874
TEST_TIME 96 9014
TEST_TIME 97 8963
TEST_TIME 98 8890
TEST_TIME 99 8869
TEST_TIME 100 8839
TEST_TIME 101 8875
TEST_TIME 102 8871
TEST_TIME 103 8824
TEST_TIME 104 8807
TEST_TIME 105 8804
TEST_TIME 106 8758
TEST_TIME 107 8720
TEST_TIME 108 8683
TEST_TIME 109 8755
TEST_TIME 110 8703
TEST_TIME 111 8675
TEST_TIME 112 8607
TEST_TIME 113 8608
TEST_TIME 114 8644
TEST_TIME 115 8607
TEST_TIME 116 8635
TEST_TIME 117 8626
TEST_TIME 118 8610
TEST_TIME 119 8663
TEST_TIME 120 8601

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-04-2022 13:38:29 to 09-04-2022 13:38:32.

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 1415169 1415169 0 0.00 0 30005 13 0 0
doc_list_biud 1415157 1415157 0 0.00 0 78070 47 0 0
sp_add_invoice_to_stock 4668 4516 152 3.25 39 212040 27389 0 152
sp_cancel_adding_invoice 5464 5464 0 0.00 6 76628 1221 0 0
sp_cancel_client_order 1650 1650 0 0.00 5 13441 474 0 0
sp_cancel_customer_reserve 532457 532457 0 0.00 1 51111 107 0 0
sp_cancel_pay_from_customer 637 637 0 0.00 3 6884 152 0 0
sp_cancel_pay_to_supplier 401 401 0 0.00 4 4100 140 0 0
sp_cancel_supplier_invoice 6055 6037 18 0.29 3 78084 1092 0 18
sp_cancel_supplier_order 391 391 0 0.00 7 1624605 186961 0 0
sp_cancel_write_off 9057 9057 0 0.00 2 11050 182 0 0
sp_client_order 19208 19208 0 0.00 5 40866 534 0 0
sp_customer_reserve 416679 416679 0 0.00 1 59257 259 0 0
sp_fill_shopping_cart 29244 29244 0 0.00 2 21218 88 0 0
sp_fill_shopping_cart_clo_res 416649 416649 0 0.00 0 26104 23 0 0
sp_get_clo_for_invoice 4478 4478 0 0.00 0 32600 353 0 0
sp_kill_cost_storno 15559 15559 0 0.00 0 22277 79 0 0
sp_kill_qstorno_ret_qs2qd 1080399 1080399 0 0.00 0 78066 48 0 0
sp_kill_qty_storno 568751 568751 0 0.00 0 78068 109 0 0
sp_lock_selected_doc 18996 18996 0 0.00 0 9116 7 0 0
sp_make_cost_storno 25647 25647 0 0.00 0 29993 82 0 0
sp_multiply_rows_for_pdistr 25647 25647 0 0.00 0 11593 41 0 0
sp_multiply_rows_for_qdistr 19208 19208 0 0.00 0 24373 208 0 0
sp_pay_from_customer 6631 6631 0 0.00 6 14128 182 0 0
sp_pay_to_supplier 5320 5320 0 0.00 6 11606 194 0 0
sp_payment_common 11949 11949 0 0.00 2 7707 44 0 0
sp_qd_handle_on_cancel_clo 1650 1650 0 0.00 0 13419 399 0 0
sp_qd_handle_on_invoice_upd_sts 9941 9941 0 0.00 0 47213 583 0 0
sp_qd_handle_on_reserve_upd_sts 9218 9218 0 0.00 0 40768 80 0 0
sp_reserve_write_off 9218 9218 0 0.00 8 40853 302 0 0
sp_supplier_invoice 4998 4998 0 0.00 7 128905 3276 0 0
sp_supplier_order 5047 5047 0 0.00 8 47824 1479 0 0
srv_aggregate_perf_data 93 92 1 1.07 2495 174003 19627 0 1
srv_fill_mon_cache_memory 69 69 0 0.00 672 128381 36367 0 0
srv_find_qd_qs_mism 985475 985475 0 0.00 0 13955 10 0 0
srv_make_invnt_saldo 424 424 0 0.00 76 68015 4660 0 0
srv_make_money_saldo 879 879 0 0.00 1 20476 384 0 0
srv_recalc_idx_stat 4 4 0 0.00 84091 213539 168200 0 0
t$perf-abend:sp_add_invoice_to_stock 152 152 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 18 18 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 4523 4523 0 0.00 0 10829 1014 0 0
t$perf-norm:sp_cancel_adding_invoice 355 355 0 0.00 1 6223 396 0 0
t$perf-norm:sp_cancel_client_order 1650 1650 0 0.00 0 1328 29 0 0
t$perf-norm:sp_cancel_customer_reserve 611 611 0 0.00 0 5905 47 0 0
t$perf-norm:sp_cancel_pay_from_customer 637 637 0 0.00 0 1502 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 401 401 0 0.00 0 449 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 345 345 0 0.00 0 7526 91 0 0
t$perf-norm:sp_cancel_supplier_order 391 391 0 0.00 0 107419 8204 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 705 18 0 0
t$perf-norm:sp_client_order 19209 19209 0 0.00 0 7735 40 0 0
t$perf-norm:sp_customer_reserve 914 914 0 0.00 0 1847 15 0 0
t$perf-norm:sp_pay_from_customer 6631 6631 0 0.00 0 6399 21 0 0
t$perf-norm:sp_pay_to_supplier 5320 5320 0 0.00 0 9457 24 0 0
t$perf-norm:sp_reserve_write_off 9218 9218 0 0.00 0 10591 28 0 0
t$perf-norm:sp_supplier_invoice 4999 4999 0 0.00 0 3378 31 0 0
t$perf-norm:sp_supplier_order 5047 5047 0 0.00 0 8571 40 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 461 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 443 10 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 330 9 0 0
t$perf-norm:srv_make_money_saldo 879 879 0 0.00 0 2535 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 7 87 53 0 0
v_add_invoice_to_stock 9250 8946 304 3.28 0 14682 27 0 304
v_all_customers 34984 34984 0 0.00 0 68 4 0 0
v_all_suppliers 28096 28096 0 0.00 0 77 4 0 0
v_all_wares 38411 38411 0 0.00 0 151 4 0 0
v_cancel_adding_invoice 710 710 0 0.00 0 4521 19 0 0
v_cancel_client_order 3300 3300 0 0.00 0 2961 11 0 0
v_cancel_customer_prepayment 1274 1274 0 0.00 0 3069 17 0 0
v_cancel_customer_reserve 1222 1222 0 0.00 0 256 9 0 0
v_cancel_payment_to_supplier 802 802 0 0.00 0 754 12 0 0
v_cancel_supplier_invoice 726 690 36 4.95 0 1157 29 0 36
v_cancel_supplier_order 782 782 0 0.00 0 321 10 0 0
v_cancel_write_off 1830 1830 0 0.00 0 3081 26 0 0
v_max_id_clo_ord 5045 5045 0 0.00 0 4196 7 0 0
v_max_id_clo_res 914 914 0 0.00 0 3033 18 0 0
v_max_id_ord_sup 4991 4991 0 0.00 0 4534 7 0 0
v_max_non_paid_invoice 1305 1305 0 0.00 0 677 8 0 0
v_max_non_paid_realizn 3473 3473 0 0.00 0 1217 8 0 0
v_min_id_clo_ord 5045 5045 0 0.00 0 3595 9 0 0
v_min_id_clo_res 914 914 0 0.00 0 9304 28 0 0
v_min_id_ord_sup 4991 4991 0 0.00 0 9031 14 0 0
v_min_non_paid_invoice 5318 5318 0 0.00 0 4173 13 0 0
v_min_non_paid_realizn 6631 6631 0 0.00 0 4941 17 0 0
v_our_firm 9742 9742 0 0.00 0 69 5 0 0
v_reserve_write_off 18434 18434 0 0.00 0 5067 11 0 0
x_lock_depdocs_on_canc_invoice 11156 11156 0 0.00 0 23257 143 0 0
x_lock_depdocs_on_canc_sup_ord 391 391 0 0.00 0 4165 363 0 0
x_make_qstorno_1000_1200 5047 5047 0 0.00 3 47813 1288 0 0
x_make_qstorno_1200_2000 4998 4998 0 0.00 3 126872 3042 0 0
x_make_qstorno_2100_3300 416679 416679 0 0.00 0 59254 223 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 09-04-2022 13:38:33 to 09-04-2022 13:38:38.

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:17 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:18 33807 8192000000 9737245056 10040086528 806043296 8.957 100 88 175 1 836117168 8005424 22068448
11:10:00 18792 8192000000 9775314688 10086989824 848919296 9.389 100 87 174 0 881729264 12386976 20422992
11:11:27 19171 8192000000 9812728656 10137239552 877400560 9.674 100 85 170 1 920265872 22880304 19985008
11:12:51 15378 8192000000 9866787728 10200965120 923007792 10.126 100 82 163 1 973835936 33302992 17525152
11:14:13 5140 8192000000 9933848736 10277941248 964628912 10.534 100 89 176 1 1047271232 63880384 18761936
11:15:21 4949 8192000000 9971313200 10327240704 998273952 10.862 100 86 171 1 1084303008 68408160 17620896
11:16:28 2075 8192000000 10010112656 10369765376 1014066080 11.015 100 94 188 3 1124940928 91302976 19571872
11:17:34 1513 8192000000 10008476096 10377113600 1043548048 11.299 100 97 192 4 1130085984 66464544 20073392
11:18:37 1175 8192000000 10029419536 10403442688 1051564704 11.376 100 92 175 0 1150152432 81080416 17507312
11:19:40 1357 8192000000 10032063040 10409549824 1064995328 11.504 100 95 188 2 1149896768 66136464 18764976
11:20:43 1135 8192000000 10024213424 10402844672 1071318176 11.565 100 93 181 0 1141244768 51861280 18065312
11:21:46 1217 8192000000 10053250048 10438971392 1074936032 11.599 100 96 185 2 1176148752 82502960 18709760
11:22:48 3006 8192000000 10052938336 10447339520 1081602752 11.663 100 65 128 0 1176589072 82994112 11992208
11:23:52 1081 8192000000 10049775488 10436624384 1092908336 11.770 100 90 173 1 1171848896 60963632 17976928
11:24:53 1126 8192000000 10062139488 10449649664 1095395248 11.794 100 91 170 3 1187047488 73501408 18150832
11:25:56 4395 8192000000 10061036672 10446659584 1092085984 11.762 100 87 164 0 1175967264 69366144 14515136
11:27:01 1019 8192000000 10059651968 10450604032 1100364576 11.841 100 97 181 1 1180873776 61172768 19336432
11:28:03 1293 8192000000 10063364320 10455678976 1098982768 11.828 100 88 162 1 1184525488 69405680 16137040
11:29:05 3493 8192000000 10038689184 10434256896 1087815904 11.722 100 82 163 0 1161958192 57760064 16382224
11:30:09 1129 8192000000 10048554144 10435203072 1085328288 11.698 100 95 184 0 1174865616 70916256 18621072
11:31:11 2905 8192000000 10060274736 10442604544 1087533808 11.719 100 86 166 4 1176313344 73081664 15697872
11:32:15 2549 8192000000 10019750992 10411393024 1077125056 11.620 100 69 133 2 1136119440 45537952 13456432
11:33:20 1133 8192000000 9988986848 10363322368 1030517824 11.173 100 86 169 1 1109793088 61931616 17343648
11:34:22 1337 8192000000 9976159056 10350002176 1028545952 11.154 100 79 155 2 1099146864 54234304 16366608
11:35:24 1085 8192000000 9997385168 10370056192 1035510096 11.221 100 96 181 3 1124367360 69150224 19707040
11:36:26 1257 8192000000 9975332256 10343100416 1033406784 11.201 100 94 173 0 1097576416 45302176 18867456
11:37:31 28705 8192000000 10014489696 10397085696 1058764864 11.445 100 62 123 1 1126079840 55272592 12042384
11:39:11 11189 8192000000 10033688400 10421608448 1080656496 11.654 100 66 124 0 1161467232 68836208 11974528
11:40:30 1172 8192000000 10095787680 10488401920 1099506704 11.833 100 84 153 0 1211836000 96759760 15569536
11:41:46 5110 8192000000 10111511088 10507227136 1115701152 11.986 100 64 122 2 1228919072 100190368 13027552
11:42:56 12153 8192000000 10147941552 10543046656 1125914208 12.083 100 74 146 3 1257740496 115583280 16243008
11:44:13 4521 8192000000 10203743664 10594955264 1132174544 12.142 100 74 143 1 1318860912 170775440 15910928
11:45:23 6279 8192000000 10239759344 10643800064 1149338512 12.303 100 58 111 1 1350655328 189208336 12108480
11:46:36 917 8192000000 10300791120 10692808704 1152019136 12.328 100 95 183 0 1413578912 242227472 19332304
11:47:42 1146 8192000000 10306619008 10702987264 1161131744 12.414 100 87 161 0 1424637584 243005216 20500624
11:48:47 1056 8192000000 10370849952 10768510976 1173860032 12.533 100 89 171 1 1484910928 291617136 19433760
11:49:51 861 8192000000 10405731744 10812612608 1174122608 12.535 100 99 191 1 1518410672 322448304 21839760
11:50:56 892 8192000000 10419594960 10824003584 1185818832 12.644 100 95 181 3 1529264912 321890448 21555632
11:52:01 1690 8192000000 10426575360 10869796864 1180463936 12.595 100 95 189 0 1534870976 332481856 21925184
11:53:05 1378 8192000000 10438696784 10866479104 1190532400 12.688 100 98 193 0 1549339200 336346800 22460000
11:54:11 895 8192000000 10460684512 10883231744 1199975920 12.776 100 98 189 3 1568981200 345945264 23060016
11:55:14 2254 8192000000 10481413312 10888867840 1193457568 12.716 100 91 176 2 1587070640 372148000 21465072
11:56:20 662 8192000000 10500482976 10907762688 1194847008 12.728 100 93 177 0 1605329200 389388480 21093712
11:57:26 854 8192000000 10525197040 10967896064 1193566688 12.717 100 97 191 1 1628919280 412016272 23336320
11:58:29 972 8192000000 10507140560 10986434560 1185530160 12.642 100 96 185 1 1610907488 402755168 22622160
11:59:33 982 8192000000 10508640816 10952921088 1187803104 12.663 100 93 182 1 1613246416 403896192 21547120
12:00:37 991 8192000000 10513937120 10988269568 1180940288 12.599 100 87 171 0 1622702480 421156656 20605536
12:01:43 7530 8192000000 10476572000 10926854144 1182499216 12.613 100 62 118 0 1603300480 406208160 14593104
12:02:55 4409 8192000000 10521072560 10984423424 1177527728 12.567 100 57 114 0 1624870176 432329376 15013072
12:04:28 14874 8192000000 10404222336 10915659776 1166778448 12.467 100 37 74 1 1554439184 377972272 9688464
12:05:45 22896 8192000000 10361570912 10826436608 1155151536 12.358 99 33 66 1 1493793808 330147056 8495216
12:07:40 9761 8192000000 10208899168 10641379328 1151697568 12.325 100 40 73 3 1309161328 148787008 8676752
12:08:50 32693 8192000000 10167982752 10629775360 1134921376 12.168 98 24 43 2 1265294704 125301216 5072112
12:10:25 16119 8192000000 10181784496 10624352256 1148664464 12.297 99 49 93 0 1281460144 121652480 11143200
12:11:47 7938 8192000000 10134463888 10579619840 1095156656 11.792 96 50 96 1 1214806928 108579280 11070992
12:13:04 128380 8192000000 10159463616 10589794304 1131031984 12.131 99 44 84 0 1262619344 122432944 9154416
12:16:19 9779 8192000000 10191988480 10616066048 1144448064 12.257 100 71 136 0 1288776640 129752400 14576176
12:17:30 51617 8192000000 10162957024 10684977152 1116175040 11.991 99 19 38 1 1289802912 168913424 4714448
12:19:26 86022 8192000000 10079788880 10523336704 1105251744 11.887 98 12 25 0 1202965456 95073840 2639872
12:21:56 67960 8192000000 10063474512 10548998144 1117857280 12.007 100 20 38 1 1170022800 48273616 3891904
12:24:10 90089 8192000000 10114042720 10577350656 1133690976 12.156 99 19 38 1 1209091216 71065936 4334304
12:26:40 96297 8192000000 10104145728 10626019328 1134530064 12.164 100 11 22 1 1228148320 90768528 2849728
12:29:29 50443 8192000000 10066256080 10525577216 1133339280 12.153 100 63 100 1 1170116800 27669312 9108208
12:31:21 36943 8192000000 10091553696 10540580864 1133892416 12.158 99 34 65 3 1186926960 45321312 7713232
12:32:59 65689 8192000000 10082390080 10543169536 1135372464 12.172 99 10 20 0 1191182880 53595760 2214656
12:35:27 78341 8192000000 10119103504 10575876096 1106656448 11.901 97 14 28 0 1200487040 90324720 3505872
12:38:28 27516 8192000000 10091993072 10566582272 1135588032 12.174 100 41 80 1 1213058176 69333664 8136480
12:39:58 58726 8192000000 10094447152 10550325248 1121785136 12.044 98 20 41 0 1165767456 40024288 3958032
12:42:50 52132 8192000000 10058824048 10545020928 1112317632 11.954 98 27 54 1 1167322160 49053360 5951168
12:44:44 61742 8192000000 10110773504 10569367552 1104209888 11.878 97 36 72 0 1174058160 62828640 7019632
12:46:50 87491 8192000000 10112658736 10574987264 1094670576 11.787 97 23 46 0 1184184832 84891168 4623088
12:49:18 43244 8192000000 10092858608 10535813120 1129590432 12.118 100 29 56 1 1190319552 54901296 5827824
12:51:02 26979 8192000000 10093644448 10560012288 1120525712 12.032 99 27 54 3 1170250160 44154320 5570128
12:52:42 67449 8192000000 10141394080 10590310400 1138290544 12.199 100 28 56 0 1215742608 71528176 5923888
12:55:24 30210 8192000000 10091866496 10576744448 1130462784 12.126 99 49 97 1 1210695568 69479440 10753344
12:57:25 41603 8192000000 10053785760 10514538496 1117483728 12.003 98 17 35 1 1164525856 43561872 3480256
12:59:08 74240 8192000000 10135416608 10612609024 1137627984 12.193 100 10 20 0 1202611872 62779136 2204752
13:01:22 78871 8192000000 10145496016 10651451392 1140789920 12.223 99 18 35 0 1288999760 143981952 4227888
13:03:47 42774 8192000000 10141200448 10615742464 1154905088 12.356 100 25 50 0 1252438464 91905792 5627584
13:05:38 38707 8192000000 10152432416 10580107264 1113791296 11.968 97 35 69 0 1218905904 97011968 8102640
13:07:22 28877 8192000000 10187143488 10615537664 1121576880 12.042 98 37 74 0 1250398496 120397712 8423904
13:08:56 45709 8192000000 10192996336 10620674048 1108329584 11.917 97 35 68 1 1262541200 146725376 7486240
13:10:42 45456 8192000000 10156592368 10625118208 1118147104 12.009 98 26 50 2 1244627776 120939216 5541456
13:12:31 89476 8192000000 10064193232 10590113792 1081070064 11.658 95 21 42 1 1210528128 124479104 4978960
13:15:01 16760 8192000000 10145210352 10565423104 1123765680 12.063 99 49 97 0 1215446336 81716960 9963696
13:16:26 90561 8192000000 10087856464 10550906880 1121567248 12.042 100 33 65 2 1221030080 92547904 6914928
13:19:05 55840 8192000000 10070256432 10529705984 1139227232 12.208 100 20 40 0 1184574960 41309008 4038720
13:22:02 37924 8192000000 10084982016 10550808576 1132690752 12.147 99 13 26 0 1165652960 30173632 2788576
13:23:41 80945 8192000000 10127064416 10584870912 1138064864 12.197 99 25 50 0 1205492528 61733968 5693696
13:26:07 81277 8192000000 10058213456 10552508416 1130898560 12.130 99 6 12 0 1176055792 43903888 1253344
13:28:29 69047 8192000000 10108798384 10571870208 1151996944 12.328 100 20 39 0 1203960432 47352704 4610784
13:31:13 58603 8192000000 10094064304 10583257088 1142115152 12.235 100 9 18 0 1219729376 75470192 2144032
13:33:25 16767 8192000000 10076561472 10523086848 1139401760 12.210 100 36 70 2 1178586976 31830896 7354320
13:34:47 65788 8192000000 10150410464 10596155392 1139214000 12.208 99 28 56 0 1241952208 96841104 5897104
13:36:54 31977 8192000000 9173943504 9478565888 318364032 3.740 28 8 14 1 359809056 39885712 1559312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-04-2022 13:38:40 to 09-04-2022 13:38:55.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 152 v_add_invoice_to_stock 335544517
except 18 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-04-2022 13:38:56 to 09-04-2022 13:38:58.

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          205686
MON$OLDEST_ACTIVE               206261
MON$OLDEST_SNAPSHOT             206261
MON$NEXT_TRANSACTION            206261
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                       2059296
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             747
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2757 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2757 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2757 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2757 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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Sat Apr  9 13:37:31 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:83: 
--- end of diff output ---

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

09.04.22 13:38:59. 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-04-09 13:39:02 No severe PSQL-related problems occured
09.04.22 13:39:03. End of report.