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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16238100 kB
MemAvailable 31232048 kB
Buffers 20 kB
Cached 15422600 kB
SwapCached 2452 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-06-16 04:37:16
DTS_END                         2022-06-16 04:37:16.9650
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/33888
TRN_ID                          220258
ATT_ID                          624
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: 8841.72 25278 1061006
customer order: creation 165.67 463 19880
customer order: refuse 14.27 375 1712
order to supplier: creation 44.16 1302 5299
order to supplier: removal 3.21 160834 385
invoice (draft): creation 43.50 3103 5220
invoice (draft): removal 48.88 933 5865
invoice accept: apply 40.85 26400 4902
invoice accept: cancel 43.96 1165 5275
customer reserve: creation 3824.65 253 458958
customer reserve: removal 4330.02 95 519602
realization accept: apply 79.48 233 9538
realization accept: cancel 78.64 172 9437
payment from customer: creation 55.98 156 6717
payment from customer: removal 5.49 121 659
payment to supplier: creation 47.01 187 5641
payment to supplier: removal 3.50 132 420
service: total inventory turnovers 3.99 3562 479
service: total monetary turnovers 7.53 373 904
service: aggregate perf. data 0.90 18513 108
service: refresh index statistics 0.04 287178 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 16-06-2022 04:38:40 to 16-06-2022 04:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6888
TEST_TIME 2 7253
TEST_TIME 3 6656
TEST_TIME 4 6190
TEST_TIME 5 5730
TEST_TIME 6 5276
TEST_TIME 7 5429
TEST_TIME 8 5689
TEST_TIME 9 5996
TEST_TIME 10 6272
TEST_TIME 11 6419
TEST_TIME 12 6433
TEST_TIME 13 6592
TEST_TIME 14 6726
TEST_TIME 15 7117
TEST_TIME 16 7169
TEST_TIME 17 7183
TEST_TIME 18 7475
TEST_TIME 19 7921
TEST_TIME 20 8276
TEST_TIME 21 8545
TEST_TIME 22 8909
TEST_TIME 23 9137
TEST_TIME 24 9539
TEST_TIME 25 9630
TEST_TIME 26 9865
TEST_TIME 27 10037
TEST_TIME 28 10523
TEST_TIME 29 10626
TEST_TIME 30 10839
TEST_TIME 31 11329
TEST_TIME 32 11209
TEST_TIME 33 10895
TEST_TIME 34 10838
TEST_TIME 35 10628
TEST_TIME 36 10462
TEST_TIME 37 10491
TEST_TIME 38 10366
TEST_TIME 39 10277
TEST_TIME 40 10313
TEST_TIME 41 10170
TEST_TIME 42 10172
TEST_TIME 43 10040
TEST_TIME 44 9836
TEST_TIME 45 9784
TEST_TIME 46 9700
TEST_TIME 47 9647
TEST_TIME 48 9596
TEST_TIME 49 9608
TEST_TIME 50 9622
TEST_TIME 51 9562
TEST_TIME 52 9528
TEST_TIME 53 9532
TEST_TIME 54 9626
TEST_TIME 55 9536
TEST_TIME 56 9529
TEST_TIME 57 9499
TEST_TIME 58 9493
TEST_TIME 59 9468
TEST_TIME 60 9478
TEST_TIME 61 9558
TEST_TIME 62 9499
TEST_TIME 63 9426
TEST_TIME 64 9396
TEST_TIME 65 9297
TEST_TIME 66 9269
TEST_TIME 67 9271
TEST_TIME 68 9219
TEST_TIME 69 9125
TEST_TIME 70 9141
TEST_TIME 71 9095
TEST_TIME 72 9111
TEST_TIME 73 9101
TEST_TIME 74 9100
TEST_TIME 75 9103
TEST_TIME 76 9101
TEST_TIME 77 9110
TEST_TIME 78 9140
TEST_TIME 79 9263
TEST_TIME 80 9218
TEST_TIME 81 9257
TEST_TIME 82 9286
TEST_TIME 83 9267
TEST_TIME 84 9458
TEST_TIME 85 9384
TEST_TIME 86 9298
TEST_TIME 87 9278
TEST_TIME 88 9208
TEST_TIME 89 9225
TEST_TIME 90 9152
TEST_TIME 91 9170
TEST_TIME 92 9147
TEST_TIME 93 9118
TEST_TIME 94 9101
TEST_TIME 95 9101
TEST_TIME 96 9072
TEST_TIME 97 9065
TEST_TIME 98 9074
TEST_TIME 99 9082
TEST_TIME 100 9048
TEST_TIME 101 9015
TEST_TIME 102 8999
TEST_TIME 103 9123
TEST_TIME 104 9218
TEST_TIME 105 9266
TEST_TIME 106 9245
TEST_TIME 107 9212
TEST_TIME 108 9170
TEST_TIME 109 9123
TEST_TIME 110 9051
TEST_TIME 111 9073
TEST_TIME 112 9023
TEST_TIME 113 8979
TEST_TIME 114 8947
TEST_TIME 115 8927
TEST_TIME 116 8919
TEST_TIME 117 8940
TEST_TIME 118 8918
TEST_TIME 119 8931
TEST_TIME 120 8874

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 16-06-2022 04:38:43 to 16-06-2022 04:38:47.

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 1482256 1482256 0 0.00 0 35982 13 0 0
doc_list_biud 1482237 1482237 0 0.00 0 43658 39 0 0
sp_add_invoice_to_stock 5023 4902 121 2.40 50 208163 26400 0 121
sp_cancel_adding_invoice 5275 5275 0 0.00 5 77224 1166 0 0
sp_cancel_client_order 1712 1712 0 0.00 6 10849 375 0 0
sp_cancel_customer_reserve 519602 519602 0 0.00 1 43661 95 0 0
sp_cancel_pay_from_customer 659 659 0 0.00 3 5152 122 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 4 3111 132 0 0
sp_cancel_supplier_invoice 5879 5865 14 0.23 2 14895 933 0 14
sp_cancel_supplier_order 385 385 0 0.00 9 1251514 160834 0 0
sp_cancel_write_off 9437 9437 0 0.00 2 27667 172 0 0
sp_client_order 19880 19880 0 0.00 6 33996 463 0 0
sp_customer_reserve 458958 458958 0 0.00 1 150805 253 0 0
sp_fill_shopping_cart 30384 30384 0 0.00 1 18582 76 0 0
sp_fill_shopping_cart_clo_res 458920 458920 0 0.00 0 17075 20 0 0
sp_get_clo_for_invoice 4857 4857 0 0.00 0 26264 283 0 0
sp_kill_cost_storno 15791 15791 0 0.00 0 27661 72 0 0
sp_kill_qstorno_ret_qs2qd 1054891 1054891 0 0.00 0 43591 42 0 0
sp_kill_qty_storno 556667 556667 0 0.00 0 43658 95 0 0
sp_lock_selected_doc 19955 19955 0 0.00 0 6789 6 0 0
sp_make_cost_storno 26753 26753 0 0.00 0 35970 70 0 0
sp_multiply_rows_for_pdistr 26749 26749 0 0.00 0 6703 35 0 0
sp_multiply_rows_for_qdistr 19878 19878 0 0.00 0 26571 215 0 0
sp_pay_from_customer 6717 6717 0 0.00 6 11708 156 0 0
sp_pay_to_supplier 5641 5641 0 0.00 7 36028 188 0 0
sp_payment_common 12356 12356 0 0.00 2 32276 45 0 0
sp_qd_handle_on_cancel_clo 1712 1712 0 0.00 0 10834 310 0 0
sp_qd_handle_on_invoice_upd_sts 10129 10129 0 0.00 0 14634 457 0 0
sp_qd_handle_on_reserve_upd_sts 9537 9537 0 0.00 0 16944 50 0 0
sp_reserve_write_off 9538 9538 0 0.00 9 16958 233 0 0
sp_supplier_invoice 5220 5220 0 0.00 9 91226 3103 0 0
sp_supplier_order 5299 5299 0 0.00 7 34061 1303 0 0
srv_aggregate_perf_data 108 108 0 0.00 2841 139583 18514 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 816 173042 37388 0 0
srv_find_qd_qs_mism 1012755 1012755 0 0.00 0 20846 11 0 0
srv_make_invnt_saldo 479 479 0 0.00 73 92723 3563 0 0
srv_make_money_saldo 904 904 0 0.00 1 16960 374 0 0
srv_recalc_idx_stat 5 5 0 0.00 206407 449033 287179 0 0
t$perf-abend:sp_add_invoice_to_stock 121 121 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4912 4912 0 0.00 1 37269 798 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 7403 452 0 0
t$perf-norm:sp_cancel_client_order 1712 1712 0 0.00 0 1661 21 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 2518 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 659 659 0 0.00 0 600 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 990 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 1852 34 0 0
t$perf-norm:sp_cancel_supplier_order 385 385 0 0.00 0 84310 6636 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 2779 21 0 0
t$perf-norm:sp_client_order 19880 19880 0 0.00 0 6091 30 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 29757 44 0 0
t$perf-norm:sp_pay_from_customer 6717 6717 0 0.00 0 8063 19 0 0
t$perf-norm:sp_pay_to_supplier 5641 5641 0 0.00 0 7939 18 0 0
t$perf-norm:sp_reserve_write_off 9538 9538 0 0.00 0 6956 21 0 0
t$perf-norm:sp_supplier_invoice 5220 5220 0 0.00 0 3309 26 0 0
t$perf-norm:sp_supplier_order 5299 5299 0 0.00 0 4132 27 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 355 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 21 3 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 706 9 0 0
t$perf-norm:srv_make_money_saldo 904 904 0 0.00 0 359 5 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 192 56 0 0
v_add_invoice_to_stock 9938 9696 242 2.43 0 5049 25 0 242
v_all_customers 36164 36164 0 0.00 0 61 3 0 0
v_all_suppliers 29174 29174 0 0.00 0 138 4 0 0
v_all_wares 39748 39748 0 0.00 0 277 3 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 786 14 0 0
v_cancel_client_order 3424 3424 0 0.00 0 4823 11 0 0
v_cancel_customer_prepayment 1318 1318 0 0.00 0 1626 13 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 72 8 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 664 10 0 0
v_cancel_supplier_invoice 782 754 28 3.58 0 2856 23 0 28
v_cancel_supplier_order 770 770 0 0.00 0 3237 18 0 0
v_cancel_write_off 1946 1946 0 0.00 0 4227 22 0 0
v_max_id_clo_ord 5296 5296 0 0.00 0 3179 5 0 0
v_max_id_clo_res 968 968 0 0.00 0 2674 14 0 0
v_max_id_ord_sup 5210 5210 0 0.00 0 2409 6 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 2212 9 0 0
v_max_non_paid_realizn 3506 3506 0 0.00 0 482 6 0 0
v_min_id_clo_ord 5296 5296 0 0.00 0 2265 9 0 0
v_min_id_clo_res 968 968 0 0.00 0 9204 27 0 0
v_min_id_ord_sup 5210 5210 0 0.00 0 6278 8 0 0
v_min_non_paid_invoice 5641 5641 0 0.00 0 32269 25 0 0
v_min_non_paid_realizn 6715 6715 0 0.00 0 5116 16 0 0
v_our_firm 10000 10000 0 0.00 0 68 4 0 0
v_reserve_write_off 19074 19074 0 0.00 0 3731 10 0 0
x_lock_depdocs_on_canc_invoice 10763 10763 0 0.00 0 9857 111 0 0
x_lock_depdocs_on_canc_sup_ord 385 385 0 0.00 0 3234 327 0 0
x_make_qstorno_1000_1200 5299 5299 0 0.00 3 34038 1143 0 0
x_make_qstorno_1200_2000 5220 5220 0 0.00 3 90727 2892 0 0
x_make_qstorno_2100_3300 458957 458957 0 0.00 0 150798 223 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 16-06-2022 04:38:48 to 16-06-2022 04:38:52.

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
02:07:20 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:21 31190 8192000000 9731017360 10030092288 804356480 8.940 100 87 174 0 833657488 7613072 21687936
02:09:56 22768 8192000000 9760736000 10070835200 836382880 9.263 100 92 183 0 870953328 12586096 21984352
02:11:23 10903 8192000000 9812890048 10133209088 875473776 9.655 100 87 174 0 920032448 24388496 20170176
02:12:54 10159 8192000000 9884278928 10219757568 928056368 10.175 100 88 172 0 995715632 48735376 18923888
02:14:07 2036 8192000000 9938612640 10284789760 969539008 10.582 100 90 174 0 1051885392 64570992 17775392
02:15:15 2764 8192000000 9965782640 10320764928 1003843792 10.916 100 91 176 3 1077879504 55708576 18327136
02:16:19 1225 8192000000 9999241456 10362241024 1028597440 11.155 100 94 182 2 1119979952 73304176 18078336
02:17:22 998 8192000000 10022001072 10394222592 1043420816 11.298 100 92 169 1 1138152528 77600432 17131280
02:18:24 1118 8192000000 10038722672 10412838912 1060442352 11.461 100 98 192 0 1156379312 76836512 19100448
02:19:26 907 8192000000 10048310000 10424037376 1072803536 11.579 100 94 185 0 1168223296 75934416 19485344
02:20:28 1065 8192000000 10046510704 10427777024 1080330384 11.651 100 94 182 2 1166413008 67242512 18840112
02:21:30 1371 8192000000 10044230544 10431262720 1084600704 11.691 100 83 163 1 1166652512 65745632 16306176
02:22:32 7755 8192000000 10043557120 10442571776 1083942128 11.685 100 59 118 0 1167455248 71971376 11541744
02:23:44 1180 8192000000 10033645568 10423328768 1089601248 11.739 100 89 172 3 1149372688 42344208 17427232
02:24:46 1338 8192000000 10068389280 10455412736 1096954480 11.809 100 91 174 0 1186535520 71560320 18020720
02:25:48 3272 8192000000 10052901232 10446991360 1095316640 11.793 100 67 130 0 1176806416 66828592 14661184
02:26:52 1027 8192000000 10043145056 10440548352 1107671936 11.910 100 88 166 3 1168212768 43907616 16633216
02:27:54 1143 8192000000 10054896448 10442752000 1101227568 11.849 100 96 187 1 1175159408 55592800 18339040
02:28:56 2073 8192000000 10020750112 10412421120 1088032048 11.724 100 73 133 1 1143701264 41141200 14528016
02:29:59 9409 8192000000 10044659360 10441519104 1091653680 11.758 100 59 112 2 1166176112 63766384 10756048
02:31:09 831 8192000000 10005657728 10387148800 1052512720 11.385 100 82 157 0 1126101424 57132976 16455728
02:32:11 1204 8192000000 10013545488 10391977984 1055456128 11.413 100 88 176 1 1131931632 58578880 17896624
02:33:13 1172 8192000000 9991570752 10362089472 1031956192 11.187 100 97 185 0 1110851920 60615600 18280128
02:34:15 3350 8192000000 9990031520 10371117056 1029088304 11.160 100 68 133 0 1116169488 73512464 13568720
02:35:19 1004 8192000000 9973693072 10347290624 1024426368 11.115 100 83 159 0 1096671728 54315296 17930064
02:36:20 3093 8192000000 9990412064 10376536064 1035615168 11.222 100 67 129 0 1111527424 61458928 14453328
02:37:25 3531 8192000000 9970913344 10354135040 1044543104 11.308 100 60 110 1 1092651264 36711808 11396352
02:38:40 14578 8192000000 10004610496 10388119552 1061793792 11.474 100 67 130 0 1127404448 52060096 13550560
02:39:59 3859 8192000000 10005951904 10387255296 1064506192 11.500 99 74 133 4 1124058128 46237792 13314144
02:41:09 4921 8192000000 10064788160 10455457792 1098397984 11.822 100 68 128 0 1186862992 75786528 12678480
02:42:19 5288 8192000000 10128048832 10516869120 1117199008 12.001 100 83 146 1 1236734496 104078256 15457232
02:43:40 6137 8192000000 10161389248 10560688128 1130047008 12.122 100 59 117 1 1282347856 139339296 12961552
02:44:57 1444 8192000000 10234577616 10630950912 1140903792 12.224 100 86 166 1 1350275168 190966368 18405008
02:46:05 1998 8192000000 10248529024 10660204544 1155519280 12.361 100 89 168 2 1360493984 187057872 17916832
02:47:10 1407 8192000000 10289571104 10688172032 1174629776 12.540 100 93 177 0 1397565216 203628416 19307024
02:48:15 1634 8192000000 10343443440 10745085952 1180024144 12.590 100 91 176 1 1457655360 258632992 18998224
02:49:19 853 8192000000 10371196288 10773569536 1190554192 12.689 100 93 185 3 1479759936 268120192 21085552
02:50:21 1223 8192000000 10399740832 10803830784 1188902864 12.673 100 94 180 0 1509896512 300557488 20436160
02:51:25 2165 8192000000 10445636160 10865242112 1196499184 12.744 100 85 169 0 1555827760 339240672 20087904
02:52:32 1397 8192000000 10460698272 10901999616 1194907904 12.729 100 96 186 2 1569152400 351559552 22684944
02:53:36 1211 8192000000 10490248912 10900414464 1197118816 12.750 100 92 180 2 1592604240 374288112 21197312
02:54:40 1154 8192000000 10508709648 10932629504 1187466960 12.660 99 92 177 5 1618530720 410269808 20793952
02:55:43 1673 8192000000 10498344992 10947346432 1189413552 12.678 100 88 172 1 1605519552 395641760 20464240
02:56:47 1693 8192000000 10486230128 10926948352 1184844048 12.635 100 83 165 1 1591471520 386601136 20026336
02:57:55 804 8192000000 10477386096 10947174400 1194069008 12.721 100 94 186 1 1580953376 364792176 22092192
02:58:58 924 8192000000 10457586000 10906750976 1192727600 12.709 100 95 189 0 1560323808 345523344 22072864
03:00:03 2614 8192000000 10441648656 10885771264 1181333376 12.603 100 61 122 1 1550884272 353171488 16379408
03:01:08 7491 8192000000 10383538336 10861248512 1182500416 12.614 100 48 94 0 1504454976 310090976 11863584
03:02:23 23820 8192000000 10378054128 10843824128 1162119584 12.423 99 28 56 2 1488559264 319086816 7352864
03:03:56 33700 8192000000 10368146480 10831958016 1156564400 12.371 100 25 50 1 1442047024 278940544 6542080
03:06:08 46163 8192000000 10254127616 10727743488 1117390144 12.002 97 20 40 1 1343841888 221164976 5286768
03:07:55 62775 8192000000 10087698256 10568372224 1099801952 11.836 97 13 24 0 1164802240 60830768 4169520
03:10:05 133677 8192000000 10064264768 10537304064 1079959328 11.647 97 36 71 3 1153014576 66294448 6760800
03:13:33 8357 8192000000 10065811744 10492403712 1069602192 11.548 97 49 92 1 1144194624 64199840 10392592
03:14:49 39113 8192000000 10054363168 10480328704 1085152016 11.697 99 37 68 3 1151461568 59010592 7298960
03:16:38 173009 8192000000 10027914912 10486628352 1058373792 11.441 95 23 46 2 1122342448 59986704 3981952
03:20:57 91662 8192000000 10079927152 10548994048 1127265680 12.096 100 24 48 1 1183858080 52024272 4568128
03:24:32 59576 8192000000 10077607152 10568896512 1126223728 12.086 100 13 25 1 1200456944 71660656 2572560
03:26:39 59017 8192000000 10064538896 10526928896 1126280448 12.086 99 11 22 1 1165158592 36589504 2288640
03:28:40 9109 8192000000 10074380032 10495549440 1112890496 11.960 98 76 131 4 1161258928 32642032 15726400
03:29:51 21061 8192000000 10088861408 10536030208 1129472400 12.116 100 51 102 1 1192306672 52420032 10414240
03:31:14 134604 8192000000 10100744096 10535669760 1108316080 11.916 98 20 40 0 1154081456 41793440 3971936
03:34:29 25178 8192000000 10086753632 10513137664 1132448976 12.144 100 51 99 0 1183059072 39905824 10704272
03:35:59 33022 8192000000 10106587056 10532077568 1129318064 12.115 100 41 82 1 1193415088 56117632 7979392
03:37:39 52718 8192000000 10121072928 10570960896 1129528944 12.117 99 32 64 0 1258900160 122418176 6953040
03:39:40 92130 8192000000 10092561120 10552631296 1137337936 12.190 100 30 60 2 1216077792 72100256 6639600
03:42:32 2791 8192000000 10073111936 10501263360 1140372768 12.219 100 62 118 0 1180777056 28442384 11961904
03:43:36 41888 8192000000 10068103344 10531868672 1124438304 12.069 99 21 43 0 1193581600 64617568 4525728
03:45:52 29376 8192000000 10069463568 10520784896 1142932560 12.243 100 14 27 0 1188189888 42542080 2715248
03:49:09 23220 8192000000 10115877200 10552901632 1150973568 12.319 100 27 55 0 1202166816 44756096 6437152
03:50:33 31262 8192000000 10130183856 10570244096 1153966352 12.347 99 28 56 3 1214265712 54456608 5842752
03:52:06 36027 8192000000 10146630032 10604630016 1109864560 11.931 95 33 65 1 1205367632 88740160 6762912
03:53:42 34417 8192000000 10161326128 10594459648 1139384432 12.210 99 25 49 1 1245967328 100778176 5804720
03:55:17 33424 8192000000 10182284656 10633367552 1132439712 12.144 99 47 93 0 1269755120 127474064 9841344
03:57:00 23362 8192000000 10118264432 10540711936 1124987424 12.074 99 47 92 0 1200652496 67114608 8550464
03:58:24 58106 8192000000 10097325472 10540740608 1103737296 11.873 97 45 90 2 1255230000 142591168 8901536
04:00:23 61162 8192000000 10156096032 10626560000 1118104416 12.009 98 41 82 1 1242785200 116298000 8382784
04:03:27 94214 8192000000 10085564928 10522804224 1091618160 11.758 96 37 71 1 1129245312 30109152 7518000
04:06:08 59000 8192000000 10078453952 10563768320 1097729360 11.816 97 13 25 0 1169376720 68957696 2689664
04:08:07 58028 8192000000 10079768704 10575228928 1120214960 12.029 99 16 32 1 1207686880 83871664 3600256
04:10:08 63778 8192000000 10079277568 10555240448 1113852160 11.969 99 11 22 0 1165515184 49373376 2289648
04:12:14 83444 8192000000 10124923968 10572697600 1109931520 11.932 97 24 48 2 1196910160 81711744 5266896
04:14:41 49092 8192000000 10121612800 10556616704 1065766464 11.512 94 31 62 1 1158517888 86090768 6660656
04:16:35 23795 8192000000 10147697936 10598629376 1112900528 11.960 98 33 66 1 1224720208 103920320 7899360
04:18:05 48184 8192000000 10228730512 10664546304 1108309552 11.916 97 33 66 2 1281697584 165960000 7428032
04:19:57 70952 8192000000 10159357264 10610130944 1069610032 11.548 95 22 44 1 1214429744 139766576 5053136
04:22:08 62748 8192000000 10092120928 10528178176 1118882320 12.016 99 37 72 2 1178121520 51750208 7488992
04:24:11 60760 8192000000 10038513936 10493685760 1105446720 11.889 98 25 50 0 1166193792 55745888 5001184
04:26:21 44293 8192000000 10076078176 10541965312 1126478112 12.088 100 5 10 0 1182885232 55678128 728992
04:29:29 71363 8192000000 10064262768 10541658112 1119440800 12.022 99 7 14 0 1176251824 55283920 1527104
04:32:08 30647 8192000000 10118830496 10570522624 1133267456 12.152 100 24 49 0 1215919632 77357904 5294272
04:33:55 68658 8192000000 10138764976 10615414784 1148428624 12.295 100 18 37 1 1236869888 84338176 4103088
04:36:09 74118 8192000000 9288319056 9641164800 379299984 4.425 33 19 34 2 450283712 66759152 4224576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 16-06-2022 04:38:54 to 16-06-2022 04:39:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 16-06-2022 04:39:10 to 16-06-2022 04:39:12.

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          220595
MON$OLDEST_ACTIVE               220596
MON$OLDEST_SNAPSHOT             220596
MON$NEXT_TRANSACTION            220596
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                       2031392
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             773
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2779 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2779 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2779 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2779 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 ---
:45: 
:46: mscs-test-161.budzdorov.ru    Thu Jun 16 04:38:02 2022
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
--- end of diff output ---

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

16.06.22 04:39:13. 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-06-16 04:39:15 No severe PSQL-related problems occured
16.06.22 04:39:16. End of report.