Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.10.2021 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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15820560 kB
MemAvailable 30659916 kB
Buffers 20 kB
Cached 15808024 kB
SwapCached 3808 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-08 04:37:07
DTS_END                         2021-10-08 04:37:07.9110
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/56434
TRN_ID                          225364
ATT_ID                          631
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: 8919.66 21117 1070359
customer order: creation 160.49 468 19259
customer order: refuse 13.37 339 1604
order to supplier: creation 41.49 1539 4979
order to supplier: removal 3.16 135686 379
invoice (draft): creation 43.36 3556 5203
invoice (draft): removal 51.30 1095 6156
invoice accept: apply 39.34 18353 4721
invoice accept: cancel 46.08 803 5529
customer reserve: creation 3668.81 178 440257
customer reserve: removal 4577.31 73 549277
realization accept: apply 77.44 199 9293
realization accept: cancel 78.77 131 9452
payment from customer: creation 54.87 130 6584
payment from customer: removal 5.26 119 631
payment to supplier: creation 45.02 161 5402
payment to supplier: removal 3.29 89 395
service: total inventory turnovers 3.83 3320 460
service: total monetary turnovers 5.68 555 682
service: aggregate perf. data 0.77 17238 92
service: refresh index statistics 0.03 238305 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-10-2021 04:38:14 to 08-10-2021 04:38:16.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5296
TEST_TIME 2 4430
TEST_TIME 3 4801
TEST_TIME 4 4437
TEST_TIME 5 4448
TEST_TIME 6 4338
TEST_TIME 7 4847
TEST_TIME 8 4769
TEST_TIME 9 4940
TEST_TIME 10 4823
TEST_TIME 11 5211
TEST_TIME 12 5774
TEST_TIME 13 5829
TEST_TIME 14 6243
TEST_TIME 15 6357
TEST_TIME 16 6571
TEST_TIME 17 7002
TEST_TIME 18 7637
TEST_TIME 19 7792
TEST_TIME 20 8366
TEST_TIME 21 8483
TEST_TIME 22 8578
TEST_TIME 23 8638
TEST_TIME 24 8842
TEST_TIME 25 8690
TEST_TIME 26 8801
TEST_TIME 27 9773
TEST_TIME 28 11333
TEST_TIME 29 11046
TEST_TIME 30 10998
TEST_TIME 31 10945
TEST_TIME 32 10702
TEST_TIME 33 10487
TEST_TIME 34 10365
TEST_TIME 35 10217
TEST_TIME 36 10093
TEST_TIME 37 10027
TEST_TIME 38 9946
TEST_TIME 39 9875
TEST_TIME 40 9825
TEST_TIME 41 9662
TEST_TIME 42 9597
TEST_TIME 43 9567
TEST_TIME 44 9425
TEST_TIME 45 9562
TEST_TIME 46 9410
TEST_TIME 47 9472
TEST_TIME 48 9537
TEST_TIME 49 9451
TEST_TIME 50 9448
TEST_TIME 51 9386
TEST_TIME 52 9422
TEST_TIME 53 9454
TEST_TIME 54 9509
TEST_TIME 55 9561
TEST_TIME 56 9533
TEST_TIME 57 9490
TEST_TIME 58 9434
TEST_TIME 59 9685
TEST_TIME 60 9721
TEST_TIME 61 9680
TEST_TIME 62 9533
TEST_TIME 63 9523
TEST_TIME 64 9444
TEST_TIME 65 9390
TEST_TIME 66 9393
TEST_TIME 67 9345
TEST_TIME 68 9283
TEST_TIME 69 9288
TEST_TIME 70 9254
TEST_TIME 71 9240
TEST_TIME 72 9139
TEST_TIME 73 9156
TEST_TIME 74 9129
TEST_TIME 75 9070
TEST_TIME 76 9058
TEST_TIME 77 9034
TEST_TIME 78 9016
TEST_TIME 79 9045
TEST_TIME 80 9077
TEST_TIME 81 9140
TEST_TIME 82 9152
TEST_TIME 83 9130
TEST_TIME 84 9093
TEST_TIME 85 9327
TEST_TIME 86 9354
TEST_TIME 87 9330
TEST_TIME 88 9246
TEST_TIME 89 9195
TEST_TIME 90 9173
TEST_TIME 91 9143
TEST_TIME 92 9089
TEST_TIME 93 9045
TEST_TIME 94 9035
TEST_TIME 95 8989
TEST_TIME 96 9006
TEST_TIME 97 8996
TEST_TIME 98 9017
TEST_TIME 99 9026
TEST_TIME 100 9043
TEST_TIME 101 9021
TEST_TIME 102 9031
TEST_TIME 103 9087
TEST_TIME 104 9071
TEST_TIME 105 9090
TEST_TIME 106 9155
TEST_TIME 107 9123
TEST_TIME 108 9066
TEST_TIME 109 9021
TEST_TIME 110 9031
TEST_TIME 111 8985
TEST_TIME 112 8943
TEST_TIME 113 8918
TEST_TIME 114 8937
TEST_TIME 115 9012
TEST_TIME 116 8988
TEST_TIME 117 8970
TEST_TIME 118 8947
TEST_TIME 119 8993
TEST_TIME 120 8922

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-10-2021 04:38:18 to 08-10-2021 04:38:21.

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 1477135 1477135 0 0.00 0 28121 7 0 0
doc_list_biud 1477134 1477134 0 0.00 0 41844 34 0 0
sp_add_invoice_to_stock 4858 4721 137 2.82 50 180890 18353 0 137
sp_cancel_adding_invoice 5529 5529 0 0.00 6 46229 803 0 0
sp_cancel_client_order 1604 1604 0 0.00 4 12131 340 0 0
sp_cancel_customer_reserve 549277 549277 0 0.00 1 24341 74 0 0
sp_cancel_pay_from_customer 631 631 0 0.00 3 6548 119 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 7405 89 0 0
sp_cancel_supplier_invoice 6167 6156 11 0.17 2 41942 1095 0 11
sp_cancel_supplier_order 379 379 0 0.00 12 1166180 135687 0 0
sp_cancel_write_off 9452 9452 0 0.00 1 18164 132 0 0
sp_client_order 19259 19259 0 0.00 4 24885 469 0 0
sp_customer_reserve 440257 440257 0 0.00 0 94631 179 0 0
sp_fill_shopping_cart 29438 29438 0 0.00 1 27727 54 0 0
sp_fill_shopping_cart_clo_res 440255 440255 0 0.00 0 13201 18 0 0
sp_get_clo_for_invoice 4718 4718 0 0.00 0 37152 434 0 0
sp_kill_cost_storno 16007 16007 0 0.00 0 18162 68 0 0
sp_kill_qstorno_ret_qs2qd 1114541 1114541 0 0.00 0 41830 40 0 0
sp_kill_qty_storno 586408 586408 0 0.00 0 41842 86 0 0
sp_lock_selected_doc 19283 19283 0 0.00 0 9874 4 0 0
sp_make_cost_storno 25997 25997 0 0.00 0 19072 63 0 0
sp_multiply_rows_for_pdistr 25997 25997 0 0.00 0 20162 40 0 0
sp_multiply_rows_for_qdistr 19259 19259 0 0.00 0 24808 274 0 0
sp_pay_from_customer 6584 6584 0 0.00 4 23707 130 0 0
sp_pay_to_supplier 5402 5402 0 0.00 5 25773 162 0 0
sp_payment_common 11986 11986 0 0.00 1 20596 34 0 0
sp_qd_handle_on_cancel_clo 1604 1604 0 0.00 0 12121 306 0 0
sp_qd_handle_on_invoice_upd_sts 10247 10247 0 0.00 0 24466 373 0 0
sp_qd_handle_on_reserve_upd_sts 9293 9293 0 0.00 0 10636 59 0 0
sp_reserve_write_off 9293 9293 0 0.00 7 28235 200 0 0
sp_supplier_invoice 5203 5203 0 0.00 7 78327 3556 0 0
sp_supplier_order 4979 4979 0 0.00 6 41216 1540 0 0
srv_aggregate_perf_data 93 92 1 1.07 3006 207326 17239 0 1
srv_fill_mon_cache_memory 62 62 0 0.00 2280 145651 49794 0 0
srv_find_qd_qs_mism 1025267 1025267 0 0.00 0 23074 5 0 0
srv_make_invnt_saldo 460 460 0 0.00 36 150360 3320 0 0
srv_make_money_saldo 682 682 0 0.00 1 30178 556 0 0
srv_recalc_idx_stat 4 4 0 0.00 191309 321573 238305 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 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 4722 4722 0 0.00 0 13205 172 0 0
t$perf-norm:sp_cancel_adding_invoice 360 360 0 0.00 0 1986 58 0 0
t$perf-norm:sp_cancel_client_order 1604 1604 0 0.00 0 6302 16 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 1480 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 631 631 0 0.00 0 476 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 3068 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 1112 13 0 0
t$perf-norm:sp_cancel_supplier_order 379 379 0 0.00 0 29204 1616 0 0
t$perf-norm:sp_cancel_write_off 915 915 0 0.00 0 2970 9 0 0
t$perf-norm:sp_client_order 19259 19259 0 0.00 0 17798 21 0 0
t$perf-norm:sp_customer_reserve 994 994 0 0.00 0 1069 6 0 0
t$perf-norm:sp_pay_from_customer 6584 6584 0 0.00 0 8435 10 0 0
t$perf-norm:sp_pay_to_supplier 5402 5402 0 0.00 0 9225 14 0 0
t$perf-norm:sp_reserve_write_off 9293 9293 0 0.00 0 9933 13 0 0
t$perf-norm:sp_supplier_invoice 5203 5203 0 0.00 0 9573 22 0 0
t$perf-norm:sp_supplier_order 4979 4979 0 0.00 0 10387 16 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 376 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 29 1 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 1224 12 0 0
t$perf-norm:srv_make_money_saldo 682 682 0 0.00 0 5907 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9710 9436 274 2.82 0 4130 20 0 274
v_all_customers 34974 34974 0 0.00 0 26 1 0 0
v_all_suppliers 28070 28070 0 0.00 0 19 1 0 0
v_all_wares 38518 38518 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 720 720 0 0.00 0 2923 18 0 0
v_cancel_client_order 3208 3208 0 0.00 0 4707 7 0 0
v_cancel_customer_prepayment 1262 1262 0 0.00 0 3904 15 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 2666 6 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 1931 13 0 0
v_cancel_supplier_invoice 740 718 22 2.97 0 9489 29 0 22
v_cancel_supplier_order 758 758 0 0.00 0 766 6 0 0
v_cancel_write_off 1830 1830 0 0.00 0 3693 18 0 0
v_max_id_clo_ord 4979 4979 0 0.00 0 15749 10 0 0
v_max_id_clo_res 994 994 0 0.00 0 1534 15 0 0
v_max_id_ord_sup 5200 5200 0 0.00 0 5787 4 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 1463 3 0 0
v_max_non_paid_realizn 3518 3518 0 0.00 0 1538 4 0 0
v_min_id_clo_ord 4979 4979 0 0.00 0 23749 14 0 0
v_min_id_clo_res 994 994 0 0.00 0 7725 24 0 0
v_min_id_ord_sup 5200 5200 0 0.00 0 7153 6 0 0
v_min_non_paid_invoice 5402 5402 0 0.00 0 18608 24 0 0
v_min_non_paid_realizn 6584 6584 0 0.00 0 14313 28 0 0
v_our_firm 9676 9676 0 0.00 0 15 1 0 0
v_reserve_write_off 18586 18586 0 0.00 0 4680 7 0 0
x_lock_depdocs_on_canc_invoice 11326 11326 0 0.00 0 10024 109 0 0
x_lock_depdocs_on_canc_sup_ord 379 379 0 0.00 0 17845 417 0 0
x_make_qstorno_1000_1200 4979 4979 0 0.00 2 41209 1433 0 0
x_make_qstorno_1200_2000 5203 5203 0 0.00 3 70894 3367 0 0
x_make_qstorno_2100_3300 440257 440257 0 0.00 0 94629 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-10-2021 04:38:22 to 08-10-2021 04:38:26.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 32671 8192000000 9721773360 10019065856 795852656 8.854 100 85 168 1 824258320 8144224 20261440
02:09:51 18165 8192000000 9759732256 10064089088 833069440 9.230 100 90 180 1 867058800 12561328 21428032
02:11:18 17392 8192000000 9809904704 10128072704 866449488 9.565 100 86 172 0 913170000 26594688 20125824
02:12:38 13115 8192000000 9878599520 10208804864 914264080 10.039 100 84 168 1 989822496 57105056 18453360
02:13:55 9179 8192000000 9910954576 10253742080 949580144 10.387 100 83 166 0 1019345904 52067808 17697952
02:15:06 6582 8192000000 9968435696 10323746816 997258832 10.852 100 80 158 0 1078933984 65972560 15702592
02:16:14 6225 8192000000 10016271120 10383917056 1021288288 11.084 100 77 154 0 1125029328 88536224 15204816
02:17:23 2298 8192000000 10014238480 10385149952 1049330768 11.354 100 82 161 1 1130791088 64882960 16577360
02:18:27 1049 8192000000 10052757584 10422231040 1065810992 11.512 100 93 187 1 1158787088 74087120 18888976
02:19:28 2561 8192000000 9998115536 10383900672 1066532480 11.519 100 78 157 2 1117136528 34112736 16491312
02:20:33 5678 8192000000 9979564672 10375315456 1076087632 11.610 100 61 120 1 1101450080 11908832 13453616
02:21:41 9439 8192000000 10006980880 10406543360 1086634304 11.711 100 34 68 0 1118374256 23944336 7795616
02:22:52 52566 8192000000 10029052752 10415616000 1091680592 11.759 100 52 107 1 1120737120 18046864 11009664
02:24:45 7506 8192000000 10016273456 10407374848 1095514336 11.795 100 43 83 2 1119232640 14921264 8797040
02:25:55 7519 8192000000 9996353744 10400727040 1094611760 11.786 100 32 65 0 1132192608 30121168 7459680
02:27:04 7200 8192000000 9995167840 10396020736 1051624208 11.376 96 31 63 1 1080453616 21906032 6923376
02:28:16 44786 8192000000 9984252080 10395258880 1042051360 11.284 96 26 49 1 1073816528 26739344 5025824
02:30:01 5305 8192000000 9971320240 10354565120 1045287008 11.315 99 40 77 0 1085240000 31216128 8736864
02:31:07 9068 8192000000 9943613120 10334543872 1002790576 10.906 98 24 48 0 1036027008 27964320 5272112
02:32:17 6896 8192000000 9959661136 10361786368 1023939536 11.110 98 35 71 1 1053954080 22087488 7927056
02:33:25 51097 8192000000 9971897504 10382225408 1031108336 11.179 98 33 66 1 1063177168 24856944 7211888
02:35:20 5549 8192000000 9980282832 10373472256 1063363792 11.489 100 39 78 1 1096926704 23729264 9833648
02:36:30 7481 8192000000 9989281504 10377625600 1067386368 11.527 100 33 64 1 1100437008 26170592 6880048
02:37:45 13591 8192000000 10032947152 10426748928 1093085760 11.772 100 57 112 1 1136633824 31226432 12321632
02:39:11 85462 8192000000 10058965344 10476572672 1110526272 11.937 100 48 96 0 1172840464 51977376 10336816
02:41:43 32440 8192000000 10090386224 10503798784 1123355408 12.059 100 34 68 0 1201800224 71042784 7402032
02:43:20 5860 8192000000 10127642640 10535411712 1135169056 12.170 100 54 107 0 1230310288 83768064 11373168
02:44:33 41084 8192000000 10185556496 10607181824 1149121792 12.301 100 41 82 1 1304084560 145361840 9600928
02:46:20 19449 8192000000 10250554560 10665734144 1164124736 12.442 100 53 107 0 1368216656 191374928 12716992
02:47:43 12095 8192000000 10303818160 10722742272 1171119072 12.507 100 54 108 3 1402014816 217926960 12968784
02:49:02 5893 8192000000 10312327280 10727616512 1177767392 12.569 100 54 108 1 1413334080 222050624 13516064
02:50:11 5367 8192000000 10321049840 10738995200 1186661280 12.652 100 57 113 1 1426198224 226673840 12863104
02:51:23 3222 8192000000 10342111648 10754908160 1183993440 12.627 100 73 143 0 1445058688 244601552 16463696
02:52:34 2279 8192000000 10400004384 10808733696 1198380288 12.761 100 82 163 0 1498063376 281248608 18434480
02:53:41 2313 8192000000 10424995008 10836303872 1188017872 12.665 100 81 159 0 1527746256 321199648 18528736
02:54:49 13503 8192000000 10413051216 10860544000 1157776912 12.382 98 42 83 1 1516503584 347752944 10973728
02:56:11 7855 8192000000 10390506016 10847240192 1153068176 12.338 98 46 91 0 1495912992 331049376 11795440
02:57:32 2857 8192000000 10422154560 10832007168 1167406288 12.473 100 82 162 1 1517573904 330434064 19733552
02:58:38 5745 8192000000 10395283872 10817380352 1162099888 12.423 100 53 106 2 1493277712 318414016 12763808
02:59:48 7762 8192000000 10442996560 10873761792 1169479136 12.492 100 41 82 1 1546769248 366623248 10666864
03:01:00 20408 8192000000 10478558320 10915713024 1132049232 12.141 97 38 76 0 1558094272 415401024 10644016
03:02:22 21409 8192000000 10512883824 10965528576 1133873280 12.158 99 32 64 1 1590851568 447910848 9067440
03:04:10 13618 8192000000 10445955760 10951643136 1123418240 12.059 100 24 49 0 1567874848 437582400 6874208
03:05:27 53735 8192000000 10090473520 10556981248 1105583216 11.891 98 16 31 2 1276217120 167254992 3378912
03:07:37 18354 8192000000 10062013744 10513965056 1078483008 11.633 97 24 48 0 1148544656 64689392 5372256
03:09:14 74150 8192000000 10035548448 10490204160 1107222816 11.906 100 14 28 1 1153362144 43301184 2838144
03:11:32 63657 8192000000 10010840736 10442076160 1049566816 11.357 94 7 13 1 1075203296 23984464 1652016
03:13:42 92675 8192000000 10046377152 10477481984 1060662272 11.463 95 21 42 0 1103905840 38981616 4261952
03:16:15 59429 8192000000 10072783296 10536787968 1107115040 11.905 99 13 26 1 1164731952 54883728 2733184
03:18:16 18117 8192000000 10108440816 10536185856 1134458240 12.163 100 38 74 1 1211559424 68688576 8412608
03:19:36 56667 8192000000 10158696064 10585640960 1113636352 11.967 98 29 58 1 1234445904 114212608 6596944
03:21:33 145651 8192000000 10151792688 10635743232 1126534208 12.089 99 13 26 0 1277415968 148050608 2831152
03:24:59 54004 8192000000 10073879344 10570690560 1130454368 12.126 100 6 12 0 1180888208 49528080 905760
03:26:59 83752 8192000000 10126482112 10620899328 1127403872 12.097 99 26 51 1 1221661824 88520464 5737488
03:29:24 56626 8192000000 10120785712 10575912960 1130736592 12.128 99 15 30 0 1203067904 68946928 3384384
03:31:20 127564 8192000000 10078599296 10534268928 1146018816 12.272 100 21 42 0 1185282496 34480848 4782832
03:34:33 32794 8192000000 10123089888 10575781888 1136343712 12.181 99 34 69 0 1202238752 59277184 6617856
03:36:07 116346 8192000000 10073542032 10567364608 1131126464 12.132 99 11 21 1 1215861744 82284480 2450800
03:39:05 102276 8192000000 10092828896 10590457856 1143362224 12.247 100 33 66 1 1218920992 68331920 7226848
03:42:04 15840 8192000000 10091534832 10521550848 1144834432 12.261 100 58 120 0 1183059536 26633328 11591776
03:43:21 51989 8192000000 10077678048 10533756928 1110215664 11.934 97 15 31 0 1149655728 36427952 3012112
03:45:18 60708 8192000000 10083099008 10561794048 1127662736 12.099 99 22 42 1 1194453520 62319840 4470944
03:47:20 62799 8192000000 10072635104 10545364992 1096495632 11.804 96 17 35 1 1152019904 51822976 3701296
03:49:26 95613 8192000000 10062433072 10561466368 1108617424 11.919 98 21 42 2 1147512448 33796864 5098160
03:52:02 83157 8192000000 10092398640 10537127936 1111736912 11.949 98 19 39 1 1167712352 51130256 4845184
03:54:27 86200 8192000000 10073189392 10519973888 1074254032 11.593 95 20 40 1 1131037984 52613408 4170544
03:56:54 133055 8192000000 10076927248 10536955904 1074749264 11.597 95 12 24 1 1114957952 37754880 2453808
04:00:08 51657 8192000000 10103907952 10543325184 1099760736 11.835 98 33 65 1 1160419568 52951952 7706880
04:02:27 54732 8192000000 10079268368 10593701888 1088254608 11.726 98 13 26 1 1171329584 80543568 2531408
04:04:23 84218 8192000000 10025495504 10452885504 1032144144 11.189 93 32 64 0 1084511696 45632272 6735280
04:07:22 45370 8192000000 10010699072 10458738688 1093655248 11.777 99 12 24 0 1121682576 25852640 2174688
04:09:08 54523 8192000000 10054619136 10503983104 1108021248 11.914 99 13 26 0 1143416736 32872528 2522960
04:11:03 38459 8192000000 10026557600 10523811840 1099345120 11.831 98 11 20 1 1132686112 31106928 2234064
04:12:41 21820 8192000000 10059821248 10497654784 1090129168 11.744 98 34 67 0 1133687808 36593296 6965344
04:14:13 38479 8192000000 10085077952 10523844608 1102437328 11.861 99 21 42 0 1173878992 67315232 4126432
04:15:52 90486 8192000000 10064664352 10538647552 1103863920 11.874 98 25 50 0 1151110240 42085488 5160832
04:18:33 46193 8192000000 10130854560 10572091392 1133916320 12.158 100 23 46 0 1203785792 65203760 4665712
04:20:20 70030 8192000000 10072197600 10535813120 1143881056 12.252 100 25 50 1 1182853488 34140512 4831920
04:22:30 73538 8192000000 10073700736 10571804672 1116787792 11.997 98 22 44 0 1189350080 68296160 4266128
04:24:45 76190 8192000000 10091154608 10530988032 1133889024 12.158 99 40 80 0 1182830672 39793680 9147968
04:27:16 48279 8192000000 10084694496 10553110528 1131396768 12.135 99 12 24 0 1200409536 66685280 2327488
04:30:12 49405 8192000000 10137457920 10581942272 1135086352 12.169 98 21 41 1 1210767744 71618592 4062800
04:32:10 74831 8192000000 10070564880 10537861120 1118432288 12.012 98 9 18 0 1176749648 56479808 1837552
04:34:26 33360 8192000000 10121122208 10560188416 1140071488 12.216 99 26 53 0 1206725152 61571392 5082272
04:36:04 68110 8192000000 9638726576 10011054080 691427472 7.783 60 24 48 0 764050816 67648304 4975040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-10-2021 04:38:28 to 08-10-2021 04:38:43.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 137 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 08-10-2021 04:38:44 to 08-10-2021 04:38:45.

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          222504
MON$OLDEST_ACTIVE               225917
MON$OLDEST_SNAPSHOT             225917
MON$NEXT_TRANSACTION            225917
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                       2047072
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             778
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2624 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2624 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2624 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2624 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Fri Oct  8 04:37:25 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

08.10.21 04:38:46. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-10-08 04:38:49 No severe PSQL-related problems occured
08.10.21 04:38:50. End of report.