Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.05.2022 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15842076 kB
MemAvailable 31307592 kB
Buffers 20 kB
Cached 15765532 kB
SwapCached 2552 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-05-27 04:37:17
DTS_END                         2022-05-27 04:37:17.3950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/37024
TRN_ID                          217891
ATT_ID                          651
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: 8830.32 20709 1059638
customer order: creation 173.40 486 20808
customer order: refuse 14.38 471 1726
order to supplier: creation 44.28 1490 5314
order to supplier: removal 3.50 172158 420
invoice (draft): creation 45.13 3455 5415
invoice (draft): removal 49.53 1068 5943
invoice accept: apply 40.43 29391 4852
invoice accept: cancel 44.34 1282 5321
customer reserve: creation 3776.53 280 453184
customer reserve: removal 4342.35 108 521082
realization accept: apply 82.09 314 9851
realization accept: cancel 84.40 192 10128
payment from customer: creation 58.46 184 7015
payment from customer: removal 5.81 143 697
payment to supplier: creation 49.43 192 5931
payment to supplier: removal 3.53 142 423
service: total inventory turnovers 3.95 4601 474
service: total monetary turnovers 7.92 407 950
service: aggregate perf. data 0.83 19807 99
service: refresh index statistics 0.04 177999 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-05-2022 04:37:59 to 27-05-2022 04:38:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6827
TEST_TIME 2 5489
TEST_TIME 3 6325
TEST_TIME 4 5707
TEST_TIME 5 5630
TEST_TIME 6 6099
TEST_TIME 7 6488
TEST_TIME 8 7050
TEST_TIME 9 7164
TEST_TIME 10 7159
TEST_TIME 11 7000
TEST_TIME 12 6832
TEST_TIME 13 7035
TEST_TIME 14 7007
TEST_TIME 15 6817
TEST_TIME 16 7108
TEST_TIME 17 7043
TEST_TIME 18 7197
TEST_TIME 19 7366
TEST_TIME 20 7627
TEST_TIME 21 7697
TEST_TIME 22 7581
TEST_TIME 23 7583
TEST_TIME 24 7614
TEST_TIME 25 7782
TEST_TIME 26 7799
TEST_TIME 27 7905
TEST_TIME 28 7949
TEST_TIME 29 8124
TEST_TIME 30 8309
TEST_TIME 31 8339
TEST_TIME 32 8590
TEST_TIME 33 8575
TEST_TIME 34 8739
TEST_TIME 35 8642
TEST_TIME 36 8938
TEST_TIME 37 9646
TEST_TIME 38 9847
TEST_TIME 39 10062
TEST_TIME 40 9885
TEST_TIME 41 9883
TEST_TIME 42 9945
TEST_TIME 43 10297
TEST_TIME 44 10227
TEST_TIME 45 10035
TEST_TIME 46 9841
TEST_TIME 47 9720
TEST_TIME 48 9727
TEST_TIME 49 9624
TEST_TIME 50 9494
TEST_TIME 51 9435
TEST_TIME 52 9476
TEST_TIME 53 9408
TEST_TIME 54 9412
TEST_TIME 55 9408
TEST_TIME 56 9396
TEST_TIME 57 9457
TEST_TIME 58 9403
TEST_TIME 59 9377
TEST_TIME 60 9390
TEST_TIME 61 9343
TEST_TIME 62 9337
TEST_TIME 63 9265
TEST_TIME 64 9285
TEST_TIME 65 9371
TEST_TIME 66 9437
TEST_TIME 67 9387
TEST_TIME 68 9401
TEST_TIME 69 9370
TEST_TIME 70 9417
TEST_TIME 71 9344
TEST_TIME 72 9330
TEST_TIME 73 9407
TEST_TIME 74 9436
TEST_TIME 75 9318
TEST_TIME 76 9245
TEST_TIME 77 9221
TEST_TIME 78 9155
TEST_TIME 79 9155
TEST_TIME 80 9111
TEST_TIME 81 9082
TEST_TIME 82 9050
TEST_TIME 83 9007
TEST_TIME 84 8948
TEST_TIME 85 8964
TEST_TIME 86 8929
TEST_TIME 87 8912
TEST_TIME 88 8935
TEST_TIME 89 8937
TEST_TIME 90 8930
TEST_TIME 91 8957
TEST_TIME 92 8945
TEST_TIME 93 8927
TEST_TIME 94 8957
TEST_TIME 95 8987
TEST_TIME 96 9028
TEST_TIME 97 9007
TEST_TIME 98 9014
TEST_TIME 99 9032
TEST_TIME 100 9115
TEST_TIME 101 9093
TEST_TIME 102 9048
TEST_TIME 103 8983
TEST_TIME 104 8969
TEST_TIME 105 8910
TEST_TIME 106 8914
TEST_TIME 107 8881
TEST_TIME 108 8871
TEST_TIME 109 8884
TEST_TIME 110 8876
TEST_TIME 111 8857
TEST_TIME 112 8816
TEST_TIME 113 8855
TEST_TIME 114 8887
TEST_TIME 115 8854
TEST_TIME 116 8858
TEST_TIME 117 8861
TEST_TIME 118 8916
TEST_TIME 119 8913
TEST_TIME 120 8857

Performance per minute, chart


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

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 1478426 1478426 0 0.00 0 25650 16 0 0
doc_list_biud 1478414 1478414 0 0.00 0 31399 44 0 0
sp_add_invoice_to_stock 4954 4852 102 2.05 68 204670 29391 0 102
sp_cancel_adding_invoice 5321 5321 0 0.00 7 68093 1282 0 0
sp_cancel_client_order 1726 1726 0 0.00 7 10993 472 0 0
sp_cancel_customer_reserve 521082 521082 0 0.00 1 31402 108 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 4 4081 143 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 4 8862 142 0 0
sp_cancel_supplier_invoice 5953 5943 10 0.16 3 16026 1068 0 10
sp_cancel_supplier_order 420 420 0 0.00 7 1637882 172159 0 0
sp_cancel_write_off 10128 10128 0 0.00 2 13879 192 0 0
sp_client_order 20808 20808 0 0.00 6 21752 487 0 0
sp_customer_reserve 453184 453184 0 0.00 0 136981 280 0 0
sp_fill_shopping_cart 31519 31519 0 0.00 1 17833 84 0 0
sp_fill_shopping_cart_clo_res 453163 453163 0 0.00 0 12785 19 0 0
sp_get_clo_for_invoice 4821 4821 0 0.00 0 18908 283 0 0
sp_kill_cost_storno 16569 16569 0 0.00 0 10621 82 0 0
sp_kill_qstorno_ret_qs2qd 1058655 1058655 0 0.00 0 31393 47 0 0
sp_kill_qty_storno 559288 559288 0 0.00 0 31398 109 0 0
sp_lock_selected_doc 20447 20447 0 0.00 0 6962 7 0 0
sp_make_cost_storno 27617 27617 0 0.00 0 14119 87 0 0
sp_multiply_rows_for_pdistr 27615 27615 0 0.00 0 10252 42 0 0
sp_multiply_rows_for_qdistr 20807 20807 0 0.00 0 13194 181 0 0
sp_pay_from_customer 7015 7015 0 0.00 5 15345 185 0 0
sp_pay_to_supplier 5931 5931 0 0.00 6 16939 193 0 0
sp_payment_common 12943 12943 0 0.00 2 11975 48 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 1 10980 396 0 0
sp_qd_handle_on_invoice_upd_sts 10141 10141 0 0.00 0 19996 559 0 0
sp_qd_handle_on_reserve_upd_sts 9848 9848 0 0.00 0 22093 77 0 0
sp_reserve_write_off 9851 9851 0 0.00 9 22115 314 0 0
sp_supplier_invoice 5415 5415 0 0.00 9 76456 3456 0 0
sp_supplier_order 5314 5314 0 0.00 8 29369 1490 0 0
srv_aggregate_perf_data 99 99 0 0.00 2435 375732 19808 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 793 120123 31939 0 0
srv_find_qd_qs_mism 1011837 1011837 0 0.00 0 25605 13 0 0
srv_make_invnt_saldo 474 474 0 0.00 58 114559 4601 0 0
srv_make_money_saldo 950 950 0 0.00 1 15305 408 0 0
srv_recalc_idx_stat 5 5 0 0.00 103358 356992 177999 0 0
t$perf-abend:sp_add_invoice_to_stock 102 102 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4857 4857 0 0.00 1 10743 999 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 7706 466 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 1098 29 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 2591 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 1887 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 816 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 415 415 0 0.00 0 3518 48 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 78599 7877 0 0
t$perf-norm:sp_cancel_write_off 1001 1001 0 0.00 0 2350 22 0 0
t$perf-norm:sp_client_order 20810 20810 0 0.00 0 8003 39 0 0
t$perf-norm:sp_customer_reserve 1031 1031 0 0.00 0 657 15 0 0
t$perf-norm:sp_pay_from_customer 7015 7015 0 0.00 0 5484 23 0 0
t$perf-norm:sp_pay_to_supplier 5931 5931 0 0.00 0 4756 20 0 0
t$perf-norm:sp_reserve_write_off 9851 9851 0 0.00 0 3531 22 0 0
t$perf-norm:sp_supplier_invoice 5415 5415 0 0.00 0 3271 33 0 0
t$perf-norm:sp_supplier_order 5314 5314 0 0.00 0 4483 35 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 393 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 4233 80 0 0
t$perf-norm:srv_make_invnt_saldo 474 474 0 0.00 0 2148 19 0 0
t$perf-norm:srv_make_money_saldo 950 950 0 0.00 0 512 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 366 103 0 0
v_add_invoice_to_stock 9828 9624 204 2.07 0 10832 29 0 204
v_all_customers 37920 37920 0 0.00 0 148 4 0 0
v_all_suppliers 30140 30140 0 0.00 0 163 4 0 0
v_all_wares 41606 41606 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 586 15 0 0
v_cancel_client_order 3452 3452 0 0.00 0 4244 12 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1989 15 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2464 14 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 8823 22 0 0
v_cancel_supplier_invoice 850 830 20 2.35 0 1715 23 0 20
v_cancel_supplier_order 840 840 0 0.00 0 1155 13 0 0
v_cancel_write_off 2002 2002 0 0.00 0 2016 22 0 0
v_max_id_clo_ord 5308 5308 0 0.00 0 8361 6 0 0
v_max_id_clo_res 1031 1031 0 0.00 0 982 11 0 0
v_max_id_ord_sup 5406 5406 0 0.00 0 9080 11 0 0
v_max_non_paid_invoice 1574 1574 0 0.00 0 4679 10 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 6855 9 0 0
v_min_id_clo_ord 5308 5308 0 0.00 0 5049 10 0 0
v_min_id_clo_res 1031 1031 0 0.00 0 6691 22 0 0
v_min_id_ord_sup 5406 5406 0 0.00 0 8484 10 0 0
v_min_non_paid_invoice 5930 5930 0 0.00 0 10034 17 0 0
v_min_non_paid_realizn 7012 7012 0 0.00 0 11958 18 0 0
v_our_firm 10418 10418 0 0.00 0 54 5 0 0
v_reserve_write_off 19692 19692 0 0.00 0 5209 12 0 0
x_lock_depdocs_on_canc_invoice 10849 10849 0 0.00 0 26848 144 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 3501 338 0 0
x_make_qstorno_1000_1200 5314 5314 0 0.00 3 28942 1306 0 0
x_make_qstorno_1200_2000 5415 5415 0 0.00 4 76429 3225 0 0
x_make_qstorno_2100_3300 453182 453182 0 0.00 0 136960 248 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 04:38:08 to 27-05-2022 04:38:12.

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:21 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:22 46483 8192000000 9731362800 10029002752 804439456 8.941 100 91 178 2 834204048 8127456 21637136
02:10:16 17178 8192000000 9758999584 10065248256 831278144 9.212 100 90 180 0 866702880 13980096 21444640
02:11:52 7680 8192000000 9811117968 10130137088 871688800 9.617 100 83 166 0 917446192 27197456 18559936
02:13:13 7943 8192000000 9885083904 10218201088 921153984 10.107 100 82 161 1 995255872 57028448 17073440
02:14:24 4768 8192000000 9944806384 10287562752 963743632 10.526 100 92 179 1 1051793376 69328288 18721456
02:15:32 2388 8192000000 9972444560 10326372352 996113712 10.841 100 95 185 0 1092609840 78104368 18391760
02:16:36 818 8192000000 10013797296 10378059776 1024988544 11.120 100 93 176 0 1135527552 93808256 16730752
02:17:39 1237 8192000000 10013449744 10382893056 1048874416 11.350 100 92 181 0 1128716384 61710240 18131728
02:18:42 1306 8192000000 10035095952 10411315200 1056776976 11.426 100 96 186 1 1157763136 81473040 19513120
02:19:45 1533 8192000000 10018496032 10395766784 1064271840 11.497 100 89 173 1 1138534880 56110864 18152176
02:20:49 1365 8192000000 10058596192 10440306688 1076508592 11.614 100 98 191 0 1180444896 85253744 18682560
02:21:51 1019 8192000000 10070233488 10454925312 1081596800 11.663 100 95 180 2 1192357664 92168544 18592320
02:22:54 1107 8192000000 10070703888 10455785472 1085982416 11.704 100 98 190 0 1184122592 79404192 18735984
02:23:56 1130 8192000000 10058252560 10439606272 1090122800 11.744 100 94 175 1 1177141792 70598736 16420256
02:24:59 2051 8192000000 10056653312 10441699328 1093928656 11.780 100 81 152 1 1174105728 65344832 14832240
02:26:02 1208 8192000000 10105996720 10494709760 1103439584 11.870 100 97 188 5 1228129728 105970224 18719920
02:27:05 1172 8192000000 10070354352 10459160576 1096240800 11.802 100 99 187 1 1187877952 72939472 18697680
02:28:07 1252 8192000000 10063009856 10451271680 1095692304 11.797 100 92 175 2 1184641200 71293200 17655696
02:29:10 2701 8192000000 10071120320 10460606464 1094089168 11.782 100 79 152 2 1193187184 84482368 14615648
02:30:13 1198 8192000000 10035412768 10415411200 1073210560 11.583 99 96 190 0 1158697024 66845888 18640576
02:31:15 3454 8192000000 10015496880 10399633408 1077529184 11.624 100 84 152 0 1142101952 48433264 16139504
02:32:19 975 8192000000 9999767264 10376388608 1039911952 11.264 100 90 169 1 1123022672 65161344 17949376
02:33:21 959 8192000000 10004189952 10387529728 1057947872 11.437 100 81 154 2 1125085968 50008240 17129856
02:34:24 7269 8192000000 10000694992 10387382272 1050305808 11.364 100 65 123 3 1122371872 58868672 13197392
02:35:32 1758 8192000000 9969399984 10344161280 1020359664 11.075 99 81 149 1 1093986528 59207376 14419488
02:36:35 4623 8192000000 9991103344 10366181376 1042138400 11.285 100 66 125 2 1097499936 42653760 12707776
02:37:42 20975 8192000000 9987558096 10370088960 1057844736 11.436 100 70 131 1 1109089584 38562640 12682208
02:39:08 1036 8192000000 10044206448 10424061952 1074868960 11.599 100 90 166 0 1160459392 68292992 17297440
02:40:14 3066 8192000000 10077266704 10463956992 1076736640 11.616 100 82 162 1 1191226912 97945856 16544416
02:41:21 15063 8192000000 10038767552 10438569984 1086479392 11.709 100 58 107 2 1166658832 68445824 11733616
02:42:39 4650 8192000000 10106244784 10493566976 1100011776 11.838 100 80 153 1 1222435248 105888320 16535152
02:43:54 2117 8192000000 10136183568 10533142528 1116675744 11.996 100 84 164 4 1250418880 116874128 16869008
02:45:01 1234 8192000000 10122850784 10525392896 1126105136 12.085 100 82 154 0 1243068176 100167040 16796000
02:46:07 784 8192000000 10125568096 10532851712 1132456208 12.145 100 81 156 0 1238651120 90705520 15489392
02:47:10 848 8192000000 10157632656 10557161472 1134540816 12.164 100 88 174 3 1272300000 116616080 21143104
02:48:17 13544 8192000000 10174289392 10591821824 1140887120 12.224 100 62 122 1 1287085872 133103856 13094896
02:49:42 2695 8192000000 10197739760 10596708352 1147328368 12.284 100 84 165 0 1308691696 143734864 17628464
02:50:48 1500 8192000000 10226461472 10621493248 1150434928 12.314 99 91 179 2 1336053888 164148784 21470176
02:51:54 7590 8192000000 10281924768 10684260352 1167201936 12.471 100 80 158 2 1394205232 209165472 17837824
02:53:10 854 8192000000 10317407520 10732974080 1171755664 12.513 100 98 193 0 1426452464 232699312 21997488
02:54:15 1051 8192000000 10306862768 10726514688 1174091376 12.535 100 84 164 0 1415757520 222538832 19127312
02:55:20 1635 8192000000 10361411024 10788904960 1183449968 12.622 100 98 195 1 1480203008 274183552 22569488
02:56:26 979 8192000000 10378728880 10807455744 1198403392 12.762 100 95 182 2 1485418608 265861824 21153392
02:57:30 1483 8192000000 10374029888 10800545792 1187610544 12.661 99 89 178 2 1491567520 283919584 20037392
02:58:36 1106 8192000000 10429902416 10845585408 1214290128 12.909 100 97 192 1 1536288352 299561440 22436784
02:59:42 1391 8192000000 10461278912 10874687488 1209120752 12.861 100 86 164 0 1568129984 340078784 18930448
03:00:47 1098 8192000000 10521269408 10931929088 1219349168 12.956 100 98 194 1 1625097296 381986768 23761360
03:01:54 2800 8192000000 10496203264 10940567552 1211281488 12.881 100 75 149 2 1601923648 371722480 18919680
03:02:59 7023 8192000000 10496483424 10942443520 1202707264 12.801 100 62 121 1 1605203376 386829808 15666304
03:04:18 3244 8192000000 10504363664 10946043904 1191462464 12.697 100 61 119 0 1605631696 399628048 14541184
03:05:34 958 8192000000 10530322144 10966921216 1193838192 12.719 100 74 143 0 1634519184 423196880 17484112
03:06:41 3412 8192000000 10491661504 10967269376 1191897728 12.701 100 74 148 0 1599276848 389916912 17462208
03:07:50 2405 8192000000 10487564400 10921562112 1178908192 12.580 100 72 139 0 1589452752 393615712 16928848
03:08:55 4729 8192000000 10504915888 10966835200 1178875840 12.580 99 67 131 2 1595329952 400894160 15559952
03:10:02 3726 8192000000 10469225872 10907033600 1183245232 12.620 99 66 132 1 1565502928 366768912 15488784
03:11:09 14724 8192000000 10478598784 10979684352 1179552800 12.586 100 33 66 0 1607761776 419483536 8725440
03:12:49 27782 8192000000 10422301520 10936750080 1157988528 12.384 100 27 54 0 1562095696 396848992 7258176
03:14:19 12455 8192000000 10323538496 10881503232 1145384928 12.266 100 37 73 1 1424570496 269787344 9398224
03:15:32 18328 8192000000 10134150448 10678030336 1162562448 12.427 100 25 50 1 1273478368 104705344 6210576
03:16:57 64761 8192000000 10181092784 10611650560 1077621280 11.625 93 25 50 0 1193985472 110392448 5971744
03:19:03 38385 8192000000 10177334144 10637295616 1136138320 12.179 100 14 28 1 1289104368 149346880 3619168
03:20:51 80006 8192000000 10064507824 10544345088 1112577088 11.957 98 8 17 0 1156808976 42442368 1789520
03:23:11 101943 8192000000 10096155792 10532188160 1087950144 11.723 96 40 75 0 1144702896 48554368 8198384
03:25:57 120122 8192000000 10093700464 10502885376 1028009104 11.149 91 47 85 4 1095905664 58970368 8926192
03:28:58 31406 8192000000 10108985808 10556071936 1110409920 11.936 99 33 65 0 1225653984 108121664 7122400
03:30:34 18060 8192000000 10149386384 10575196160 1123402640 12.059 100 66 127 1 1237411056 99876128 14132288
03:32:00 56328 8192000000 10135217488 10615992320 1096082000 11.800 98 16 32 0 1231070720 130907776 4080944
03:34:13 48146 8192000000 10067930976 10538852352 1109780368 11.930 99 16 33 0 1174481200 61198464 3502368
03:36:01 118623 8192000000 10025637456 10473517056 1072206704 11.573 95 9 15 1 1106651584 33057392 1387488
03:39:01 43932 8192000000 10074175456 10527338496 1134511392 12.164 100 26 51 2 1188551328 48654656 5385280
03:40:45 23035 8192000000 10157170784 10597142528 1145248528 12.265 100 39 78 1 1239426672 86280336 7897808
03:42:40 100699 8192000000 10095254432 10562412544 1124605728 12.070 98 9 18 1 1166611040 40132384 1872928
03:45:31 27234 8192000000 10088237712 10521219072 1121763936 12.044 98 26 52 0 1164734000 37946272 5023792
03:47:00 53686 8192000000 10081566928 10564378624 1121030464 12.037 98 8 14 0 1192436928 69969808 1436656
03:48:54 15327 8192000000 10131066688 10553032704 1139309888 12.209 100 53 102 2 1205746144 55441088 10995168
03:50:13 57850 8192000000 10135068016 10598506496 1144101232 12.254 100 20 40 0 1207143872 59014512 4028128
03:52:16 92810 8192000000 10062256304 10503757824 1103472128 11.871 97 27 54 2 1122031024 12886176 5672720
03:54:49 46606 8192000000 10054092512 10510020608 1125786992 12.082 100 17 34 0 1151892784 22089504 4016288
03:56:55 20146 8192000000 10063538608 10517471232 1119096656 12.018 99 22 43 0 1151117360 27243120 4777584
03:58:23 57641 8192000000 10090918640 10537353216 1105145600 11.886 97 35 68 6 1156319696 43364496 7809600
04:00:24 94042 8192000000 10083686800 10547556352 1107749920 11.911 98 15 30 0 1192611152 81268496 3592736
04:03:16 37940 8192000000 10054535600 10516283392 1109862576 11.931 98 21 41 1 1143876400 29675104 4338720
04:04:54 48531 8192000000 10081621680 10525011968 1100014176 11.838 97 35 68 2 1159503328 51430384 8058768
04:06:45 27701 8192000000 10072830192 10506391552 1120159664 12.028 99 52 97 1 1192599520 61684208 10755648
04:08:44 28654 8192000000 10091711536 10534948864 1112632336 11.957 98 38 76 2 1184306208 64396208 7277664
04:10:13 17056 8192000000 10125521136 10562293760 1123711600 12.062 99 44 87 0 1207284832 74790336 8782896
04:11:32 49807 8192000000 10095564192 10574524416 1122496304 12.051 99 36 70 2 1219662272 90178640 6987328
04:13:23 46087 8192000000 10101360096 10561216512 1122442768 12.050 99 39 78 1 1211125392 80150592 8532032
04:15:10 11491 8192000000 10104814576 10563821568 1114017760 11.970 98 50 93 3 1184162912 59682048 10463104
04:16:23 33157 8192000000 10140876560 10581782528 1126635888 12.090 99 41 80 0 1222154784 86736224 8782672
04:17:58 95840 8192000000 10043386560 10542235648 1115575056 11.985 99 5 10 0 1172367632 56032880 759696
04:20:40 3325 8192000000 10064667536 10492534784 1115687504 11.986 100 57 108 1 1165571216 38026672 11857040
04:21:44 77981 8192000000 10081820688 10538975232 1092712784 11.768 98 16 32 0 1176556224 80405344 3438096
04:24:16 64547 8192000000 10061851472 10540490752 1117682224 12.005 100 13 26 1 1167471072 46330720 3458128
04:26:53 89642 8192000000 10059065408 10515578880 1099716304 11.835 98 10 20 0 1144768496 43055600 1996592
04:29:23 57093 8192000000 10137103776 10603020288 1127086016 12.094 99 39 76 0 1221364000 86594128 7683856
04:31:30 56486 8192000000 10103093488 10543800320 1108984720 11.923 98 24 46 0 1173285472 59938928 4361824
04:33:28 59370 8192000000 10149662112 10618789888 1136031648 12.178 100 26 52 1 1230354928 88538352 5784928
04:35:27 43875 8192000000 10094675264 10531803136 1099608864 11.834 97 37 72 0 1163893152 57018400 7265888
04:37:14 17575 8192000000 8894846560 9056894976 60285392 0.730 6 1 2 0 63444416 3123616 35408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 27-05-2022 04:38:13 to 27-05-2022 04:38:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 102 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-05-2022 04:38:31 to 27-05-2022 04:38:33.

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          218267
MON$OLDEST_ACTIVE               218268
MON$OLDEST_SNAPSHOT             218268
MON$NEXT_TRANSACTION            218268
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                       2091648
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             767
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

27.05.22 04:38:34. 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-05-27 04:38:36 No severe PSQL-related problems occured
27.05.22 04:38:38. End of report.