Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.06.2022 07: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 16182080 kB
MemAvailable 31331168 kB
Buffers 0 kB
Cached 15512168 kB
SwapCached 2444 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-06-03 07:37:16
DTS_END                         2022-06-03 07:37:16.4800
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/33192
TRN_ID                          226753
ATT_ID                          655
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: 8989.42 26580 1078730
customer order: creation 170.38 427 20445
customer order: refuse 15.03 427 1804
order to supplier: creation 47.11 1330 5653
order to supplier: removal 3.18 168472 382
invoice (draft): creation 46.80 3211 5616
invoice (draft): removal 49.28 976 5913
invoice accept: apply 42.21 26549 5065
invoice accept: cancel 44.97 1186 5396
customer reserve: creation 3910.79 255 469295
customer reserve: removal 4363.58 96 523630
realization accept: apply 85.26 291 10231
realization accept: cancel 78.58 162 9429
payment from customer: creation 59.93 169 7192
payment from customer: removal 5.97 142 716
payment to supplier: creation 50.33 198 6039
payment to supplier: removal 3.63 112 436
service: total inventory turnovers 3.98 4308 478
service: total monetary turnovers 7.56 363 907
service: aggregate perf. data 0.82 20264 98
service: refresh index statistics 0.04 302670 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-06-2022 07:38:35 to 03-06-2022 07:38:37.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9459
TEST_TIME 2 8426
TEST_TIME 3 7242
TEST_TIME 4 6662
TEST_TIME 5 6283
TEST_TIME 6 5986
TEST_TIME 7 6257
TEST_TIME 8 6056
TEST_TIME 9 6120
TEST_TIME 10 6245
TEST_TIME 11 6463
TEST_TIME 12 6587
TEST_TIME 13 6543
TEST_TIME 14 6934
TEST_TIME 15 7021
TEST_TIME 16 7242
TEST_TIME 17 7537
TEST_TIME 18 7804
TEST_TIME 19 7798
TEST_TIME 20 7940
TEST_TIME 21 7833
TEST_TIME 22 8077
TEST_TIME 23 7984
TEST_TIME 24 8254
TEST_TIME 25 8325
TEST_TIME 26 8332
TEST_TIME 27 8661
TEST_TIME 28 8918
TEST_TIME 29 8865
TEST_TIME 30 8988
TEST_TIME 31 9019
TEST_TIME 32 9805
TEST_TIME 33 10101
TEST_TIME 34 10724
TEST_TIME 35 11016
TEST_TIME 36 10820
TEST_TIME 37 10690
TEST_TIME 38 10707
TEST_TIME 39 10617
TEST_TIME 40 10509
TEST_TIME 41 10387
TEST_TIME 42 10289
TEST_TIME 43 10262
TEST_TIME 44 10126
TEST_TIME 45 10082
TEST_TIME 46 10068
TEST_TIME 47 10034
TEST_TIME 48 10087
TEST_TIME 49 9963
TEST_TIME 50 9841
TEST_TIME 51 9739
TEST_TIME 52 9841
TEST_TIME 53 9738
TEST_TIME 54 9755
TEST_TIME 55 9854
TEST_TIME 56 9810
TEST_TIME 57 9758
TEST_TIME 58 9838
TEST_TIME 59 9843
TEST_TIME 60 9863
TEST_TIME 61 9806
TEST_TIME 62 9801
TEST_TIME 63 9863
TEST_TIME 64 9817
TEST_TIME 65 9758
TEST_TIME 66 9817
TEST_TIME 67 9765
TEST_TIME 68 9754
TEST_TIME 69 9671
TEST_TIME 70 9614
TEST_TIME 71 9574
TEST_TIME 72 9506
TEST_TIME 73 9459
TEST_TIME 74 9380
TEST_TIME 75 9376
TEST_TIME 76 9448
TEST_TIME 77 9431
TEST_TIME 78 9403
TEST_TIME 79 9359
TEST_TIME 80 9379
TEST_TIME 81 9455
TEST_TIME 82 9458
TEST_TIME 83 9466
TEST_TIME 84 9430
TEST_TIME 85 9464
TEST_TIME 86 9488
TEST_TIME 87 9480
TEST_TIME 88 9556
TEST_TIME 89 9579
TEST_TIME 90 9481
TEST_TIME 91 9440
TEST_TIME 92 9420
TEST_TIME 93 9375
TEST_TIME 94 9333
TEST_TIME 95 9297
TEST_TIME 96 9325
TEST_TIME 97 9276
TEST_TIME 98 9263
TEST_TIME 99 9260
TEST_TIME 100 9248
TEST_TIME 101 9263
TEST_TIME 102 9244
TEST_TIME 103 9229
TEST_TIME 104 9249
TEST_TIME 105 9325
TEST_TIME 106 9306
TEST_TIME 107 9284
TEST_TIME 108 9294
TEST_TIME 109 9380
TEST_TIME 110 9399
TEST_TIME 111 9320
TEST_TIME 112 9279
TEST_TIME 113 9259
TEST_TIME 114 9221
TEST_TIME 115 9179
TEST_TIME 116 9112
TEST_TIME 117 9100
TEST_TIME 118 9099
TEST_TIME 119 9085
TEST_TIME 120 9017

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 03-06-2022 07:38:38 to 03-06-2022 07:38:42.

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 1510902 1510902 0 0.00 0 21236 14 0 0
doc_list_biud 1510896 1510896 0 0.00 0 80907 39 0 0
sp_add_invoice_to_stock 5237 5065 172 3.28 25 220738 26549 0 172
sp_cancel_adding_invoice 5396 5396 0 0.00 6 100106 1187 0 0
sp_cancel_client_order 1804 1804 0 0.00 5 12558 427 0 0
sp_cancel_customer_reserve 523630 523630 0 0.00 1 22662 96 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 4 11143 142 0 0
sp_cancel_pay_to_supplier 436 436 0 0.00 3 2288 113 0 0
sp_cancel_supplier_invoice 5925 5913 12 0.20 2 80910 976 0 12
sp_cancel_supplier_order 382 382 0 0.00 14 1530291 168472 0 0
sp_cancel_write_off 9429 9429 0 0.00 2 8490 163 0 0
sp_client_order 20445 20445 0 0.00 5 26834 428 0 0
sp_customer_reserve 469295 469295 0 0.00 0 45978 255 0 0
sp_fill_shopping_cart 31699 31699 0 0.00 1 23820 80 0 0
sp_fill_shopping_cart_clo_res 469259 469259 0 0.00 0 20334 18 0 0
sp_get_clo_for_invoice 5025 5025 0 0.00 0 21897 268 0 0
sp_kill_cost_storno 15977 15977 0 0.00 0 12906 71 0 0
sp_kill_qstorno_ret_qs2qd 1062984 1062984 0 0.00 0 80905 42 0 0
sp_kill_qty_storno 561807 561807 0 0.00 0 80906 97 0 0
sp_lock_selected_doc 21181 21181 0 0.00 0 8436 8 0 0
sp_make_cost_storno 28484 28484 0 0.00 0 21233 80 0 0
sp_multiply_rows_for_pdistr 28484 28484 0 0.00 0 12806 38 0 0
sp_multiply_rows_for_qdistr 20445 20445 0 0.00 0 23317 161 0 0
sp_pay_from_customer 7192 7192 0 0.00 6 12160 170 0 0
sp_pay_to_supplier 6039 6039 0 0.00 6 12386 198 0 0
sp_payment_common 13231 13231 0 0.00 2 10375 44 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 1 12550 356 0 0
sp_qd_handle_on_invoice_upd_sts 10418 10418 0 0.00 0 42830 502 0 0
sp_qd_handle_on_reserve_upd_sts 10231 10231 0 0.00 0 12755 71 0 0
sp_reserve_write_off 10231 10231 0 0.00 9 21246 291 0 0
sp_supplier_invoice 5616 5616 0 0.00 11 99781 3212 0 0
sp_supplier_order 5653 5653 0 0.00 9 26602 1330 0 0
srv_aggregate_perf_data 99 98 1 1.01 2582 237662 20264 0 1
srv_fill_mon_cache_memory 71 71 0 0.00 890 163200 33895 0 0
srv_find_qd_qs_mism 1029174 1029174 0 0.00 0 14616 11 0 0
srv_make_invnt_saldo 478 478 0 0.00 35 63849 4309 0 0
srv_make_money_saldo 907 907 0 0.00 1 10515 363 0 0
srv_recalc_idx_stat 5 5 0 0.00 175048 408431 302670 0 0
t$perf-abend:sp_add_invoice_to_stock 172 172 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 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 5073 5073 0 0.00 1 9771 874 0 0
t$perf-norm:sp_cancel_adding_invoice 446 446 0 0.00 1 4870 409 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 4865 30 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 14102 57 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 3407 19 0 0
t$perf-norm:sp_cancel_pay_to_supplier 436 436 0 0.00 0 632 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 421 421 0 0.00 0 2996 62 0 0
t$perf-norm:sp_cancel_supplier_order 382 382 0 0.00 0 89832 6967 0 0
t$perf-norm:sp_cancel_write_off 1013 1013 0 0.00 0 1240 18 0 0
t$perf-norm:sp_client_order 20445 20445 0 0.00 0 17272 41 0 0
t$perf-norm:sp_customer_reserve 1023 1023 0 0.00 0 1722 17 0 0
t$perf-norm:sp_pay_from_customer 7192 7192 0 0.00 0 6466 21 0 0
t$perf-norm:sp_pay_to_supplier 6039 6039 0 0.00 0 17356 26 0 0
t$perf-norm:sp_reserve_write_off 10231 10231 0 0.00 0 6467 25 0 0
t$perf-norm:sp_supplier_invoice 5616 5616 0 0.00 0 10745 34 0 0
t$perf-norm:sp_supplier_order 5653 5653 0 0.00 0 8193 31 0 0
t$perf-norm:srv_aggregate_perf_data 98 98 0 0.00 0 1042 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 699 16 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 741 13 0 0
t$perf-norm:srv_make_money_saldo 907 907 0 0.00 0 3437 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 182 60 0 0
v_add_invoice_to_stock 10382 10038 344 3.31 0 3061 25 0 344
v_all_customers 37336 37336 0 0.00 0 75 4 0 0
v_all_suppliers 31516 31516 0 0.00 0 195 4 0 0
v_all_wares 40890 40890 0 0.00 0 72 4 0 0
v_cancel_adding_invoice 892 892 0 0.00 0 912 15 0 0
v_cancel_client_order 3608 3608 0 0.00 0 2667 11 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 2267 14 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 0 1754 12 0 0
v_cancel_payment_to_supplier 872 872 0 0.00 0 1116 12 0 0
v_cancel_supplier_invoice 866 842 24 2.77 0 401 25 0 24
v_cancel_supplier_order 764 764 0 0.00 0 181 9 0 0
v_cancel_write_off 2026 2026 0 0.00 0 2614 22 0 0
v_max_id_clo_ord 5650 5650 0 0.00 0 443 5 0 0
v_max_id_clo_res 1023 1023 0 0.00 0 3564 17 0 0
v_max_id_ord_sup 5603 5603 0 0.00 0 3325 7 0 0
v_max_non_paid_invoice 1534 1534 0 0.00 0 126 8 0 0
v_max_non_paid_realizn 3791 3791 0 0.00 0 1138 7 0 0
v_min_id_clo_ord 5650 5650 0 0.00 0 1182 8 0 0
v_min_id_clo_res 1023 1023 0 0.00 0 2001 14 0 0
v_min_id_ord_sup 5603 5603 0 0.00 0 4686 9 0 0
v_min_non_paid_invoice 6039 6039 0 0.00 0 10357 15 0 0
v_min_non_paid_realizn 7192 7192 0 0.00 0 8043 17 0 0
v_our_firm 10354 10354 0 0.00 0 84 4 0 0
v_reserve_write_off 20462 20462 0 0.00 0 3949 10 0 0
x_lock_depdocs_on_canc_invoice 10888 10888 0 0.00 0 17427 138 0 0
x_lock_depdocs_on_canc_sup_ord 382 382 0 0.00 0 5080 361 0 0
x_make_qstorno_1000_1200 5653 5653 0 0.00 3 26586 1149 0 0
x_make_qstorno_1200_2000 5616 5616 0 0.00 5 97499 3015 0 0
x_make_qstorno_2100_3300 469294 469294 0 0.00 0 43791 225 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-06-2022 07:38:43 to 03-06-2022 07:38:47.

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
05:07:20 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 29390 8192000000 9721265520 10020159488 796283536 8.859 100 87 174 1 824708688 7392672 21032480
05:09:56 26579 8192000000 9768548336 10075598848 840091200 9.301 100 89 178 0 873882688 12880400 20911088
05:11:26 15937 8192000000 9815936768 10136293376 879203760 9.692 100 88 176 0 922405104 23396080 19805264
05:12:46 10428 8192000000 9866873024 10202017792 920608544 10.102 100 83 166 1 979753024 40620192 18524288
05:14:00 4448 8192000000 9912267376 10255859712 961207408 10.501 100 83 162 0 1028424832 50503088 16714336
05:15:08 2108 8192000000 9974927952 10327801856 996055568 10.840 100 94 184 1 1096902768 82033344 18813856
05:16:12 2362 8192000000 9999919632 10363408384 1023719504 11.108 100 87 169 1 1109635680 68872368 17043808
05:17:16 1423 8192000000 10017762416 10384826368 1042505328 11.289 100 94 182 2 1138517360 78414624 17597408
05:18:18 1050 8192000000 10023878624 10394370048 1056048912 11.419 100 95 188 0 1142184176 66717792 19417472
05:19:21 1137 8192000000 10033174304 10407305216 1060709600 11.463 100 95 185 0 1150273680 71184352 18379728
05:20:23 1458 8192000000 10041268336 10427334656 1067644320 11.530 100 79 154 2 1163186736 79640336 15902080
05:21:25 4198 8192000000 10047021536 10424750080 1073771776 11.588 100 93 165 4 1165597520 76638688 15187056
05:22:30 1174 8192000000 10026198752 10407231488 1081554736 11.662 100 96 181 0 1146360592 46602368 18203488
05:23:32 1162 8192000000 10064606256 10451730432 1087810976 11.722 100 93 178 1 1185067200 78739760 18516464
05:24:34 2048 8192000000 10048345552 10438144000 1087522320 11.719 100 86 166 1 1176139952 72672080 15945552
05:25:38 1445 8192000000 10084179888 10472763392 1095960752 11.799 100 96 186 0 1205709584 90857984 18890848
05:26:40 1157 8192000000 10058083376 10448330752 1097961232 11.818 100 96 190 0 1185677600 67132624 20583744
05:27:42 1022 8192000000 10057141296 10446016512 1093631088 11.777 100 81 157 1 1183041088 74231888 15178112
05:28:45 1086 8192000000 10037109616 10423672832 1074956976 11.599 100 95 180 0 1160006128 68172128 16877024
05:29:48 8565 8192000000 10031239168 10429243392 1083743344 11.683 100 60 115 4 1151782800 55535168 12504288
05:30:57 928 8192000000 10021061232 10408906752 1071038208 11.562 100 85 165 0 1151788720 62625184 18125328
05:31:59 1015 8192000000 10010617040 10391887872 1064016784 11.495 100 93 176 1 1132516976 51102288 17397904
05:33:00 1269 8192000000 9991353776 10361778176 1028194848 11.151 98 95 184 0 1110694784 64033472 18466464
05:34:02 1118 8192000000 9993513184 10363297792 1020542224 11.077 99 96 185 1 1114350272 74539408 19268640
05:35:04 1276 8192000000 10003739808 10375098368 1034940544 11.216 100 99 192 0 1124730304 69569200 20220560
05:36:06 1023 8192000000 9986856672 10365853696 1037635664 11.242 100 83 159 1 1110097152 54534368 17927120
05:37:10 1335 8192000000 9994940144 10369429504 1042844928 11.292 99 87 171 2 1118151952 57935872 17371152
05:38:17 3349 8192000000 10022524816 10403360768 1066452528 11.518 100 76 144 0 1143700352 61273680 15974144
05:39:23 1968 8192000000 10041703248 10426773504 1077590800 11.625 100 81 153 2 1167772272 73939184 16242288
05:40:29 3903 8192000000 10024302544 10418282496 1082259104 11.669 100 68 128 4 1147951248 52611280 13080864
05:41:37 2095 8192000000 10074700800 10465169408 1098917600 11.827 100 81 153 1 1191171504 76337936 15915968
05:42:48 1972 8192000000 10109473984 10500493312 1113961936 11.970 100 79 145 0 1227224912 95742432 17520544
05:43:56 2029 8192000000 10156073984 10561769472 1122377328 12.049 100 91 174 5 1269665584 127772736 19515520
05:45:03 7742 8192000000 10180625024 10577948672 1134346256 12.162 100 66 128 2 1296339600 147669360 14323984
05:46:17 5879 8192000000 10197752880 10597220352 1145944016 12.271 100 70 138 1 1318545504 157556656 15044832
05:47:27 1201 8192000000 10281923936 10674970624 1161071344 12.413 100 88 168 0 1395550832 216183472 18296016
05:48:32 967 8192000000 10276363200 10680549376 1167422528 12.473 100 96 180 2 1390814928 203625584 19766816
05:49:36 1616 8192000000 10319580912 10721562624 1172279504 12.518 100 88 170 0 1431373136 240148640 18944992
05:50:42 1081 8192000000 10354769424 10758451200 1173576848 12.530 100 92 174 3 1469866400 275512528 20777024
05:51:45 3039 8192000000 10343963536 10762584064 1166981552 12.469 99 91 170 2 1453489552 266834032 19673968
05:52:53 2732 8192000000 10347704112 10772963328 1183320992 12.621 100 84 164 1 1460357952 258465216 18571744
05:53:59 2036 8192000000 10339944688 10772656128 1166608240 12.465 100 83 162 2 1451188672 265904848 18675584
05:55:03 1010 8192000000 10390450528 10820087808 1193569216 12.717 100 99 194 1 1499718720 283653456 22496048
05:56:07 1022 8192000000 10426339664 10832637952 1186347152 12.649 100 87 169 0 1530625744 324990656 19287936
05:57:09 1528 8192000000 10423638864 10850947072 1179706128 12.587 100 91 176 0 1532143296 331325664 21111504
05:58:17 1049 8192000000 10449940288 10872725504 1186475408 12.651 100 91 178 2 1552643664 345715152 20453104
05:59:21 1725 8192000000 10476206192 10909216768 1188244432 12.667 100 73 145 0 1583579584 377993472 17341680
06:00:28 885 8192000000 10480795664 10927759360 1197801232 12.756 100 87 170 0 1581565776 362902816 20861728
06:01:36 4227 8192000000 10514784736 10948575232 1194311696 12.723 100 61 121 1 1625888864 416805264 14771904
06:02:53 2168 8192000000 10532619360 10968977408 1195626800 12.736 100 76 146 1 1635590368 423357056 16606512
06:04:01 3460 8192000000 10524537440 10978869248 1181456400 12.604 100 63 125 1 1631344112 434033088 15854624
06:05:08 10057 8192000000 10474833056 10925899776 1184996432 12.637 100 42 83 0 1586201520 390510720 10694368
06:06:23 14434 8192000000 10504896928 10940375040 1171957488 12.515 100 40 80 0 1603413696 421245200 10211008
06:07:40 15244 8192000000 10487962288 10947526656 1161917392 12.421 100 38 73 1 1591847840 419548544 10381904
06:09:01 20856 8192000000 10352703248 10948595712 1144655216 12.259 99 33 66 1 1534637568 381184144 8798208
06:10:22 19149 8192000000 10280177568 10822250496 1144713632 12.260 100 20 40 2 1401197280 251149904 5333744
06:11:42 87212 8192000000 10063910960 10540384256 1087285552 11.717 95 12 24 0 1197626064 107553296 2787216
06:14:21 65330 8192000000 10076363040 10555805696 1075467584 11.604 95 21 42 1 1165203808 85359168 4377056
06:16:27 13476 8192000000 10042914240 10458398720 1101482400 11.852 99 53 92 5 1132467696 21818400 9166896
06:17:45 114161 8192000000 10076143232 10575060992 1117361024 12.002 99 32 60 3 1180186144 55949936 6875184
06:20:45 57345 8192000000 10072820016 10521292800 1117057008 11.999 99 28 55 0 1175274048 52735664 5481376
06:22:46 16081 8192000000 10123508128 10550697984 1121357824 12.040 98 52 92 3 1195414160 64136352 9919984
06:24:03 16430 8192000000 10129829744 10559946752 1125322672 12.077 99 64 128 1 1219069328 80430784 13315872
06:25:22 40887 8192000000 10115552112 10610286592 1140428784 12.220 100 23 46 1 1222196464 77245520 4522160
06:27:05 24359 8192000000 10133998288 10586697728 1125434864 12.078 99 22 44 1 1220566336 90596864 4534608
06:28:41 70165 8192000000 10164004144 10612350976 1125492960 12.079 98 28 57 0 1267349872 135386432 6470480
06:31:17 96144 8192000000 10112335600 10623631360 1142084736 12.235 100 15 30 0 1232159504 86486752 3588016
06:34:09 11235 8192000000 10084001584 10517512192 1119041488 12.018 99 53 101 1 1172020592 41319792 11659312
06:35:21 47844 8192000000 10106414880 10562150400 1106585296 11.900 98 22 44 2 1172572624 61794480 4192848
06:37:17 51032 8192000000 10076602064 10515509248 1117380848 12.002 99 33 65 0 1158862864 34871168 6610848
06:39:09 12856 8192000000 10133431264 10576388096 1140781840 12.223 100 56 111 2 1229943264 76090576 13070848
06:40:40 50441 8192000000 10084929504 10545954816 1124205728 12.067 99 27 53 1 1170461936 40467920 5788288
06:42:31 41919 8192000000 10153705280 10599088128 1144916768 12.262 100 52 104 0 1242663632 86349632 11397232
06:44:14 109282 8192000000 10056636496 10515062784 1108994096 11.923 98 24 48 1 1190282976 76091328 5197552
06:47:05 68955 8192000000 10092216240 10548285440 1123311072 12.058 99 27 54 0 1180443424 51148672 5983680
06:49:17 80284 8192000000 10106810288 10615828480 1126670048 12.090 99 10 20 0 1203390976 74360448 2360480
06:51:38 78966 8192000000 10108003536 10566885376 1126899008 12.092 99 15 30 0 1213353616 83530624 2923984
06:54:05 55758 8192000000 10056434128 10519154688 1115235792 11.982 98 10 18 1 1143567280 26801744 1529744
06:56:29 13793 8192000000 10133154000 10567315456 1129935216 12.121 99 46 90 2 1213246544 74341472 8969856
06:57:45 45308 8192000000 10105735776 10583076864 1100188880 11.839 97 28 56 1 1187492464 81587056 5716528
06:59:59 38753 8192000000 10072240576 10529054720 1106861984 11.903 98 15 30 1 1148881328 39110944 2908400
07:01:44 20106 8192000000 10113446176 10560344064 1126401056 12.087 100 45 90 1 1201028864 65636304 8991504
07:03:07 60118 8192000000 10095497312 10567589888 1112095536 11.952 98 18 36 0 1186816384 70991984 3728864
07:05:07 90989 8192000000 10073657152 10555248640 1098826016 11.827 97 17 34 0 1163226400 61191904 3208480
07:07:38 67586 8192000000 10070316464 10516959232 1123737920 12.062 99 24 48 2 1151264592 22211072 5315600
07:09:59 71452 8192000000 10107702512 10597036032 1122105168 12.047 99 9 18 0 1185605904 61658240 1842496
07:13:41 32446 8192000000 10024909920 10469330944 1094381488 11.784 97 20 40 1 1122598208 22761344 5455376
07:15:18 33374 8192000000 10058781648 10498527232 1112730192 11.958 99 20 36 2 1163389920 45866960 4792768
07:16:53 51405 8192000000 10091646128 10532630528 1097174800 11.811 97 41 82 0 1153709456 47916528 8618128
07:18:45 18017 8192000000 10087457936 10522746880 1137611168 12.193 100 37 74 0 1183179408 38367216 7201024
07:20:05 17396 8192000000 10139158352 10568933376 1131154096 12.132 99 48 95 0 1221073920 80233520 9686304
07:21:25 28822 8192000000 10158961184 10621390848 1121606592 12.042 99 34 68 1 1261113616 132439728 7067296
07:22:55 64469 8192000000 10085523648 10531590144 1099225600 11.830 97 20 40 1 1152400560 48608384 4566576
07:25:00 34745 8192000000 10171702416 10624081920 1111188928 11.944 98 34 66 1 1217840880 100068496 6583456
07:26:35 163199 8192000000 10073793056 10513121280 1131005552 12.131 100 21 42 1 1188905536 53447840 4452144
07:30:19 49114 8192000000 10039487744 10507870208 1092149024 11.763 96 10 20 0 1113644352 19662784 1832544
07:32:18 103489 8192000000 10056981072 10505039872 1107116304 11.905 98 11 22 0 1138729232 29339888 2273040
07:35:13 53131 8192000000 10043924704 10523484160 1107449408 11.908 98 5 10 0 1144716064 36437072 829584

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 03-06-2022 07:38:49 to 03-06-2022 07:39:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 172 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-06-2022 07:39:06 to 03-06-2022 07:39:08.

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          225538
MON$OLDEST_ACTIVE               226887
MON$OLDEST_SNAPSHOT             226887
MON$NEXT_TRANSACTION            226887
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                       2055264
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             787
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2772 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2772 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2772 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2772 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 ---
:42: 
:43: mscs-test-161.budzdorov.ru    Fri Jun  3 07:37:18 2022
:44:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:45: 
:46: 
:47: mscs-test-161.budzdorov.ru    Fri Jun  3 07:37:20 2022
:48:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:49: 
:50: 
:51: mscs-test-161.budzdorov.ru    Fri Jun  3 07:37:21 2022
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Fri Jun  3 07:37:21 2022
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
:58: 
:59: mscs-test-161.budzdorov.ru    Fri Jun  3 07:37:21 2022
:60:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:61: 
--- end of diff output ---

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

03.06.22 07:39:09. 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-06-03 07:39:12 No severe PSQL-related problems occured
03.06.22 07:39:13. End of report.