Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 12.01.2022 10: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 16226580 kB
MemAvailable 31256452 kB
Buffers 0 kB
Cached 15426488 kB
SwapCached 2332 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.1
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-01-12 10:37:11
DTS_END                         2022-01-12 10:37:11.0060
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/58732
TRN_ID                          216792
ATT_ID                          654
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: 8803.40 27955 1056408
customer order: creation 167.16 479 20059
customer order: refuse 14.52 463 1742
order to supplier: creation 45.28 1343 5434
order to supplier: removal 3.21 177597 385
invoice (draft): creation 45.37 3469 5444
invoice (draft): removal 49.34 1022 5921
invoice accept: apply 41.07 28262 4928
invoice accept: cancel 44.45 1289 5334
customer reserve: creation 3808.93 267 457072
customer reserve: removal 4290.17 103 514820
realization accept: apply 82.88 304 9945
realization accept: cancel 82.17 175 9860
payment from customer: creation 58.98 183 7077
payment from customer: removal 5.76 155 691
payment to supplier: creation 47.98 201 5758
payment to supplier: removal 3.63 131 435
service: total inventory turnovers 3.94 4527 473
service: total monetary turnovers 7.68 436 921
service: aggregate perf. data 0.88 16093 105
service: refresh index statistics 0.03 322608 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-01-2022 10:38:09 to 12-01-2022 10:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8121
TEST_TIME 2 7514
TEST_TIME 3 7171
TEST_TIME 4 6744
TEST_TIME 5 6751
TEST_TIME 6 7213
TEST_TIME 7 7144
TEST_TIME 8 7551
TEST_TIME 9 7061
TEST_TIME 10 6975
TEST_TIME 11 6811
TEST_TIME 12 6696
TEST_TIME 13 6626
TEST_TIME 14 6767
TEST_TIME 15 7005
TEST_TIME 16 7004
TEST_TIME 17 7042
TEST_TIME 18 7046
TEST_TIME 19 7230
TEST_TIME 20 7307
TEST_TIME 21 7365
TEST_TIME 22 7427
TEST_TIME 23 7394
TEST_TIME 24 7356
TEST_TIME 25 7402
TEST_TIME 26 7567
TEST_TIME 27 7886
TEST_TIME 28 8411
TEST_TIME 29 8295
TEST_TIME 30 9005
TEST_TIME 31 9161
TEST_TIME 32 9317
TEST_TIME 33 9366
TEST_TIME 34 9642
TEST_TIME 35 10043
TEST_TIME 36 10538
TEST_TIME 37 10369
TEST_TIME 38 10257
TEST_TIME 39 10254
TEST_TIME 40 10258
TEST_TIME 41 10209
TEST_TIME 42 10107
TEST_TIME 43 9909
TEST_TIME 44 9883
TEST_TIME 45 9765
TEST_TIME 46 9692
TEST_TIME 47 9571
TEST_TIME 48 9538
TEST_TIME 49 9481
TEST_TIME 50 9395
TEST_TIME 51 9317
TEST_TIME 52 9424
TEST_TIME 53 9400
TEST_TIME 54 9409
TEST_TIME 55 9350
TEST_TIME 56 9338
TEST_TIME 57 9263
TEST_TIME 58 9240
TEST_TIME 59 9143
TEST_TIME 60 9143
TEST_TIME 61 9166
TEST_TIME 62 9159
TEST_TIME 63 9115
TEST_TIME 64 9045
TEST_TIME 65 9067
TEST_TIME 66 8953
TEST_TIME 67 8883
TEST_TIME 68 8896
TEST_TIME 69 8936
TEST_TIME 70 8969
TEST_TIME 71 8984
TEST_TIME 72 8968
TEST_TIME 73 9050
TEST_TIME 74 8988
TEST_TIME 75 9017
TEST_TIME 76 9063
TEST_TIME 77 9237
TEST_TIME 78 9294
TEST_TIME 79 9241
TEST_TIME 80 9283
TEST_TIME 81 9274
TEST_TIME 82 9201
TEST_TIME 83 9165
TEST_TIME 84 9095
TEST_TIME 85 9102
TEST_TIME 86 9048
TEST_TIME 87 9019
TEST_TIME 88 9012
TEST_TIME 89 8945
TEST_TIME 90 8920
TEST_TIME 91 8917
TEST_TIME 92 8875
TEST_TIME 93 8926
TEST_TIME 94 8911
TEST_TIME 95 8918
TEST_TIME 96 8936
TEST_TIME 97 8901
TEST_TIME 98 8913
TEST_TIME 99 8938
TEST_TIME 100 8949
TEST_TIME 101 8932
TEST_TIME 102 8978
TEST_TIME 103 9025
TEST_TIME 104 8975
TEST_TIME 105 8925
TEST_TIME 106 8908
TEST_TIME 107 8933
TEST_TIME 108 8868
TEST_TIME 109 8851
TEST_TIME 110 8842
TEST_TIME 111 8798
TEST_TIME 112 8795
TEST_TIME 113 8771
TEST_TIME 114 8824
TEST_TIME 115 8829
TEST_TIME 116 8838
TEST_TIME 117 8913
TEST_TIME 118 8878
TEST_TIME 119 8889
TEST_TIME 120 8836

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 12-01-2022 10:38:12 to 12-01-2022 10:38:16.

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 1475700 1475700 0 0.00 0 15626 15 0 0
doc_list_biud 1475684 1475684 0 0.00 0 43829 43 0 0
sp_add_invoice_to_stock 5047 4928 119 2.35 36 273900 28263 0 119
sp_cancel_adding_invoice 5334 5334 0 0.00 6 93428 1289 0 0
sp_cancel_client_order 1742 1742 0 0.00 6 11989 463 0 0
sp_cancel_customer_reserve 514820 514820 0 0.00 1 22977 104 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 4 4879 155 0 0
sp_cancel_pay_to_supplier 435 435 0 0.00 3 3340 132 0 0
sp_cancel_supplier_invoice 5932 5921 11 0.18 3 43833 1023 0 11
sp_cancel_supplier_order 385 385 0 0.00 10 1411185 177597 0 0
sp_cancel_write_off 9860 9860 0 0.00 2 15628 176 0 0
sp_client_order 20059 20059 0 0.00 6 28750 479 0 0
sp_customer_reserve 457072 457072 0 0.00 1 51006 267 0 0
sp_fill_shopping_cart 30925 30925 0 0.00 2 29766 90 0 0
sp_fill_shopping_cart_clo_res 457038 457038 0 0.00 0 19249 21 0 0
sp_get_clo_for_invoice 4882 4882 0 0.00 0 35958 306 0 0
sp_kill_cost_storno 16320 16320 0 0.00 0 15623 81 0 0
sp_kill_qstorno_ret_qs2qd 1045806 1045806 0 0.00 0 43827 45 0 0
sp_kill_qty_storno 552887 552887 0 0.00 0 43828 105 0 0
sp_lock_selected_doc 20501 20501 0 0.00 0 9776 9 0 0
sp_make_cost_storno 27661 27661 0 0.00 0 13946 81 0 0
sp_multiply_rows_for_pdistr 27660 27660 0 0.00 0 9541 43 0 0
sp_multiply_rows_for_qdistr 20057 20057 0 0.00 0 11619 192 0 0
sp_pay_from_customer 7077 7077 0 0.00 5 7040 184 0 0
sp_pay_to_supplier 5758 5758 0 0.00 7 9860 201 0 0
sp_payment_common 12834 12834 0 0.00 2 9752 48 0 0
sp_qd_handle_on_cancel_clo 1742 1742 0 0.00 0 11979 383 0 0
sp_qd_handle_on_invoice_upd_sts 10216 10216 0 0.00 0 13425 566 0 0
sp_qd_handle_on_reserve_upd_sts 9943 9943 0 0.00 0 26732 75 0 0
sp_reserve_write_off 9945 9945 0 0.00 9 26850 304 0 0
sp_supplier_invoice 5444 5444 0 0.00 7 110270 3469 0 0
sp_supplier_order 5434 5434 0 0.00 8 23773 1344 0 0
srv_aggregate_perf_data 105 105 0 0.00 2619 191184 16093 0 0
srv_fill_mon_cache_memory 70 70 0 0.00 642 107954 32636 0 0
srv_find_qd_qs_mism 1007322 1007322 0 0.00 0 9842 11 0 0
srv_make_invnt_saldo 473 473 0 0.00 53 67125 4527 0 0
srv_make_money_saldo 921 921 0 0.00 2 13136 437 0 0
srv_recalc_idx_stat 4 4 0 0.00 96805 456762 322608 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4932 4932 0 0.00 1 9375 1036 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 2 7441 527 0 0
t$perf-norm:sp_cancel_client_order 1742 1742 0 0.00 0 3284 35 0 0
t$perf-norm:sp_cancel_customer_reserve 675 675 0 0.00 0 4161 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 2182 23 0 0
t$perf-norm:sp_cancel_pay_to_supplier 435 435 0 0.00 0 1499 17 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 1052 50 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 107874 8029 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 4917 27 0 0
t$perf-norm:sp_client_order 20060 20060 0 0.00 0 13097 45 0 0
t$perf-norm:sp_customer_reserve 1079 1079 0 0.00 0 5541 28 0 0
t$perf-norm:sp_pay_from_customer 7077 7077 0 0.00 0 5364 20 0 0
t$perf-norm:sp_pay_to_supplier 5758 5758 0 0.00 0 3279 19 0 0
t$perf-norm:sp_reserve_write_off 9945 9945 0 0.00 0 7204 25 0 0
t$perf-norm:sp_supplier_invoice 5445 5445 0 0.00 0 4298 36 0 0
t$perf-norm:sp_supplier_order 5434 5434 0 0.00 0 6604 37 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 517 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 70 70 0 0.00 0 101 8 0 0
t$perf-norm:srv_make_invnt_saldo 473 473 0 0.00 0 2975 26 0 0
t$perf-norm:srv_make_money_saldo 921 921 0 0.00 0 3671 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 171 55 0 0
v_add_invoice_to_stock 10000 9762 238 2.38 0 4829 27 0 238
v_all_customers 36706 36706 0 0.00 0 153 4 0 0
v_all_suppliers 30124 30124 0 0.00 0 150 5 0 0
v_all_wares 40108 40108 0 0.00 0 199 4 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 150 12 0 0
v_cancel_client_order 3484 3484 0 0.00 0 2490 12 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 870 12 0 0
v_cancel_customer_reserve 1350 1350 0 0.00 1 557 12 0 0
v_cancel_payment_to_supplier 870 870 0 0.00 0 3288 17 0 0
v_cancel_supplier_invoice 794 772 22 2.77 0 578 29 0 22
v_cancel_supplier_order 770 770 0 0.00 0 1695 16 0 0
v_cancel_write_off 1972 1972 0 0.00 0 2472 25 0 0
v_max_id_clo_ord 5431 5431 0 0.00 0 1252 5 0 0
v_max_id_clo_res 1079 1079 0 0.00 0 2033 20 0 0
v_max_id_ord_sup 5437 5437 0 0.00 0 7321 10 0 0
v_max_non_paid_invoice 1563 1563 0 0.00 0 3749 11 0 0
v_max_non_paid_realizn 3712 3712 0 0.00 0 1811 8 0 0
v_min_id_clo_ord 5431 5431 0 0.00 0 1928 9 0 0
v_min_id_clo_res 1079 1079 0 0.00 0 788 9 0 0
v_min_id_ord_sup 5437 5437 0 0.00 0 8133 17 0 0
v_min_non_paid_invoice 5757 5757 0 0.00 0 7601 16 0 0
v_min_non_paid_realizn 7077 7077 0 0.00 0 6949 18 0 0
v_our_firm 10132 10132 0 0.00 0 62 5 0 0
v_reserve_write_off 19886 19886 0 0.00 0 3314 11 0 0
x_lock_depdocs_on_canc_invoice 10869 10869 0 0.00 0 18110 136 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 3354 370 0 0
x_make_qstorno_1000_1200 5434 5434 0 0.00 2 23759 1155 0 0
x_make_qstorno_1200_2000 5444 5444 0 0.00 2 110016 3226 0 0
x_make_qstorno_2100_3300 457065 457065 0 0.00 0 43511 232 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-01-2022 10:38:17 to 12-01-2022 10:38:21.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:15 23615 8192000000 9716156848 10013245440 796440064 8.860 100 89 178 0 826418944 8137632 21841248
08:09:43 15565 8192000000 9761607632 10065342464 837415536 9.274 100 92 184 1 870992496 11632176 21944784
08:11:02 11799 8192000000 9790023040 10104401920 864510912 9.545 100 81 162 1 900761360 17345952 18904496
08:12:16 14532 8192000000 9841964672 10171129856 900559040 9.904 100 76 150 1 948960416 31888272 16513104
08:13:38 6940 8192000000 9902846192 10240806912 948903232 10.380 100 84 168 1 1016784448 50083328 17797888
08:14:49 4848 8192000000 9953730112 10298425344 982805040 10.711 100 89 170 2 1065355632 64932288 17618304
08:15:58 2384 8192000000 9990480080 10346250240 1006191328 10.939 100 91 180 1 1108693920 84458800 18043792
08:17:02 1451 8192000000 10001541776 10366300160 1026862960 11.138 100 89 169 0 1118253568 74807216 16583392
08:18:07 1174 8192000000 10033689728 10408386560 1046297680 11.325 100 96 184 1 1156056288 91533488 18225120
08:19:09 1178 8192000000 10031064016 10410545152 1057357264 11.431 100 93 176 0 1152258000 76801504 18099232
08:20:11 1131 8192000000 10039171280 10419126272 1066329552 11.517 100 93 173 0 1162986800 78063872 18593376
08:21:14 1114 8192000000 10059882336 10437353472 1074927232 11.599 100 96 188 1 1174993296 81678240 18387824
08:22:16 4418 8192000000 10034760688 10415529984 1079914912 11.647 100 70 137 1 1164174656 71855456 12404288
08:23:22 1131 8192000000 10041052032 10427879424 1092464208 11.766 100 95 186 1 1163047920 53077920 17505792
08:24:25 1488 8192000000 10062596416 10445373440 1096622720 11.806 100 96 184 0 1181961456 66002912 19335824
08:25:27 1327 8192000000 10065870928 10451238912 1099172304 11.830 100 97 185 2 1190490704 72435072 18883328
08:26:30 1153 8192000000 10074275920 10459217920 1100443312 11.842 100 96 183 0 1191682944 71978720 19260912
08:27:32 1503 8192000000 10081372320 10468249600 1103730240 11.873 100 98 189 0 1197875056 75653424 18491392
08:28:34 3274 8192000000 10043573600 10426421248 1090676064 11.749 100 69 132 1 1165339776 61291216 13372496
08:29:40 1076 8192000000 10082517440 10469445632 1099892496 11.837 100 95 184 1 1204377952 83717040 20768416
08:30:43 836 8192000000 10045895776 10428518400 1079810608 11.646 100 94 183 2 1168067568 68632608 19624352
08:31:46 1959 8192000000 10038143440 10429767680 1090875216 11.751 100 70 136 0 1165032624 60849888 13307520
08:32:48 1899 8192000000 10013849248 10401021952 1072555872 11.576 100 82 158 1 1145580384 56712256 16312256
08:33:54 1085 8192000000 10012876096 10391764992 1060592448 11.462 100 86 167 1 1137901648 58280640 19028560
08:34:57 1223 8192000000 9983750368 10355011584 1031701184 11.185 100 91 176 1 1106303392 55525744 19076464
08:35:59 1317 8192000000 9985912896 10352267264 1024099120 11.112 99 94 177 4 1109210224 65346672 19764432
08:37:03 1890 8192000000 9997851248 10375520256 1033385280 11.201 100 80 156 0 1124825312 75792128 15647904
08:38:15 13971 8192000000 9996415040 10369462272 1047544688 11.337 100 76 139 1 1118431088 57462240 13424160
08:39:34 1295 8192000000 10029094064 10403602432 1056543040 11.423 99 90 174 0 1147140848 72164800 18433008
08:40:40 11596 8192000000 10038297056 10418798592 1067068832 11.524 100 65 126 4 1154494784 74847600 12578352
08:41:58 13930 8192000000 10076725424 10458411008 1085064464 11.696 100 73 144 0 1203822112 104066480 14691168
08:43:18 950 8192000000 10117961664 10499047424 1107091520 11.905 100 96 181 0 1234636944 108535776 19009648
08:44:24 1723 8192000000 10134418768 10520006656 1122565088 12.051 100 91 173 1 1251167024 109026832 19575104
08:45:30 2075 8192000000 10146723696 10556854272 1124141872 12.066 100 70 138 0 1263808496 124051888 15614736
08:46:42 8469 8192000000 10190864672 10592296960 1136997552 12.187 100 74 145 1 1309436464 156790032 15648880
08:47:55 6548 8192000000 10221633376 10619289600 1150735456 12.316 100 68 129 1 1331032448 166372032 13924960
08:49:08 2232 8192000000 10253935760 10653040640 1164941344 12.450 100 72 140 1 1369816720 188863824 16011552
08:50:16 1825 8192000000 10296240112 10695303168 1174899776 12.543 100 91 179 1 1409107760 214110704 20097280
08:51:23 3670 8192000000 10322379904 10733670400 1169785728 12.495 99 80 155 1 1431343520 244019328 17538464
08:52:31 1400 8192000000 10315199312 10726154240 1175662272 12.550 100 97 189 2 1424387952 227862896 20862784
08:53:36 1294 8192000000 10353286352 10756235264 1184720832 12.634 100 91 179 1 1467012480 262985008 19306640
08:54:40 1047 8192000000 10402955072 10806919168 1192934720 12.711 100 89 172 1 1512421872 301157856 18329296
08:55:44 2429 8192000000 10419547856 10825318400 1180990176 12.599 100 95 190 1 1530086128 327855472 21240480
08:56:50 1910 8192000000 10430404768 10838978560 1187295712 12.658 100 83 163 0 1536471232 330511760 18663760
08:57:56 1486 8192000000 10456309872 10870558720 1199775440 12.774 100 89 176 2 1573606416 353641008 20189968
08:59:00 821 8192000000 10508927136 10910789632 1194329792 12.724 100 95 183 0 1614707968 398511632 21866544
09:00:04 1524 8192000000 10509515584 10918617088 1183166304 12.620 100 95 184 0 1612333920 407601392 21566224
09:01:09 639 8192000000 10564696560 10980646912 1189800592 12.682 100 91 182 0 1665941392 453919840 22220960
09:02:13 1578 8192000000 10600812656 11018182656 1192684272 12.708 100 94 184 1 1705650336 490421264 22544800
09:03:19 1028 8192000000 10582536544 11041501184 1178332480 12.575 100 95 182 1 1683800112 483443296 22024336
09:04:23 803 8192000000 10488350928 11012993024 1170917344 12.505 100 93 182 0 1588716416 395261872 22537200
09:05:28 5161 8192000000 10480156032 10925391872 1165873904 12.458 99 71 140 0 1577705696 394131536 17700256
09:06:45 6852 8192000000 10412550688 10949484544 1149806864 12.308 98 51 102 0 1499731472 336417872 13506736
09:07:54 3142 8192000000 10431099968 10857365504 1175463984 12.548 100 68 136 0 1535347904 344062432 15821488
09:09:01 27209 8192000000 10351471424 10819346432 1158350928 12.388 100 32 64 0 1479296016 312467664 8477424
09:10:42 31502 8192000000 10300958544 10802429952 1157028272 12.375 100 24 48 0 1468374528 304726240 6620016
09:12:53 97023 8192000000 10053849904 10495815680 1094333264 11.784 97 12 24 0 1199851040 102629776 2888000
09:15:39 96479 8192000000 10085593456 10564198400 1106271696 11.897 98 22 39 0 1139917168 29330688 4314784
09:18:20 50240 8192000000 10086875024 10529509376 1114246848 11.973 98 34 66 1 1148319696 27985696 6087152
09:20:12 41634 8192000000 10126642480 10553090048 1139138896 12.207 100 50 95 0 1215830656 67647888 9043872
09:21:54 64362 8192000000 10090433248 10570506240 1139961424 12.215 100 41 75 0 1235207552 87354368 7891760
09:24:06 34131 8192000000 10063755360 10505699328 1128337040 12.106 99 21 42 1 1170272176 37962080 3973056
09:25:44 25223 8192000000 10101180720 10547482624 1135336960 12.172 100 25 50 3 1210209312 69432064 5440288
09:27:10 53570 8192000000 10172720112 10619191296 1110783568 11.940 97 46 91 1 1226565232 106134192 9647472
09:29:13 43452 8192000000 10142225680 10617810944 1123608944 12.061 99 18 37 1 1257785600 130071264 4105392
09:30:57 30404 8192000000 10123338880 10600914944 1118072224 12.009 99 12 24 1 1223095312 102239264 2783824
09:32:32 50535 8192000000 10094928720 10542809088 1103358176 11.869 98 20 40 0 1175336928 67527936 4450816
09:34:24 44569 8192000000 10084811920 10558218240 1129496736 12.117 100 25 48 1 1173548512 38376336 5675440
09:36:09 59319 8192000000 10153257392 10611687424 1115392656 11.983 98 16 32 2 1216845584 97890544 3562384
09:39:19 51144 8192000000 10059832944 10505310208 1103501360 11.871 98 32 59 0 1135497664 25488576 6507728
09:41:11 65588 8192000000 10060731904 10518032384 1106836304 11.902 99 9 18 0 1156539136 47910432 1792400
09:45:11 80089 8192000000 10106388672 10577096704 1093600384 11.777 97 12 24 0 1187649472 91495760 2553328
09:47:31 25870 8192000000 10083514032 10557165568 1115576960 11.985 99 30 57 0 1183003184 61560800 5865424
09:49:01 59453 8192000000 10107287184 10554503168 1108839536 11.921 98 22 44 1 1194216976 80573568 4803872
09:51:08 42078 8192000000 10179820768 10614218752 1128690224 12.109 98 37 74 0 1232273920 95902736 7680960
09:52:51 52538 8192000000 10168944144 10633183232 1130700016 12.128 99 19 38 0 1257149504 121961184 4488304
09:54:57 67888 8192000000 10087109744 10527555584 1083284368 11.679 95 31 62 1 1146994800 57367376 6343056
09:57:07 23530 8192000000 10108858752 10560204800 1126358928 12.087 100 57 110 3 1225649216 88194992 11095296
09:58:36 81100 8192000000 10070686304 10507583488 1131247488 12.133 100 38 76 1 1183217600 44194208 7775904
10:01:11 62961 8192000000 10058010032 10476322816 1079826944 11.646 95 35 70 0 1134370880 46852464 7691472
10:03:14 41203 8192000000 10058011696 10499506176 1094438816 11.785 97 14 28 0 1140191312 42996880 2755616
10:05:09 74496 8192000000 10078800704 10541903872 1095727824 11.797 97 6 12 0 1135217440 38167664 1321952
10:10:23 28116 8192000000 10099245120 10543042560 1121986480 12.046 99 27 54 0 1178980608 51324560 5669568
10:12:00 20961 8192000000 10081465024 10508476416 1096754800 11.807 97 33 66 1 1145160752 41953616 6452336
10:13:26 50859 8192000000 10052317056 10482769920 1112763792 11.959 98 30 60 0 1152297216 33894528 5638896
10:15:24 11356 8192000000 10105374912 10526961664 1125060448 12.075 100 52 103 0 1199823920 63981328 10782144
10:16:35 59180 8192000000 10050590096 10477342720 1099822384 11.836 97 25 50 0 1162524832 58161296 4541152
10:18:38 18981 8192000000 10154745040 10581901312 1139399376 12.210 100 54 108 2 1233882992 83565312 10918304
10:19:58 58761 8192000000 10072571392 10516987904 1118371456 12.012 98 20 40 0 1189590944 67041120 4178368
10:22:01 107952 8192000000 10139298752 10625830912 1145676160 12.269 100 46 92 1 1243372528 87848176 9848192
10:24:49 57900 8192000000 10097700048 10549641216 1148580016 12.296 100 5 10 0 1215112112 65647392 884704
10:26:48 75634 8192000000 10120682192 10590883840 1124128608 12.066 97 21 42 0 1209989232 81092672 4767952
10:29:04 51845 8192000000 10153108720 10605445120 1134974656 12.168 99 19 38 0 1236209840 97092464 4142720
10:30:56 26347 8192000000 10190235248 10626334720 1144389072 12.257 100 48 96 1 1292793984 137202608 11202304
10:32:30 83558 8192000000 10123260736 10558590976 1099298240 11.831 96 27 53 1 1183312768 78246832 5767696
10:35:12 27969 8192000000 10105852640 10539315200 1147690128 12.288 100 39 75 1 1188482400 32721232 8071040
10:36:40 27834 8192000000 10152751088 10601189376 1144538208 12.258 100 50 100 1 1268045504 113366064 10141232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-01-2022 10:38:23 to 12-01-2022 10:38:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-01-2022 10:38:39 to 12-01-2022 10:38:41.

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          215212
MON$OLDEST_ACTIVE               218517
MON$OLDEST_SNAPSHOT             218517
MON$NEXT_TRANSACTION            218517
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                       2058848
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             769
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

12.01.22 10:38:42. 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-01-12 10:38:45 No severe PSQL-related problems occured
12.01.22 10:38:46. End of report.