Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.04.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 16395192 kB
MemAvailable 31551128 kB
Buffers 0 kB
Cached 15347784 kB
SwapCached 2688 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-14 13:37:15
DTS_END                         2022-04-14 13:37:15.4560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37766
TRN_ID                          225462
ATT_ID                          637
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: 9183.17 23665 1101980
customer order: creation 174.68 434 20961
customer order: refuse 15.27 398 1832
order to supplier: creation 47.19 1260 5663
order to supplier: removal 3.28 164340 394
invoice (draft): creation 47.08 3206 5649
invoice (draft): removal 49.30 951 5916
invoice accept: apply 43.13 26420 5176
invoice accept: cancel 44.88 1122 5386
customer reserve: creation 4034.61 252 484153
customer reserve: removal 4425.38 95 531045
realization accept: apply 85.57 275 10268
realization accept: cancel 78.87 162 9464
payment from customer: creation 61.25 157 7350
payment from customer: removal 5.86 140 703
payment to supplier: creation 50.44 182 6053
payment to supplier: removal 3.44 126 413
service: total inventory turnovers 4.27 3603 512
service: total monetary turnovers 7.76 325 931
service: aggregate perf. data 0.89 15003 107
service: refresh index statistics 0.03 254845 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 14-04-2022 13:39:41 to 14-04-2022 13:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5146
TEST_TIME 2 5700
TEST_TIME 3 5358
TEST_TIME 4 5673
TEST_TIME 5 5191
TEST_TIME 6 5336
TEST_TIME 7 5466
TEST_TIME 8 5988
TEST_TIME 9 6133
TEST_TIME 10 6218
TEST_TIME 11 6211
TEST_TIME 12 6230
TEST_TIME 13 6705
TEST_TIME 14 6674
TEST_TIME 15 6726
TEST_TIME 16 6878
TEST_TIME 17 7029
TEST_TIME 18 7169
TEST_TIME 19 7299
TEST_TIME 20 7526
TEST_TIME 21 7584
TEST_TIME 22 8268
TEST_TIME 23 8167
TEST_TIME 24 8386
TEST_TIME 25 8700
TEST_TIME 26 8819
TEST_TIME 27 9199
TEST_TIME 28 9584
TEST_TIME 29 9895
TEST_TIME 30 10227
TEST_TIME 31 10528
TEST_TIME 32 10746
TEST_TIME 33 10859
TEST_TIME 34 11081
TEST_TIME 35 11239
TEST_TIME 36 11160
TEST_TIME 37 10963
TEST_TIME 38 10960
TEST_TIME 39 10869
TEST_TIME 40 10762
TEST_TIME 41 10581
TEST_TIME 42 10436
TEST_TIME 43 10460
TEST_TIME 44 10370
TEST_TIME 45 10270
TEST_TIME 46 10170
TEST_TIME 47 10188
TEST_TIME 48 10144
TEST_TIME 49 10180
TEST_TIME 50 10151
TEST_TIME 51 10065
TEST_TIME 52 10064
TEST_TIME 53 9980
TEST_TIME 54 9958
TEST_TIME 55 9795
TEST_TIME 56 9874
TEST_TIME 57 9988
TEST_TIME 58 9992
TEST_TIME 59 9920
TEST_TIME 60 9831
TEST_TIME 61 9886
TEST_TIME 62 9960
TEST_TIME 63 9896
TEST_TIME 64 9968
TEST_TIME 65 10033
TEST_TIME 66 9970
TEST_TIME 67 9960
TEST_TIME 68 10027
TEST_TIME 69 10005
TEST_TIME 70 9977
TEST_TIME 71 9925
TEST_TIME 72 9845
TEST_TIME 73 9807
TEST_TIME 74 9728
TEST_TIME 75 9685
TEST_TIME 76 9710
TEST_TIME 77 9659
TEST_TIME 78 9590
TEST_TIME 79 9534
TEST_TIME 80 9487
TEST_TIME 81 9475
TEST_TIME 82 9452
TEST_TIME 83 9506
TEST_TIME 84 9534
TEST_TIME 85 9551
TEST_TIME 86 9549
TEST_TIME 87 9542
TEST_TIME 88 9544
TEST_TIME 89 9528
TEST_TIME 90 9577
TEST_TIME 91 9547
TEST_TIME 92 9585
TEST_TIME 93 9528
TEST_TIME 94 9492
TEST_TIME 95 9432
TEST_TIME 96 9407
TEST_TIME 97 9348
TEST_TIME 98 9317
TEST_TIME 99 9267
TEST_TIME 100 9324
TEST_TIME 101 9325
TEST_TIME 102 9309
TEST_TIME 103 9274
TEST_TIME 104 9233
TEST_TIME 105 9243
TEST_TIME 106 9219
TEST_TIME 107 9290
TEST_TIME 108 9305
TEST_TIME 109 9425
TEST_TIME 110 9459
TEST_TIME 111 9507
TEST_TIME 112 9476
TEST_TIME 113 9412
TEST_TIME 114 9378
TEST_TIME 115 9331
TEST_TIME 116 9321
TEST_TIME 117 9317
TEST_TIME 118 9290
TEST_TIME 119 9270
TEST_TIME 120 9202

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-04-2022 13:39:45 to 14-04-2022 13:39:48.

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 1547029 1547029 0 0.00 0 33502 13 0 0
doc_list_biud 1547019 1547019 0 0.00 0 26819 38 0 0
sp_add_invoice_to_stock 5306 5176 130 2.45 65 216326 26420 0 130
sp_cancel_adding_invoice 5386 5386 0 0.00 7 91975 1122 0 0
sp_cancel_client_order 1832 1832 0 0.00 6 11869 398 0 0
sp_cancel_customer_reserve 531045 531045 0 0.00 1 21335 96 0 0
sp_cancel_pay_from_customer 703 703 0 0.00 4 5057 140 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 4 2254 126 0 0
sp_cancel_supplier_invoice 5923 5916 7 0.11 3 22121 951 0 7
sp_cancel_supplier_order 394 394 0 0.00 9 1337636 164341 0 0
sp_cancel_write_off 9464 9464 0 0.00 2 8317 162 0 0
sp_client_order 20961 20961 0 0.00 6 28178 434 0 0
sp_customer_reserve 484153 484153 0 0.00 1 55294 253 0 0
sp_fill_shopping_cart 32249 32249 0 0.00 2 11511 76 0 0
sp_fill_shopping_cart_clo_res 484131 484131 0 0.00 0 18815 16 0 0
sp_get_clo_for_invoice 5147 5147 0 0.00 0 28464 270 0 0
sp_kill_cost_storno 15966 15966 0 0.00 0 8302 71 0 0
sp_kill_qstorno_ret_qs2qd 1077864 1077864 0 0.00 0 22089 42 0 0
sp_kill_qty_storno 569448 569448 0 0.00 0 26817 96 0 0
sp_lock_selected_doc 21309 21309 0 0.00 0 10794 8 0 0
sp_make_cost_storno 28818 28818 0 0.00 0 33490 76 0 0
sp_multiply_rows_for_pdistr 28815 28815 0 0.00 0 6430 32 0 0
sp_multiply_rows_for_qdistr 20960 20960 0 0.00 0 21655 144 0 0
sp_pay_from_customer 7350 7350 0 0.00 5 13303 158 0 0
sp_pay_to_supplier 6053 6053 0 0.00 6 12903 182 0 0
sp_payment_common 13402 13402 0 0.00 2 12796 42 0 0
sp_qd_handle_on_cancel_clo 1832 1832 0 0.00 0 11862 324 0 0
sp_qd_handle_on_invoice_upd_sts 10530 10530 0 0.00 0 26816 487 0 0
sp_qd_handle_on_reserve_upd_sts 10267 10267 0 0.00 0 20215 71 0 0
sp_reserve_write_off 10268 10268 0 0.00 8 20335 276 0 0
sp_supplier_invoice 5649 5649 0 0.00 8 84856 3207 0 0
sp_supplier_order 5663 5663 0 0.00 8 37461 1260 0 0
srv_aggregate_perf_data 108 107 1 0.92 2936 173919 15004 0 1
srv_fill_mon_cache_memory 73 73 0 0.00 741 106525 34905 0 0
srv_find_qd_qs_mism 1050993 1050993 0 0.00 0 10132 11 0 0
srv_make_invnt_saldo 512 512 0 0.00 41 121641 3604 0 0
srv_make_money_saldo 931 931 0 0.00 1 13490 325 0 0
srv_recalc_idx_stat 4 4 0 0.00 149890 353547 254846 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 5179 5179 0 0.00 0 10503 787 0 0
t$perf-norm:sp_cancel_adding_invoice 433 433 0 0.00 1 6434 374 0 0
t$perf-norm:sp_cancel_client_order 1832 1832 0 0.00 0 1590 27 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 5536 38 0 0
t$perf-norm:sp_cancel_pay_from_customer 703 703 0 0.00 0 6721 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1588 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 2473 33 0 0
t$perf-norm:sp_cancel_supplier_order 394 394 0 0.00 0 97656 7224 0 0
t$perf-norm:sp_cancel_write_off 1047 1047 0 0.00 0 5979 28 0 0
t$perf-norm:sp_client_order 20961 20961 0 0.00 0 6894 34 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 707 11 0 0
t$perf-norm:sp_pay_from_customer 7351 7351 0 0.00 0 3854 17 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 2895 17 0 0
t$perf-norm:sp_reserve_write_off 10268 10268 0 0.00 0 10375 22 0 0
t$perf-norm:sp_supplier_invoice 5649 5649 0 0.00 0 6343 30 0 0
t$perf-norm:sp_supplier_order 5663 5663 0 0.00 0 2972 27 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 433 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 73 73 0 0.00 0 502 9 0 0
t$perf-norm:srv_make_invnt_saldo 512 512 0 0.00 0 1019 13 0 0
t$perf-norm:srv_make_money_saldo 931 931 0 0.00 0 404 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 94 39 0 0
v_add_invoice_to_stock 10532 10272 260 2.46 0 4748 23 0 260
v_all_customers 38854 38854 0 0.00 0 87 3 0 0
v_all_suppliers 31869 31869 0 0.00 0 136 4 0 0
v_all_wares 41906 41906 0 0.00 0 214 3 0 0
v_cancel_adding_invoice 866 866 0 0.00 0 1435 14 0 0
v_cancel_client_order 3664 3664 0 0.00 0 3915 11 0 0
v_cancel_customer_prepayment 1406 1406 0 0.00 0 3703 19 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1580 10 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 2242 15 0 0
v_cancel_supplier_invoice 820 806 14 1.70 0 523 21 0 14
v_cancel_supplier_order 788 788 0 0.00 0 697 11 0 0
v_cancel_write_off 2094 2094 0 0.00 0 4364 22 0 0
v_max_id_clo_ord 5656 5656 0 0.00 0 3914 5 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 2973 15 0 0
v_max_id_ord_sup 5636 5636 0 0.00 0 1234 5 0 0
v_max_non_paid_invoice 1410 1410 0 0.00 0 5380 13 0 0
v_max_non_paid_realizn 3754 3754 0 0.00 0 892 6 0 0
v_min_id_clo_ord 5656 5656 0 0.00 0 2928 8 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 299 7 0 0
v_min_id_ord_sup 5636 5636 0 0.00 0 6173 13 0 0
v_min_non_paid_invoice 6053 6053 0 0.00 0 6932 15 0 0
v_min_non_paid_realizn 7349 7349 0 0.00 0 12763 15 0 0
v_our_firm 10242 10242 0 0.00 0 51 4 0 0
v_reserve_write_off 20532 20532 0 0.00 0 5081 9 0 0
x_lock_depdocs_on_canc_invoice 10899 10899 0 0.00 0 26041 132 0 0
x_lock_depdocs_on_canc_sup_ord 394 394 0 0.00 0 19584 379 0 0
x_make_qstorno_1000_1200 5663 5663 0 0.00 3 37441 1105 0 0
x_make_qstorno_1200_2000 5649 5649 0 0.00 4 84826 3000 0 0
x_make_qstorno_2100_3300 484153 484153 0 0.00 0 46383 225 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 14-04-2022 13:39:49 to 14-04-2022 13:39:54.

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:19 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:20 36888 8192000000 9720915072 10021560320 798513696 8.881 100 91 182 1 829258080 8357408 22386976
11:10:00 10699 8192000000 9754630576 10060730368 830384976 9.203 100 87 174 0 864515248 13207088 20923184
11:11:21 20749 8192000000 9807442832 10127593472 871886304 9.619 100 83 166 0 910123552 19064480 19172768
11:12:45 9284 8192000000 9868579568 10197934080 911746320 10.015 100 82 164 1 974609456 44758016 18105120
11:14:05 4447 8192000000 9921660528 10268565504 957077696 10.460 100 82 160 1 1035186560 61199760 16909104
11:15:12 2364 8192000000 9974891104 10328489984 994116064 10.821 100 89 176 0 1090885712 78473856 18295792
11:16:16 1271 8192000000 10000757824 10365042688 1025598592 11.126 100 87 173 1 1119729152 76494816 17635744
11:17:19 1254 8192000000 10022452880 10393247744 1054184608 11.401 100 94 180 0 1144870912 72429856 18256448
11:18:22 1075 8192000000 10030465888 10411003904 1062464128 11.480 100 94 184 2 1155676928 74623696 18589104
11:19:24 1448 8192000000 10063444112 10443112448 1066744208 11.521 100 90 176 0 1180489888 96824640 16921040
11:20:28 1021 8192000000 10048795760 10429308928 1075912672 11.609 100 95 182 0 1175036976 80609680 18514624
11:21:29 5025 8192000000 10016126528 10401550336 1073121104 11.582 100 77 147 1 1136240784 48829136 14290544
11:22:38 1135 8192000000 10054393664 10440933376 1084608144 11.691 100 96 176 0 1168006560 65515440 17882976
11:23:41 1183 8192000000 10069746032 10461396992 1091113424 11.753 100 97 188 1 1190732000 80918800 18699776
11:24:43 935 8192000000 10065566336 10456825856 1094943280 11.790 100 89 174 1 1185571344 70889664 19738400
11:25:45 2520 8192000000 10063528112 10454999040 1097208768 11.811 100 78 144 2 1181319872 68176272 15934832
11:26:49 8127 8192000000 10041788736 10441170944 1089422352 11.737 99 52 98 0 1161747040 61478048 10846640
11:27:58 4177 8192000000 10038916800 10432258048 1099202528 11.830 100 71 130 1 1161898032 49295680 13399824
11:29:03 1185 8192000000 10035898240 10415767552 1071116240 11.563 100 97 190 0 1159525776 69057744 19351792
11:30:05 1038 8192000000 10033336272 10409816064 1064553504 11.500 100 94 185 2 1149001376 64989200 19458672
11:31:07 1136 8192000000 10030162752 10409574400 1065011904 11.504 100 96 183 0 1156505136 72458960 19034272
11:32:09 1250 8192000000 10013852784 10393640960 1074537312 11.595 100 97 189 0 1133679888 40287072 18855504
11:33:11 1062 8192000000 10003862896 10379927552 1056455520 11.423 100 98 182 1 1126291280 49770064 20065696
11:34:13 2127 8192000000 9988656912 10365620224 1048563216 11.347 100 83 154 1 1116568752 52612416 15393120
11:35:16 778 8192000000 9977656480 10356879360 1045322512 11.316 100 78 145 1 1102283632 41930432 15030688
11:36:17 1804 8192000000 10012063296 10388410368 1052861136 11.388 100 78 155 2 1131498416 62809808 15827472
11:37:22 10125 8192000000 9989831472 10376015872 1058870912 11.446 100 62 123 0 1113136816 41717840 12548064
11:38:40 6420 8192000000 10015022000 10400575488 1069235216 11.545 99 60 117 0 1131369456 51164384 10969856
11:40:02 12542 8192000000 10078787744 10466885632 1103715184 11.873 100 71 135 1 1189598576 72309280 13574112
11:41:25 1938 8192000000 10124823616 10510422016 1126457888 12.088 100 90 165 3 1243764576 99633696 17672992
11:42:30 3567 8192000000 10184685648 10576379904 1136573776 12.183 100 77 154 0 1304558864 151435824 16549264
11:43:37 2097 8192000000 10220057056 10625511424 1140583024 12.221 100 71 140 1 1339704560 182249808 16871728
11:44:44 1216 8192000000 10228079264 10634731520 1154889696 12.355 100 90 168 1 1344082432 170802048 18390688
11:45:50 2171 8192000000 10284334752 10689052672 1165297648 12.453 100 80 159 1 1393839360 211014624 17527088
11:46:57 2110 8192000000 10315349072 10719227904 1171467008 12.511 100 83 158 2 1427049360 237033376 18548976
11:48:04 3682 8192000000 10343229632 10745462784 1177147728 12.564 100 93 178 0 1451679840 256122112 18410000
11:49:10 7541 8192000000 10362392880 10788634624 1183947392 12.627 100 65 128 0 1471428064 271892768 15587904
11:50:23 887 8192000000 10371248304 10809376768 1175301216 12.546 100 96 186 0 1480198464 283067968 21829280
11:51:28 1855 8192000000 10395959056 10800984064 1196877008 12.747 100 95 181 1 1504437952 286443296 21117648
11:52:34 1630 8192000000 10437358480 10849796096 1193846400 12.719 100 91 180 0 1543189600 328908288 20434912
11:53:39 1472 8192000000 10467984704 10890665984 1192575296 12.707 100 94 183 0 1574855632 359132688 23147648
11:54:45 829 8192000000 10489497056 10927648768 1191553136 12.698 100 96 190 1 1596379360 380151552 24674672
11:55:50 756 8192000000 10500411792 10923712512 1197981536 12.758 100 96 183 0 1607534464 386782960 22769968
11:56:55 999 8192000000 10519306400 10942324736 1207072176 12.842 100 96 185 1 1633430800 401992480 24366144
11:57:58 723 8192000000 10512940992 10979557376 1205604336 12.828 100 85 169 0 1619633696 393482832 20546528
11:59:05 2803 8192000000 10527126288 11017420800 1188562544 12.670 99 82 157 0 1629031584 420744080 19724960
12:00:11 5320 8192000000 10517653888 10965790720 1189294352 12.677 100 48 96 0 1620362480 418039408 13028720
12:01:18 26949 8192000000 10546914208 11009507328 1172310512 12.518 98 39 78 1 1651542224 468706912 10524800
12:02:48 14739 8192000000 10499385792 10992492544 1179217152 12.583 100 36 72 0 1636865056 448125232 9522672
12:04:04 15960 8192000000 10447475568 10950459392 1176169360 12.554 100 35 70 0 1592734576 407137120 9428096
12:05:21 14754 8192000000 10407500752 10881875968 1151758480 12.326 99 30 59 0 1496363600 337075824 7529296
12:06:39 16897 8192000000 10358068768 10836185088 1136652400 12.184 98 24 48 0 1444538720 301537920 6348400
12:07:58 21467 8192000000 10238314096 10682310656 1128742496 12.110 98 22 44 0 1344290608 210472528 5075584
12:09:22 49286 8192000000 10164104272 10675126272 1125063968 12.075 98 11 22 0 1266179728 138431696 2684064
12:11:11 38072 8192000000 10106238416 10554392576 1120615888 12.033 98 24 48 1 1191051440 65771456 4664096
12:12:56 81786 8192000000 10066315952 10493841408 1100606976 11.843 96 22 42 0 1131321344 26749904 3964464
12:15:23 73714 8192000000 10065515920 10499809280 1119753728 12.025 98 27 50 3 1168443232 43186320 5503184
12:17:45 106015 8192000000 10106122400 10552889344 1071394112 11.565 94 38 76 0 1123978272 44608448 7975712
12:20:35 52306 8192000000 10082297232 10530754560 1121351264 12.040 99 27 54 1 1181940800 54420464 6169072
12:22:31 38376 8192000000 10099525712 10534752256 1122373088 12.049 99 30 57 0 1173208016 44862544 5972384
12:24:11 1606 8192000000 10155340400 10571313152 1131335952 12.134 100 71 142 0 1251085488 104664816 15084720
12:25:14 12972 8192000000 10144720800 10564333568 1133334992 12.153 100 77 149 3 1242005888 93579776 15091120
12:26:30 12988 8192000000 10097283232 10535178240 1125795376 12.082 100 67 119 2 1202733536 66104240 10833920
12:27:50 43552 8192000000 10103166912 10546073600 1123283248 12.058 100 21 42 0 1192190288 64023280 4883760
12:29:39 44004 8192000000 10099099888 10579415040 1103846592 11.874 98 20 38 1 1219031456 110671392 4513472
12:31:41 98226 8192000000 10120726112 10593579008 1109269968 11.926 99 16 32 0 1216711712 103779792 3661952
12:34:40 84494 8192000000 10042343440 10487050240 1092187632 11.763 97 11 22 0 1151757792 57028560 2541600
12:37:10 15568 8192000000 10126690368 10550124544 1132364112 12.144 100 62 120 0 1202854080 58239680 12250288
12:38:32 43779 8192000000 10144929440 10587156480 1138566144 12.202 100 39 78 0 1257528944 111076352 7886448
12:40:16 28903 8192000000 10114766672 10546642944 1120973152 12.036 99 35 70 1 1200449312 72307216 7168944
12:41:50 38763 8192000000 10104024144 10540703744 1128518992 12.107 99 30 60 0 1207065248 72505184 6041072
12:44:04 36671 8192000000 10114839952 10549903360 1129126256 12.113 99 41 82 0 1200075936 62316000 8633680
12:45:42 31507 8192000000 10103421072 10559979520 1108293504 11.916 97 31 62 1 1200787072 86082400 6411168
12:47:14 27649 8192000000 10117667344 10555662336 1134852528 12.167 100 54 108 0 1198617168 52559968 11204672
12:48:46 76640 8192000000 10057159808 10491781120 1098671120 11.825 97 33 66 0 1141350144 35474528 7204496
12:51:05 29188 8192000000 10057220432 10485284864 1101332032 11.850 98 45 87 0 1142103440 31750192 9021216
12:52:34 11883 8192000000 10099499696 10530168832 1127424544 12.097 100 50 98 1 1202778288 65103472 10250272
12:53:47 71917 8192000000 10064014592 10525069312 1111783712 11.949 98 14 27 1 1142998384 28943904 2270768
12:56:19 62334 8192000000 10068069120 10554028032 1108412432 11.917 98 10 20 0 1189226896 78557888 2256576
12:58:38 54691 8192000000 10107635968 10571984896 1130811456 12.129 100 14 28 0 1236527792 102744496 2971840
13:00:36 80367 8192000000 10072477856 10518937600 1078180640 11.630 95 19 38 0 1125881008 43587952 4112416
13:02:57 54469 8192000000 10057721776 10478956544 1084979760 11.695 96 36 70 0 1135579312 43204352 7395200
13:04:52 14538 8192000000 10085921360 10501103616 1115956224 11.989 100 66 120 5 1179623744 49336176 14331344
13:06:12 106524 8192000000 10076539600 10542596096 1083340464 11.679 96 33 66 0 1157248032 67104608 6802960
13:09:03 37375 8192000000 10095335552 10553139200 1131844080 12.139 100 35 70 0 1198177504 59874560 6458864
13:10:42 101404 8192000000 10073045312 10509250560 1112148672 11.953 98 35 68 2 1149527328 30601344 6777312
13:13:24 89811 8192000000 10110245968 10577747968 1122615696 12.052 98 11 22 0 1175970256 51042896 2311664
13:15:58 62043 8192000000 10078427760 10547027968 1107164048 11.906 97 19 36 3 1155779472 44301872 4313552
13:18:02 30841 8192000000 10077126816 10525151232 1111709408 11.949 99 24 48 2 1156176144 39353888 5112848
13:19:37 38147 8192000000 10131805984 10580996096 1146443056 12.276 100 25 50 0 1222990416 71221232 5326128
13:21:15 40945 8192000000 10225980160 10658660352 1135927216 12.177 99 47 93 3 1292033360 145897696 10208448
13:22:56 84743 8192000000 10219680752 10719666176 1115184624 11.981 97 29 58 0 1317324368 195346592 6793152
13:25:24 68140 8192000000 10098703168 10628308992 1103997312 11.876 98 11 22 0 1265066768 158479136 2590320
13:27:33 87209 8192000000 10072011504 10545831936 1096651184 11.806 97 18 36 0 1170270128 70310896 3308048
13:30:08 84310 8192000000 10029155568 10481745920 1079078160 11.639 97 23 46 0 1113260528 29437952 4744416
13:32:33 8706 8192000000 10047808512 10484412416 1114830768 11.978 100 51 90 6 1150891040 26051792 10008480
13:33:43 40553 8192000000 10071221296 10534850560 1090182256 11.744 97 23 46 0 1168465200 73664688 4618256
13:35:24 30155 8192000000 10051250592 10520346624 1118345280 12.011 100 7 14 0 1155748368 36048944 1354144
13:36:55 39288 8192000000 9337920720 9662910464 470743088 5.434 42 4 7 0 489379632 17952576 683968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 14-04-2022 13:39:55 to 14-04-2022 13:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 130 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-04-2022 13:40:13 to 14-04-2022 13:40:15.

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          225354
MON$OLDEST_ACTIVE               225752
MON$OLDEST_SNAPSHOT             225752
MON$NEXT_TRANSACTION            225752
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                       2059552
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             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2761 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2761 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2761 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2761 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Thu Apr 14 13:37:52 2022
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Thu Apr 14 13:37:54 2022
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
--- end of diff output ---

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

14.04.22 13:40:16. 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-14 13:40:18 No severe PSQL-related problems occured
14.04.22 13:40:19. End of report.