Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.09.2022 04: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 15103624 kB
MemAvailable 30581872 kB
Buffers 20 kB
Cached 16574536 kB
SwapCached 2028 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-09-28 04:37:20
DTS_END                         2022-09-28 04:37:20.0160
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/55638
TRN_ID                          231460
ATT_ID                          576
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: 9053.39 19334 1086407
customer order: creation 182.93 456 21951
customer order: refuse 15.49 393 1859
order to supplier: creation 47.63 1220 5715
order to supplier: removal 3.30 159059 396
invoice (draft): creation 47.97 3079 5756
invoice (draft): removal 47.64 958 5717
invoice accept: apply 43.78 26666 5253
invoice accept: cancel 43.43 1330 5211
customer reserve: creation 4064.67 255 487760
customer reserve: removal 4255.66 97 510679
realization accept: apply 88.66 279 10639
realization accept: cancel 74.54 167 8945
payment from customer: creation 63.03 177 7564
payment from customer: removal 6.33 143 759
payment to supplier: creation 51.36 192 6163
payment to supplier: removal 3.39 117 407
service: total inventory turnovers 4.42 3590 530
service: total monetary turnovers 8.06 375 967
service: aggregate perf. data 1.09 14341 131
service: refresh index statistics 0.04 173784 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-09-2022 04:39:04 to 28-09-2022 04:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6972
TEST_TIME 2 4812
TEST_TIME 3 4774
TEST_TIME 4 6064
TEST_TIME 5 6667
TEST_TIME 6 6862
TEST_TIME 7 6692
TEST_TIME 8 6847
TEST_TIME 9 7099
TEST_TIME 10 7143
TEST_TIME 11 7442
TEST_TIME 12 7340
TEST_TIME 13 7482
TEST_TIME 14 7682
TEST_TIME 15 7711
TEST_TIME 16 7674
TEST_TIME 17 7626
TEST_TIME 18 7853
TEST_TIME 19 7954
TEST_TIME 20 7956
TEST_TIME 21 7955
TEST_TIME 22 8118
TEST_TIME 23 8404
TEST_TIME 24 8485
TEST_TIME 25 8643
TEST_TIME 26 8902
TEST_TIME 27 8959
TEST_TIME 28 9402
TEST_TIME 29 9701
TEST_TIME 30 9799
TEST_TIME 31 10032
TEST_TIME 32 10101
TEST_TIME 33 10451
TEST_TIME 34 11581
TEST_TIME 35 11541
TEST_TIME 36 11364
TEST_TIME 37 11280
TEST_TIME 38 11185
TEST_TIME 39 11143
TEST_TIME 40 11084
TEST_TIME 41 11172
TEST_TIME 42 11123
TEST_TIME 43 10888
TEST_TIME 44 10813
TEST_TIME 45 10715
TEST_TIME 46 10594
TEST_TIME 47 10470
TEST_TIME 48 10372
TEST_TIME 49 10278
TEST_TIME 50 10234
TEST_TIME 51 10317
TEST_TIME 52 10225
TEST_TIME 53 10121
TEST_TIME 54 10139
TEST_TIME 55 10138
TEST_TIME 56 10051
TEST_TIME 57 10006
TEST_TIME 58 10009
TEST_TIME 59 9927
TEST_TIME 60 9841
TEST_TIME 61 9766
TEST_TIME 62 9723
TEST_TIME 63 9764
TEST_TIME 64 9710
TEST_TIME 65 9688
TEST_TIME 66 9779
TEST_TIME 67 9753
TEST_TIME 68 9732
TEST_TIME 69 9801
TEST_TIME 70 9783
TEST_TIME 71 9795
TEST_TIME 72 9840
TEST_TIME 73 9868
TEST_TIME 74 9897
TEST_TIME 75 9849
TEST_TIME 76 9747
TEST_TIME 77 9723
TEST_TIME 78 9712
TEST_TIME 79 9660
TEST_TIME 80 9583
TEST_TIME 81 9514
TEST_TIME 82 9480
TEST_TIME 83 9444
TEST_TIME 84 9462
TEST_TIME 85 9469
TEST_TIME 86 9478
TEST_TIME 87 9464
TEST_TIME 88 9418
TEST_TIME 89 9461
TEST_TIME 90 9480
TEST_TIME 91 9464
TEST_TIME 92 9500
TEST_TIME 93 9576
TEST_TIME 94 9526
TEST_TIME 95 9457
TEST_TIME 96 9416
TEST_TIME 97 9362
TEST_TIME 98 9351
TEST_TIME 99 9284
TEST_TIME 100 9272
TEST_TIME 101 9284
TEST_TIME 102 9309
TEST_TIME 103 9281
TEST_TIME 104 9249
TEST_TIME 105 9216
TEST_TIME 106 9337
TEST_TIME 107 9313
TEST_TIME 108 9301
TEST_TIME 109 9328
TEST_TIME 110 9353
TEST_TIME 111 9343
TEST_TIME 112 9354
TEST_TIME 113 9323
TEST_TIME 114 9250
TEST_TIME 115 9273
TEST_TIME 116 9242
TEST_TIME 117 9186
TEST_TIME 118 9144
TEST_TIME 119 9118
TEST_TIME 120 9069

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 28-09-2022 04:39:07 to 28-09-2022 04:39:11.

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 1535367 1535367 0 0.00 0 30950 14 0 0
doc_list_biud 1535362 1535362 0 0.00 0 24970 38 0 0
sp_add_invoice_to_stock 5408 5253 155 2.86 66 222448 26666 0 155
sp_cancel_adding_invoice 5211 5211 0 0.00 6 97305 1331 0 0
sp_cancel_client_order 1859 1859 0 0.00 6 8212 394 0 0
sp_cancel_customer_reserve 510679 510679 0 0.00 1 24973 98 0 0
sp_cancel_pay_from_customer 759 759 0 0.00 4 8916 143 0 0
sp_cancel_pay_to_supplier 407 407 0 0.00 4 3362 117 0 0
sp_cancel_supplier_invoice 5727 5717 10 0.17 2 24109 959 0 10
sp_cancel_supplier_order 396 396 0 0.00 12 1616580 159060 0 0
sp_cancel_write_off 8945 8945 0 0.00 2 14884 168 0 0
sp_client_order 21951 21951 0 0.00 5 30373 457 0 0
sp_customer_reserve 487760 487760 0 0.00 1 177457 256 0 0
sp_fill_shopping_cart 33408 33408 0 0.00 1 38366 85 0 0
sp_fill_shopping_cart_clo_res 487731 487731 0 0.00 0 18063 18 0 0
sp_get_clo_for_invoice 5223 5223 0 0.00 0 32013 283 0 0
sp_kill_cost_storno 15322 15322 0 0.00 0 6351 70 0 0
sp_kill_qstorno_ret_qs2qd 1036416 1036416 0 0.00 0 24947 41 0 0
sp_kill_qty_storno 548666 548666 0 0.00 0 24970 97 0 0
sp_lock_selected_doc 21905 21905 0 0.00 0 7568 8 0 0
sp_make_cost_storno 29589 29589 0 0.00 0 16162 74 0 0
sp_multiply_rows_for_pdistr 29587 29587 0 0.00 0 6992 37 0 0
sp_multiply_rows_for_qdistr 21951 21951 0 0.00 0 13866 166 0 0
sp_pay_from_customer 7564 7564 0 0.00 5 17364 178 0 0
sp_pay_to_supplier 6163 6163 0 0.00 6 13301 192 0 0
sp_payment_common 13727 13727 0 0.00 2 12650 46 0 0
sp_qd_handle_on_cancel_clo 1859 1859 0 0.00 0 8206 313 0 0
sp_qd_handle_on_invoice_upd_sts 10432 10432 0 0.00 0 19941 519 0 0
sp_qd_handle_on_reserve_upd_sts 10638 10638 0 0.00 0 14987 67 0 0
sp_reserve_write_off 10639 10639 0 0.00 7 15033 280 0 0
sp_supplier_invoice 5756 5756 0 0.00 7 97485 3079 0 0
sp_supplier_order 5715 5715 0 0.00 7 25963 1221 0 0
srv_aggregate_perf_data 132 131 1 0.75 2436 271688 14342 0 1
srv_fill_mon_cache_memory 72 72 0 0.00 812 131400 35391 0 0
srv_find_qd_qs_mism 1034583 1034583 0 0.00 0 30949 12 0 0
srv_make_invnt_saldo 530 530 0 0.00 41 89062 3590 0 0
srv_make_money_saldo 967 967 0 0.00 1 11844 375 0 0
srv_recalc_idx_stat 5 5 0 0.00 71944 259055 173784 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 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 5259 5259 0 0.00 1 11997 948 0 0
t$perf-norm:sp_cancel_adding_invoice 454 454 0 0.00 1 6433 461 0 0
t$perf-norm:sp_cancel_client_order 1859 1859 0 0.00 0 1948 25 0 0
t$perf-norm:sp_cancel_customer_reserve 729 729 0 0.00 0 2576 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 759 759 0 0.00 0 6991 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 407 407 0 0.00 0 5447 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 2118 48 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 102320 7182 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 2882 20 0 0
t$perf-norm:sp_client_order 21951 21951 0 0.00 0 5419 35 0 0
t$perf-norm:sp_customer_reserve 1053 1053 0 0.00 0 328 13 0 0
t$perf-norm:sp_pay_from_customer 7564 7564 0 0.00 0 4103 18 0 0
t$perf-norm:sp_pay_to_supplier 6163 6163 0 0.00 0 2965 19 0 0
t$perf-norm:sp_reserve_write_off 10639 10639 0 0.00 0 5542 23 0 0
t$perf-norm:sp_supplier_invoice 5756 5756 0 0.00 0 4644 28 0 0
t$perf-norm:sp_supplier_order 5715 5715 0 0.00 0 2971 32 0 0
t$perf-norm:srv_aggregate_perf_data 131 131 0 0.00 0 670 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 375 8 0 0
t$perf-norm:srv_make_invnt_saldo 530 530 0 0.00 0 1335 18 0 0
t$perf-norm:srv_make_money_saldo 967 967 0 0.00 0 199 4 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 5 198 84 0 0
v_add_invoice_to_stock 10748 10438 310 2.88 0 5245 26 0 310
v_all_customers 40110 40110 0 0.00 0 87 4 0 0
v_all_suppliers 31870 31870 0 0.00 0 80 5 0 0
v_all_wares 43900 43900 0 0.00 0 118 4 0 0
v_cancel_adding_invoice 908 908 0 0.00 0 1077 15 0 0
v_cancel_client_order 3718 3718 0 0.00 0 3677 12 0 0
v_cancel_customer_prepayment 1518 1518 0 0.00 0 4852 18 0 0
v_cancel_customer_reserve 1458 1458 0 0.00 0 4159 14 0 0
v_cancel_payment_to_supplier 814 814 0 0.00 0 1014 12 0 0
v_cancel_supplier_invoice 904 884 20 2.21 0 424 27 0 20
v_cancel_supplier_order 792 792 0 0.00 0 678 12 0 0
v_cancel_write_off 2004 2004 0 0.00 0 2821 26 0 0
v_max_id_clo_ord 5712 5712 0 0.00 0 571 6 0 0
v_max_id_clo_res 1053 1053 0 0.00 0 7221 19 0 0
v_max_id_ord_sup 5744 5744 0 0.00 0 7220 7 0 0
v_max_non_paid_invoice 1684 1684 0 0.00 0 1835 9 0 0
v_max_non_paid_realizn 3988 3988 0 0.00 0 2279 8 0 0
v_min_id_clo_ord 5712 5712 0 0.00 0 1815 8 0 0
v_min_id_clo_res 1053 1053 0 0.00 0 5398 12 0 0
v_min_id_ord_sup 5744 5744 0 0.00 0 14219 12 0 0
v_min_non_paid_invoice 6163 6163 0 0.00 0 7007 16 0 0
v_min_non_paid_realizn 7564 7564 0 0.00 0 12643 18 0 0
v_our_firm 10942 10942 0 0.00 0 74 5 0 0
v_reserve_write_off 21276 21276 0 0.00 0 3784 11 0 0
x_lock_depdocs_on_canc_invoice 10486 10486 0 0.00 0 15312 123 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 4229 299 0 0
x_make_qstorno_1000_1200 5715 5715 0 0.00 2 25925 1045 0 0
x_make_qstorno_1200_2000 5756 5756 0 0.00 3 97453 2857 0 0
x_make_qstorno_2100_3300 487760 487760 0 0.00 0 177448 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-09-2022 04:39:12 to 28-09-2022 04:39:16.

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:23 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:24 28839 8192000000 9726864704 10027171840 803937824 8.936 100 90 180 0 833480784 7767696 21775264
02:10:05 13836 8192000000 9759670208 10070142976 839326400 9.293 100 89 178 0 873225472 12695344 21203728
02:11:22 13402 8192000000 9790595008 10110701568 864478720 9.545 100 84 168 0 904146288 20140848 19526720
02:12:41 10049 8192000000 9858265520 10191740928 908929408 9.987 100 84 164 0 968335296 41910352 17495536
02:13:55 4496 8192000000 9912519920 10256162816 949921776 10.390 100 85 163 2 1022832256 56183792 16726688
02:15:03 1770 8192000000 9963442960 10314063872 993618864 10.817 100 91 180 1 1088507472 76783184 18105424
02:16:07 1252 8192000000 9997440064 10360487936 1022082400 11.092 100 95 177 0 1110564800 70493984 17988416
02:17:09 858 8192000000 10005241536 10376044544 1044333136 11.306 100 94 184 0 1126869328 64095568 18440624
02:18:11 902 8192000000 10038659344 10414481408 1059541008 11.452 100 93 182 0 1159763360 82142240 18080112
02:19:13 982 8192000000 10051582208 10431213568 1065387520 11.508 100 96 176 0 1176400736 92990448 18022768
02:20:15 994 8192000000 10061124784 10446323712 1072623968 11.577 100 93 179 0 1179533744 90343344 16566432
02:21:17 1155 8192000000 10061770176 10441277440 1073406944 11.585 100 99 194 1 1184041744 90964160 19670640
02:22:19 1166 8192000000 10078403248 10463584256 1076431376 11.613 100 96 182 2 1203592928 109117456 18044096
02:23:21 1017 8192000000 10055459456 10444328960 1088185088 11.725 100 79 152 1 1175441648 72495760 14760800
02:24:23 1037 8192000000 10056256032 10440941568 1089582368 11.739 100 93 176 1 1180895120 74161376 17151376
02:25:25 1274 8192000000 10062401424 10452471808 1090897920 11.751 100 94 179 3 1185232224 75933856 18400448
02:26:27 2038 8192000000 10046838432 10437451776 1090356720 11.746 100 87 165 4 1174323760 66444736 17522304
02:27:30 1071 8192000000 10058173504 10445942784 1086047120 11.705 100 94 176 2 1179776816 75985760 17743936
02:28:32 1417 8192000000 10061327648 10444828672 1092630912 11.768 100 91 176 0 1184567680 74075104 17861664
02:29:35 1123 8192000000 10039632016 10426437632 1086767408 11.712 100 95 181 1 1161083200 55929440 18386352
02:30:37 1117 8192000000 10029889120 10412175360 1067611648 11.529 100 94 179 0 1151103008 64820512 18670848
02:31:38 1012 8192000000 10000138688 10381180928 1063425312 11.489 100 85 158 4 1122167296 41883104 16858880
02:32:40 890 8192000000 10002471760 10376826880 1046950544 11.331 99 92 177 2 1123585232 58032528 18602160
02:33:41 1237 8192000000 10023685904 10402816000 1047019696 11.332 100 95 180 2 1142793056 78332336 17441024
02:34:44 945 8192000000 10000356720 10370400256 1041690160 11.281 100 98 190 2 1116772928 56585936 18496832
02:35:46 1183 8192000000 9995626368 10374418432 1040683344 11.271 100 96 184 0 1123550992 62777120 20090528
02:36:47 7505 8192000000 9965281040 10356723712 1053453472 11.394 100 51 97 1 1102143200 38033024 10656704
02:37:59 6405 8192000000 10006741888 10393362432 1071646960 11.568 100 64 123 4 1129949936 46054336 12248640
02:39:10 21364 8192000000 10040542848 10423431168 1082828912 11.674 99 81 145 1 1159404144 63794624 12780608
02:40:34 10216 8192000000 10083710432 10478317568 1100493872 11.842 100 73 146 0 1197889328 81807440 15588016
02:41:57 1008 8192000000 10130147408 10519556096 1113530592 11.966 100 99 189 0 1254685008 120721808 20432608
02:43:02 1347 8192000000 10150262576 10542002176 1129010576 12.112 100 83 152 3 1259570400 115079760 15480064
02:44:08 4337 8192000000 10140024400 10540949504 1134318464 12.162 100 69 133 0 1258978864 109528400 15132000
02:45:16 3880 8192000000 10193003056 10596524032 1146665584 12.278 100 77 148 0 1316530432 153811408 16053440
02:46:21 1903 8192000000 10211633040 10621394944 1161259072 12.415 100 87 166 0 1324054368 144101312 18693984
02:47:26 1914 8192000000 10264321280 10670014464 1158360128 12.388 100 88 174 0 1379452320 200742016 20350176
02:48:31 2748 8192000000 10235244864 10643689472 1165990096 12.459 100 74 137 3 1351208848 170502992 14715760
02:49:36 5008 8192000000 10294609120 10707140608 1174673264 12.540 100 77 154 1 1406148880 212950000 18525616
02:50:50 2758 8192000000 10306914784 10721660928 1175679760 12.550 100 86 168 1 1430637312 236472288 18485264
02:51:55 973 8192000000 10357489584 10769342464 1184042960 12.628 100 96 183 0 1467114064 262374240 20696864
02:53:03 1638 8192000000 10402330272 10809139200 1199234384 12.769 100 91 178 1 1508113552 288613664 20265504
02:54:08 1999 8192000000 10428340080 10841505792 1190234288 12.686 100 88 167 1 1536959472 328375520 18349664
02:55:14 802 8192000000 10466353328 10893705216 1198448032 12.762 100 96 187 1 1574150736 353114000 22588704
02:56:17 925 8192000000 10483742752 10900615168 1199206672 12.769 100 98 197 2 1594733984 371183664 24343648
02:57:20 1069 8192000000 10517288144 10931937280 1192271344 12.704 100 99 196 1 1624237632 408646224 23320064
02:58:25 811 8192000000 10525979488 10945810432 1178048128 12.572 99 92 179 0 1630252080 429253376 22950576
02:59:28 2145 8192000000 10576424992 10994368512 1194992864 12.730 100 88 174 0 1685291840 469012832 21286144
03:00:34 1193 8192000000 10573322992 11017953280 1189601344 12.680 100 76 150 0 1677329744 469036256 18692144
03:01:37 3136 8192000000 10571442560 11018514432 1183332528 12.621 100 61 122 1 1675283184 476612464 15338192
03:02:55 918 8192000000 10547812592 11010363392 1175230208 12.546 100 85 162 2 1651496736 457112752 19153776
03:04:00 1241 8192000000 10575901472 11015081984 1181995808 12.609 100 83 159 2 1677983504 475686448 20301248
03:05:02 2926 8192000000 10544712208 11016077312 1171421296 12.510 100 51 102 0 1653724272 468850176 13452800
03:06:06 27249 8192000000 10486514224 10972356608 1173197472 12.527 100 35 70 0 1588134768 405810752 9126544
03:07:37 42246 8192000000 10479636272 10959126528 1165777040 12.457 100 27 54 0 1599920496 426853728 7289728
03:09:26 16723 8192000000 10422328832 10905227264 1142619680 12.240 100 23 46 0 1517613936 369016352 5977904
03:10:46 25858 8192000000 10237606976 10754990080 1140678144 12.222 100 29 57 1 1387092528 239555856 6858528
03:12:18 78841 8192000000 10069425696 10512490496 1087659056 11.720 95 14 29 0 1110953936 20433936 2860944
03:14:38 93668 8192000000 10096030176 10563620864 1108122576 11.915 97 21 34 0 1168579008 56513536 3942896
03:17:21 69790 8192000000 10111110304 10587656192 1111524592 11.947 98 25 44 1 1172212304 56643856 4043856
03:19:32 105991 8192000000 10085305248 10524495872 1124220272 12.067 99 32 61 0 1171607648 40382224 7005152
03:22:30 65058 8192000000 10070037056 10515161088 1115438160 11.984 99 33 66 0 1149255840 26351840 7465840
03:24:47 119857 8192000000 10096772640 10546597888 1081457088 11.661 96 33 61 1 1165614240 77967504 6189648
03:27:50 25341 8192000000 10097401504 10553032704 1115704912 11.986 100 35 65 1 1199865328 78531088 5629328
03:29:19 62856 8192000000 10096806416 10559651840 1118058048 12.009 99 18 36 0 1171708256 49695328 3954880
03:31:33 42066 8192000000 10088741792 10560983040 1133029024 12.150 100 13 26 0 1196753856 60741984 2982848
03:33:16 59066 8192000000 10068982368 10517839872 1114844288 11.978 98 20 38 1 1158164496 39128128 4192080
03:35:15 69203 8192000000 10074823392 10555052032 1130963408 12.130 100 21 42 0 1165530544 29989792 4577344
03:37:30 69315 8192000000 10071396624 10558988288 1123869104 12.064 99 9 17 1 1161802112 36378320 1554688
03:39:40 53782 8192000000 10086017568 10566692864 1129691728 12.118 99 14 27 0 1166503840 34242160 2569952
03:41:34 12233 8192000000 10088420896 10527485952 1132179184 12.142 99 35 70 1 1179228880 39695280 7354416
03:42:47 26702 8192000000 10155659728 10598735872 1145600176 12.268 100 40 79 0 1243664496 89666288 8398032
03:44:26 5292 8192000000 10057057808 10487328768 1121934752 12.045 99 60 120 2 1151402336 17832160 11635424
03:45:33 15402 8192000000 10152186672 10587783168 1136719488 12.185 100 54 103 1 1265529344 118279312 10530544
03:46:53 45055 8192000000 10116071808 10558558208 1065348512 11.508 95 43 86 0 1131210240 56586768 9274960
03:48:41 24643 8192000000 10148665696 10597376000 1121022640 12.037 99 39 77 0 1228868592 100154336 7691616
03:50:11 36448 8192000000 10075414256 10519404544 1104717200 11.882 98 34 62 0 1192293296 80334576 7241520
03:51:48 32367 8192000000 10065337744 10519941120 1047866944 11.340 94 37 71 1 1104675312 50195152 6613216
03:53:26 61690 8192000000 10089558400 10517254144 1103204560 11.868 99 43 86 0 1192663568 80709024 8749984
03:55:34 40596 8192000000 10047835472 10518892544 1115648272 11.986 100 16 32 0 1161522880 42465840 3408768
03:57:19 67039 8192000000 10031136464 10504216576 1073077344 11.581 96 5 10 0 1100204864 26225552 901968
03:59:26 45041 8192000000 10064581824 10512826368 1102565136 11.862 98 18 36 1 1146963136 40392592 4005408
04:01:21 57451 8192000000 10062964960 10538717184 1132988352 12.150 100 8 16 0 1164873216 30207648 1677216
04:03:33 33533 8192000000 10097663744 10562404352 1126000080 12.084 99 38 76 0 1197804304 64367792 7436432
04:05:18 15202 8192000000 10102298624 10540425216 1125781376 12.082 99 36 72 0 1186438512 53259136 7398000
04:06:34 73296 8192000000 10072405968 10541608960 1094940144 11.790 97 23 46 1 1179406720 79560880 4905696
04:08:48 47114 8192000000 10149838816 10589704192 1128944560 12.111 100 43 86 0 1239795920 102446880 8404480
04:10:38 131399 8192000000 10072981280 10518249472 1117476288 12.003 98 34 68 0 1157972064 33323664 7172112
04:13:50 51335 8192000000 10121746928 10566197248 1118020752 12.008 98 34 68 0 1189869392 64573536 7275104
04:15:55 73396 8192000000 10089571008 10551390208 1108394896 11.917 97 10 20 0 1186489440 75839504 2255040
04:18:16 65081 8192000000 10085260640 10595373056 1127767232 12.100 100 11 22 0 1175814736 45637616 2409888
04:20:23 49360 8192000000 10098835488 10539290624 1109469664 11.927 98 23 46 0 1168672016 53992816 5209536
04:22:30 28407 8192000000 10160887600 10591703040 1138740688 12.204 100 35 70 0 1246114192 99789152 7584352
04:24:08 48197 8192000000 10085371536 10532679680 1094633920 11.787 97 31 62 2 1143948560 43018080 6296560
04:26:02 68060 8192000000 10100504304 10596265984 1118028128 12.008 99 30 60 0 1204163120 80337088 5797904
04:28:14 63066 8192000000 10086055440 10553581568 1105468464 11.889 97 38 76 1 1172420560 59327776 7624320
04:30:22 90768 8192000000 10118068384 10562932736 1108747888 11.921 98 43 85 0 1200769536 83574496 8447152
04:32:54 47277 8192000000 10070066880 10556035072 1118641360 12.014 99 20 40 0 1157176896 34284016 4251520
04:35:03 59704 8192000000 10086317344 10547994624 1112988528 11.961 99 14 28 0 1163825968 47674320 3163120
04:37:03 23088 8192000000 9171483632 9485258752 312521616 3.674 28 4 8 0 356272016 42959632 790768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 28-09-2022 04:39:18 to 28-09-2022 04:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 155 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-09-2022 04:39:35 to 28-09-2022 04:39:37.

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          229856
MON$OLDEST_ACTIVE               231890
MON$OLDEST_SNAPSHOT             231890
MON$NEXT_TRANSACTION            231890
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                       2081120
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2850 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2850 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2850 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2850 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 ---
:41: 
:42: mscs-test-161.budzdorov.ru    Wed Sep 28 04:37:45 2022
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
--- end of diff output ---

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

28.09.22 04:39:38. 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-09-28 04:39:41 No severe PSQL-related problems occured
28.09.22 04:39:42. End of report.