Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.08.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 15369268 kB
MemAvailable 30972544 kB
Buffers 0 kB
Cached 16307816 kB
SwapCached 2512 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-08-02 10:37:19
DTS_END                         2022-08-02 10:37:19.5750
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/49304
TRN_ID                          225039
ATT_ID                          662
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: 9232.86 22523 1107943
customer order: creation 180.57 476 21668
customer order: refuse 15.26 466 1831
order to supplier: creation 46.67 1346 5600
order to supplier: removal 3.66 157798 439
invoice (draft): creation 48.01 3371 5761
invoice (draft): removal 49.78 1042 5973
invoice accept: apply 43.12 29229 5174
invoice accept: cancel 45.16 1410 5419
customer reserve: creation 4051.62 273 486194
customer reserve: removal 4441.80 102 533016
realization accept: apply 88.73 309 10647
realization accept: cancel 83.47 179 10016
payment from customer: creation 61.63 192 7395
payment from customer: removal 5.70 183 684
payment to supplier: creation 49.87 221 5984
payment to supplier: removal 4.00 155 480
service: total inventory turnovers 4.20 4568 504
service: total monetary turnovers 8.60 362 1032
service: aggregate perf. data 1.02 16432 122
service: refresh index statistics 0.03 232347 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-08-2022 10:38:12 to 02-08-2022 10:38:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7535
TEST_TIME 2 6739
TEST_TIME 3 6365
TEST_TIME 4 6348
TEST_TIME 5 6069
TEST_TIME 6 5893
TEST_TIME 7 5903
TEST_TIME 8 6411
TEST_TIME 9 6572
TEST_TIME 10 6443
TEST_TIME 11 6816
TEST_TIME 12 7099
TEST_TIME 13 7422
TEST_TIME 14 7554
TEST_TIME 15 7518
TEST_TIME 16 7655
TEST_TIME 17 7676
TEST_TIME 18 7741
TEST_TIME 19 7881
TEST_TIME 20 7907
TEST_TIME 21 7967
TEST_TIME 22 8100
TEST_TIME 23 8070
TEST_TIME 24 8205
TEST_TIME 25 8438
TEST_TIME 26 8530
TEST_TIME 27 8532
TEST_TIME 28 8558
TEST_TIME 29 8941
TEST_TIME 30 9125
TEST_TIME 31 9372
TEST_TIME 32 9523
TEST_TIME 33 9475
TEST_TIME 34 9405
TEST_TIME 35 9610
TEST_TIME 36 9952
TEST_TIME 37 10022
TEST_TIME 38 10367
TEST_TIME 39 10303
TEST_TIME 40 10166
TEST_TIME 41 10141
TEST_TIME 42 10500
TEST_TIME 43 10704
TEST_TIME 44 10679
TEST_TIME 45 10467
TEST_TIME 46 10296
TEST_TIME 47 10253
TEST_TIME 48 10195
TEST_TIME 49 10212
TEST_TIME 50 10150
TEST_TIME 51 10108
TEST_TIME 52 9985
TEST_TIME 53 9880
TEST_TIME 54 9922
TEST_TIME 55 9882
TEST_TIME 56 9858
TEST_TIME 57 9905
TEST_TIME 58 9831
TEST_TIME 59 9824
TEST_TIME 60 9777
TEST_TIME 61 9771
TEST_TIME 62 9752
TEST_TIME 63 9865
TEST_TIME 64 9979
TEST_TIME 65 9934
TEST_TIME 66 9840
TEST_TIME 67 9843
TEST_TIME 68 9838
TEST_TIME 69 9857
TEST_TIME 70 9978
TEST_TIME 71 9908
TEST_TIME 72 10017
TEST_TIME 73 9989
TEST_TIME 74 9926
TEST_TIME 75 9995
TEST_TIME 76 9991
TEST_TIME 77 9897
TEST_TIME 78 9821
TEST_TIME 79 9787
TEST_TIME 80 9743
TEST_TIME 81 9701
TEST_TIME 82 9675
TEST_TIME 83 9636
TEST_TIME 84 9646
TEST_TIME 85 9660
TEST_TIME 86 9572
TEST_TIME 87 9547
TEST_TIME 88 9501
TEST_TIME 89 9521
TEST_TIME 90 9474
TEST_TIME 91 9480
TEST_TIME 92 9465
TEST_TIME 93 9517
TEST_TIME 94 9492
TEST_TIME 95 9486
TEST_TIME 96 9562
TEST_TIME 97 9564
TEST_TIME 98 9532
TEST_TIME 99 9590
TEST_TIME 100 9565
TEST_TIME 101 9577
TEST_TIME 102 9556
TEST_TIME 103 9570
TEST_TIME 104 9500
TEST_TIME 105 9442
TEST_TIME 106 9439
TEST_TIME 107 9378
TEST_TIME 108 9363
TEST_TIME 109 9348
TEST_TIME 110 9297
TEST_TIME 111 9315
TEST_TIME 112 9286
TEST_TIME 113 9284
TEST_TIME 114 9299
TEST_TIME 115 9255
TEST_TIME 116 9271
TEST_TIME 117 9262
TEST_TIME 118 9241
TEST_TIME 119 9283
TEST_TIME 120 9261

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-08-2022 10:38:15 to 02-08-2022 10:38:18.

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 1554864 1554864 0 0.00 0 18242 15 0 0
doc_list_biud 1554851 1554851 0 0.00 0 20782 42 0 0
sp_add_invoice_to_stock 5303 5174 129 2.43 42 213604 29230 0 129
sp_cancel_adding_invoice 5419 5419 0 0.00 6 122164 1410 0 0
sp_cancel_client_order 1831 1831 0 0.00 6 9317 466 0 0
sp_cancel_customer_reserve 533016 533016 0 0.00 1 20803 102 0 0
sp_cancel_pay_from_customer 684 684 0 0.00 4 11808 183 0 0
sp_cancel_pay_to_supplier 480 480 0 0.00 3 7186 155 0 0
sp_cancel_supplier_invoice 5982 5973 9 0.15 2 19485 1042 0 9
sp_cancel_supplier_order 439 439 0 0.00 17 1594163 157798 0 0
sp_cancel_write_off 10016 10016 0 0.00 2 9354 179 0 0
sp_client_order 21668 21668 0 0.00 5 19090 477 0 0
sp_customer_reserve 486194 486194 0 0.00 1 112342 274 0 0
sp_fill_shopping_cart 33015 33015 0 0.00 1 17864 88 0 0
sp_fill_shopping_cart_clo_res 486159 486159 0 0.00 0 16593 18 0 0
sp_get_clo_for_invoice 5131 5131 0 0.00 0 39460 288 0 0
sp_kill_cost_storno 16599 16599 0 0.00 0 8979 81 0 0
sp_kill_qstorno_ret_qs2qd 1082460 1082460 0 0.00 0 20736 44 0 0
sp_kill_qty_storno 572470 572470 0 0.00 0 20780 103 0 0
sp_lock_selected_doc 21885 21885 0 0.00 0 8194 8 0 0
sp_make_cost_storno 29157 29157 0 0.00 0 18239 95 0 0
sp_multiply_rows_for_pdistr 29154 29154 0 0.00 0 5646 42 0 0
sp_multiply_rows_for_qdistr 21666 21666 0 0.00 0 11828 177 0 0
sp_pay_from_customer 7395 7395 0 0.00 5 12490 192 0 0
sp_pay_to_supplier 5984 5984 0 0.00 6 14553 222 0 0
sp_payment_common 13378 13378 0 0.00 2 10893 51 0 0
sp_qd_handle_on_cancel_clo 1831 1831 0 0.00 0 9310 380 0 0
sp_qd_handle_on_invoice_upd_sts 10548 10548 0 0.00 0 11389 571 0 0
sp_qd_handle_on_reserve_upd_sts 10647 10647 0 0.00 0 13939 65 0 0
sp_reserve_write_off 10647 10647 0 0.00 7 18257 309 0 0
sp_supplier_invoice 5761 5761 0 0.00 7 96205 3371 0 0
sp_supplier_order 5600 5600 0 0.00 9 31089 1346 0 0
srv_aggregate_perf_data 122 122 0 0.00 2465 136012 16432 0 0
srv_fill_mon_cache_memory 74 74 0 0.00 742 137412 31383 0 0
srv_find_qd_qs_mism 1056408 1056408 0 0.00 0 15221 11 0 0
srv_make_invnt_saldo 504 504 0 0.00 60 114856 4569 0 0
srv_make_money_saldo 1032 1032 0 0.00 1 13569 363 0 0
srv_recalc_idx_stat 4 4 0 0.00 74047 375573 232348 0 0
t$perf-abend:sp_add_invoice_to_stock 129 129 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5182 5182 0 0.00 1 12606 1135 0 0
t$perf-norm:sp_cancel_adding_invoice 451 451 0 0.00 1 10446 518 0 0
t$perf-norm:sp_cancel_client_order 1831 1831 0 0.00 0 4570 32 0 0
t$perf-norm:sp_cancel_customer_reserve 713 713 0 0.00 0 1075 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 684 684 0 0.00 0 2089 25 0 0
t$perf-norm:sp_cancel_pay_to_supplier 480 480 0 0.00 0 2635 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 457 457 0 0.00 0 1967 50 0 0
t$perf-norm:sp_cancel_supplier_order 439 439 0 0.00 0 101625 7695 0 0
t$perf-norm:sp_cancel_write_off 1056 1056 0 0.00 0 2855 22 0 0
t$perf-norm:sp_client_order 21668 21668 0 0.00 0 11686 43 0 0
t$perf-norm:sp_customer_reserve 985 985 0 0.00 0 726 17 0 0
t$perf-norm:sp_pay_from_customer 7395 7395 0 0.00 0 9450 22 0 0
t$perf-norm:sp_pay_to_supplier 5984 5984 0 0.00 0 5699 24 0 0
t$perf-norm:sp_reserve_write_off 10647 10647 0 0.00 0 9280 26 0 0
t$perf-norm:sp_supplier_invoice 5761 5761 0 0.00 0 11551 37 0 0
t$perf-norm:sp_supplier_order 5600 5600 0 0.00 0 2612 36 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 1051 41 0 0
t$perf-norm:srv_fill_mon_cache_memory 74 74 0 0.00 0 126 8 0 0
t$perf-norm:srv_make_invnt_saldo 504 504 0 0.00 0 1326 26 0 0
t$perf-norm:srv_make_money_saldo 1032 1032 0 0.00 0 1157 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 39 184 89 0 0
v_add_invoice_to_stock 10512 10254 258 2.45 0 5158 30 0 258
v_all_customers 39565 39565 0 0.00 0 105 4 0 0
v_all_suppliers 31493 31493 0 0.00 0 132 5 0 0
v_all_wares 43320 43320 0 0.00 0 62 5 0 0
v_cancel_adding_invoice 902 902 0 0.00 0 1133 17 0 0
v_cancel_client_order 3662 3662 0 0.00 0 2969 12 0 0
v_cancel_customer_prepayment 1368 1368 0 0.00 0 5652 23 0 0
v_cancel_customer_reserve 1426 1426 0 0.00 1 1759 13 0 0
v_cancel_payment_to_supplier 960 960 0 0.00 0 5161 21 0 0
v_cancel_supplier_invoice 932 914 18 1.93 0 1915 29 0 18
v_cancel_supplier_order 878 878 0 0.00 0 1243 14 0 0
v_cancel_write_off 2112 2112 0 0.00 0 3701 29 0 0
v_max_id_clo_ord 5598 5598 0 0.00 0 708 6 0 0
v_max_id_clo_res 985 985 0 0.00 0 2999 12 0 0
v_max_id_ord_sup 5756 5756 0 0.00 0 3638 9 0 0
v_max_non_paid_invoice 1589 1589 0 0.00 0 4936 12 0 0
v_max_non_paid_realizn 3827 3827 0 0.00 0 339 8 0 0
v_min_id_clo_ord 5598 5598 0 0.00 0 3470 10 0 0
v_min_id_clo_res 985 985 0 0.00 0 401 10 0 0
v_min_id_ord_sup 5756 5756 0 0.00 0 6291 10 0 0
v_min_non_paid_invoice 5983 5983 0 0.00 0 5230 21 0 0
v_min_non_paid_realizn 7394 7394 0 0.00 0 10889 18 0 0
v_our_firm 10888 10888 0 0.00 0 67 5 0 0
v_reserve_write_off 21294 21294 0 0.00 0 6266 13 0 0
x_lock_depdocs_on_canc_invoice 10935 10935 0 0.00 0 17167 141 0 0
x_lock_depdocs_on_canc_sup_ord 439 439 0 0.00 0 9938 397 0 0
x_make_qstorno_1000_1200 5600 5600 0 0.00 2 31076 1153 0 0
x_make_qstorno_1200_2000 5761 5761 0 0.00 3 92900 3146 0 0
x_make_qstorno_2100_3300 486192 486192 0 0.00 0 112329 241 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-08-2022 10:38:19 to 02-08-2022 10:38:23.

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:23 2 8192000000 8796669104 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:25 23567 8192000000 9705636704 9999511552 781096576 8.704 100 84 169 0 810035776 6901968 22037232
08:09:55 22631 8192000000 9753872960 10060353536 827777072 9.177 100 91 180 0 861205072 11704192 21723808
08:11:23 16109 8192000000 9792546816 10106617856 855276992 9.453 100 81 163 0 895879280 21375120 19227168
08:12:44 12394 8192000000 9860795312 10193186816 912125216 10.018 100 90 179 0 971641664 39674160 19842288
08:14:03 5063 8192000000 9928465200 10269138944 955065120 10.441 100 90 178 0 1038685808 65818320 17802368
08:15:11 2806 8192000000 9954054624 10307178496 984853744 10.731 100 92 181 0 1071300160 67570000 18876416
08:16:15 1651 8192000000 9986174720 10345029632 1012598896 11.001 100 91 177 0 1105908848 75788400 17521552
08:17:19 1175 8192000000 10025760496 10391904256 1037673872 11.242 100 91 179 0 1141012688 84378016 18960800
08:18:22 951 8192000000 10029387360 10398892032 1058828544 11.445 100 92 185 0 1150787456 72822576 19136336
08:19:26 8704 8192000000 10040559600 10414014464 1065105408 11.505 100 88 161 0 1162202496 82354144 14742944
08:20:36 1225 8192000000 10040701552 10415230976 1074318480 11.593 100 91 180 1 1154037232 63479440 16239312
08:21:39 1174 8192000000 10026400640 10411237376 1076533216 11.614 100 81 161 1 1154483328 59826336 18123776
08:22:42 1007 8192000000 10048172240 10437951488 1091991488 11.762 100 84 162 3 1166382384 58468048 15922848
08:23:44 1129 8192000000 10042242080 10429067264 1092054128 11.762 100 97 186 0 1160591952 49176320 19361504
08:24:46 1658 8192000000 10078013072 10470965248 1091438480 11.756 100 86 163 2 1199747008 91779968 16528560
08:25:50 1181 8192000000 10051268704 10437414912 1096876304 11.808 100 96 187 0 1174134864 58300336 18958224
08:26:52 5959 8192000000 10064852928 10462597120 1097198976 11.811 100 65 130 1 1201461104 91949776 12312352
08:28:00 1135 8192000000 10043795520 10434367488 1102590880 11.862 100 85 166 1 1168237808 48445872 17201056
08:29:02 2946 8192000000 10010205024 10410196992 1091514944 11.757 99 62 117 0 1132507952 29403376 11589632
08:30:05 851 8192000000 10059816656 10444976128 1084199392 11.687 100 86 166 0 1179676496 78452240 17024864
08:31:07 1199 8192000000 10033690000 10414080000 1068137584 11.534 99 94 179 0 1159003232 71815104 19050544
08:32:10 2558 8192000000 10015153968 10398769152 1052541520 11.385 98 74 133 2 1135401136 70213376 12646240
08:33:13 1408 8192000000 10006297056 10386223104 1047751888 11.339 99 87 160 3 1133850912 67718608 18380416
08:34:16 5355 8192000000 9970466752 10344587264 1010807648 10.983 98 68 133 2 1088121952 63429600 13884704
08:35:22 1190 8192000000 9977513328 10346938368 1038262672 11.248 100 94 174 0 1100591280 43837088 18491520
08:36:24 849 8192000000 9974839968 10344009728 1015255632 11.026 98 91 169 3 1095002032 61992672 17753728
08:37:27 4720 8192000000 10012880160 10391457792 1065423088 11.508 100 81 135 2 1130764800 52684336 12657376
08:38:36 1184 8192000000 10028857968 10406408192 1067247536 11.526 100 96 178 2 1148969888 63181568 18540784
08:39:46 2459 8192000000 10065032384 10449465344 1083106672 11.677 100 84 168 0 1191473248 90127008 18239568
08:40:57 4712 8192000000 10077464752 10472722432 1097422640 11.813 100 74 141 1 1201696272 88950096 15323536
08:42:05 7162 8192000000 10085314176 10470330368 1108937248 11.922 100 62 112 2 1185567200 65616192 11013760
08:43:20 2022 8192000000 10164323344 10552987648 1131035888 12.131 100 92 172 0 1277936768 129233296 17667584
08:44:25 3670 8192000000 10182706432 10582822912 1123692528 12.062 100 74 143 0 1303157760 163243920 16221312
08:45:33 1535 8192000000 10167653056 10579509248 1131576336 12.136 100 91 161 2 1284597120 133945616 19075168
08:46:38 2871 8192000000 10188564752 10587488256 1134197040 12.161 100 75 142 0 1312190848 162748656 15245152
08:47:53 4911 8192000000 10199323728 10601840640 1140763328 12.223 100 85 164 3 1313663904 155150544 17750032
08:49:02 3337 8192000000 10236602128 10649784320 1153547472 12.343 100 90 170 1 1357092256 183161968 20382816
08:50:08 1377 8192000000 10239804560 10665058304 1163692688 12.438 100 94 185 1 1354124752 169986784 20445280
08:51:14 1969 8192000000 10263012480 10676649984 1170209536 12.499 100 90 172 0 1372207728 182412368 19585824
08:52:22 782 8192000000 10262182096 10664558592 1171046416 12.507 100 95 186 1 1376007648 183696096 21265136
08:53:25 2865 8192000000 10285763152 10701193216 1177068912 12.563 100 82 151 0 1397252336 200818736 19364688
08:54:36 1270 8192000000 10328177440 10736263168 1184128496 12.629 100 87 167 0 1436616800 233948208 18540096
08:55:43 920 8192000000 10332074144 10750668800 1190545104 12.688 100 90 172 0 1436890944 227665056 18680784
08:56:48 2169 8192000000 10335300352 10755706880 1192792240 12.709 100 92 180 1 1451454976 237077360 21585376
08:57:54 2797 8192000000 10373364928 10795941888 1197469280 12.753 100 94 182 0 1481576144 262537568 21569296
08:59:00 1093 8192000000 10402129888 10823516160 1190236208 12.686 100 97 190 1 1506013456 292763664 23013584
09:00:04 889 8192000000 10422616752 10850172928 1195552736 12.735 100 97 185 1 1531454976 314540032 21362208
09:01:09 2322 8192000000 10424016032 10872672256 1193916064 12.720 100 90 175 0 1529909664 315845872 20147728
09:02:15 1009 8192000000 10439266640 10885586944 1197381664 12.752 100 94 184 0 1543751024 324536352 21833008
09:03:18 838 8192000000 10465950976 10885455872 1185822528 12.644 100 94 185 2 1570172096 362057648 22291920
09:04:26 5674 8192000000 10462675392 10883903488 1173489248 12.529 100 64 127 0 1573923168 384890224 15543696
09:05:35 1428 8192000000 10441577536 10898272256 1178576608 12.577 100 96 181 2 1543216672 344326864 20313200
09:06:38 8742 8192000000 10436987328 10889080832 1189454480 12.678 100 51 101 1 1563513232 361266240 12792512
09:07:59 22194 8192000000 10396269136 10861486080 1177171760 12.564 100 28 56 0 1527572768 343002160 7398848
09:09:22 46737 8192000000 10346703120 10828484608 1182747152 12.616 100 27 53 0 1485309760 296076752 6485856
09:11:12 727 8192000000 10369169824 10805194752 1160475520 12.408 99 79 147 3 1468736176 289690272 18570384
09:12:16 1014 8192000000 10304039088 10817593344 1158635024 12.390 99 93 176 2 1402953168 222811280 21506864
09:13:19 3139 8192000000 10317015312 10766958592 1173101568 12.526 100 66 129 1 1420995808 233476976 14417264
09:14:34 34629 8192000000 10271822704 10787250176 1153870384 12.346 99 24 48 0 1399603008 239718544 6014080
09:16:45 54348 8192000000 10225314976 10665009152 1133201168 12.152 98 38 75 0 1313285632 171191632 8892832
09:18:40 30955 8192000000 10221228064 10727931904 1142971968 12.243 99 19 37 0 1346803184 198957744 4873472
09:20:20 137412 8192000000 10047935088 10464702464 1038474080 11.250 91 16 27 3 1066782416 25757680 2550656
09:23:39 69326 8192000000 10091671168 10531049472 1107199904 11.906 98 29 58 0 1175364112 61467344 6696864
09:25:49 63035 8192000000 10076072944 10522116096 1096922752 11.808 96 21 40 1 1150716608 49832288 3961568
09:27:53 121077 8192000000 10116672224 10559188992 1136615248 12.184 99 32 61 0 1199068208 55363280 7089680
09:31:02 1185 8192000000 10167796688 10587865088 1148873856 12.299 100 84 168 1 1264407680 96820272 18713552
09:32:09 11697 8192000000 10164489040 10589413376 1137066576 12.188 99 52 101 0 1248398080 100636928 10694576
09:33:24 5564 8192000000 10198431072 10615627776 1141049488 12.225 99 78 142 1 1290074192 135105808 13918896
09:34:30 35062 8192000000 10167756336 10637967360 1153375408 12.341 100 15 30 0 1280375008 123052336 3947264
09:36:06 49587 8192000000 10189211680 10664935424 1151560080 12.324 100 25 50 1 1283944896 127051728 5333088
09:38:34 79517 8192000000 10209601760 10714439680 1161301856 12.415 100 17 34 0 1321853216 156469408 4081952
09:41:02 100611 8192000000 10081283088 10527465472 1132641840 12.146 98 16 28 0 1168749360 33688576 2418944
09:44:10 19263 8192000000 10196894832 10629787648 1156776912 12.373 100 65 130 0 1282033440 111951760 13304768
09:45:42 71389 8192000000 10172236080 10643910656 1134889264 12.167 98 13 26 0 1237709664 99714576 3105824
09:47:55 43752 8192000000 10164771952 10604400640 1144243344 12.255 99 38 76 0 1222576576 70146432 8186800
09:49:40 75967 8192000000 10114429360 10575261696 1083057728 11.677 94 27 54 0 1163942208 75086352 5798128
09:51:58 49806 8192000000 10100125344 10579689472 1089349952 11.736 96 18 37 0 1176189536 83229600 3609984
09:53:49 31627 8192000000 10078625808 10511572992 1120288560 12.030 99 59 112 0 1160028080 27791888 11947632
09:55:34 58084 8192000000 10064777136 10502283264 1115461488 11.984 98 27 54 0 1160830752 40262096 5107168
09:57:35 56131 8192000000 10086654928 10522202112 1136838720 12.186 100 59 118 0 1190324672 41619120 11866832
09:59:33 11589 8192000000 10110600432 10537463808 1133802128 12.157 100 57 109 0 1215713248 70316544 11594576
10:00:48 23054 8192000000 10113017904 10574753792 1131713568 12.138 100 38 77 1 1241829472 102038864 8077040
10:02:17 58313 8192000000 10081599136 10576265216 1129192688 12.114 99 11 23 0 1171935840 40120976 2622176
10:04:16 69068 8192000000 10071917488 10583642112 1128841424 12.110 99 6 12 0 1176253248 46220288 1191536
10:06:28 69044 8192000000 10082324496 10562134016 1123582032 12.061 99 15 31 0 1176533744 50131088 2820624
10:08:38 49441 8192000000 10102919840 10542055424 1074227136 11.592 94 18 36 0 1122583168 44763888 3592144
10:10:34 45162 8192000000 10067857712 10502774784 1079197056 11.640 96 24 48 0 1111686816 27509040 4980720
10:12:32 77717 8192000000 10082140368 10554114048 1097873296 11.817 98 21 40 0 1161377264 59405280 4098688
10:14:50 69914 8192000000 10112430800 10562125824 1082053872 11.667 96 25 51 0 1154476304 67218848 5203584
10:17:06 13220 8192000000 10084640576 10511282176 1116211696 11.991 100 44 88 0 1169019600 44317904 8490000
10:18:21 5257 8192000000 10115210192 10534895616 1124230176 12.067 100 63 124 0 1209837344 72084336 13522832
10:19:29 16464 8192000000 10105823200 10531811328 1121259984 12.039 100 42 82 1 1197682880 67822608 8600288
10:20:45 90118 8192000000 10062349424 10508484608 1126107488 12.085 100 22 43 0 1173872672 43341616 4423568
10:23:43 74018 8192000000 10125553760 10585448448 1129748304 12.119 99 17 32 1 1230742016 97356688 3637024
10:27:08 89598 8192000000 10085008864 10570174464 1130483296 12.126 99 7 14 0 1196383888 64150960 1749632
10:29:38 36785 8192000000 10092528432 10538795008 1134806816 12.167 99 29 52 0 1164905200 24442064 5656320
10:31:27 47114 8192000000 10082985360 10553298944 1136284784 12.181 99 20 39 0 1170502864 30458016 3760064
10:33:15 52794 8192000000 10099330080 10572378112 1130346640 12.125 98 22 44 0 1181850096 46722944 4780512
10:35:16 44515 8192000000 10121560576 10570416128 1111373840 11.945 97 33 66 0 1193480256 75461088 6645328
10:37:06 30286 8192000000 8916684272 9108488192 79991616 0.967 8 2 4 0 85766784 5704048 71120

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-08-2022 10:38:25 to 02-08-2022 10:38:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 129 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-08-2022 10:38:42 to 02-08-2022 10:38:44.

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          224754
MON$OLDEST_ACTIVE               225344
MON$OLDEST_SNAPSHOT             225344
MON$NEXT_TRANSACTION            225344
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                       2099328
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             781
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2812 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2812 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2812 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2812 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Tue Aug  2 10:37:38 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

02.08.22 10:38:45. 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-08-02 10:38:48 No severe PSQL-related problems occured
02.08.22 10:38:49. End of report.