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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 14640048 kB
MemAvailable 30373580 kB
Buffers 0 kB
Cached 17013088 kB
SwapCached 11600 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.3
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-12-30 13:37:22
DTS_END                         2022-12-30 13:37:22.8530
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52240
TRN_ID                          228005
ATT_ID                          614
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: 9306.95 21567 1116834
customer order: creation 181.27 471 21752
customer order: refuse 15.04 420 1805
order to supplier: creation 47.54 1450 5705
order to supplier: removal 3.83 146046 459
invoice (draft): creation 47.47 3188 5696
invoice (draft): removal 50.94 1018 6113
invoice accept: apply 43.14 24113 5177
invoice accept: cancel 46.15 1248 5538
customer reserve: creation 4016.19 227 481943
customer reserve: removal 4558.98 96 547078
realization accept: apply 85.48 299 10258
realization accept: cancel 76.17 171 9140
payment from customer: creation 62.07 172 7448
payment from customer: removal 6.28 145 754
payment to supplier: creation 50.16 197 6019
payment to supplier: removal 3.60 123 432
service: total inventory turnovers 3.85 4853 462
service: total monetary turnovers 7.86 381 943
service: aggregate perf. data 0.88 18564 106
service: refresh index statistics 0.05 228164 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-12-2022 13:38:24 to 30-12-2022 13:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8426
TEST_TIME 2 7482
TEST_TIME 3 6842
TEST_TIME 4 7489
TEST_TIME 5 7279
TEST_TIME 6 7188
TEST_TIME 7 7352
TEST_TIME 8 7394
TEST_TIME 9 7621
TEST_TIME 10 7513
TEST_TIME 11 7631
TEST_TIME 12 7591
TEST_TIME 13 7666
TEST_TIME 14 7966
TEST_TIME 15 8192
TEST_TIME 16 8226
TEST_TIME 17 8202
TEST_TIME 18 8119
TEST_TIME 19 8377
TEST_TIME 20 8324
TEST_TIME 21 8404
TEST_TIME 22 8411
TEST_TIME 23 8236
TEST_TIME 24 8288
TEST_TIME 25 8461
TEST_TIME 26 8452
TEST_TIME 27 8672
TEST_TIME 28 9270
TEST_TIME 29 9112
TEST_TIME 30 9532
TEST_TIME 31 9725
TEST_TIME 32 9875
TEST_TIME 33 10080
TEST_TIME 34 10562
TEST_TIME 35 10728
TEST_TIME 36 10609
TEST_TIME 37 10879
TEST_TIME 38 11132
TEST_TIME 39 11084
TEST_TIME 40 11182
TEST_TIME 41 10972
TEST_TIME 42 10965
TEST_TIME 43 10904
TEST_TIME 44 10937
TEST_TIME 45 10838
TEST_TIME 46 10763
TEST_TIME 47 10603
TEST_TIME 48 10443
TEST_TIME 49 10303
TEST_TIME 50 10258
TEST_TIME 51 10102
TEST_TIME 52 10079
TEST_TIME 53 10113
TEST_TIME 54 10042
TEST_TIME 55 9948
TEST_TIME 56 9947
TEST_TIME 57 9921
TEST_TIME 58 9917
TEST_TIME 59 9932
TEST_TIME 60 9875
TEST_TIME 61 9760
TEST_TIME 62 9651
TEST_TIME 63 9698
TEST_TIME 64 9763
TEST_TIME 65 9796
TEST_TIME 66 9774
TEST_TIME 67 9761
TEST_TIME 68 9809
TEST_TIME 69 9789
TEST_TIME 70 9842
TEST_TIME 71 9843
TEST_TIME 72 9838
TEST_TIME 73 9913
TEST_TIME 74 9913
TEST_TIME 75 10085
TEST_TIME 76 10010
TEST_TIME 77 10044
TEST_TIME 78 10107
TEST_TIME 79 9984
TEST_TIME 80 9942
TEST_TIME 81 9869
TEST_TIME 82 9850
TEST_TIME 83 9823
TEST_TIME 84 9772
TEST_TIME 85 9748
TEST_TIME 86 9742
TEST_TIME 87 9705
TEST_TIME 88 9644
TEST_TIME 89 9589
TEST_TIME 90 9558
TEST_TIME 91 9542
TEST_TIME 92 9483
TEST_TIME 93 9507
TEST_TIME 94 9511
TEST_TIME 95 9476
TEST_TIME 96 9477
TEST_TIME 97 9485
TEST_TIME 98 9533
TEST_TIME 99 9589
TEST_TIME 100 9548
TEST_TIME 101 9612
TEST_TIME 102 9590
TEST_TIME 103 9604
TEST_TIME 104 9598
TEST_TIME 105 9539
TEST_TIME 106 9485
TEST_TIME 107 9486
TEST_TIME 108 9440
TEST_TIME 109 9382
TEST_TIME 110 9357
TEST_TIME 111 9318
TEST_TIME 112 9305
TEST_TIME 113 9324
TEST_TIME 114 9363
TEST_TIME 115 9396
TEST_TIME 116 9383
TEST_TIME 117 9380
TEST_TIME 118 9353
TEST_TIME 119 9356
TEST_TIME 120 9326

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-12-2022 13:38:27 to 30-12-2022 13:38:31.

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 1561069 1561069 0 0.00 0 17512 13 0 0
doc_list_biud 1561048 1561048 0 0.00 0 25286 40 0 0
sp_add_invoice_to_stock 5258 5177 81 1.54 80 242212 24114 0 81
sp_cancel_adding_invoice 5538 5538 0 0.00 7 70850 1248 0 0
sp_cancel_client_order 1805 1805 0 0.00 6 9718 420 0 0
sp_cancel_customer_reserve 547078 547078 0 0.00 1 25336 97 0 0
sp_cancel_pay_from_customer 754 754 0 0.00 4 8436 145 0 0
sp_cancel_pay_to_supplier 432 432 0 0.00 4 3485 124 0 0
sp_cancel_supplier_invoice 6123 6113 10 0.16 3 13328 1018 0 10
sp_cancel_supplier_order 459 459 0 0.00 8 1395559 146047 0 0
sp_cancel_write_off 9140 9140 0 0.00 2 12818 171 0 0
sp_client_order 21752 21752 0 0.00 5 20657 471 0 0
sp_customer_reserve 481943 481943 0 0.00 0 175547 227 0 0
sp_fill_shopping_cart 33142 33142 0 0.00 1 14656 86 0 0
sp_fill_shopping_cart_clo_res 481906 481906 0 0.00 0 15669 17 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 29487 266 0 0
sp_kill_cost_storno 15864 15864 0 0.00 0 5527 73 0 0
sp_kill_qstorno_ret_qs2qd 1109868 1109868 0 0.00 0 22725 43 0 0
sp_kill_qty_storno 585527 585527 0 0.00 0 25277 98 0 0
sp_lock_selected_doc 21397 21397 0 0.00 0 8644 8 0 0
sp_make_cost_storno 28865 28865 0 0.00 0 15617 88 0 0
sp_multiply_rows_for_pdistr 28864 28864 0 0.00 0 10596 37 0 0
sp_multiply_rows_for_qdistr 21752 21752 0 0.00 0 20021 184 0 0
sp_pay_from_customer 7448 7448 0 0.00 5 17380 173 0 0
sp_pay_to_supplier 6019 6019 0 0.00 6 18685 197 0 0
sp_payment_common 13467 13467 0 0.00 2 7538 46 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 9006 338 0 0
sp_qd_handle_on_invoice_upd_sts 10675 10675 0 0.00 0 16383 518 0 0
sp_qd_handle_on_reserve_upd_sts 10257 10257 0 0.00 0 21374 74 0 0
sp_reserve_write_off 10258 10258 0 0.00 8 21907 299 0 0
sp_supplier_invoice 5696 5696 0 0.00 12 71638 3189 0 0
sp_supplier_order 5705 5705 0 0.00 7 30766 1450 0 0
srv_aggregate_perf_data 106 106 0 0.00 2368 190982 18564 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 872 199220 36883 0 0
srv_find_qd_qs_mism 1065984 1065984 0 0.00 0 9412 9 0 0
srv_make_invnt_saldo 462 462 0 0.00 49 70956 4853 0 0
srv_make_money_saldo 943 943 0 0.00 0 12012 381 0 0
srv_recalc_idx_stat 6 6 0 0.00 44599 401128 228165 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 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 5180 5180 0 0.00 1 11585 969 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 2 6464 481 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 1019 26 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 969 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 754 754 0 0.00 0 1096 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 432 432 0 0.00 0 315 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 1651 40 0 0
t$perf-norm:sp_cancel_supplier_order 459 459 0 0.00 0 120152 6272 0 0
t$perf-norm:sp_cancel_write_off 996 996 0 0.00 0 8802 35 0 0
t$perf-norm:sp_client_order 21753 21753 0 0.00 0 13316 37 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 8062 25 0 0
t$perf-norm:sp_pay_from_customer 7448 7448 0 0.00 0 7427 20 0 0
t$perf-norm:sp_pay_to_supplier 6019 6019 0 0.00 0 4842 18 0 0
t$perf-norm:sp_reserve_write_off 10258 10258 0 0.00 0 9193 24 0 0
t$perf-norm:sp_supplier_invoice 5696 5696 0 0.00 0 6813 34 0 0
t$perf-norm:sp_supplier_order 5705 5705 0 0.00 0 3699 28 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 469 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 22 4 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 2426 18 0 0
t$perf-norm:srv_make_money_saldo 943 943 0 0.00 0 4957 10 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 4 548 164 0 0
v_add_invoice_to_stock 10432 10270 162 1.55 0 6020 24 0 162
v_all_customers 39898 39898 0 0.00 0 223 4 0 0
v_all_suppliers 31636 31636 0 0.00 0 78 4 0 0
v_all_wares 43504 43504 0 0.00 0 327 4 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 119 14 0 0
v_cancel_client_order 3610 3610 0 0.00 0 9626 14 0 0
v_cancel_customer_prepayment 1508 1508 0 0.00 0 3724 19 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 1 4341 15 0 0
v_cancel_payment_to_supplier 864 864 0 0.00 0 3354 16 0 0
v_cancel_supplier_invoice 836 816 20 2.39 0 3626 32 0 20
v_cancel_supplier_order 918 918 0 0.00 0 1681 12 0 0
v_cancel_write_off 1992 1992 0 0.00 0 2302 27 0 0
v_max_id_clo_ord 5699 5699 0 0.00 0 1971 6 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 961 12 0 0
v_max_id_ord_sup 5689 5689 0 0.00 0 4709 7 0 0
v_max_non_paid_invoice 1589 1589 0 0.00 0 1856 10 0 0
v_max_non_paid_realizn 3886 3886 0 0.00 0 2149 8 0 0
v_min_id_clo_ord 5699 5699 0 0.00 0 8330 16 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 2652 13 0 0
v_min_id_ord_sup 5689 5689 0 0.00 0 5181 9 0 0
v_min_non_paid_invoice 6019 6019 0 0.00 0 5938 14 0 0
v_min_non_paid_realizn 7448 7448 0 0.00 0 5900 18 0 0
v_our_firm 10730 10730 0 0.00 0 267 5 0 0
v_reserve_write_off 20514 20514 0 0.00 0 4191 12 0 0
x_lock_depdocs_on_canc_invoice 11243 11243 0 0.00 0 10824 121 0 0
x_lock_depdocs_on_canc_sup_ord 459 459 0 0.00 0 2617 277 0 0
x_make_qstorno_1000_1200 5705 5705 0 0.00 2 28355 1275 0 0
x_make_qstorno_1200_2000 5696 5696 0 0.00 5 67119 2954 0 0
x_make_qstorno_2100_3300 481943 481943 0 0.00 0 175544 197 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-12-2022 13:38:32 to 30-12-2022 13:38:36.

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
11:07:26 48 8192000000 8819029216 8838045696 13084000 0.159 8 1 2 0 13194112 74336 35776
11:08:27 33176 8192000000 9730796672 10030247936 801174160 8.908 100 82 164 0 829616480 7639360 20802960
11:10:09 29103 8192000000 9783668304 10095783936 850920832 9.409 100 93 186 0 886785744 14044976 21819936
11:11:41 9342 8192000000 9826505024 10149163008 880816752 9.708 100 79 156 0 934004032 35513312 17673968
11:12:56 7029 8192000000 9864681616 10198671360 919190928 10.088 100 84 166 1 980188384 43324304 17673152
11:14:06 3636 8192000000 9917824816 10257342464 947167040 10.363 100 94 187 0 1030468496 63162096 20139360
11:15:12 4160 8192000000 9949961792 10304159744 982129120 10.705 100 82 154 4 1067290752 69261536 15900096
11:16:18 926 8192000000 9973816544 10333364224 1012215072 10.997 100 92 182 0 1095321648 64470880 18635696
11:17:22 982 8192000000 9997317920 10362400768 1035625184 11.223 100 92 170 2 1110601888 57274880 17701824
11:18:25 891 8192000000 10023542080 10396835840 1051893200 11.379 100 91 173 2 1146628752 76755680 17979872
11:19:26 1007 8192000000 10028529872 10404954112 1063701312 11.492 100 96 189 0 1148656480 65478592 19476576
11:20:28 4176 8192000000 10051343376 10425483264 1063720272 11.492 100 90 166 2 1172040704 93185680 15134752
11:21:33 1145 8192000000 10045545952 10425380864 1071701808 11.568 100 96 184 1 1169831152 79766992 18362352
11:22:35 1182 8192000000 10058187040 10439913472 1076182608 11.611 100 99 185 2 1172743200 78532832 18027760
11:23:37 1036 8192000000 10041792752 10428264448 1081519088 11.662 100 91 172 0 1161405696 62190768 17695840
11:24:39 1240 8192000000 10051382400 10441359360 1084331088 11.689 100 86 168 3 1170417168 70241600 15844480
11:25:42 1032 8192000000 10054090912 10442489856 1092863392 11.770 100 94 175 1 1180030272 66799584 20367296
11:26:44 4270 8192000000 10049510512 10445848576 1091942672 11.761 100 73 133 1 1171476160 65176560 14356928
11:27:51 4682 8192000000 10025284000 10422738944 1081956608 11.666 100 67 124 1 1141235696 46718464 12560624
11:28:57 3250 8192000000 10045643600 10433728512 1090476496 11.747 100 78 148 2 1173087824 69112432 13498896
11:30:01 1135 8192000000 10054785872 10443776000 1089897952 11.742 100 92 168 4 1176945584 69777424 17270208
11:31:04 1157 8192000000 10038420576 10423037952 1062093136 11.477 99 95 184 3 1154496768 74412704 17990928
11:32:07 1126 8192000000 10030056336 10412793856 1063359968 11.489 100 97 181 5 1154763552 72170944 19232640
11:33:09 1135 8192000000 10007470000 10388496384 1052231872 11.382 100 86 158 1 1128447104 59644032 16571200
11:34:10 1009 8192000000 9960629792 10326831104 1006465296 10.941 99 96 177 0 1082699120 57658512 18575312
11:35:12 3013 8192000000 9931175792 10309279744 1001908336 10.897 98 51 95 1 1049548528 38203792 9436400
11:36:17 987 8192000000 9995402832 10378387456 1045743520 11.320 100 89 175 1 1117160192 52463392 18953280
11:37:19 2380 8192000000 9985938704 10369191936 1051997520 11.380 100 70 125 4 1116272752 51389168 12886064
11:38:25 1172 8192000000 10031606272 10408493056 1067347152 11.527 100 82 158 1 1143459968 60631072 15481744
11:39:32 1337 8192000000 10030202928 10411585536 1062935920 11.485 99 76 142 0 1151491920 74269760 14286240
11:40:39 8375 8192000000 10080877568 10459209728 1086640368 11.711 100 82 156 0 1189166464 86801568 15724528
11:41:53 1123 8192000000 10082307760 10466480128 1103892832 11.875 100 95 179 3 1202598768 79178272 19527664
11:42:58 8675 8192000000 10102023456 10499076096 1112745600 11.958 100 75 150 0 1217496288 89060832 15689856
11:44:16 4100 8192000000 10131807072 10525605888 1130161472 12.123 100 78 146 0 1244997552 100643456 14192624
11:45:22 4133 8192000000 10175180016 10579456000 1140965264 12.225 100 80 150 2 1291084928 133669664 16450000
11:46:33 1598 8192000000 10182521328 10577305600 1147990896 12.291 100 92 176 2 1289905008 123282480 18631632
11:47:39 1101 8192000000 10232843536 10627391488 1148188192 12.292 100 95 186 0 1338324080 169522224 20613664
11:48:42 3064 8192000000 10253134320 10665603072 1159182560 12.396 100 77 149 1 1365349792 189840864 16326368
11:49:52 1001 8192000000 10283675200 10697728000 1170827200 12.505 100 91 180 0 1393309232 202601344 19880688
11:50:58 1002 8192000000 10328136064 10745044992 1179796272 12.588 100 94 183 4 1436530800 236190064 20544464
11:52:02 1752 8192000000 10313826752 10730430464 1175589504 12.549 100 96 186 3 1422816640 226525328 20701808
11:53:06 1231 8192000000 10336769040 10739621888 1190521376 12.688 100 91 169 0 1443192304 233781136 18889792
11:54:11 1081 8192000000 10378555200 10783330304 1196069984 12.740 100 93 184 1 1484962256 267524288 21367984
11:55:14 1563 8192000000 10391999312 10821582848 1200785056 12.784 100 95 188 1 1502514928 279242016 22487856
11:56:18 867 8192000000 10427106240 10865655808 1204728768 12.820 100 92 180 0 1528225776 301261792 22235216
11:57:22 1099 8192000000 10414597936 10823495680 1191414160 12.697 100 73 145 0 1523568176 314771600 17382416
11:58:28 5819 8192000000 10452762688 10892161024 1182258816 12.611 99 59 118 0 1567518512 370591152 14668544
11:59:38 2128 8192000000 10449067792 10864697344 1182706720 12.615 99 76 150 1 1555147488 354571664 17869104
12:00:50 1005 8192000000 10504154656 10929725440 1186777632 12.653 100 92 178 2 1609395104 401428912 21188560
12:01:53 1296 8192000000 10509624352 10948993024 1175504736 12.548 100 87 164 0 1619115264 423182272 20428256
12:02:58 890 8192000000 10552997312 10964889600 1192230496 12.704 100 86 168 0 1655816160 443190352 20395312
12:04:03 4621 8192000000 10530914576 11009896448 1182773520 12.616 100 61 118 1 1622440144 423635472 16031152
12:05:10 4725 8192000000 10487081200 10979655680 1175457760 12.548 100 54 107 0 1594755344 404375392 14922192
12:06:18 13794 8192000000 10486966320 10928263168 1184150128 12.629 100 42 84 0 1585989280 391130320 10708832
12:07:39 16099 8192000000 10441131088 10896818176 1150712320 12.316 98 31 62 0 1539996944 381426224 7858400
12:09:00 13419 8192000000 10366778320 10851823616 1157374640 12.379 100 33 66 1 1491683968 325835392 8473936
12:10:15 3122 8192000000 10345544992 10797305856 1145980896 12.272 100 55 107 1 1450666480 292177696 12507888
12:11:21 7835 8192000000 10274585840 10752770048 1157494960 12.380 100 41 82 0 1392995440 225966304 9534176
12:12:33 27428 8192000000 10193596240 10617798656 1159797920 12.401 100 56 98 0 1295626336 124540480 11287936
12:14:01 25765 8192000000 10237965152 10735439872 1156494640 12.370 100 16 31 0 1344990176 184445008 4050528
12:15:28 100853 8192000000 10117055344 10663964672 1112457360 11.956 97 7 14 1 1181857648 68108048 1292240
12:18:15 94378 8192000000 10082771056 10555748352 1092168000 11.763 96 28 53 0 1152559056 53852464 6538592
12:20:51 135321 8192000000 10064953792 10522574848 1088077824 11.724 96 11 22 0 1112639056 22871136 1690096
12:24:10 138989 8192000000 10067133616 10507780096 1116070336 11.990 98 21 42 0 1149227984 29071232 4086416
12:27:35 65782 8192000000 10107263248 10547998720 1144766112 12.260 100 23 44 0 1195289920 46056464 4467344
12:29:43 7775 8192000000 10095564048 10523529216 1141166416 12.227 100 53 102 2 1189157216 37921920 10068880
12:30:51 34215 8192000000 10099766128 10538708992 1148466768 12.295 100 25 50 0 1193594064 40063616 5063680
12:32:30 17621 8192000000 10136537712 10562863104 1149198816 12.302 99 54 99 2 1207714448 46751712 11763920
12:33:56 199220 8192000000 10062060752 10509529088 1081061776 11.658 94 24 47 0 1142465040 56486144 4917120
12:39:34 41011 8192000000 10118022032 10605465600 1132530928 12.145 99 14 28 0 1219152480 83583280 3038272
12:41:52 102450 8192000000 10046915200 10499969024 1037404848 11.240 90 22 44 0 1088743424 46136752 5201824
12:44:35 36903 8192000000 10111587024 10581295104 1130302048 12.124 99 35 68 0 1228653648 91116224 7235376
12:46:49 46971 8192000000 10098368080 10592440320 1099558928 11.833 96 25 49 1 1184868352 80445040 4864384
12:49:58 53512 8192000000 10163307904 10704363520 1107857280 11.912 96 22 44 0 1250697456 137421392 5418784
12:52:04 127306 8192000000 10139993824 10640416768 1109891104 11.931 98 14 27 0 1182054752 69563040 2600608
12:55:12 103531 8192000000 10057755920 10492694528 1131692704 12.137 100 29 58 0 1213386672 74115488 7578480
12:58:10 79482 8192000000 10097292832 10541711360 1110928048 11.941 98 26 52 0 1178586752 62315872 5342832
13:00:31 46359 8192000000 10071672704 10516516864 1106276176 11.897 97 24 47 0 1150181424 39478080 4427168
13:02:29 58678 8192000000 10088413712 10553290752 1108035792 11.914 98 12 24 0 1174064688 63377936 2650960
13:04:38 44394 8192000000 10045021568 10512670720 1110385104 11.936 99 13 24 0 1153763872 40943136 2435632
13:06:23 72268 8192000000 10029175248 10504380416 1084311024 11.689 97 16 32 0 1121478160 33813024 3354112
13:08:41 70229 8192000000 10062161936 10495262720 1067742496 11.531 96 26 50 0 1115888352 42690032 5455824
13:10:54 5210 8192000000 10051729104 10475290624 1090573424 11.748 99 56 104 1 1138079744 37229376 10276944
13:12:00 29825 8192000000 10115971264 10545909760 1109611120 11.929 100 55 103 0 1209513024 89465440 10436464
13:13:39 34144 8192000000 10161891552 10606874624 1124738000 12.072 100 45 88 1 1225315808 92068128 8509680
13:15:27 49237 8192000000 10096280704 10564984832 1113797584 11.968 98 27 54 0 1203931600 83741296 6392720
13:17:20 76517 8192000000 10091267952 10587398144 1118974816 12.017 98 19 38 1 1211600016 88677248 3947952
13:19:40 25437 8192000000 10109438480 10540658688 1135146336 12.170 100 40 81 0 1203875520 60493024 8236160
13:21:08 3771 8192000000 10100964800 10528743424 1139637024 12.212 100 50 99 1 1187011744 38091792 9282928
13:22:13 98738 8192000000 10092887040 10543624192 1132574960 12.146 99 40 76 1 1206411504 65307696 8528848
13:25:05 58338 8192000000 10096635360 10560143360 1146741008 12.279 100 15 30 0 1201608768 51484016 3383744
13:27:06 61004 8192000000 10119551008 10602352640 1129596144 12.118 98 15 30 0 1196169600 62806544 3766912
13:29:11 70370 8192000000 10177179056 10674630656 1137958560 12.196 99 19 36 1 1264208784 121853648 4396576
13:31:23 59480 8192000000 10096148432 10580336640 1116991824 11.999 98 21 42 0 1200233952 78339056 4903072
13:33:23 24618 8192000000 10138453744 10581041152 1143451680 12.248 100 30 60 1 1213170320 63292272 6426368
13:34:54 67811 8192000000 10127735056 10571599872 1113481776 11.965 98 23 46 0 1194181344 75697024 5002544
13:37:05 30688 8192000000 8992577776 9258471424 138419936 1.661 13 4 8 0 183501888 44194768 887184

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-12-2022 13:38:38 to 30-12-2022 13:38:54.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 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 30-12-2022 13:38:55 to 30-12-2022 13:38:57.

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          228320
MON$OLDEST_ACTIVE               228321
MON$OLDEST_SNAPSHOT             228321
MON$NEXT_TRANSACTION            228321
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                       2108608
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

30.12.22 13:38:58. 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-12-30 13:39:00 No severe PSQL-related problems occured
30.12.22 13:39:02. End of report.