Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.05.2022 07: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 16202924 kB
MemAvailable 31410124 kB
Buffers 0 kB
Cached 15537976 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 07:37:15
DTS_END                         2022-05-27 07:37:15.6620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38540
TRN_ID                          219393
ATT_ID                          588
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: 8629.16 21698 1035499
customer order: creation 170.93 475 20511
customer order: refuse 14.62 482 1754
order to supplier: creation 45.84 1443 5501
order to supplier: removal 3.31 160293 397
invoice (draft): creation 45.76 3218 5491
invoice (draft): removal 45.78 1140 5494
invoice accept: apply 41.28 28597 4954
invoice accept: cancel 41.81 1398 5017
customer reserve: creation 3806.69 272 456803
customer reserve: removal 4123.36 99 494803
realization accept: apply 83.00 297 9960
realization accept: cancel 79.21 178 9505
payment from customer: creation 59.20 180 7104
payment from customer: removal 5.43 120 651
payment to supplier: creation 47.49 218 5699
payment to supplier: removal 3.50 157 420
service: total inventory turnovers 3.52 4906 422
service: total monetary turnovers 7.43 388 891
service: aggregate perf. data 0.97 16144 116
service: refresh index statistics 0.05 213959 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-05-2022 07:39:23 to 27-05-2022 07:39:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6660
TEST_TIME 2 5715
TEST_TIME 3 6083
TEST_TIME 4 6264
TEST_TIME 5 6278
TEST_TIME 6 6387
TEST_TIME 7 6129
TEST_TIME 8 6199
TEST_TIME 9 6384
TEST_TIME 10 6336
TEST_TIME 11 6484
TEST_TIME 12 6356
TEST_TIME 13 6627
TEST_TIME 14 6650
TEST_TIME 15 6942
TEST_TIME 16 7043
TEST_TIME 17 7273
TEST_TIME 18 7301
TEST_TIME 19 7523
TEST_TIME 20 7480
TEST_TIME 21 7992
TEST_TIME 22 8407
TEST_TIME 23 8606
TEST_TIME 24 8570
TEST_TIME 25 8581
TEST_TIME 26 8684
TEST_TIME 27 9048
TEST_TIME 28 9054
TEST_TIME 29 9174
TEST_TIME 30 9559
TEST_TIME 31 10070
TEST_TIME 32 10238
TEST_TIME 33 10652
TEST_TIME 34 10629
TEST_TIME 35 10561
TEST_TIME 36 10585
TEST_TIME 37 10425
TEST_TIME 38 10179
TEST_TIME 39 10038
TEST_TIME 40 9947
TEST_TIME 41 9988
TEST_TIME 42 9838
TEST_TIME 43 9736
TEST_TIME 44 9961
TEST_TIME 45 9820
TEST_TIME 46 9844
TEST_TIME 47 9744
TEST_TIME 48 9654
TEST_TIME 49 9795
TEST_TIME 50 9658
TEST_TIME 51 9607
TEST_TIME 52 9552
TEST_TIME 53 9442
TEST_TIME 54 9480
TEST_TIME 55 9484
TEST_TIME 56 9417
TEST_TIME 57 9354
TEST_TIME 58 9315
TEST_TIME 59 9267
TEST_TIME 60 9245
TEST_TIME 61 9403
TEST_TIME 62 9353
TEST_TIME 63 9317
TEST_TIME 64 9309
TEST_TIME 65 9357
TEST_TIME 66 9322
TEST_TIME 67 9347
TEST_TIME 68 9320
TEST_TIME 69 9268
TEST_TIME 70 9305
TEST_TIME 71 9277
TEST_TIME 72 9204
TEST_TIME 73 9147
TEST_TIME 74 9128
TEST_TIME 75 9077
TEST_TIME 76 9070
TEST_TIME 77 9033
TEST_TIME 78 9032
TEST_TIME 79 8959
TEST_TIME 80 8903
TEST_TIME 81 8869
TEST_TIME 82 8889
TEST_TIME 83 8914
TEST_TIME 84 8928
TEST_TIME 85 8946
TEST_TIME 86 8918
TEST_TIME 87 8938
TEST_TIME 88 8951
TEST_TIME 89 8888
TEST_TIME 90 8925
TEST_TIME 91 9049
TEST_TIME 92 9096
TEST_TIME 93 9084
TEST_TIME 94 9061
TEST_TIME 95 8993
TEST_TIME 96 8975
TEST_TIME 97 8929
TEST_TIME 98 8900
TEST_TIME 99 8843
TEST_TIME 100 8769
TEST_TIME 101 8747
TEST_TIME 102 8742
TEST_TIME 103 8861
TEST_TIME 104 8901
TEST_TIME 105 8869
TEST_TIME 106 8851
TEST_TIME 107 8823
TEST_TIME 108 8940
TEST_TIME 109 8913
TEST_TIME 110 8906
TEST_TIME 111 8914
TEST_TIME 112 8907
TEST_TIME 113 8905
TEST_TIME 114 8925
TEST_TIME 115 8863
TEST_TIME 116 8818
TEST_TIME 117 8785
TEST_TIME 118 8739
TEST_TIME 119 8702
TEST_TIME 120 8659

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-05-2022 07:39:27 to 27-05-2022 07:39:30.

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 1454799 1454799 0 0.00 0 15478 15 0 0
doc_list_biud 1454797 1454797 0 0.00 0 114148 41 0 0
sp_add_invoice_to_stock 5104 4954 150 2.93 32 255791 28598 0 150
sp_cancel_adding_invoice 5017 5017 0 0.00 6 95410 1398 0 0
sp_cancel_client_order 1754 1754 0 0.00 5 14417 483 0 0
sp_cancel_customer_reserve 494803 494803 0 0.00 1 48160 100 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 4 2819 120 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 4 9462 158 0 0
sp_cancel_supplier_invoice 5508 5494 14 0.25 3 114256 1140 0 14
sp_cancel_supplier_order 397 397 0 0.00 8 1595683 160294 0 0
sp_cancel_write_off 9505 9505 0 0.00 2 14349 178 0 0
sp_client_order 20511 20511 0 0.00 6 15571 476 0 0
sp_customer_reserve 456803 456803 0 0.00 1 45382 273 0 0
sp_fill_shopping_cart 31496 31496 0 0.00 1 28780 89 0 0
sp_fill_shopping_cart_clo_res 456765 456765 0 0.00 0 16366 20 0 0
sp_get_clo_for_invoice 4909 4909 0 0.00 0 44562 333 0 0
sp_kill_cost_storno 15593 15593 0 0.00 0 10390 82 0 0
sp_kill_qstorno_ret_qs2qd 1005002 1005002 0 0.00 0 114145 45 0 0
sp_kill_qty_storno 531838 531838 0 0.00 0 114146 104 0 0
sp_lock_selected_doc 20469 20469 0 0.00 0 7591 8 0 0
sp_make_cost_storno 27672 27672 0 0.00 0 15463 86 0 0
sp_multiply_rows_for_pdistr 27671 27671 0 0.00 0 15297 39 0 0
sp_multiply_rows_for_qdistr 20511 20511 0 0.00 0 11302 164 0 0
sp_pay_from_customer 7104 7104 0 0.00 6 11244 181 0 0
sp_pay_to_supplier 5699 5699 0 0.00 6 15369 219 0 0
sp_payment_common 12803 12803 0 0.00 2 7174 47 0 0
sp_qd_handle_on_cancel_clo 1754 1754 0 0.00 1 14372 389 0 0
sp_qd_handle_on_invoice_upd_sts 9925 9925 0 0.00 0 25096 586 0 0
sp_qd_handle_on_reserve_upd_sts 9960 9960 0 0.00 0 22620 70 0 0
sp_reserve_write_off 9960 9960 0 0.00 9 22838 298 0 0
sp_supplier_invoice 5491 5491 0 0.00 8 77921 3218 0 0
sp_supplier_order 5501 5501 0 0.00 8 28854 1444 0 0
srv_aggregate_perf_data 116 116 0 0.00 2685 116516 16144 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 635 131213 35652 0 0
srv_find_qd_qs_mism 986395 986395 0 0.00 0 12241 12 0 0
srv_make_invnt_saldo 422 422 0 0.00 105 72723 4907 0 0
srv_make_money_saldo 891 891 0 0.00 1 23326 389 0 0
srv_recalc_idx_stat 6 6 0 0.00 82577 423478 213959 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4956 4956 0 0.00 1 13306 1043 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6306 486 0 0
t$perf-norm:sp_cancel_client_order 1754 1754 0 0.00 0 6027 34 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 1316 31 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 2205 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 8783 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 691 34 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 109093 7887 0 0
t$perf-norm:sp_cancel_write_off 931 931 0 0.00 0 3480 23 0 0
t$perf-norm:sp_client_order 20511 20511 0 0.00 0 9545 38 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 1660 16 0 0
t$perf-norm:sp_pay_from_customer 7104 7104 0 0.00 0 3853 20 0 0
t$perf-norm:sp_pay_to_supplier 5699 5699 0 0.00 0 5861 22 0 0
t$perf-norm:sp_reserve_write_off 9960 9960 0 0.00 0 6028 24 0 0
t$perf-norm:sp_supplier_invoice 5491 5491 0 0.00 0 5534 34 0 0
t$perf-norm:sp_supplier_order 5501 5501 0 0.00 0 4348 33 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 3222 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 36 4 0 0
t$perf-norm:srv_make_invnt_saldo 422 422 0 0.00 0 1012 17 0 0
t$perf-norm:srv_make_money_saldo 891 891 0 0.00 0 488 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 5 195 76 0 0
v_add_invoice_to_stock 10110 9810 300 2.96 0 4590 27 0 300
v_all_customers 37322 37322 0 0.00 0 116 4 0 0
v_all_suppliers 30184 30184 0 0.00 0 119 4 0 0
v_all_wares 41020 41020 0 0.00 0 107 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 1004 14 0 0
v_cancel_client_order 3508 3508 0 0.00 0 4140 14 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 2746 11 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 1 3411 13 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 4167 15 0 0
v_cancel_supplier_invoice 794 766 28 3.52 0 1764 29 0 28
v_cancel_supplier_order 794 794 0 0.00 0 4055 20 0 0
v_cancel_write_off 1862 1862 0 0.00 0 3458 27 0 0
v_max_id_clo_ord 5500 5500 0 0.00 0 13228 7 0 0
v_max_id_clo_res 986 986 0 0.00 0 2849 17 0 0
v_max_id_ord_sup 5485 5485 0 0.00 0 5718 8 0 0
v_max_non_paid_invoice 1592 1592 0 0.00 0 132 7 0 0
v_max_non_paid_realizn 3826 3826 0 0.00 0 7139 9 0 0
v_min_id_clo_ord 5500 5500 0 0.00 0 1372 7 0 0
v_min_id_clo_res 986 986 0 0.00 0 3140 16 0 0
v_min_id_ord_sup 5485 5485 0 0.00 0 5850 9 0 0
v_min_non_paid_invoice 5699 5699 0 0.00 0 5140 17 0 0
v_min_non_paid_realizn 7104 7104 0 0.00 0 6944 15 0 0
v_our_firm 10254 10254 0 0.00 0 58 5 0 0
v_reserve_write_off 19920 19920 0 0.00 0 4151 12 0 0
x_lock_depdocs_on_canc_invoice 10128 10128 0 0.00 0 14807 142 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 4515 357 0 0
x_make_qstorno_1000_1200 5501 5501 0 0.00 3 28509 1253 0 0
x_make_qstorno_1200_2000 5491 5491 0 0.00 3 77905 2984 0 0
x_make_qstorno_2100_3300 456803 456803 0 0.00 0 45378 239 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-05-2022 07:39:31 to 27-05-2022 07:39:35.

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:19 2 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:20 26944 8192000000 9720626896 10019995648 797854368 8.875 100 89 177 1 826094976 7463408 20777200
05:09:50 18858 8192000000 9762459664 10068422656 833030832 9.230 100 87 174 0 866420416 12812848 20576736
05:11:15 23984 8192000000 9801423504 10121568256 865550032 9.556 100 82 164 0 907124656 22778912 18795712
05:12:44 10803 8192000000 9859527456 10194776064 917669824 10.073 100 82 162 1 971692944 36410144 17612976
05:13:58 7521 8192000000 9916091760 10259075072 954550720 10.436 100 79 158 1 1023543328 51950016 17042592
05:15:09 2824 8192000000 9961796784 10314756096 990166336 10.783 100 82 160 0 1073339280 66885248 16287696
05:16:14 1032 8192000000 10021979984 10387402752 1021138544 11.083 100 93 183 1 1135521344 96524944 17857856
05:17:17 1044 8192000000 10010378016 10382200832 1043722320 11.300 100 94 179 1 1130713360 68415152 18575888
05:18:20 1026 8192000000 10036287440 10413789184 1057448224 11.432 100 94 178 1 1157150784 80314656 19387904
05:19:22 1186 8192000000 10058550880 10439843840 1069774064 11.550 100 95 187 0 1181037760 92415552 18848144
05:20:24 1075 8192000000 10052681744 10434834432 1074815296 11.598 100 93 179 1 1175968832 82988032 18165504
05:21:26 1071 8192000000 10055419520 10436239360 1077465536 11.623 100 97 185 2 1173346048 77984304 17896208
05:22:28 4307 8192000000 10032458032 10424066048 1079946496 11.647 100 71 136 0 1157236944 64184000 13106448
05:23:35 987 8192000000 10072385616 10462347264 1083715392 11.683 100 90 176 0 1193250928 91405056 18130480
05:24:37 1398 8192000000 10061734560 10450391040 1086883232 11.713 100 91 172 2 1181905344 77260192 17761920
05:25:40 1289 8192000000 10070942288 10456756224 1094089616 11.782 100 96 183 2 1192957152 78823088 20044448
05:26:43 1299 8192000000 10070603232 10460164096 1097270320 11.812 100 96 192 1 1197722288 81796064 18655904
05:27:45 1111 8192000000 10078423696 10467508224 1094730720 11.788 100 95 184 1 1200443536 87475376 18237440
05:28:47 1148 8192000000 10061776768 10453454848 1082442288 11.671 99 94 177 1 1182341952 82475744 17423920
05:29:50 1235 8192000000 10044408800 10435432448 1095511536 11.795 100 100 192 1 1171879648 56820880 19547232
05:30:52 1517 8192000000 10026620832 10410622976 1075346816 11.603 100 87 170 1 1149656448 57110880 17198752
05:31:54 2650 8192000000 10042977472 10430681088 1067227936 11.526 100 80 156 1 1158649520 74406688 17014896
05:32:59 1578 8192000000 10007803744 10385625088 1059650704 11.453 100 96 181 3 1133552512 54975680 18926128
05:34:02 1063 8192000000 10014060080 10391310336 1049907392 11.360 99 98 191 1 1136566240 67437152 19221696
05:35:04 4250 8192000000 9985586336 10367827968 1033185024 11.199 99 79 148 1 1107628192 59836432 14606736
05:36:10 927 8192000000 9979834768 10353479680 1028884192 11.158 100 86 164 2 1098539456 53650576 16004688
05:37:12 6715 8192000000 10008560272 10393350144 1042726672 11.291 100 63 116 1 1139015280 85031648 11256960
05:38:28 6494 8192000000 10022441888 10396344320 1057569456 11.433 99 77 145 4 1135279072 62903376 14806240
05:39:40 8353 8192000000 10051881168 10439004160 1079107664 11.639 100 75 146 1 1172167152 78644144 14415344
05:41:05 2242 8192000000 10089325904 10468257792 1087110160 11.715 100 86 161 1 1204235232 101360000 15765072
05:42:13 18543 8192000000 10135817056 10539540480 1102213520 11.859 100 71 140 0 1249714720 131981232 15519968
05:43:36 4048 8192000000 10148116064 10549530624 1117145152 12.000 100 66 122 2 1260504144 129515808 13843184
05:44:49 1976 8192000000 10209688720 10600542208 1126803552 12.091 100 89 167 0 1324981488 180389392 17788544
05:45:56 2062 8192000000 10220112032 10623811584 1140675440 12.222 100 85 161 2 1333966304 174177392 19113472
05:47:01 1303 8192000000 10271215264 10665730048 1152845008 12.336 100 91 175 4 1383953024 210659296 20448720
05:48:07 2589 8192000000 10298509056 10696298496 1162534832 12.427 100 88 174 1 1411793168 229049808 20208528
05:49:13 3658 8192000000 10311753008 10712608768 1167043056 12.469 100 96 183 2 1424644128 237230768 20370304
05:50:24 1167 8192000000 10358231728 10762620928 1174043120 12.535 100 94 181 3 1467985360 274335856 19606384
05:51:28 1043 8192000000 10403389168 10808418304 1175384656 12.547 100 94 183 1 1519544160 323034784 21124720
05:52:33 632 8192000000 10409321696 10813800448 1179557632 12.586 100 99 190 0 1515824272 315564752 20701888
05:53:37 1098 8192000000 10485141824 10896404480 1192423920 12.706 100 90 173 0 1594780000 382051904 20304176
05:54:42 1335 8192000000 10442436352 10881077248 1189660192 12.680 100 88 168 1 1553108528 343526096 19922240
05:55:46 905 8192000000 10483494192 10908999680 1188705840 12.671 100 99 195 0 1591878224 380841168 22331216
05:56:53 2327 8192000000 10464282528 10909036544 1182747520 12.616 100 89 176 0 1570097936 366209936 21140480
05:57:59 1700 8192000000 10453617088 10919673856 1177525312 12.567 100 97 194 1 1569830800 370119760 22185728
05:59:04 860 8192000000 10460462992 10918064128 1183877808 12.626 100 99 194 1 1562963536 355931648 23154080
06:00:09 4341 8192000000 10410360320 10867408896 1174028496 12.534 100 53 106 0 1516055840 328169328 13858016
06:01:16 11312 8192000000 10421119888 10868654080 1178123808 12.573 100 48 96 1 1530380448 340276384 11980256
06:02:35 11244 8192000000 10415501344 10846916608 1140601344 12.221 99 45 89 1 1517213984 365231248 11381392
06:03:53 922 8192000000 10405493872 10862809088 1168998896 12.487 100 100 198 1 1504471392 311318336 24154160
06:04:56 1932 8192000000 10353557856 10799669248 1147086064 12.282 99 72 137 2 1463945824 300879728 15980032
06:06:03 961 8192000000 10415088496 10839343104 1155077520 12.357 100 98 194 2 1518885856 341365152 22443184
06:07:05 4270 8192000000 10389943760 10860400640 1158916000 12.393 100 59 119 0 1494263856 321167296 14180560
06:08:13 54914 8192000000 10228689328 10698178560 1132140288 12.142 98 24 48 0 1359312816 221388256 5784272
06:10:09 32721 8192000000 10112075136 10590121984 1123014000 12.055 98 23 44 1 1261232544 133300592 4917952
06:11:45 29488 8192000000 10110028464 10538737664 1108585824 11.919 97 30 59 3 1195196656 80299744 6311088
06:13:20 3390 8192000000 10138631168 10560126976 1149904128 12.309 100 51 101 1 1241540512 80815088 10821296
06:14:29 95226 8192000000 10189396656 10623586304 1151167520 12.320 100 49 97 2 1260607968 98944256 10496192
06:17:46 35471 8192000000 10136370624 10596945920 1105428288 11.889 96 34 67 1 1223973968 110968464 7577216
06:19:30 60183 8192000000 10197436704 10632454144 1111423376 11.946 97 39 78 1 1267989936 147934720 8631840
06:21:34 62900 8192000000 10113115760 10551042048 1075262304 11.602 95 20 41 0 1172508512 92381040 4865168
06:23:39 13129 8192000000 10117812720 10605518848 1129611264 12.118 100 27 54 1 1236762368 100794352 6356752
06:25:02 73342 8192000000 10144789888 10601885696 1118660176 12.014 99 25 47 2 1234874400 111443712 4770512
06:27:15 24730 8192000000 10085401616 10554155008 1126857136 12.092 100 17 35 1 1196833872 65854528 4122208
06:28:41 52097 8192000000 10103473424 10542039040 1130108000 12.122 100 17 34 1 1201260976 66747104 4405872
06:30:34 112380 8192000000 10083195920 10565431296 1085430224 11.699 96 18 34 1 1207490704 118091632 3968848
06:33:29 80154 8192000000 10070905088 10522087424 1108116224 11.915 98 16 32 0 1149721728 38372512 3232992
06:35:50 100566 8192000000 10122101056 10578280448 1109902704 11.931 98 34 69 0 1163342304 46481200 6958400
06:38:38 92956 8192000000 10048779568 10482716672 1091230368 11.754 96 16 32 0 1133272304 39213248 2828688
06:41:26 19825 8192000000 10094239552 10519437312 1114253440 11.973 98 39 78 0 1184522000 62544240 7724320
06:42:46 33242 8192000000 10082385664 10524717056 1104795392 11.883 97 46 91 0 1152420224 38665808 8959024
06:44:29 49875 8192000000 10098994240 10547707904 1115825392 11.988 98 31 62 1 1184133552 62347648 5960512
06:46:22 10912 8192000000 10121043504 10542411776 1127029248 12.093 99 60 115 1 1204819680 66807232 10983200
06:47:37 64342 8192000000 10089434512 10574450688 1125998224 12.084 99 36 70 0 1203433728 70821952 6613552
06:50:14 54343 8192000000 10090859552 10522750976 1099871824 11.836 96 40 76 3 1148488064 41009968 7606272
06:52:12 11480 8192000000 10080097056 10559991808 1137286400 12.190 100 23 44 1 1199902480 57607680 5008400
06:53:25 71115 8192000000 10079955536 10561478656 1112475584 11.956 98 18 35 0 1182338112 66336352 3526176
06:56:09 69034 8192000000 10084175840 10543988736 1094586848 11.786 97 19 38 0 1173515328 74127376 4801104
06:59:13 38982 8192000000 10089830816 10554892288 1127248016 12.095 100 22 43 0 1181834864 50077312 4509536
07:01:09 75583 8192000000 10118674640 10592514048 1140751232 12.223 100 20 39 2 1193287312 48502496 4033584
07:03:28 23036 8192000000 10132293616 10570883072 1125605616 12.080 98 30 58 2 1217201440 85284368 6311456
07:04:55 50269 8192000000 10154089888 10624983040 1140473920 12.220 99 24 48 1 1239054624 93319136 5261568
07:07:08 40469 8192000000 10173577696 10629804032 1127312000 12.096 99 30 60 3 1288199024 154204512 6682512
07:08:56 64819 8192000000 10085446672 10531618816 1094293840 11.783 96 28 52 1 1134902160 35800848 4807472
07:11:02 7454 8192000000 10103268288 10516348928 1132701472 12.147 100 81 152 4 1191278704 42468384 16108848
07:12:12 86104 8192000000 10075825424 10514132992 1098908960 11.827 98 54 108 1 1177391744 67867104 10615680
07:14:46 57899 8192000000 10087955056 10539548672 1114063904 11.971 99 31 61 0 1184624128 63330352 7229872
07:16:59 84710 8192000000 10167449296 10645192704 1140201968 12.217 100 18 37 3 1248172048 103906800 4063280
07:19:56 70603 8192000000 10068752656 10609172480 1111353520 11.945 98 12 24 0 1223482960 109179216 2950224
07:22:06 69847 8192000000 10089962128 10526572544 1116162880 11.991 98 36 70 1 1178320400 52997600 9159920
07:24:19 95151 8192000000 10106426224 10653372416 1124525920 12.070 98 26 52 1 1224661680 94594240 5541520
07:27:04 31377 8192000000 10075599600 10538635264 1135165024 12.170 100 38 76 0 1178071296 35256448 7649824
07:29:33 65963 8192000000 10127453840 10589622272 1097658992 11.815 96 24 48 2 1162224112 59663328 4901792
07:31:49 131213 8192000000 10040861696 10517925888 1084600272 11.691 96 25 50 0 1136992048 47607552 4784224
07:35:01 54379 8192000000 10065842800 10524803072 1123609456 12.061 100 9 18 0 1171778656 46409232 1759968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-05-2022 07:39:37 to 27-05-2022 07:39:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-05-2022 07:39:54 to 27-05-2022 07:39:56.

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          216776
MON$OLDEST_ACTIVE               219639
MON$OLDEST_SNAPSHOT             219639
MON$NEXT_TRANSACTION            219639
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                       2064928
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             777
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 07:39:57. 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 07:40:00 No severe PSQL-related problems occured
27.05.22 07:40:01. End of report.