Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.10.2022 04: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 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 15084768 kB
MemAvailable 30430192 kB
Buffers 20 kB
Cached 16540152 kB
SwapCached 2060 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.3
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-10-18 04:37:24
DTS_END                         2022-10-18 04:37:24.2570
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53322
TRN_ID                          222089
ATT_ID                          656
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: 9198.97 17812 1103876
customer order: creation 175.38 487 21046
customer order: refuse 14.66 381 1759
order to supplier: creation 45.97 1308 5516
order to supplier: removal 3.56 149465 427
invoice (draft): creation 44.24 3025 5309
invoice (draft): removal 50.84 944 6101
invoice accept: apply 42.33 27113 5080
invoice accept: cancel 46.16 1128 5539
customer reserve: creation 3931.08 260 471730
customer reserve: removal 4545.08 91 545409
realization accept: apply 85.05 268 10206
realization accept: cancel 84.23 153 10108
payment from customer: creation 59.10 179 7092
payment from customer: removal 6.03 128 724
payment to supplier: creation 49.02 198 5882
payment to supplier: removal 3.43 145 412
service: total inventory turnovers 4.07 3959 488
service: total monetary turnovers 7.77 398 932
service: aggregate perf. data 0.93 17430 111
service: refresh index statistics 0.04 149183 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-10-2022 04:38:15 to 18-10-2022 04:38:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8668
TEST_TIME 2 7142
TEST_TIME 3 5769
TEST_TIME 4 6911
TEST_TIME 5 6567
TEST_TIME 6 6814
TEST_TIME 7 7054
TEST_TIME 8 7007
TEST_TIME 9 6882
TEST_TIME 10 6904
TEST_TIME 11 7095
TEST_TIME 12 7440
TEST_TIME 13 7536
TEST_TIME 14 7761
TEST_TIME 15 7642
TEST_TIME 16 7696
TEST_TIME 17 7707
TEST_TIME 18 7595
TEST_TIME 19 7620
TEST_TIME 20 7580
TEST_TIME 21 7889
TEST_TIME 22 8055
TEST_TIME 23 8218
TEST_TIME 24 8412
TEST_TIME 25 8562
TEST_TIME 26 8787
TEST_TIME 27 9221
TEST_TIME 28 9518
TEST_TIME 29 9835
TEST_TIME 30 9739
TEST_TIME 31 10062
TEST_TIME 32 10755
TEST_TIME 33 10611
TEST_TIME 34 10725
TEST_TIME 35 10895
TEST_TIME 36 10922
TEST_TIME 37 10860
TEST_TIME 38 10685
TEST_TIME 39 10549
TEST_TIME 40 10418
TEST_TIME 41 10282
TEST_TIME 42 10522
TEST_TIME 43 10448
TEST_TIME 44 10529
TEST_TIME 45 10572
TEST_TIME 46 10479
TEST_TIME 47 10298
TEST_TIME 48 10092
TEST_TIME 49 9949
TEST_TIME 50 9960
TEST_TIME 51 9903
TEST_TIME 52 9782
TEST_TIME 53 9758
TEST_TIME 54 9724
TEST_TIME 55 9684
TEST_TIME 56 9627
TEST_TIME 57 9650
TEST_TIME 58 9568
TEST_TIME 59 9473
TEST_TIME 60 9394
TEST_TIME 61 9552
TEST_TIME 62 9601
TEST_TIME 63 9559
TEST_TIME 64 9505
TEST_TIME 65 9535
TEST_TIME 66 9514
TEST_TIME 67 9740
TEST_TIME 68 9740
TEST_TIME 69 9681
TEST_TIME 70 9687
TEST_TIME 71 9713
TEST_TIME 72 9708
TEST_TIME 73 9672
TEST_TIME 74 9665
TEST_TIME 75 9600
TEST_TIME 76 9580
TEST_TIME 77 9516
TEST_TIME 78 9485
TEST_TIME 79 9455
TEST_TIME 80 9448
TEST_TIME 81 9374
TEST_TIME 82 9328
TEST_TIME 83 9339
TEST_TIME 84 9272
TEST_TIME 85 9241
TEST_TIME 86 9328
TEST_TIME 87 9275
TEST_TIME 88 9327
TEST_TIME 89 9274
TEST_TIME 90 9264
TEST_TIME 91 9300
TEST_TIME 92 9292
TEST_TIME 93 9307
TEST_TIME 94 9314
TEST_TIME 95 9337
TEST_TIME 96 9389
TEST_TIME 97 9403
TEST_TIME 98 9356
TEST_TIME 99 9330
TEST_TIME 100 9269
TEST_TIME 101 9266
TEST_TIME 102 9210
TEST_TIME 103 9227
TEST_TIME 104 9185
TEST_TIME 105 9146
TEST_TIME 106 9120
TEST_TIME 107 9083
TEST_TIME 108 9049
TEST_TIME 109 9073
TEST_TIME 110 9127
TEST_TIME 111 9099
TEST_TIME 112 9142
TEST_TIME 113 9243
TEST_TIME 114 9200
TEST_TIME 115 9156
TEST_TIME 116 9200
TEST_TIME 117 9230
TEST_TIME 118 9240
TEST_TIME 119 9278
TEST_TIME 120 9226

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 18-10-2022 04:38:19 to 18-10-2022 04:38:22.

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 1538823 1538823 0 0.00 0 19185 14 0 0
doc_list_biud 1538812 1538812 0 0.00 0 38370 38 0 0
sp_add_invoice_to_stock 5215 5080 135 2.58 56 222655 27113 0 135
sp_cancel_adding_invoice 5539 5539 0 0.00 5 97183 1128 0 0
sp_cancel_client_order 1759 1759 0 0.00 7 10660 381 0 0
sp_cancel_customer_reserve 545409 545409 0 0.00 1 38422 92 0 0
sp_cancel_pay_from_customer 724 724 0 0.00 4 4544 128 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 9313 145 0 0
sp_cancel_supplier_invoice 6116 6101 15 0.24 3 19897 944 0 15
sp_cancel_supplier_order 427 427 0 0.00 13 1454467 149465 0 0
sp_cancel_write_off 10108 10108 0 0.00 2 15423 154 0 0
sp_client_order 21046 21046 0 0.00 5 34329 487 0 0
sp_customer_reserve 471730 471730 0 0.00 0 51748 260 0 0
sp_fill_shopping_cart 31860 31860 0 0.00 1 20287 78 0 0
sp_fill_shopping_cart_clo_res 471696 471696 0 0.00 0 25525 21 0 0
sp_get_clo_for_invoice 5032 5032 0 0.00 0 33475 258 0 0
sp_kill_cost_storno 16783 16783 0 0.00 0 13913 72 0 0
sp_kill_qstorno_ret_qs2qd 1107454 1107454 0 0.00 0 38254 40 0 0
sp_kill_qty_storno 584578 584578 0 0.00 0 38358 92 0 0
sp_lock_selected_doc 21027 21027 0 0.00 0 9275 7 0 0
sp_make_cost_storno 28211 28211 0 0.00 0 13787 87 0 0
sp_multiply_rows_for_pdistr 28209 28209 0 0.00 0 9615 39 0 0
sp_multiply_rows_for_qdistr 21045 21045 0 0.00 0 15484 180 0 0
sp_pay_from_customer 7092 7092 0 0.00 5 14380 180 0 0
sp_pay_to_supplier 5882 5882 0 0.00 6 10964 199 0 0
sp_payment_common 12974 12974 0 0.00 2 14329 46 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 10649 315 0 0
sp_qd_handle_on_invoice_upd_sts 10569 10569 0 0.00 0 14991 468 0 0
sp_qd_handle_on_reserve_upd_sts 10205 10205 0 0.00 0 27053 59 0 0
sp_reserve_write_off 10206 10206 0 0.00 7 27075 268 0 0
sp_supplier_invoice 5309 5309 0 0.00 8 84432 3026 0 0
sp_supplier_order 5516 5516 0 0.00 7 30972 1308 0 0
srv_aggregate_perf_data 112 111 1 0.89 2473 165573 17430 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 782 195918 36654 0 0
srv_find_qd_qs_mism 1054655 1054655 0 0.00 0 19176 11 0 0
srv_make_invnt_saldo 488 488 0 0.00 47 93303 3959 0 0
srv_make_money_saldo 932 932 0 0.00 1 16257 399 0 0
srv_recalc_idx_stat 5 5 0 0.00 51404 247847 149184 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 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 5082 5082 0 0.00 1 8632 877 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 1 6915 433 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 9671 30 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2066 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 724 724 0 0.00 0 306 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 796 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 1326 41 0 0
t$perf-norm:sp_cancel_supplier_order 427 427 0 0.00 0 75169 6297 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 2133 18 0 0
t$perf-norm:sp_client_order 21046 21046 0 0.00 0 9552 38 0 0
t$perf-norm:sp_customer_reserve 1018 1018 0 0.00 0 3054 18 0 0
t$perf-norm:sp_pay_from_customer 7092 7092 0 0.00 0 6261 22 0 0
t$perf-norm:sp_pay_to_supplier 5882 5882 0 0.00 0 5744 21 0 0
t$perf-norm:sp_reserve_write_off 10207 10207 0 0.00 0 5920 24 0 0
t$perf-norm:sp_supplier_invoice 5309 5309 0 0.00 0 5054 36 0 0
t$perf-norm:sp_supplier_order 5516 5516 0 0.00 0 4718 32 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 195 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 268 6 0 0
t$perf-norm:srv_make_invnt_saldo 488 488 0 0.00 0 2397 21 0 0
t$perf-norm:srv_make_money_saldo 932 932 0 0.00 0 1775 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 4 227 98 0 0
v_add_invoice_to_stock 10322 10052 270 2.61 0 4485 22 0 270
v_all_customers 38717 38717 0 0.00 0 80 4 0 0
v_all_suppliers 30414 30414 0 0.00 0 104 4 0 0
v_all_wares 42084 42084 0 0.00 0 98 4 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2900 17 0 0
v_cancel_client_order 3518 3518 0 0.00 0 2241 11 0 0
v_cancel_customer_prepayment 1448 1448 0 0.00 0 1946 13 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 1206 11 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2135 13 0 0
v_cancel_supplier_invoice 814 784 30 3.68 0 3059 28 0 30
v_cancel_supplier_order 854 854 0 0.00 0 773 11 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2458 22 0 0
v_max_id_clo_ord 5513 5513 0 0.00 0 2646 6 0 0
v_max_id_clo_res 1018 1018 0 0.00 0 3004 14 0 0
v_max_id_ord_sup 5304 5304 0 0.00 0 6224 9 0 0
v_max_non_paid_invoice 1492 1492 0 0.00 0 133 7 0 0
v_max_non_paid_realizn 3622 3622 0 0.00 0 595 7 0 0
v_min_id_clo_ord 5513 5513 0 0.00 0 1647 8 0 0
v_min_id_clo_res 1018 1018 0 0.00 0 2603 14 0 0
v_min_id_ord_sup 5304 5304 0 0.00 0 8000 11 0 0
v_min_non_paid_invoice 5882 5882 0 0.00 0 4580 15 0 0
v_min_non_paid_realizn 7091 7091 0 0.00 0 14318 23 0 0
v_our_firm 10298 10298 0 0.00 0 87 4 0 0
v_reserve_write_off 20406 20406 0 0.00 0 2746 11 0 0
x_lock_depdocs_on_canc_invoice 11248 11248 0 0.00 0 20955 120 0 0
x_lock_depdocs_on_canc_sup_ord 427 427 0 0.00 0 18040 362 0 0
x_make_qstorno_1000_1200 5516 5516 0 0.00 2 30961 1159 0 0
x_make_qstorno_1200_2000 5309 5309 0 0.00 4 84419 2801 0 0
x_make_qstorno_2100_3300 471727 471727 0 0.00 0 44119 227 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-10-2022 04:38:23 to 18-10-2022 04:38:27.

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
02:07:28 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:29 23743 8192000000 9714573248 10011258880 794429360 8.840 100 90 178 0 823951216 7955584 21566272
02:09:59 21543 8192000000 9762672608 10067906560 833805312 9.238 100 94 188 0 868548432 12454272 22288848
02:11:31 19420 8192000000 9807739536 10123575296 863068432 9.531 100 88 176 0 910694560 26376560 21249568
02:12:54 6050 8192000000 9865120432 10197061632 909280864 9.990 100 79 158 1 970430512 43797856 17351792
02:14:04 4445 8192000000 9917054416 10262253568 960393840 10.493 100 87 174 0 1030146128 50484992 19267296
02:15:11 1848 8192000000 9971744496 10321887232 996398528 10.844 100 95 186 0 1084932704 69097312 19436864
02:16:16 1929 8192000000 10007522800 10369056768 1019195936 11.064 100 93 182 0 1124754816 87165664 18393216
02:17:19 1188 8192000000 10029430688 10399887360 1039776240 11.263 100 93 184 1 1151734368 93438800 18519328
02:18:21 1226 8192000000 10039010960 10414997504 1056543312 11.423 100 94 186 2 1156957856 80901392 19513152
02:19:24 4241 8192000000 10039637296 10414170112 1067022288 11.524 100 85 163 2 1163887552 81971120 14894144
02:20:29 1951 8192000000 10041345360 10422243328 1075255776 11.602 100 79 139 2 1162725136 73607168 13862192
02:21:32 951 8192000000 10048775376 10434383872 1080405072 11.651 100 94 175 1 1171045280 71299792 19340416
02:22:34 1326 8192000000 10046052720 10437001216 1083513248 11.681 100 92 174 1 1171393152 70189104 17690800
02:23:36 1209 8192000000 10059625824 10448015360 1088736608 11.731 100 96 183 0 1180546816 73085536 18724672
02:24:39 1100 8192000000 10085887072 10471464960 1092977456 11.771 100 99 187 1 1202794224 91468992 18347776
02:25:41 1212 8192000000 10066137248 10452951040 1099916224 11.837 100 98 182 1 1192588320 73339936 19332160
02:26:44 842 8192000000 10052200224 10441007104 1099800544 11.836 100 97 178 2 1172701632 54720800 18180288
02:27:45 12646 8192000000 10047279168 10451480576 1091116512 11.753 100 58 111 0 1159733392 57439648 11177232
02:29:00 1132 8192000000 10029684304 10420482048 1096813504 11.807 100 90 166 1 1156027968 41384544 17829920
02:30:02 960 8192000000 10009144864 10387517440 1056031488 11.418 100 90 163 2 1130970608 57766304 17172816
02:31:04 2171 8192000000 10005180816 10381156352 1049011168 11.351 98 79 146 4 1126194144 61204160 15978816
02:32:07 4565 8192000000 10007504656 10390810624 1050187232 11.362 99 67 125 2 1129509984 67295312 12027440
02:33:13 3542 8192000000 9989864384 10365038592 1028638656 11.155 98 82 155 2 1105171744 60604848 15928240
02:34:17 1097 8192000000 9986641328 10364403712 1032236096 11.190 100 80 154 2 1112569872 63816512 16517264
02:35:19 897 8192000000 9977086064 10348511232 1026359488 11.133 100 87 160 5 1101479312 57869328 17250496
02:36:21 1268 8192000000 10013117312 10386300928 1043345264 11.297 100 96 182 2 1132023312 70405584 18272464
02:37:23 1194 8192000000 9993959136 10367160320 1047297136 11.335 100 96 169 4 1115135504 52509104 15329264
02:38:31 2564 8192000000 10006701440 10389041152 1063913376 11.494 100 80 151 4 1130033792 50909296 15211120
02:39:37 6992 8192000000 10045930800 10430754816 1075201776 11.602 100 74 142 1 1152598848 63520048 13877024
02:40:49 6857 8192000000 10089495232 10479271936 1087443504 11.718 100 81 154 0 1201744960 97867040 16434416
02:42:03 1798 8192000000 10060118816 10454908928 1104116368 11.877 100 67 123 0 1177978240 61378448 12483424
02:43:11 10731 8192000000 10119091776 10517266432 1121171792 12.038 100 76 143 3 1235318544 99755312 14391440
02:44:25 2943 8192000000 10154956896 10554441728 1140654544 12.222 100 81 155 2 1270805312 113893184 16257584
02:45:33 1437 8192000000 10173718592 10572152832 1147974000 12.290 100 80 158 2 1288183248 122625184 17584064
02:46:37 3019 8192000000 10224407616 10623246336 1153941200 12.346 100 81 154 2 1330972448 159970144 17061104
02:47:45 1087 8192000000 10226459904 10635612160 1162898320 12.430 100 86 168 1 1344717728 162779888 19039520
02:48:48 1552 8192000000 10258835712 10674122752 1155338144 12.360 99 86 165 0 1371329008 197667520 18323344
02:49:54 997 8192000000 10269347472 10690019328 1182465952 12.613 100 83 160 1 1385962448 185485520 18010976
02:50:59 1933 8192000000 10320294128 10724970496 1190185232 12.685 100 97 187 2 1433456720 221839712 21431776
02:52:03 5212 8192000000 10292797552 10725453824 1198195104 12.760 100 68 132 2 1406640064 192930208 15514752
02:53:15 1433 8192000000 10351323184 10768736256 1200408224 12.780 100 86 167 1 1463629456 243656016 19565216
02:54:19 1246 8192000000 10390340528 10795425792 1200756480 12.783 100 93 180 1 1496158576 274930800 20471296
02:55:25 1529 8192000000 10431225440 10839912448 1212267136 12.890 100 97 192 0 1534005056 299681616 22056304
02:56:28 827 8192000000 10475131200 10888777728 1211803760 12.886 100 98 189 2 1582962224 348902704 22255760
02:57:32 776 8192000000 10505352608 10940039168 1209595728 12.865 100 94 181 1 1610440816 379574384 21270704
02:58:35 1347 8192000000 10509135024 10959527936 1216103008 12.926 100 93 181 0 1613249088 374416400 22729680
02:59:40 933 8192000000 10535154560 10952757248 1206014256 12.832 100 95 182 2 1638508144 410689072 21804816
03:00:42 9015 8192000000 10512894368 10969444352 1210176256 12.871 100 55 106 2 1611630144 387702864 13751024
03:01:54 9792 8192000000 10508763872 10970468352 1188253680 12.667 99 50 100 1 1606155392 404708752 13192960
03:03:06 4931 8192000000 10495822864 10952085504 1181739856 12.606 100 67 130 0 1593498176 395931664 15826656
03:04:14 13015 8192000000 10420918736 10930872320 1172933104 12.524 100 43 85 0 1545727536 361540832 11253600
03:05:29 20370 8192000000 10359311488 10793340928 1146906160 12.280 99 38 75 0 1448232656 292432720 8893776
03:07:06 11808 8192000000 10340279760 10771795968 1132170256 12.142 98 35 69 0 1423332864 282608992 8553616
03:08:36 16341 8192000000 10270033264 10774941696 1123509840 12.060 99 31 60 0 1387516832 256885872 7121120
03:09:53 24877 8192000000 10140145520 10562215936 1118854448 12.016 99 46 92 1 1224412992 94824496 10734048
03:11:46 29513 8192000000 10106131264 10608496640 1125715264 12.081 99 13 26 0 1204616672 76117696 2783712
03:13:17 119658 8192000000 10039307744 10457882624 1074677456 11.597 95 22 38 1 1100163824 21645648 3840720
03:16:17 54429 8192000000 10100927056 10535067648 1111206448 11.944 98 35 69 0 1176425648 57585136 7634064
03:18:16 73386 8192000000 10098486864 10595856384 1139550752 12.211 100 22 44 0 1215838736 71683568 4604416
03:20:38 195918 8192000000 10045175104 10493890560 1066227808 11.516 94 15 28 0 1115167648 46416992 2522848
03:24:54 90371 8192000000 10101393696 10540490752 1130498432 12.126 100 42 72 2 1191181600 53832288 6850880
03:27:25 95036 8192000000 10079030000 10555121664 1116385728 11.993 98 28 54 1 1168651856 45834464 6431664
03:30:11 46601 8192000000 10107499088 10560835584 1110334800 11.936 97 23 46 0 1185600272 69967584 5297888
03:32:00 11991 8192000000 10120868960 10557857792 1125855744 12.082 99 68 131 0 1215211296 74604736 14750816
03:33:43 86391 8192000000 10152094432 10646904832 1144353296 12.256 100 15 30 0 1238785728 90987472 3444960
03:36:09 50117 8192000000 10178388016 10646687744 1150702832 12.316 100 15 30 0 1271862016 117501792 3657392
03:38:13 40027 8192000000 10096268464 10582212608 1134932704 12.168 99 8 16 0 1189664896 53340672 1391520
03:39:55 57005 8192000000 10104038016 10562170880 1133381888 12.153 98 20 39 0 1177208480 39845040 3981552
03:41:52 52217 8192000000 10165690240 10644115456 1137149152 12.189 98 31 59 1 1276191712 132370032 6672528
03:43:49 62692 8192000000 10141702528 10671071232 1148295376 12.293 99 20 38 0 1280724848 128402576 4026896
03:46:00 32883 8192000000 10111717520 10540281856 1127964176 12.102 98 40 77 0 1166925024 30686384 8274464
03:47:36 40066 8192000000 10107218544 10547490816 1130965344 12.130 98 35 68 0 1191181552 53160384 7055824
03:49:19 36923 8192000000 10088716896 10527817728 1115889344 11.988 97 34 68 0 1161046096 38113968 7042784
03:50:59 20345 8192000000 10134613376 10574680064 1141126736 12.226 100 53 104 3 1211909344 60727664 10054944
03:52:20 102656 8192000000 10094457856 10563514368 1130071680 12.122 99 42 83 1 1211321664 72920192 8329792
03:55:19 32494 8192000000 10092553184 10552188928 1112061056 11.952 98 32 64 0 1173985856 55014240 6910560
03:56:52 79297 8192000000 10084304784 10580271104 1115201136 11.982 98 25 50 0 1176229632 55314448 5714048
03:59:11 52842 8192000000 10119754896 10583195648 1127052848 12.094 99 16 32 0 1205448448 74800480 3595120
04:01:19 45940 8192000000 10099761216 10558517248 1130400320 12.125 100 18 29 3 1188919216 54885232 3633664
04:04:22 61690 8192000000 10093575600 10535940096 1088015184 11.724 96 46 85 0 1132310256 35686288 8608784
04:06:30 17895 8192000000 10103589120 10532405248 1120266064 12.030 99 49 98 0 1173282384 42753472 10262848
04:07:50 55757 8192000000 10071382480 10514677760 1106743776 11.902 98 40 74 0 1167129424 52252800 8132848
04:09:47 14445 8192000000 10068099360 10505293824 1083136272 11.677 97 50 100 0 1133407920 40653712 9617936
04:11:10 19495 8192000000 10110888448 10561957888 1099423936 11.832 99 37 74 0 1179279664 72753008 7102720
04:12:31 47144 8192000000 10089466528 10518646784 1069992720 11.552 96 25 50 0 1154651104 79321200 5337184
04:14:19 57706 8192000000 10057213296 10486571008 1079159712 11.639 96 32 64 0 1120274304 34672320 6442272
04:16:23 35379 8192000000 10106299072 10548228096 1122180688 12.048 100 49 99 0 1217540720 85239936 10120096
04:18:03 53150 8192000000 10046652032 10523140096 1114806288 11.978 99 19 37 0 1158875088 40571328 3497472
04:19:56 63868 8192000000 10062901760 10549739520 1122537056 12.051 100 31 62 1 1200778288 71697392 6543840
04:22:00 47226 8192000000 10067304416 10529746944 1123702752 12.062 99 23 46 1 1172644512 43865072 5076688
04:23:53 99201 8192000000 10128489568 10590052352 1138877104 12.205 100 16 32 0 1219660672 77208736 3574832
04:26:32 26672 8192000000 10126116592 10577809408 1129501488 12.117 99 36 72 0 1224396064 87047776 7846800
04:28:00 33231 8192000000 10135718944 10578259968 1140116096 12.217 99 41 80 1 1230824160 81545120 9162944
04:29:39 66378 8192000000 10138342784 10677186560 1133689456 12.156 99 20 40 0 1281719168 143354784 4674928
04:31:46 47594 8192000000 10136766496 10580594688 1143187344 12.246 99 37 74 0 1209190272 58361392 7641536
04:33:34 88947 8192000000 10132910288 10647625728 1142900672 12.243 99 15 30 0 1241698880 95712144 3086064
04:36:03 91043 8192000000 9447786944 9874661376 525536176 6.028 46 4 8 0 565861552 39724064 601312

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 18-10-2022 04:38:29 to 18-10-2022 04:38:45.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 135 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 18-10-2022 04:38:46 to 18-10-2022 04:38:49.

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          221854
MON$OLDEST_ACTIVE               222374
MON$OLDEST_SNAPSHOT             222374
MON$NEXT_TRANSACTION            222374
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                       2064992
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             770
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2860 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2860 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2860 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2860 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'

18.10.22 04:38:50. 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-10-18 04:38:52 No severe PSQL-related problems occured
18.10.22 04:38:54. End of report.