Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.05.2022 13: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 16161292 kB
MemAvailable 31394536 kB
Buffers 0 kB
Cached 15579628 kB
SwapCached 2440 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-31 13:37:17
DTS_END                         2022-05-31 13:37:17.5340
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/47000
TRN_ID                          219811
ATT_ID                          575
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: 8936.92 22491 1072431
customer order: creation 169.11 462 20293
customer order: refuse 13.89 389 1667
order to supplier: creation 45.38 1426 5446
order to supplier: removal 3.29 155088 395
invoice (draft): creation 45.18 3165 5421
invoice (draft): removal 48.47 986 5816
invoice accept: apply 41.78 26141 5014
invoice accept: cancel 44.45 1149 5334
customer reserve: creation 3828.10 254 459372
customer reserve: removal 4403.79 90 528455
realization accept: apply 81.97 276 9836
realization accept: cancel 83.08 163 9970
payment from customer: creation 57.48 191 6897
payment from customer: removal 5.74 135 689
payment to supplier: creation 48.75 208 5850
payment to supplier: removal 3.45 100 414
service: total inventory turnovers 4.25 3859 510
service: total monetary turnovers 7.85 301 942
service: aggregate perf. data 0.88 16966 106
service: refresh index statistics 0.03 238479 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-05-2022 13:40:09 to 31-05-2022 13:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4004
TEST_TIME 2 6058
TEST_TIME 3 5597
TEST_TIME 4 5951
TEST_TIME 5 5467
TEST_TIME 6 5550
TEST_TIME 7 5842
TEST_TIME 8 6144
TEST_TIME 9 6225
TEST_TIME 10 6448
TEST_TIME 11 6460
TEST_TIME 12 6627
TEST_TIME 13 7214
TEST_TIME 14 7380
TEST_TIME 15 7386
TEST_TIME 16 7678
TEST_TIME 17 7770
TEST_TIME 18 7902
TEST_TIME 19 8081
TEST_TIME 20 8159
TEST_TIME 21 8212
TEST_TIME 22 8294
TEST_TIME 23 8333
TEST_TIME 24 8855
TEST_TIME 25 8931
TEST_TIME 26 8958
TEST_TIME 27 9011
TEST_TIME 28 9477
TEST_TIME 29 9641
TEST_TIME 30 9872
TEST_TIME 31 10701
TEST_TIME 32 11392
TEST_TIME 33 11369
TEST_TIME 34 11358
TEST_TIME 35 11136
TEST_TIME 36 11042
TEST_TIME 37 10986
TEST_TIME 38 10845
TEST_TIME 39 10769
TEST_TIME 40 10579
TEST_TIME 41 10395
TEST_TIME 42 10440
TEST_TIME 43 10327
TEST_TIME 44 10229
TEST_TIME 45 10312
TEST_TIME 46 10163
TEST_TIME 47 10043
TEST_TIME 48 10030
TEST_TIME 49 9912
TEST_TIME 50 9966
TEST_TIME 51 9809
TEST_TIME 52 9715
TEST_TIME 53 9647
TEST_TIME 54 9576
TEST_TIME 55 9579
TEST_TIME 56 9636
TEST_TIME 57 9598
TEST_TIME 58 9457
TEST_TIME 59 9429
TEST_TIME 60 9512
TEST_TIME 61 9431
TEST_TIME 62 9441
TEST_TIME 63 9442
TEST_TIME 64 9378
TEST_TIME 65 9434
TEST_TIME 66 9410
TEST_TIME 67 9456
TEST_TIME 68 9486
TEST_TIME 69 9535
TEST_TIME 70 9517
TEST_TIME 71 9568
TEST_TIME 72 9555
TEST_TIME 73 9527
TEST_TIME 74 9495
TEST_TIME 75 9411
TEST_TIME 76 9432
TEST_TIME 77 9380
TEST_TIME 78 9334
TEST_TIME 79 9260
TEST_TIME 80 9233
TEST_TIME 81 9229
TEST_TIME 82 9145
TEST_TIME 83 9154
TEST_TIME 84 9119
TEST_TIME 85 9136
TEST_TIME 86 9110
TEST_TIME 87 9125
TEST_TIME 88 9157
TEST_TIME 89 9144
TEST_TIME 90 9142
TEST_TIME 91 9139
TEST_TIME 92 9164
TEST_TIME 93 9205
TEST_TIME 94 9182
TEST_TIME 95 9198
TEST_TIME 96 9223
TEST_TIME 97 9256
TEST_TIME 98 9196
TEST_TIME 99 9182
TEST_TIME 100 9136
TEST_TIME 101 9128
TEST_TIME 102 9080
TEST_TIME 103 9037
TEST_TIME 104 9017
TEST_TIME 105 9020
TEST_TIME 106 9046
TEST_TIME 107 9066
TEST_TIME 108 9046
TEST_TIME 109 9036
TEST_TIME 110 9011
TEST_TIME 111 9055
TEST_TIME 112 9064
TEST_TIME 113 9113
TEST_TIME 114 9070
TEST_TIME 115 9050
TEST_TIME 116 9089
TEST_TIME 117 9103
TEST_TIME 118 9040
TEST_TIME 119 9022
TEST_TIME 120 8954

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 31-05-2022 13:40:12 to 31-05-2022 13:40: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 1495404 1495404 0 0.00 0 31828 14 0 0
doc_list_biud 1495400 1495400 0 0.00 0 34882 37 0 0
sp_add_invoice_to_stock 5085 5014 71 1.39 40 213996 26142 0 71
sp_cancel_adding_invoice 5334 5334 0 0.00 6 85245 1150 0 0
sp_cancel_client_order 1667 1667 0 0.00 5 9792 390 0 0
sp_cancel_customer_reserve 528455 528455 0 0.00 1 21951 90 0 0
sp_cancel_pay_from_customer 689 689 0 0.00 4 10372 136 0 0
sp_cancel_pay_to_supplier 414 414 0 0.00 4 1341 101 0 0
sp_cancel_supplier_invoice 5823 5816 7 0.12 3 34888 987 0 7
sp_cancel_supplier_order 395 395 0 0.00 8 1503423 155089 0 0
sp_cancel_write_off 9970 9970 0 0.00 2 31837 163 0 0
sp_client_order 20293 20293 0 0.00 6 24884 463 0 0
sp_customer_reserve 459372 459372 0 0.00 1 104508 254 0 0
sp_fill_shopping_cart 31157 31157 0 0.00 2 31158 85 0 0
sp_fill_shopping_cart_clo_res 459343 459343 0 0.00 0 22134 18 0 0
sp_get_clo_for_invoice 4968 4968 0 0.00 0 43506 280 0 0
sp_kill_cost_storno 16407 16407 0 0.00 0 31820 74 0 0
sp_kill_qstorno_ret_qs2qd 1073091 1073091 0 0.00 0 34880 39 0 0
sp_kill_qty_storno 566440 566440 0 0.00 0 34882 91 0 0
sp_lock_selected_doc 20432 20432 0 0.00 0 12100 8 0 0
sp_make_cost_storno 27550 27550 0 0.00 0 14251 89 0 0
sp_multiply_rows_for_pdistr 27550 27550 0 0.00 0 9431 37 0 0
sp_multiply_rows_for_qdistr 20293 20293 0 0.00 0 13411 166 0 0
sp_pay_from_customer 6897 6897 0 0.00 6 24832 191 0 0
sp_pay_to_supplier 5850 5850 0 0.00 6 26252 208 0 0
sp_payment_common 12746 12746 0 0.00 2 16115 50 0 0
sp_qd_handle_on_cancel_clo 1667 1667 0 0.00 0 9746 318 0 0
sp_qd_handle_on_invoice_upd_sts 10301 10301 0 0.00 0 32545 486 0 0
sp_qd_handle_on_reserve_upd_sts 9836 9836 0 0.00 0 17891 66 0 0
sp_reserve_write_off 9836 9836 0 0.00 9 17973 276 0 0
sp_supplier_invoice 5421 5421 0 0.00 10 106065 3166 0 0
sp_supplier_order 5446 5446 0 0.00 7 51651 1426 0 0
srv_aggregate_perf_data 106 106 0 0.00 2679 172902 16967 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 802 147147 38942 0 0
srv_find_qd_qs_mism 1024005 1024005 0 0.00 0 21283 11 0 0
srv_make_invnt_saldo 510 510 0 0.00 85 76673 3860 0 0
srv_make_money_saldo 942 942 0 0.00 1 9753 302 0 0
srv_recalc_idx_stat 4 4 0 0.00 112797 383852 238480 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5014 5014 0 0.00 1 12383 870 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 1 7424 443 0 0
t$perf-norm:sp_cancel_client_order 1667 1667 0 0.00 0 1501 28 0 0
t$perf-norm:sp_cancel_customer_reserve 683 683 0 0.00 0 9665 39 0 0
t$perf-norm:sp_cancel_pay_from_customer 689 689 0 0.00 0 1040 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 414 414 0 0.00 0 389 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 690 21 0 0
t$perf-norm:sp_cancel_supplier_order 395 395 0 0.00 0 88054 7056 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 7277 28 0 0
t$perf-norm:sp_client_order 20293 20293 0 0.00 0 19276 36 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 11856 28 0 0
t$perf-norm:sp_pay_from_customer 6897 6897 0 0.00 0 20510 22 0 0
t$perf-norm:sp_pay_to_supplier 5850 5850 0 0.00 0 14546 29 0 0
t$perf-norm:sp_reserve_write_off 9836 9836 0 0.00 0 8588 24 0 0
t$perf-norm:sp_supplier_invoice 5421 5421 0 0.00 0 5039 28 0 0
t$perf-norm:sp_supplier_order 5446 5446 0 0.00 0 7194 30 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 1110 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 396 13 0 0
t$perf-norm:srv_make_invnt_saldo 510 510 0 0.00 0 891 14 0 0
t$perf-norm:srv_make_money_saldo 942 942 0 0.00 0 1602 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 1042 275 0 0
v_add_invoice_to_stock 10076 9934 142 1.40 0 6304 26 0 142
v_all_customers 36882 36882 0 0.00 0 93 3 0 0
v_all_suppliers 30486 30486 0 0.00 0 79 4 0 0
v_all_wares 40586 40586 0 0.00 0 115 4 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 390 12 0 0
v_cancel_client_order 3334 3334 0 0.00 0 2633 9 0 0
v_cancel_customer_prepayment 1378 1378 0 0.00 0 5415 14 0 0
v_cancel_customer_reserve 1366 1366 0 0.00 0 820 9 0 0
v_cancel_payment_to_supplier 828 828 0 0.00 0 728 10 0 0
v_cancel_supplier_invoice 800 786 14 1.75 0 2412 31 0 14
v_cancel_supplier_order 790 790 0 0.00 0 1615 12 0 0
v_cancel_write_off 1974 1974 0 0.00 0 2460 22 0 0
v_max_id_clo_ord 5446 5446 0 0.00 0 5756 6 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 2791 16 0 0
v_max_id_ord_sup 5418 5418 0 0.00 0 6920 8 0 0
v_max_non_paid_invoice 1471 1471 0 0.00 0 7696 12 0 0
v_max_non_paid_realizn 3658 3658 0 0.00 0 929 6 0 0
v_min_id_clo_ord 5446 5446 0 0.00 0 5568 9 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 3746 16 0 0
v_min_id_ord_sup 5418 5418 0 0.00 0 7329 9 0 0
v_min_non_paid_invoice 5850 5850 0 0.00 0 8414 22 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 9185 24 0 0
v_our_firm 10180 10180 0 0.00 0 74 4 0 0
v_reserve_write_off 19670 19670 0 0.00 0 2638 10 0 0
x_lock_depdocs_on_canc_invoice 10757 10757 0 0.00 0 16128 128 0 0
x_lock_depdocs_on_canc_sup_ord 395 395 0 0.00 0 10617 353 0 0
x_make_qstorno_1000_1200 5446 5446 0 0.00 3 51620 1251 0 0
x_make_qstorno_1200_2000 5421 5421 0 0.00 5 94085 2936 0 0
x_make_qstorno_2100_3300 459371 459371 0 0.00 0 104506 224 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-05-2022 13:40:17 to 31-05-2022 13:40: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
11:07:21 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:21 23256 8192000000 9724108000 10024099840 801868992 8.915 100 89 178 0 831589904 8054080 21666832
11:09:53 24079 8192000000 9771217664 10080223232 846364864 9.364 100 95 190 0 880890400 12364176 22161360
11:11:21 19338 8192000000 9805338000 10125115392 869519728 9.595 100 83 164 1 906937696 18515632 18902336
11:12:42 10248 8192000000 9861469728 10195996672 914690816 10.044 100 78 156 0 974548960 43034480 16823664
11:13:58 5653 8192000000 9913765936 10260344832 956858096 10.458 100 88 168 0 1038438448 64424608 17155744
11:15:06 2046 8192000000 9973066752 10325000192 992006288 10.801 100 94 177 1 1088317056 77803072 18507696
11:16:10 1223 8192000000 9986182464 10350444544 1028239072 11.151 100 93 180 1 1109427248 62917120 18271056
11:17:13 1277 8192000000 10003189568 10375118848 1045466944 11.317 100 91 174 1 1126899728 64183600 17249184
11:18:16 1047 8192000000 10029472752 10407231488 1061107424 11.467 100 91 173 1 1152250064 73288064 17854576
11:19:18 5300 8192000000 10037340976 10414104576 1068630560 11.539 100 73 133 0 1158518176 78112800 11774816
11:20:24 998 8192000000 10035394896 10416181248 1072536848 11.576 100 96 179 0 1159453312 67385824 19530640
11:21:26 1184 8192000000 10051293808 10434719744 1078974192 11.638 100 93 176 0 1173383072 75633392 18775488
11:22:27 1327 8192000000 10059401664 10442985472 1086217440 11.707 100 96 188 0 1180001904 75250400 18534064
11:23:30 1116 8192000000 10060578880 10445561856 1085927120 11.704 100 95 183 1 1180677248 77274496 17475632
11:24:33 1795 8192000000 10069962832 10465112064 1088880848 11.732 100 79 156 1 1191842416 87551424 15410144
11:25:41 3161 8192000000 10053436912 10435559424 1094102560 11.782 100 92 170 0 1169337200 58481376 16753264
11:26:45 3061 8192000000 10066790912 10456039424 1094745088 11.788 100 78 149 2 1184492704 75053856 14693760
11:27:49 7902 8192000000 10029621120 10425036800 1088953840 11.733 100 59 113 3 1158213600 58707280 10552480
11:28:58 5220 8192000000 10039815712 10422169600 1084575120 11.691 100 69 127 2 1164977808 68781056 11621632
11:30:04 900 8192000000 10041364576 10427994112 1080641792 11.654 100 93 181 0 1168040912 68800464 18598656
11:31:06 1281 8192000000 10036329888 10415427584 1071802704 11.569 100 95 182 1 1160193264 68946544 19444016
11:32:08 1176 8192000000 10013317056 10388852736 1044019696 11.303 99 92 180 0 1138018368 73683424 20315248
11:33:10 1167 8192000000 10025918304 10400407552 1049640928 11.357 100 97 185 2 1144678128 76031664 19005536
11:34:12 841 8192000000 10005577216 10383470592 1049418624 11.355 100 89 176 0 1126795200 58866512 18510064
11:35:14 3585 8192000000 9990369504 10380296192 1045216496 11.315 100 72 141 1 1114162592 54604848 14341248
11:36:20 2117 8192000000 9973615664 10358038528 1047175824 11.334 100 81 158 2 1101118848 36767728 17175296
11:37:23 1120 8192000000 9984629792 10362429440 1051753712 11.377 100 89 162 0 1107209344 39513104 15942528
11:38:33 15814 8192000000 10026860656 10412818432 1067002048 11.523 100 61 116 0 1152158352 72876400 12279904
11:39:55 22969 8192000000 10055588640 10439450624 1085697920 11.702 100 62 120 0 1177763392 80027312 12038160
11:41:27 1760 8192000000 10128380672 10519945216 1106834048 11.902 100 80 154 5 1250408848 126188672 17386128
11:42:39 13074 8192000000 10158080112 10553675776 1120691936 12.034 100 71 139 0 1275063760 139642032 14729792
11:43:57 1662 8192000000 10208412624 10606141440 1131658992 12.137 100 90 169 2 1324944240 174132112 19153136
11:45:03 1268 8192000000 10217273488 10620612608 1134874320 12.167 100 90 166 1 1330919184 178906944 17137920
11:46:08 1907 8192000000 10259876576 10665517056 1149810880 12.308 100 89 176 0 1371135872 201641264 19683728
11:47:16 1355 8192000000 10276297184 10687242240 1161440896 12.417 100 91 181 1 1391722256 209702912 20578448
11:48:20 1515 8192000000 10286407296 10689478656 1162179296 12.424 100 93 175 0 1396015152 213569472 20266384
11:49:25 1060 8192000000 10323076272 10749403136 1170310448 12.500 100 98 189 0 1432974400 241971472 20692480
11:50:29 800 8192000000 10338613792 10766467072 1178250704 12.574 100 96 187 1 1442902160 242980672 21670784
11:51:33 837 8192000000 10367727776 10780106752 1181480672 12.604 99 93 183 1 1478370864 274572544 22317648
11:52:38 887 8192000000 10380209024 10795548672 1192778160 12.709 100 91 177 2 1491423424 277800000 20845264
11:53:41 1035 8192000000 10395538352 10826493952 1195693600 12.736 100 92 175 1 1508487696 290583568 22210528
11:54:45 1341 8192000000 10442203648 10874834944 1203906544 12.813 100 91 177 0 1552799824 327724800 21168480
11:55:49 1094 8192000000 10468252464 10890244096 1213448432 12.901 100 95 185 1 1576838320 340716416 22673472
11:56:54 957 8192000000 10506453136 10940096512 1206505968 12.837 100 95 185 2 1607811776 376407248 24898560
11:57:56 938 8192000000 10549472912 10962825216 1198053840 12.758 100 98 196 1 1653621440 432915168 22652432
11:59:00 1591 8192000000 10569644848 11000639488 1202423856 12.799 100 72 139 0 1678828080 458468272 17935952
12:00:12 6639 8192000000 10567672448 11022258176 1195824704 12.738 100 49 97 0 1698890896 489352928 13713264
12:01:21 3263 8192000000 10543632384 11025547264 1201253296 12.788 100 61 119 2 1653663504 437528912 14881296
12:02:25 8145 8192000000 10528037712 10985025536 1161526688 12.418 98 40 80 0 1621971296 449414816 11029792
12:03:35 21029 8192000000 10549198464 11016122368 1148404816 12.295 98 37 74 1 1634001632 475958096 9638720
12:05:02 19282 8192000000 10440094432 10955116544 1147450800 12.286 99 27 54 2 1564849056 409971424 7426832
12:06:31 23515 8192000000 10435559696 10912604160 1142461312 12.239 100 29 58 2 1542722448 392192368 8068768
12:08:24 10844 8192000000 10185673744 10709372928 1129169616 12.114 99 37 72 3 1304713024 167403504 8139904
12:09:42 70977 8192000000 10104120112 10593263616 1138842896 12.205 99 13 25 0 1199098800 57838784 2417120
12:11:54 90616 8192000000 10102869712 10585653248 1144538176 12.258 99 25 50 1 1225307088 75829120 4939792
12:14:26 44331 8192000000 10106812112 10571362304 1136560240 12.183 99 12 24 0 1189179936 50160240 2459456
12:16:19 136173 8192000000 10124188128 10579374080 1060418256 11.460 93 16 33 0 1104224048 40630592 3175200
12:19:41 109083 8192000000 10077338096 10534236160 1054164480 11.401 93 19 38 0 1146644336 88207696 4272160
12:22:30 102601 8192000000 10089128272 10555355136 1118741616 12.015 99 19 36 0 1168242832 45197824 4303392
12:25:50 124204 8192000000 10045222544 10508959744 1068237264 11.535 94 29 58 2 1118685712 43691760 6756688
12:28:56 25566 8192000000 10065723440 10506534912 1114767744 11.978 100 29 54 0 1175353824 55167568 5418512
12:30:22 40600 8192000000 10105268448 10542473216 1112454208 11.956 99 40 79 0 1196043904 75411216 8178480
12:32:15 23965 8192000000 10130507280 10599718912 1133736400 12.157 100 32 63 1 1232272288 91028928 7506960
12:33:40 39522 8192000000 10093659680 10566021120 1126772368 12.091 100 10 20 0 1198053600 68152416 3128816
12:35:30 95244 8192000000 10113635088 10610221056 1134683248 12.165 100 12 24 0 1230475248 92573904 3218096
12:38:05 71567 8192000000 10060582896 10522542080 1104815664 11.883 97 18 36 0 1148324384 39838016 3670704
12:40:18 75779 8192000000 10097594928 10541772800 1114564160 11.976 98 21 41 0 1172660800 54241152 3855488
12:42:34 20737 8192000000 10100002688 10546565120 1135663344 12.175 100 41 76 0 1194506688 50825120 8018224
12:43:56 55290 8192000000 10140400960 10616811520 1135617488 12.174 100 30 59 0 1233424064 91577984 6228592
12:45:57 55654 8192000000 10063713376 10517147648 1088802880 11.731 96 23 46 0 1132556528 39100320 4653328
12:48:01 42114 8192000000 10079713680 10563776512 1120808496 12.035 99 17 34 1 1225385888 100709600 3867792
12:49:47 5381 8192000000 10089596208 10511724544 1129745376 12.119 100 69 124 1 1180637024 38717872 12173776
12:50:55 42375 8192000000 10111509328 10547662848 1122657776 12.052 99 36 73 0 1211136896 80892912 7586208
12:52:38 97501 8192000000 10082208464 10540417024 1111407280 11.946 98 36 70 1 1185793824 67389968 6996576
12:55:16 14856 8192000000 10056408128 10518618112 1104553840 11.881 98 32 54 4 1149331632 38732640 6045152
12:56:38 55627 8192000000 10071226400 10526789632 1092573088 11.767 97 14 28 0 1153659360 57769408 3316864
12:58:34 66256 8192000000 10069714368 10518880256 1108281696 11.916 99 9 17 0 1150281456 39424320 2575440
13:01:18 64140 8192000000 10056297648 10510749696 1094593536 11.786 97 10 18 0 1126581456 30090400 1897520
13:03:23 43919 8192000000 10090222480 10520408064 1133514784 12.154 100 33 66 0 1188425296 47902816 7007696
13:05:08 38894 8192000000 10102025168 10546024448 1113346224 11.964 98 24 48 1 1180262688 61898912 5017552
13:06:50 21057 8192000000 10109857440 10544476160 1131197280 12.133 99 42 84 2 1192845056 53171408 8476368
13:08:16 58995 8192000000 10106107248 10592792576 1097600304 11.815 96 29 57 1 1186370080 83226784 5542992
13:10:25 40367 8192000000 10092461808 10545766400 1126117168 12.085 99 44 86 0 1187617792 52847392 8653232
13:12:11 44632 8192000000 10107116768 10523222016 1090488720 11.747 96 36 69 3 1158847984 61144624 7214640
13:13:57 28505 8192000000 10099184384 10545086464 1114423760 11.974 99 42 80 0 1181116848 59026048 7667040
13:15:26 147144 8192000000 10075150592 10517422080 1116617968 11.995 99 43 85 1 1198366304 73210656 8537680
13:18:56 35463 8192000000 10066538096 10535960576 1111781728 11.949 98 11 21 2 1180497312 66506288 2209296
13:20:37 60845 8192000000 10075222960 10530836480 1078545216 11.634 95 21 41 1 1147466448 63720368 5200864
13:22:48 82907 8192000000 10075456608 10613030912 1111522400 11.947 98 12 24 4 1193598016 79247504 2828112
13:25:15 40337 8192000000 10068602848 10497871872 1122884656 12.054 100 44 87 1 1157668848 24267696 10516496
13:27:36 15579 8192000000 10106398928 10537086976 1117778816 12.006 98 55 102 3 1188583200 60872432 9931952
13:28:53 31638 8192000000 10096355296 10542194688 1131002640 12.131 100 33 66 1 1197348464 59646384 6699440
13:30:29 87113 8192000000 10086276400 10543964160 1091683168 11.759 96 13 26 0 1151091424 56925248 2483008
13:32:57 66942 8192000000 10063837184 10541359104 1085586608 11.701 96 24 48 0 1181345296 90777248 4981440
13:35:10 106539 8192000000 10081696960 10528485376 1092770240 11.769 97 35 66 0 1166984832 67141152 7073440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 31-05-2022 13:40:23 to 31-05-2022 13:40:39.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 71 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 31-05-2022 13:40:40 to 31-05-2022 13:40:42.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          220699
MON$OLDEST_ACTIVE               220700
MON$OLDEST_SNAPSHOT             220700
MON$NEXT_TRANSACTION            220700
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                       2068256
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             780
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 ---
:292: 
:293: mscs-test-161.budzdorov.ru    Tue May 31 13:37:46 2022
:294:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:295: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

31.05.22 13:40:43. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-05-31 13:40:45 No severe PSQL-related problems occured
31.05.22 13:40:46. End of report.