Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.04.2022 10:39
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 16372400 kB
MemAvailable 31557908 kB
Buffers 0 kB
Cached 15362908 kB
SwapCached 2540 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-04-24 10:37:15
DTS_END                         2022-04-24 10:37:15.3220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(134:18) ==> SP_CANCEL_SUPPLIER_INVOICE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/46838
TRN_ID                          222799
ATT_ID                          599
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: 9172.56 25747 1100707
customer order: creation 178.64 462 21437
customer order: refuse 15.19 438 1823
order to supplier: creation 47.63 1474 5715
order to supplier: removal 3.43 187137 412
invoice (draft): creation 46.97 3262 5636
invoice (draft): removal 50.08 1095 6010
invoice accept: apply 43.45 27325 5214
invoice accept: cancel 44.93 1346 5392
customer reserve: creation 4001.09 261 480131
customer reserve: removal 4440.90 113 532908
realization accept: apply 87.98 297 10558
realization accept: cancel 77.71 205 9325
payment from customer: creation 60.23 184 7228
payment from customer: removal 6.00 165 720
payment to supplier: creation 51.28 188 6153
payment to supplier: removal 3.73 101 447
service: total inventory turnovers 3.92 4582 470
service: total monetary turnovers 8.34 359 1001
service: aggregate perf. data 1.03 15887 123
service: refresh index statistics 0.03 270060 4

Performance in TOTAL, chart


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

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4014
TEST_TIME 2 4369
TEST_TIME 3 5308
TEST_TIME 4 6060
TEST_TIME 5 6626
TEST_TIME 6 6402
TEST_TIME 7 6228
TEST_TIME 8 6218
TEST_TIME 9 6324
TEST_TIME 10 6684
TEST_TIME 11 6742
TEST_TIME 12 6855
TEST_TIME 13 6810
TEST_TIME 14 6954
TEST_TIME 15 6986
TEST_TIME 16 7153
TEST_TIME 17 7097
TEST_TIME 18 7206
TEST_TIME 19 7193
TEST_TIME 20 7121
TEST_TIME 21 7121
TEST_TIME 22 7165
TEST_TIME 23 7322
TEST_TIME 24 7628
TEST_TIME 25 7734
TEST_TIME 26 7968
TEST_TIME 27 8356
TEST_TIME 28 8485
TEST_TIME 29 8391
TEST_TIME 30 8614
TEST_TIME 31 8743
TEST_TIME 32 9334
TEST_TIME 33 9523
TEST_TIME 34 9716
TEST_TIME 35 10442
TEST_TIME 36 11029
TEST_TIME 37 10851
TEST_TIME 38 10771
TEST_TIME 39 10661
TEST_TIME 40 10589
TEST_TIME 41 10487
TEST_TIME 42 10376
TEST_TIME 43 10378
TEST_TIME 44 10309
TEST_TIME 45 10175
TEST_TIME 46 10031
TEST_TIME 47 10070
TEST_TIME 48 10007
TEST_TIME 49 9951
TEST_TIME 50 9920
TEST_TIME 51 9840
TEST_TIME 52 9910
TEST_TIME 53 9880
TEST_TIME 54 9836
TEST_TIME 55 9749
TEST_TIME 56 9648
TEST_TIME 57 9597
TEST_TIME 58 9511
TEST_TIME 59 9444
TEST_TIME 60 9424
TEST_TIME 61 9443
TEST_TIME 62 9444
TEST_TIME 63 9395
TEST_TIME 64 9426
TEST_TIME 65 9483
TEST_TIME 66 9554
TEST_TIME 67 9555
TEST_TIME 68 9586
TEST_TIME 69 9746
TEST_TIME 70 9730
TEST_TIME 71 9711
TEST_TIME 72 9751
TEST_TIME 73 9801
TEST_TIME 74 9898
TEST_TIME 75 9782
TEST_TIME 76 9701
TEST_TIME 77 9617
TEST_TIME 78 9643
TEST_TIME 79 9580
TEST_TIME 80 9516
TEST_TIME 81 9478
TEST_TIME 82 9500
TEST_TIME 83 9415
TEST_TIME 84 9403
TEST_TIME 85 9399
TEST_TIME 86 9346
TEST_TIME 87 9297
TEST_TIME 88 9284
TEST_TIME 89 9290
TEST_TIME 90 9298
TEST_TIME 91 9309
TEST_TIME 92 9399
TEST_TIME 93 9452
TEST_TIME 94 9534
TEST_TIME 95 9534
TEST_TIME 96 9489
TEST_TIME 97 9586
TEST_TIME 98 9549
TEST_TIME 99 9488
TEST_TIME 100 9460
TEST_TIME 101 9403
TEST_TIME 102 9378
TEST_TIME 103 9387
TEST_TIME 104 9341
TEST_TIME 105 9315
TEST_TIME 106 9273
TEST_TIME 107 9250
TEST_TIME 108 9250
TEST_TIME 109 9227
TEST_TIME 110 9206
TEST_TIME 111 9256
TEST_TIME 112 9247
TEST_TIME 113 9219
TEST_TIME 114 9251
TEST_TIME 115 9296
TEST_TIME 116 9295
TEST_TIME 117 9308
TEST_TIME 118 9311
TEST_TIME 119 9270
TEST_TIME 120 9196

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-04-2022 10:40:12 to 24-04-2022 10:40:15.

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 1540955 1540955 0 0.00 0 14383 16 0 0
doc_list_biud 1540944 1540944 0 0.00 0 30639 45 0 0
sp_add_invoice_to_stock 5347 5214 133 2.48 55 195586 27325 0 133
sp_cancel_adding_invoice 5392 5392 0 0.00 7 90757 1346 0 0
sp_cancel_client_order 1823 1823 0 0.00 6 10337 439 0 0
sp_cancel_customer_reserve 532908 532908 0 0.00 1 22154 114 0 0
sp_cancel_pay_from_customer 720 720 0 0.00 4 16472 166 0 0
sp_cancel_pay_to_supplier 447 447 0 0.00 4 1173 101 0 0
sp_cancel_supplier_invoice 6022 6010 12 0.19 3 14253 1096 0 12
sp_cancel_supplier_order 412 412 0 0.00 8 1776302 187137 0 0
sp_cancel_write_off 9325 9325 0 0.00 2 14077 205 0 0
sp_client_order 21437 21437 0 0.00 6 22711 463 0 0
sp_customer_reserve 480131 480131 0 0.00 1 73551 261 0 0
sp_fill_shopping_cart 32781 32781 0 0.00 1 20546 90 0 0
sp_fill_shopping_cart_clo_res 480100 480100 0 0.00 0 16728 19 0 0
sp_get_clo_for_invoice 5181 5181 0 0.00 0 15688 245 0 0
sp_kill_cost_storno 15884 15884 0 0.00 0 6511 86 0 0
sp_kill_qstorno_ret_qs2qd 1081563 1081563 0 0.00 0 30294 49 0 0
sp_kill_qty_storno 571608 571608 0 0.00 0 30636 112 0 0
sp_lock_selected_doc 21653 21653 0 0.00 0 10944 10 0 0
sp_make_cost_storno 29120 29120 0 0.00 0 11055 84 0 0
sp_multiply_rows_for_pdistr 29119 29119 0 0.00 0 7442 39 0 0
sp_multiply_rows_for_qdistr 21437 21437 0 0.00 0 11931 171 0 0
sp_pay_from_customer 7228 7228 0 0.00 4 10489 184 0 0
sp_pay_to_supplier 6153 6153 0 0.00 7 14614 188 0 0
sp_payment_common 13381 13381 0 0.00 2 9399 47 0 0
sp_qd_handle_on_cancel_clo 1823 1823 0 0.00 0 10105 361 0 0
sp_qd_handle_on_invoice_upd_sts 10572 10572 0 0.00 0 30629 574 0 0
sp_qd_handle_on_reserve_upd_sts 10558 10558 0 0.00 0 18845 73 0 0
sp_reserve_write_off 10558 10558 0 0.00 8 18863 297 0 0
sp_supplier_invoice 5636 5636 0 0.00 10 109457 3262 0 0
sp_supplier_order 5715 5715 0 0.00 7 91918 1474 0 0
srv_aggregate_perf_data 123 123 0 0.00 2330 192236 15887 0 0
srv_fill_mon_cache_memory 75 75 0 0.00 814 166653 32709 0 0
srv_find_qd_qs_mism 1049018 1049018 0 0.00 0 7569 12 0 0
srv_make_invnt_saldo 470 470 0 0.00 72 121937 4583 0 0
srv_make_money_saldo 1001 1001 0 0.00 1 12975 360 0 0
srv_recalc_idx_stat 4 4 0 0.00 172091 352998 270061 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 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5217 5217 0 0.00 0 10659 1043 0 0
t$perf-norm:sp_cancel_adding_invoice 382 382 0 0.00 2 8599 552 0 0
t$perf-norm:sp_cancel_client_order 1823 1823 0 0.00 0 3740 30 0 0
t$perf-norm:sp_cancel_customer_reserve 691 691 0 0.00 0 1385 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 720 720 0 0.00 0 1517 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 447 447 0 0.00 0 3831 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 1206 34 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 102093 8034 0 0
t$perf-norm:sp_cancel_write_off 1044 1044 0 0.00 0 6725 22 0 0
t$perf-norm:sp_client_order 21438 21438 0 0.00 0 6423 38 0 0
t$perf-norm:sp_customer_reserve 1057 1057 0 0.00 0 645 13 0 0
t$perf-norm:sp_pay_from_customer 7229 7229 0 0.00 0 5301 23 0 0
t$perf-norm:sp_pay_to_supplier 6153 6153 0 0.00 0 5769 20 0 0
t$perf-norm:sp_reserve_write_off 10558 10558 0 0.00 0 6963 26 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 6351 36 0 0
t$perf-norm:sp_supplier_order 5717 5717 0 0.00 0 5654 35 0 0
t$perf-norm:srv_aggregate_perf_data 123 123 0 0.00 0 332 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 75 75 0 0.00 0 135 7 0 0
t$perf-norm:srv_make_invnt_saldo 470 470 0 0.00 0 1293 25 0 0
t$perf-norm:srv_make_money_saldo 1001 1001 0 0.00 0 1846 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 54 27 0 0
v_add_invoice_to_stock 10614 10348 266 2.50 0 7239 27 0 266
v_all_customers 38356 38356 0 0.00 0 111 4 0 0
v_all_suppliers 31780 31780 0 0.00 0 78 4 0 0
v_all_wares 42872 42872 0 0.00 0 116 4 0 0
v_cancel_adding_invoice 764 764 0 0.00 0 1117 16 0 0
v_cancel_client_order 3646 3646 0 0.00 0 1216 9 0 0
v_cancel_customer_prepayment 1440 1440 0 0.00 0 1152 12 0 0
v_cancel_customer_reserve 1382 1382 0 0.00 0 1990 12 0 0
v_cancel_payment_to_supplier 894 894 0 0.00 0 180 10 0 0
v_cancel_supplier_invoice 830 806 24 2.89 0 1809 23 0 24
v_cancel_supplier_order 824 824 0 0.00 0 1155 14 0 0
v_cancel_write_off 2088 2088 0 0.00 0 3344 26 0 0
v_max_id_clo_ord 5711 5711 0 0.00 0 185 5 0 0
v_max_id_clo_res 1057 1057 0 0.00 0 2697 15 0 0
v_max_id_ord_sup 5632 5632 0 0.00 0 5039 8 0 0
v_max_non_paid_invoice 1606 1606 0 0.00 0 564 7 0 0
v_max_non_paid_realizn 3955 3955 0 0.00 0 856 8 0 0
v_min_id_clo_ord 5711 5711 0 0.00 0 1651 8 0 0
v_min_id_clo_res 1057 1057 0 0.00 0 1823 9 0 0
v_min_id_ord_sup 5632 5632 0 0.00 0 12964 17 0 0
v_min_non_paid_invoice 6153 6153 0 0.00 0 7085 14 0 0
v_min_non_paid_realizn 7228 7228 0 0.00 0 9384 21 0 0
v_our_firm 11062 11062 0 0.00 0 56 5 0 0
v_reserve_write_off 21114 21114 0 0.00 0 1812 10 0 0
x_lock_depdocs_on_canc_invoice 10999 10999 0 0.00 0 18415 123 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 5182 385 0 0
x_make_qstorno_1000_1200 5715 5715 0 0.00 2 91859 1287 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 4 109350 3015 0 0
x_make_qstorno_2100_3300 480131 480131 0 0.00 0 73544 229 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-04-2022 10:40:16 to 24-04-2022 10:40:20.

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
08:07:18 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:19 32750 8192000000 9745008368 10051145728 819115392 9.090 100 84 168 0 846498176 7860080 19522704
08:09:59 5149 8192000000 9780798576 10090799104 853636688 9.436 100 94 188 0 890117200 14518016 21962496
08:11:14 22341 8192000000 9816405696 10137542656 876111408 9.661 100 82 164 1 920555360 25151360 19292592
08:12:39 11548 8192000000 9864584896 10199941120 916564224 10.062 100 74 146 0 966322320 33562064 16196032
08:13:52 8500 8192000000 9907027264 10250981376 958094032 10.470 100 80 158 1 1023146384 48704784 16347568
08:15:03 3093 8192000000 9948085792 10298949632 988032960 10.762 100 88 173 0 1065468704 59442048 17993696
08:16:09 2088 8192000000 9981874224 10341019648 1014320192 11.017 100 79 154 1 1097185456 67428240 15437024
08:17:14 1179 8192000000 10000657680 10371239936 1042411472 11.288 100 95 180 2 1123841344 63407168 18022704
08:18:17 993 8192000000 10040242576 10417115136 1048045392 11.342 100 95 188 1 1157200976 89922416 19233168
08:19:21 1061 8192000000 10045926112 10422644736 1057248336 11.430 100 97 179 0 1163736496 86204752 20283408
08:20:23 2087 8192000000 10034025936 10413264896 1059723888 11.454 100 93 178 0 1150905504 72497968 18683648
08:21:34 1177 8192000000 10057975280 10443063296 1071697680 11.568 100 97 190 0 1184730176 93138368 19894128
08:22:39 1169 8192000000 10060867168 10442526720 1082812720 11.674 100 98 187 0 1186235712 84450688 18972304
08:23:42 1247 8192000000 10058689248 10445189120 1082320640 11.670 100 95 185 0 1179823984 77757984 19745360
08:24:45 1163 8192000000 10057886816 10442829824 1084347824 11.689 100 96 181 2 1179139024 76909120 17882080
08:25:47 1200 8192000000 10043753408 10428772352 1093715456 11.778 100 96 182 1 1169856960 55331072 20810432
08:26:49 1209 8192000000 10077959760 10468651008 1091929440 11.761 100 95 182 3 1191167744 81003984 18234320
08:27:51 1198 8192000000 10048866064 10439962624 1098976448 11.828 100 96 178 0 1172110304 53813408 19320448
08:28:54 1061 8192000000 10057134528 10446258176 1092077696 11.762 100 90 172 0 1183386816 74052512 17256608
08:29:56 1228 8192000000 10053480688 10439688192 1087693920 11.721 100 92 175 2 1174357168 68624336 18038912
08:30:58 1807 8192000000 10049687920 10439217152 1083679408 11.683 100 70 131 0 1171528768 75580208 12269152
08:32:01 1257 8192000000 10050723552 10436825088 1079560944 11.643 100 96 186 0 1172288912 73765344 18962624
08:33:04 1350 8192000000 10014059072 10392096768 1056605952 11.424 100 97 190 1 1136599344 61043024 18950368
08:34:06 1211 8192000000 10021624832 10397433856 1046238176 11.325 100 97 188 1 1143688048 78424832 19025040
08:35:09 1087 8192000000 9994870928 10377691136 1050494288 11.365 100 81 158 2 1119858768 52628080 16736400
08:36:12 1167 8192000000 9995992224 10368552960 1045694704 11.319 100 90 176 1 1113428000 50619184 17114112
08:37:13 3852 8192000000 10000344032 10379321344 1050348928 11.364 100 78 141 3 1122332864 57920272 14063664
08:38:32 7702 8192000000 10022383344 10408701952 1071062848 11.562 100 56 112 1 1135152864 53083232 11006784
08:39:43 16155 8192000000 10067207536 10455855104 1076665296 11.616 99 65 122 3 1175824992 86772176 12387520
08:41:04 6681 8192000000 10067635664 10457382912 1091197120 11.754 100 76 145 1 1189056592 82156096 15703376
08:42:16 1765 8192000000 10116770912 10503778304 1112041568 11.952 100 85 157 1 1234859520 106197152 16620800
08:43:24 5037 8192000000 10127669952 10524839936 1126375920 12.087 100 71 136 1 1246868336 106352384 14140032
08:44:32 7346 8192000000 10162944432 10559508480 1131310800 12.134 100 75 149 0 1283815120 136495680 16008640
08:45:51 1782 8192000000 10227050064 10628866048 1145520416 12.267 100 88 174 2 1336171520 171794928 18856176
08:46:58 1546 8192000000 10241128912 10638671872 1156323568 12.369 100 90 175 2 1346343232 170868256 19151408
08:48:03 2765 8192000000 10264902624 10664382464 1161784672 12.420 100 87 158 1 1376920000 198659472 16475856
08:49:07 2438 8192000000 10281180448 10686152704 1167838912 12.477 100 78 151 1 1400030720 216074560 16117248
08:50:14 1319 8192000000 10335190352 10743037952 1175596048 12.549 100 89 175 0 1449425152 253475792 20353312
08:51:18 1125 8192000000 10364107152 10778181632 1184895232 12.636 100 93 181 0 1479697600 273245888 21556480
08:52:24 1136 8192000000 10361920720 10791301120 1192216752 12.704 100 91 174 0 1472575920 259651696 20707472
08:53:31 2172 8192000000 10391195312 10800824320 1187465392 12.660 100 90 173 0 1503940928 296450176 20025360
08:54:39 1165 8192000000 10442526848 10847784960 1204776464 12.821 100 90 178 0 1549547440 323725232 21045744
08:55:42 950 8192000000 10475327408 10882174976 1209499936 12.864 100 95 185 0 1582433568 350525120 22408512
08:56:47 1359 8192000000 10528144448 10942275584 1215750224 12.922 100 93 184 2 1634339008 397330320 21258464
08:57:52 1454 8192000000 10584290480 10996178944 1202802816 12.802 100 96 186 1 1692420880 467472624 22145440
08:58:58 1332 8192000000 10602398384 11043889152 1212961760 12.897 100 95 185 0 1706636224 471109152 22565312
09:00:06 1159 8192000000 10618972448 11057049600 1202532496 12.800 100 98 194 0 1723532384 497352384 23647504
09:01:09 825 8192000000 10632508720 11104120832 1198024416 12.758 100 97 194 2 1738538624 516882112 23632096
09:02:13 806 8192000000 10592359904 11051479040 1183093600 12.619 100 90 175 0 1697701552 492417744 22190208
09:03:21 995 8192000000 10629454176 11086680064 1190414416 12.687 100 88 174 1 1733823584 520839952 22569216
09:04:26 1863 8192000000 10552167072 11026014208 1162855168 12.430 100 77 150 1 1650198672 467624960 19718544
09:05:32 1859 8192000000 10598917776 11041058816 1186525728 12.651 100 71 142 0 1698700224 494459776 17714720
09:06:43 8701 8192000000 10625231360 11069829120 1186746928 12.653 100 53 104 1 1724280400 523918832 13614640
09:07:58 9311 8192000000 10578044944 11044364288 1177847440 12.570 100 58 114 1 1674492240 481479200 15165600
09:09:09 23422 8192000000 10475450896 11012526080 1168305840 12.481 99 34 65 0 1586858688 409711632 8841216
09:10:36 26113 8192000000 10423838544 10909933568 1149098624 12.301 99 23 46 0 1542724000 387709632 5915744
09:12:19 40439 8192000000 10218330592 10758557696 1151678368 12.325 100 21 42 0 1356121728 199260032 5183328
09:14:24 29008 8192000000 10110365888 10544324608 1145657424 12.269 99 42 79 1 1205257872 50345856 9254592
09:15:57 25238 8192000000 10119763264 10545356800 1148559216 12.296 99 54 92 0 1216627120 58715456 9352448
09:17:23 130543 8192000000 10080369888 10524037120 1131368576 12.134 98 22 42 1 1184596736 47988992 5239168
09:20:39 34960 8192000000 10090337216 10531270656 1131155680 12.132 99 58 107 1 1199582960 57516800 10910480
09:22:15 87881 8192000000 10076482048 10504134656 1090543520 11.748 96 29 53 1 1119082112 22899920 5638672
09:24:44 8258 8192000000 10080426656 10512769024 1126702400 12.090 99 63 115 3 1179377136 40346560 12328176
09:25:55 1277 8192000000 10109385808 10539327488 1135609536 12.174 100 66 125 2 1209669360 60634624 13425200
09:26:58 17111 8192000000 10127917616 10573545472 1131618192 12.137 99 35 70 0 1216698816 77866096 7214528
09:28:23 26370 8192000000 10114804640 10567307264 1112422272 11.955 98 17 34 0 1202460400 85586608 4451520
09:29:57 10954 8192000000 10064243040 10541342720 1120474800 12.031 100 28 54 1 1175576608 49303584 5798224
09:31:10 80964 8192000000 10056711504 10540146688 1116398608 11.993 99 11 22 0 1156254304 37124784 2730912
09:33:48 43768 8192000000 10081321808 10535686144 1128316560 12.105 100 25 50 1 1171454416 37782896 5354960
09:35:33 65275 8192000000 10107444464 10572603392 1117903776 12.007 99 22 44 2 1197016032 74223664 4888592
09:37:40 76763 8192000000 10081513248 10562248704 1103725984 11.873 98 11 22 1 1194913152 89258352 1928816
09:40:03 19588 8192000000 10124272544 10561802240 1138007664 12.197 100 31 61 1 1223226496 78388512 6830320
09:41:25 78504 8192000000 10150349616 10630766592 1102422272 11.861 96 29 54 1 1254000256 145620416 5957568
09:43:56 32316 8192000000 10140542624 10587807744 1102069264 11.857 97 36 72 0 1208206304 98803520 7333520
09:45:30 37747 8192000000 10136302624 10593210368 1114871200 11.979 99 40 80 0 1242608160 119236256 8500704
09:47:12 23702 8192000000 10060973088 10499772416 1090346384 11.746 98 41 76 2 1138222320 39130704 8745232
09:48:38 23058 8192000000 10152944736 10574286848 1129904160 12.120 100 43 86 0 1226691040 88234560 8552320
09:50:05 76667 8192000000 10094752192 10582822912 1103500768 11.871 98 13 26 0 1216675920 110401728 2773424
09:52:22 124160 8192000000 10090379088 10527772672 1138122128 12.198 100 28 53 0 1197561168 53630944 5808096
09:55:27 69508 8192000000 10085636848 10588581888 1108416080 11.917 98 12 24 0 1166238944 55146656 2676208
09:58:03 83958 8192000000 10087475904 10610847744 1129294736 12.115 100 10 20 2 1193151456 61178592 2678128
10:00:27 57530 8192000000 10078290432 10565726208 1095867600 11.798 97 17 34 2 1144687264 45506816 3312848
10:02:48 36170 8192000000 10097885664 10531663872 1125011504 12.074 99 30 59 0 1170322720 38020896 7290320
10:04:30 21556 8192000000 10168395568 10605744128 1137086432 12.188 100 41 82 0 1251033744 105020336 8926976
10:05:58 21511 8192000000 10187308624 10648854528 1148243872 12.293 100 30 60 1 1289707392 134543248 6920272
10:07:22 26804 8192000000 10187086288 10623766528 1137577056 12.193 98 34 68 1 1273812576 128980944 7254576
10:08:59 37944 8192000000 10176254064 10655973376 1133811392 12.157 99 15 30 0 1294905072 157377776 3715904
10:10:41 47868 8192000000 10129560416 10561806336 1115921040 11.988 97 39 78 1 1201129712 77214736 7993936
10:12:32 21225 8192000000 10141342080 10598535168 1138879072 12.205 99 39 78 0 1230418016 83654208 7884736
10:13:56 39176 8192000000 10128376272 10583896064 1119643584 12.024 98 34 67 1 1243437696 116511600 7282512
10:15:35 166652 8192000000 10049057584 10503131136 1107365584 11.907 97 28 56 0 1151969440 38518320 6085536
10:19:23 82634 8192000000 10066810608 10560663552 1130904464 12.130 99 17 34 0 1176372528 42242480 3225584
10:21:46 45336 8192000000 10074068000 10531360768 1125789488 12.082 99 15 30 0 1154356800 25386848 3180464
10:23:33 31586 8192000000 10064278224 10526867456 1132283808 12.143 100 40 70 2 1176084288 37490720 6309760
10:25:05 59184 8192000000 10075325936 10556649472 1094113520 11.782 96 20 39 0 1144275360 45611920 4549920
10:27:05 26359 8192000000 10127722048 10572681216 1125798560 12.082 98 43 86 0 1205622240 70621008 9202672
10:28:37 47096 8192000000 10074892192 10523516928 1116752432 11.996 98 27 53 0 1176280608 53465872 6062304
10:30:38 41510 8192000000 10161752928 10619318272 1138180576 12.198 99 35 70 0 1242472784 97098720 7193488
10:32:20 86530 8192000000 10095030208 10560167936 1100548944 11.843 97 24 47 2 1165720784 59987264 5184576
10:34:52 45891 8192000000 10095753168 10525913088 1106100128 11.895 97 39 78 0 1167548768 53569408 7879232
10:36:39 118154 8192000000 8990079056 9206054912 146021264 1.751 13 7 14 0 180641760 33109888 1510608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 24-04-2022 10:40:22 to 24-04-2022 10:40:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 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 24-04-2022 10:40:40 to 24-04-2022 10:40:42.

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          224366
MON$OLDEST_ACTIVE               224367
MON$OLDEST_SNAPSHOT             224367
MON$NEXT_TRANSACTION            224367
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                       2063584
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             779
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

24.04.22 10:40:43. 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-04-24 10:40:45 No severe PSQL-related problems occured
24.04.22 10:40:47. End of report.