Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.08.2022 04:40
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 15782220 kB
MemAvailable 30988140 kB
Buffers 20 kB
Cached 15899740 kB
SwapCached 2512 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-08-02 04:37:21
DTS_END                         2022-08-02 04:37:21.3380
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/45510
TRN_ID                          216872
ATT_ID                          633
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: 9117.91 21624 1094149
customer order: creation 171.03 534 20524
customer order: refuse 14.54 440 1745
order to supplier: creation 44.32 1396 5318
order to supplier: removal 3.41 165144 409
invoice (draft): creation 45.03 3267 5404
invoice (draft): removal 50.33 1020 6039
invoice accept: apply 41.64 28234 4997
invoice accept: cancel 45.29 1327 5435
customer reserve: creation 3864.36 269 463723
customer reserve: removal 4551.36 97 546163
realization accept: apply 81.63 320 9796
realization accept: cancel 75.63 185 9075
payment from customer: creation 58.68 197 7041
payment from customer: removal 5.70 132 684
payment to supplier: creation 49.40 218 5928
payment to supplier: removal 3.14 128 377
service: total inventory turnovers 3.77 4326 452
service: total monetary turnovers 7.73 315 928
service: aggregate perf. data 0.89 14875 107
service: refresh index statistics 0.03 210056 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2022 04:40:47 to 02-08-2022 04:40:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5856
TEST_TIME 2 6158
TEST_TIME 3 6073
TEST_TIME 4 5446
TEST_TIME 5 5304
TEST_TIME 6 5392
TEST_TIME 7 5138
TEST_TIME 8 5436
TEST_TIME 9 5842
TEST_TIME 10 5942
TEST_TIME 11 6416
TEST_TIME 12 6399
TEST_TIME 13 6502
TEST_TIME 14 6587
TEST_TIME 15 6755
TEST_TIME 16 6972
TEST_TIME 17 7170
TEST_TIME 18 7204
TEST_TIME 19 7379
TEST_TIME 20 7682
TEST_TIME 21 7923
TEST_TIME 22 8067
TEST_TIME 23 7958
TEST_TIME 24 8093
TEST_TIME 25 8072
TEST_TIME 26 8105
TEST_TIME 27 8626
TEST_TIME 28 9100
TEST_TIME 29 9159
TEST_TIME 30 9327
TEST_TIME 31 9604
TEST_TIME 32 9787
TEST_TIME 33 9834
TEST_TIME 34 10396
TEST_TIME 35 10332
TEST_TIME 36 10254
TEST_TIME 37 10140
TEST_TIME 38 10040
TEST_TIME 39 9934
TEST_TIME 40 10168
TEST_TIME 41 10235
TEST_TIME 42 10445
TEST_TIME 43 10273
TEST_TIME 44 10193
TEST_TIME 45 10085
TEST_TIME 46 9976
TEST_TIME 47 10011
TEST_TIME 48 9880
TEST_TIME 49 9816
TEST_TIME 50 9783
TEST_TIME 51 9697
TEST_TIME 52 9739
TEST_TIME 53 9801
TEST_TIME 54 9783
TEST_TIME 55 9745
TEST_TIME 56 9731
TEST_TIME 57 9724
TEST_TIME 58 9743
TEST_TIME 59 9723
TEST_TIME 60 9713
TEST_TIME 61 9690
TEST_TIME 62 9706
TEST_TIME 63 9716
TEST_TIME 64 9742
TEST_TIME 65 9677
TEST_TIME 66 9691
TEST_TIME 67 9768
TEST_TIME 68 9782
TEST_TIME 69 9826
TEST_TIME 70 9853
TEST_TIME 71 9812
TEST_TIME 72 9690
TEST_TIME 73 9614
TEST_TIME 74 9567
TEST_TIME 75 9533
TEST_TIME 76 9451
TEST_TIME 77 9407
TEST_TIME 78 9393
TEST_TIME 79 9413
TEST_TIME 80 9356
TEST_TIME 81 9327
TEST_TIME 82 9272
TEST_TIME 83 9228
TEST_TIME 84 9231
TEST_TIME 85 9262
TEST_TIME 86 9218
TEST_TIME 87 9221
TEST_TIME 88 9269
TEST_TIME 89 9295
TEST_TIME 90 9246
TEST_TIME 91 9377
TEST_TIME 92 9373
TEST_TIME 93 9393
TEST_TIME 94 9456
TEST_TIME 95 9445
TEST_TIME 96 9430
TEST_TIME 97 9366
TEST_TIME 98 9321
TEST_TIME 99 9302
TEST_TIME 100 9251
TEST_TIME 101 9212
TEST_TIME 102 9210
TEST_TIME 103 9170
TEST_TIME 104 9137
TEST_TIME 105 9077
TEST_TIME 106 9082
TEST_TIME 107 9040
TEST_TIME 108 9068
TEST_TIME 109 9072
TEST_TIME 110 9066
TEST_TIME 111 9062
TEST_TIME 112 9112
TEST_TIME 113 9130
TEST_TIME 114 9131
TEST_TIME 115 9115
TEST_TIME 116 9137
TEST_TIME 117 9273
TEST_TIME 118 9242
TEST_TIME 119 9215
TEST_TIME 120 9138
TEST_TIME 121 9062

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-08-2022 04:40:51 to 02-08-2022 04:40:54.

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 1520411 1520411 0 0.00 0 29821 14 0 0
doc_list_biud 1520399 1520399 0 0.00 0 20086 41 0 0
sp_add_invoice_to_stock 5130 4997 133 2.59 72 194246 28235 0 133
sp_cancel_adding_invoice 5435 5435 0 0.00 6 94639 1327 0 0
sp_cancel_client_order 1745 1745 0 0.00 6 11498 440 0 0
sp_cancel_customer_reserve 546163 546163 0 0.00 1 18388 97 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 4 2075 132 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 4 3201 128 0 0
sp_cancel_supplier_invoice 6048 6039 9 0.14 2 20093 1021 0 9
sp_cancel_supplier_order 409 409 0 0.00 11 1292575 165145 0 0
sp_cancel_write_off 9075 9075 0 0.00 2 13709 186 0 0
sp_client_order 20524 20524 0 0.00 6 67758 535 0 0
sp_customer_reserve 463723 463723 0 0.00 0 95441 269 0 0
sp_fill_shopping_cart 31230 31230 0 0.00 1 14339 85 0 0
sp_fill_shopping_cart_clo_res 463701 463701 0 0.00 0 19357 21 0 0
sp_get_clo_for_invoice 4965 4965 0 0.00 0 29216 272 0 0
sp_kill_cost_storno 15571 15571 0 0.00 0 25277 83 0 0
sp_kill_qstorno_ret_qs2qd 1107849 1107849 0 0.00 0 20085 43 0 0
sp_kill_qty_storno 583623 583623 0 0.00 0 20086 99 0 0
sp_lock_selected_doc 20371 20371 0 0.00 0 8934 8 0 0
sp_make_cost_storno 27729 27729 0 0.00 0 20758 94 0 0
sp_multiply_rows_for_pdistr 27728 27728 0 0.00 0 9414 44 0 0
sp_multiply_rows_for_qdistr 20521 20521 0 0.00 0 67728 195 0 0
sp_pay_from_customer 7041 7041 0 0.00 5 13521 198 0 0
sp_pay_to_supplier 5928 5928 0 0.00 6 25865 218 0 0
sp_payment_common 12969 12969 0 0.00 2 8436 52 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 11481 364 0 0
sp_qd_handle_on_invoice_upd_sts 10399 10399 0 0.00 0 11698 557 0 0
sp_qd_handle_on_reserve_upd_sts 9793 9793 0 0.00 0 18754 76 0 0
sp_reserve_write_off 9796 9796 0 0.00 8 18880 320 0 0
sp_supplier_invoice 5404 5404 0 0.00 9 116929 3268 0 0
sp_supplier_order 5318 5318 0 0.00 7 77353 1396 0 0
srv_aggregate_perf_data 107 107 0 0.00 2646 150816 14876 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 707 103576 34771 0 0
srv_find_qd_qs_mism 1045014 1045014 0 0.00 0 29819 11 0 0
srv_make_invnt_saldo 452 452 0 0.00 39 59252 4327 0 0
srv_make_money_saldo 928 928 0 0.00 1 5279 315 0 0
srv_recalc_idx_stat 4 4 0 0.00 62512 333598 210057 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4999 4999 0 0.00 1 26535 1038 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 2 7367 524 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 5815 35 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 8913 46 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 1079 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 724 15 0 0
t$perf-norm:sp_cancel_supplier_invoice 373 373 0 0.00 0 1810 40 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 101029 7931 0 0
t$perf-norm:sp_cancel_write_off 983 983 0 0.00 0 1246 21 0 0
t$perf-norm:sp_client_order 20526 20526 0 0.00 0 9990 41 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 7401 27 0 0
t$perf-norm:sp_pay_from_customer 7041 7041 0 0.00 0 7802 22 0 0
t$perf-norm:sp_pay_to_supplier 5929 5929 0 0.00 0 3131 21 0 0
t$perf-norm:sp_reserve_write_off 9798 9798 0 0.00 0 7926 28 0 0
t$perf-norm:sp_supplier_invoice 5405 5405 0 0.00 0 7280 38 0 0
t$perf-norm:sp_supplier_order 5318 5318 0 0.00 0 13817 41 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 221 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 236 8 0 0
t$perf-norm:srv_make_invnt_saldo 452 452 0 0.00 0 589 16 0 0
t$perf-norm:srv_make_money_saldo 928 928 0 0.00 0 488 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 193 57 0 0
v_add_invoice_to_stock 10178 9912 266 2.61 0 5117 25 0 266
v_all_customers 37201 37201 0 0.00 0 79 4 0 0
v_all_suppliers 30437 30437 0 0.00 0 122 5 0 0
v_all_wares 41032 41032 0 0.00 0 181 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 506 16 0 0
v_cancel_client_order 3490 3490 0 0.00 0 3139 11 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 1268 12 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 1 3434 21 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 3136 20 0 0
v_cancel_supplier_invoice 764 746 18 2.35 0 989 25 0 18
v_cancel_supplier_order 818 818 0 0.00 0 3105 15 0 0
v_cancel_write_off 1966 1966 0 0.00 0 4714 28 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 3346 6 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 3921 19 0 0
v_max_id_ord_sup 5396 5396 0 0.00 0 3214 8 0 0
v_max_non_paid_invoice 1420 1420 0 0.00 0 1926 10 0 0
v_max_non_paid_realizn 3731 3731 0 0.00 0 1679 8 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 6876 10 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 3828 16 0 0
v_min_id_ord_sup 5396 5396 0 0.00 0 4733 8 0 0
v_min_non_paid_invoice 5927 5927 0 0.00 0 4754 17 0 0
v_min_non_paid_realizn 7041 7041 0 0.00 0 7159 25 0 0
v_our_firm 10448 10448 0 0.00 0 61 5 0 0
v_reserve_write_off 19582 19582 0 0.00 0 3291 11 0 0
x_lock_depdocs_on_canc_invoice 11101 11101 0 0.00 0 10123 110 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 16903 412 0 0
x_make_qstorno_1000_1200 5318 5318 0 0.00 3 77320 1221 0 0
x_make_qstorno_1200_2000 5404 5404 0 0.00 4 116711 3033 0 0
x_make_qstorno_2100_3300 463721 463721 0 0.00 0 95420 234 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2022 04:40:55 to 02-08-2022 04:40:59.

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 3 8192000000 8799631040 8811175936 5414896 0.066 2 1 2 0 5516496 65824 35776
02:08:24 31428 8192000000 9727683856 10029858816 803823232 8.935 100 88 176 0 832966576 7598112 21545232
02:09:58 22759 8192000000 9762317904 10070720512 830810544 9.207 100 86 171 0 863307104 11801936 20694624
02:11:30 14694 8192000000 9803161456 10122383360 871533968 9.615 100 88 176 0 910860432 19313792 20012672
02:12:55 13802 8192000000 9859426576 10195460096 913553552 10.032 100 84 168 1 972529456 40483632 18492272
02:14:13 5432 8192000000 9901230000 10245591040 947960432 10.371 100 85 169 0 1019515856 53399856 18155568
02:15:23 2192 8192000000 9964116704 10318983168 988208096 10.764 100 86 170 0 1083482720 77956576 17318048
02:16:27 1335 8192000000 10001641120 10359808000 1009918192 10.975 100 96 188 0 1116888800 88256256 18714352
02:17:31 1366 8192000000 10004912128 10371645440 1037344592 11.239 100 97 190 3 1123696816 67572208 18780016
02:18:33 828 8192000000 10026195776 10400944128 1050074624 11.361 100 89 171 2 1147181952 79920176 17187152
02:19:36 1230 8192000000 10032541232 10410827776 1057139472 11.429 100 90 170 1 1154781616 80954144 16688000
02:20:39 1160 8192000000 10051023680 10432966656 1064063296 11.495 100 92 174 2 1173993072 93100240 16829536
02:21:41 1043 8192000000 10044320240 10426245120 1070127536 11.553 100 94 179 1 1167014304 78223840 18662928
02:22:45 1098 8192000000 10052506448 10437292032 1073287440 11.583 100 97 182 1 1172671456 80965488 18418528
02:23:47 1005 8192000000 10030556320 10423742464 1085924352 11.704 100 75 132 4 1159019536 58525360 14569824
02:24:49 11020 8192000000 10018505584 10417250304 1085350368 11.698 100 60 111 3 1155329376 57841888 12137120
02:26:01 1881 8192000000 10026002480 10414592000 1092522576 11.767 100 84 156 0 1148021840 40742160 14757104
02:27:03 7447 8192000000 10023362640 10419888128 1092168144 11.763 100 71 130 1 1135534080 30192640 13173296
02:28:11 1114 8192000000 10057297344 10444206080 1092476624 11.766 100 96 180 3 1178797408 67545600 18775184
02:29:13 1042 8192000000 10035681040 10422013952 1090909248 11.751 100 90 168 1 1159598512 51666592 17022672
02:30:16 3256 8192000000 10042527520 10436149248 1084469568 11.690 100 61 120 0 1147009968 50187248 12353152
02:31:21 1199 8192000000 10037681600 10425012224 1071659888 11.568 100 97 186 1 1162685296 71224224 19801184
02:32:23 1061 8192000000 10035183568 10415382528 1070036880 11.552 100 96 183 0 1153124752 64744208 18343664
02:33:25 1815 8192000000 10008469568 10395774976 1060364240 11.460 100 65 124 3 1129608048 56389632 12854176
02:34:29 1906 8192000000 9995500160 10378309632 1038543264 11.251 100 62 117 1 1117788336 66827472 12417600
02:35:32 1722 8192000000 9964959968 10338521088 1006066160 10.937 99 79 146 2 1091623008 70132064 15424784
02:36:35 1001 8192000000 9937091648 10301759488 1014025648 11.014 100 94 168 1 1056004960 24604352 17374960
02:37:40 17329 8192000000 9976286688 10355355648 1052259632 11.382 100 71 126 3 1095646912 30813072 12574208
02:39:05 7126 8192000000 10035577696 10413592576 1065566928 11.510 100 81 155 0 1155728928 73458816 16703184
02:40:21 7239 8192000000 10050995664 10440740864 1082578224 11.672 100 75 144 1 1166972112 69094752 15299136
02:41:34 12325 8192000000 10070911808 10467041280 1092627584 11.768 100 81 152 1 1195264048 86504224 16132240
02:42:52 6598 8192000000 10124403344 10510487552 1103371184 11.870 100 74 127 0 1224840224 108403632 13065408
02:44:03 1983 8192000000 10120723664 10513240064 1120123584 12.028 100 81 155 1 1239338992 100974384 18241024
02:45:13 1961 8192000000 10189521152 10589515776 1134128800 12.160 100 91 177 6 1309408624 155666480 19613344
02:46:19 11354 8192000000 10191685648 10616918016 1148635392 12.297 100 67 132 0 1315161520 151654640 14871488
02:47:35 1748 8192000000 10242679520 10639388672 1152835760 12.336 100 95 185 1 1358898592 184511968 21550864
02:48:40 2227 8192000000 10250253312 10650656768 1163234624 12.434 100 77 149 0 1362007264 182859472 15913168
02:49:48 1367 8192000000 10284207984 10682662912 1171265920 12.509 100 90 169 3 1392186240 203268016 17652304
02:50:55 1280 8192000000 10317998752 10720022528 1180251616 12.593 100 90 175 0 1426479520 227008768 19219136
02:51:59 1096 8192000000 10350904384 10757492736 1183886048 12.626 100 92 180 0 1465936816 262678496 19372272
02:53:03 955 8192000000 10393412384 10799538176 1185594352 12.642 100 97 192 0 1507131184 298598640 22938192
02:54:09 749 8192000000 10399806912 10817449984 1185833344 12.645 100 92 181 1 1513073168 307409936 19829888
02:55:15 3633 8192000000 10400234352 10824523776 1184089152 12.628 100 83 165 1 1505167312 302157104 18921056
02:56:24 701 8192000000 10444714000 10884993024 1201511184 12.790 100 94 187 0 1553395632 329743696 22140752
02:57:29 1272 8192000000 10411223088 10875949056 1200297744 12.779 100 95 185 2 1526614208 304345408 21971056
02:58:34 912 8192000000 10417468336 10861998080 1204970544 12.822 100 95 186 1 1522149328 294543088 22635696
02:59:39 2673 8192000000 10469683024 10887905280 1198469520 12.762 100 79 156 0 1575338272 358399040 18469712
03:00:47 781 8192000000 10497042816 10926178304 1196097216 12.740 100 90 175 1 1600793248 383887120 20808912
03:01:52 1706 8192000000 10536107536 10974556160 1189325088 12.677 100 83 165 0 1638626528 428139056 21162384
03:02:59 3788 8192000000 10446475376 10946863104 1171298688 12.509 100 71 135 0 1553007280 364805888 16902704
03:04:06 805 8192000000 10465485360 10957193216 1167571920 12.474 100 97 183 0 1567991104 377275120 23144064
03:05:12 2703 8192000000 10373859568 10864734208 1173496928 12.530 100 78 150 1 1479507760 287543712 18467120
03:06:22 4660 8192000000 10346530048 10773213184 1151741296 12.326 100 57 105 4 1452126320 286929040 13455984
03:07:28 14102 8192000000 10338969568 10824417280 1152861664 12.336 100 41 82 0 1465521376 302497248 10162464
03:08:53 23715 8192000000 10283206752 10757214208 1150807360 12.317 99 33 63 1 1369127616 211029952 7290304
03:10:31 39947 8192000000 10143169856 10648322048 1107536032 11.909 97 15 30 0 1301571488 189935808 4099648
03:12:41 19251 8192000000 10063710432 10479165440 1092396912 11.765 98 56 103 2 1150813664 45943232 12473520
03:14:03 83852 8192000000 10161313904 10622095360 1122409392 12.050 99 31 56 1 1236106016 107658976 6037648
03:16:28 11019 8192000000 10188626368 10611990528 1132038896 12.141 99 69 127 2 1274020672 127671600 14310176
03:17:40 62961 8192000000 10148011536 10652102656 1115270384 11.982 98 14 29 0 1260874624 142363520 3240720
03:19:54 96774 8192000000 10065574752 10538733568 1100975152 11.847 97 10 18 0 1144337616 41145072 2217392
03:23:10 61814 8192000000 10136031504 10577928192 1114720640 11.977 98 38 75 0 1198657904 76627968 7309296
03:25:26 53603 8192000000 10097665472 10531307520 1091870336 11.760 96 60 108 1 1170028560 64481472 13676752
03:27:21 45581 8192000000 10176531184 10607493120 1132515136 12.145 100 40 80 0 1256898944 115872944 8510864
03:29:13 29825 8192000000 10208848096 10673778688 1148411600 12.295 100 52 102 0 1299802416 139223456 12167360
03:30:45 44625 8192000000 10171028608 10641313792 1148052112 12.291 100 27 51 0 1279590080 125850336 5687632
03:32:30 81480 8192000000 10117091536 10589962240 1106504848 11.899 97 15 30 0 1210203728 100470528 3228352
03:34:53 35677 8192000000 10069974624 10568929280 1136650880 12.184 100 7 14 0 1174024080 35901072 1472128
03:37:20 49847 8192000000 10087221200 10535010304 1132298304 12.143 99 29 58 0 1176247904 38057856 5891744
03:39:15 29610 8192000000 10100981392 10542669824 1148552784 12.296 100 32 61 0 1191076800 36322016 6202000
03:40:51 47335 8192000000 10083682704 10546712576 1122657776 12.052 98 20 36 2 1169107488 42946096 3503616
03:43:05 35841 8192000000 10142380176 10601078784 1143532288 12.249 99 31 61 0 1234102608 84269984 6300336
03:44:48 72294 8192000000 10133160080 10612891648 1130928080 12.130 98 13 26 0 1221535360 87663232 2944048
03:47:06 67028 8192000000 10094291008 10536214528 1141497152 12.230 99 18 36 0 1186063248 41150896 3415200
03:49:15 87124 8192000000 10091125680 10517331968 1112064736 11.952 98 54 106 1 1178465568 54933552 11467280
03:52:36 60541 8192000000 10041315616 10480656384 1103948752 11.875 97 43 77 0 1136067088 24034448 8083888
03:54:38 12864 8192000000 10133198784 10566955008 1139660256 12.212 100 45 90 0 1225755312 77260688 8834368
03:55:54 51977 8192000000 10085030496 10564255744 1106809696 11.902 97 16 32 0 1185787664 75297872 3680096
03:58:41 61708 8192000000 10088202496 10582818816 1129624272 12.118 100 19 38 0 1205079184 71074144 4380768
04:00:43 54944 8192000000 10100119392 10575306752 1093173952 11.773 96 22 44 0 1176342656 77967040 5201664
04:02:44 41487 8192000000 10115394496 10559332352 1102574416 11.862 97 29 58 0 1181781264 72772304 6434544
04:04:31 31074 8192000000 10152643536 10594033664 1133512896 12.154 100 31 61 0 1254344768 113617760 7214112
04:06:02 39258 8192000000 10133782432 10592731136 1106042560 11.895 98 27 52 0 1230089488 117865808 6181120
04:07:58 60561 8192000000 10097300528 10626256896 1118120688 12.009 99 15 30 0 1248150112 126604288 3425136
04:09:58 84274 8192000000 10131860448 10578350080 1080001680 11.647 95 26 50 0 1156726560 71574160 5150720
04:12:23 36240 8192000000 10084156864 10514014208 1111971792 11.951 99 37 69 0 1162160704 43802368 6386544
04:14:00 77862 8192000000 10097429584 10544529408 1111212784 11.944 99 45 89 1 1183528128 62989280 9326064
04:16:24 48616 8192000000 10073268368 10550464512 1124684608 12.071 100 12 24 1 1168636816 41717296 2234912
04:18:21 56227 8192000000 10045203440 10543878144 1115850416 11.988 99 7 14 0 1174714160 57282896 1580848
04:20:18 58122 8192000000 10069846288 10520514560 1131242720 12.133 100 17 34 1 1172643040 37810896 3589424
04:22:16 88878 8192000000 10102204304 10586480640 1128032464 12.103 100 19 38 0 1178932192 46426816 4472912
04:24:50 75876 8192000000 10049626896 10527895552 1122303344 12.049 99 19 38 0 1183901856 57496656 4101856
04:27:16 34214 8192000000 10111492256 10553729024 1133378992 12.153 99 28 56 0 1193540128 54280416 5880720
04:28:53 71988 8192000000 10136465904 10644123648 1147341136 12.285 100 21 42 0 1240137520 87911952 4884432
04:31:31 29351 8192000000 10133877296 10573893632 1133226224 12.152 99 34 68 0 1207616720 67454864 6935632
04:33:06 61748 8192000000 10155885136 10654068736 1121589360 12.042 99 26 52 0 1280071248 153063472 5418416
04:35:53 103571 8192000000 9914514304 10349514752 988555968 10.767 87 26 53 1 1027774464 33614320 5604176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-08-2022 04:41:01 to 02-08-2022 04:41:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-08-2022 04:41:18 to 02-08-2022 04:41:20.

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          218005
MON$OLDEST_ACTIVE               218006
MON$OLDEST_SNAPSHOT             218006
MON$NEXT_TRANSACTION            218006
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                       2052832
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             764
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

02.08.22 04:41:21. 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-08-02 04:41:24 No severe PSQL-related problems occured
02.08.22 04:41:25. End of report.