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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15730380 kB
MemAvailable 30725500 kB
Buffers 0 kB
Cached 16000076 kB
SwapCached 3848 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-10-11 13:37:09
DTS_END                         2021-10-11 13:37:09.5340
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/52820
TRN_ID                          245210
ATT_ID                          637
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: 9122.71 22335 1094725
customer order: creation 181.55 368 21786
customer order: refuse 15.46 345 1855
order to supplier: creation 47.77 1355 5732
order to supplier: removal 3.15 122639 378
invoice (draft): creation 47.64 3867 5717
invoice (draft): removal 50.07 1059 6008
invoice accept: apply 43.16 18632 5179
invoice accept: cancel 45.40 883 5448
customer reserve: creation 3956.34 184 474761
customer reserve: removal 4429.63 67 531556
realization accept: apply 87.22 181 10466
realization accept: cancel 79.43 122 9532
payment from customer: creation 62.41 108 7489
payment from customer: removal 6.01 104 721
payment to supplier: creation 52.53 131 6304
payment to supplier: removal 3.76 57 451
service: total inventory turnovers 3.83 3889 460
service: total monetary turnovers 6.39 521 767
service: aggregate perf. data 0.93 13905 111
service: refresh index statistics 0.03 278279 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-10-2021 13:38:38 to 11-10-2021 13:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6783
TEST_TIME 2 6209
TEST_TIME 3 5244
TEST_TIME 4 4971
TEST_TIME 5 5550
TEST_TIME 6 5696
TEST_TIME 7 6371
TEST_TIME 8 6811
TEST_TIME 9 6916
TEST_TIME 10 7067
TEST_TIME 11 7380
TEST_TIME 12 7565
TEST_TIME 13 7865
TEST_TIME 14 7708
TEST_TIME 15 7750
TEST_TIME 16 8166
TEST_TIME 17 8203
TEST_TIME 18 8466
TEST_TIME 19 8887
TEST_TIME 20 9255
TEST_TIME 21 9507
TEST_TIME 22 9501
TEST_TIME 23 9852
TEST_TIME 24 10310
TEST_TIME 25 10053
TEST_TIME 26 10033
TEST_TIME 27 10226
TEST_TIME 28 11410
TEST_TIME 29 11197
TEST_TIME 30 11138
TEST_TIME 31 11217
TEST_TIME 32 11042
TEST_TIME 33 11104
TEST_TIME 34 11311
TEST_TIME 35 11118
TEST_TIME 36 10954
TEST_TIME 37 10821
TEST_TIME 38 10788
TEST_TIME 39 10592
TEST_TIME 40 10528
TEST_TIME 41 10386
TEST_TIME 42 10341
TEST_TIME 43 10266
TEST_TIME 44 10163
TEST_TIME 45 10199
TEST_TIME 46 10157
TEST_TIME 47 10117
TEST_TIME 48 10185
TEST_TIME 49 10090
TEST_TIME 50 9953
TEST_TIME 51 9858
TEST_TIME 52 9780
TEST_TIME 53 9706
TEST_TIME 54 9642
TEST_TIME 55 9708
TEST_TIME 56 9701
TEST_TIME 57 9686
TEST_TIME 58 9727
TEST_TIME 59 9792
TEST_TIME 60 9796
TEST_TIME 61 9728
TEST_TIME 62 9927
TEST_TIME 63 9855
TEST_TIME 64 9956
TEST_TIME 65 10001
TEST_TIME 66 10066
TEST_TIME 67 10062
TEST_TIME 68 9928
TEST_TIME 69 9848
TEST_TIME 70 9848
TEST_TIME 71 9775
TEST_TIME 72 9695
TEST_TIME 73 9624
TEST_TIME 74 9576
TEST_TIME 75 9556
TEST_TIME 76 9503
TEST_TIME 77 9487
TEST_TIME 78 9471
TEST_TIME 79 9419
TEST_TIME 80 9468
TEST_TIME 81 9523
TEST_TIME 82 9581
TEST_TIME 83 9532
TEST_TIME 84 9511
TEST_TIME 85 9600
TEST_TIME 86 9560
TEST_TIME 87 9596
TEST_TIME 88 9546
TEST_TIME 89 9473
TEST_TIME 90 9434
TEST_TIME 91 9436
TEST_TIME 92 9387
TEST_TIME 93 9402
TEST_TIME 94 9399
TEST_TIME 95 9381
TEST_TIME 96 9337
TEST_TIME 97 9410
TEST_TIME 98 9391
TEST_TIME 99 9421
TEST_TIME 100 9388
TEST_TIME 101 9383
TEST_TIME 102 9448
TEST_TIME 103 9431
TEST_TIME 104 9470
TEST_TIME 105 9522
TEST_TIME 106 9514
TEST_TIME 107 9476
TEST_TIME 108 9392
TEST_TIME 109 9365
TEST_TIME 110 9388
TEST_TIME 111 9336
TEST_TIME 112 9299
TEST_TIME 113 9255
TEST_TIME 114 9235
TEST_TIME 115 9216
TEST_TIME 116 9186
TEST_TIME 117 9215
TEST_TIME 118 9210
TEST_TIME 119 9201
TEST_TIME 120 9129

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-10-2021 13:38:42 to 11-10-2021 13:38:45.

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 1533349 1533349 0 0.00 0 14731 7 0 0
doc_list_biud 1533348 1533348 0 0.00 0 37963 30 0 0
sp_add_invoice_to_stock 5325 5179 146 2.74 50 194703 18632 0 146
sp_cancel_adding_invoice 5448 5448 0 0.00 7 96737 884 0 0
sp_cancel_client_order 1855 1855 0 0.00 5 14414 345 0 0
sp_cancel_customer_reserve 531556 531556 0 0.00 1 29110 68 0 0
sp_cancel_pay_from_customer 721 721 0 0.00 3 8604 104 0 0
sp_cancel_pay_to_supplier 451 451 0 0.00 3 3004 57 0 0
sp_cancel_supplier_invoice 6025 6008 17 0.28 2 37976 1059 0 17
sp_cancel_supplier_order 378 378 0 0.00 7 1062219 122639 0 0
sp_cancel_write_off 9532 9532 0 0.00 1 14241 123 0 0
sp_client_order 21786 21786 0 0.00 4 41543 368 0 0
sp_customer_reserve 474761 474761 0 0.00 0 89854 185 0 0
sp_fill_shopping_cart 33224 33224 0 0.00 1 35098 49 0 0
sp_fill_shopping_cart_clo_res 474747 474747 0 0.00 0 18064 17 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 22771 373 0 0
sp_kill_cost_storno 16152 16152 0 0.00 0 6091 56 0 0
sp_kill_qstorno_ret_qs2qd 1079030 1079030 0 0.00 0 37952 37 0 0
sp_kill_qty_storno 570408 570408 0 0.00 0 37963 80 0 0
sp_lock_selected_doc 21719 21719 0 0.00 0 9041 7 0 0
sp_make_cost_storno 29424 29424 0 0.00 0 11653 54 0 0
sp_multiply_rows_for_pdistr 29424 29424 0 0.00 0 9812 31 0 0
sp_multiply_rows_for_qdistr 21786 21786 0 0.00 0 19279 183 0 0
sp_pay_from_customer 7489 7489 0 0.00 4 9857 109 0 0
sp_pay_to_supplier 6304 6304 0 0.00 5 12582 131 0 0
sp_payment_common 13793 13793 0 0.00 1 12574 27 0 0
sp_qd_handle_on_cancel_clo 1855 1855 0 0.00 0 14405 313 0 0
sp_qd_handle_on_invoice_upd_sts 10613 10613 0 0.00 0 33089 370 0 0
sp_qd_handle_on_reserve_upd_sts 10466 10466 0 0.00 0 15689 54 0 0
sp_reserve_write_off 10466 10466 0 0.00 6 16043 182 0 0
sp_supplier_invoice 5717 5717 0 0.00 7 102552 3868 0 0
sp_supplier_order 5732 5732 0 0.00 5 30298 1355 0 0
srv_aggregate_perf_data 111 111 0 0.00 2676 175494 13906 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2335 129888 43202 0 0
srv_find_qd_qs_mism 1044408 1044408 0 0.00 0 14729 6 0 0
srv_make_invnt_saldo 460 460 0 0.00 65 88476 3890 0 0
srv_make_money_saldo 767 767 0 0.00 1 11985 521 0 0
srv_recalc_idx_stat 4 4 0 0.00 165333 356684 278280 0 0
t$perf-abend:sp_add_invoice_to_stock 146 146 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 17 17 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5179 5179 0 0.00 0 7957 151 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 11254 111 0 0
t$perf-norm:sp_cancel_client_order 1855 1855 0 0.00 0 4037 15 0 0
t$perf-norm:sp_cancel_customer_reserve 727 727 0 0.00 0 3016 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 721 721 0 0.00 0 1365 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 451 451 0 0.00 0 405 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 437 437 0 0.00 0 653 14 0 0
t$perf-norm:sp_cancel_supplier_order 378 378 0 0.00 0 30366 1817 0 0
t$perf-norm:sp_cancel_write_off 1090 1090 0 0.00 0 2684 9 0 0
t$perf-norm:sp_client_order 21786 21786 0 0.00 0 7619 18 0 0
t$perf-norm:sp_customer_reserve 1042 1042 0 0.00 0 2494 7 0 0
t$perf-norm:sp_pay_from_customer 7489 7489 0 0.00 0 8525 10 0 0
t$perf-norm:sp_pay_to_supplier 6304 6304 0 0.00 0 6485 11 0 0
t$perf-norm:sp_reserve_write_off 10466 10466 0 0.00 0 7224 9 0 0
t$perf-norm:sp_supplier_invoice 5717 5717 0 0.00 0 7675 15 0 0
t$perf-norm:sp_supplier_order 5732 5732 0 0.00 0 8519 17 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 183 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 38 1 0 0
t$perf-norm:srv_make_invnt_saldo 460 460 0 0.00 0 1075 6 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 658 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 8 4 0 0
v_add_invoice_to_stock 10620 10328 292 2.74 0 6121 17 0 292
v_all_customers 39664 39664 0 0.00 0 28 1 0 0
v_all_suppliers 31814 31814 0 0.00 0 20 1 0 0
v_all_wares 43572 43572 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 145 6 0 0
v_cancel_client_order 3710 3710 0 0.00 0 3041 7 0 0
v_cancel_customer_prepayment 1442 1442 0 0.00 0 5026 13 0 0
v_cancel_customer_reserve 1454 1454 0 0.00 0 3705 6 0 0
v_cancel_payment_to_supplier 902 902 0 0.00 0 1060 7 0 0
v_cancel_supplier_invoice 908 874 34 3.74 0 5492 22 0 34
v_cancel_supplier_order 756 756 0 0.00 0 2902 8 0 0
v_cancel_write_off 2180 2180 0 0.00 0 5810 23 0 0
v_max_id_clo_ord 5728 5728 0 0.00 0 10544 3 0 0
v_max_id_clo_res 1042 1042 0 0.00 0 6026 15 0 0
v_max_id_ord_sup 5709 5709 0 0.00 0 3524 5 0 0
v_max_non_paid_invoice 1834 1834 0 0.00 0 1368 3 0 0
v_max_non_paid_realizn 3954 3954 0 0.00 0 1754 4 0 0
v_min_id_clo_ord 5728 5728 0 0.00 0 4034 7 0 0
v_min_id_clo_res 1042 1042 0 0.00 0 5779 16 0 0
v_min_id_ord_sup 5709 5709 0 0.00 0 3347 5 0 0
v_min_non_paid_invoice 6304 6304 0 0.00 0 12571 22 0 0
v_min_non_paid_realizn 7489 7489 0 0.00 0 9766 18 0 0
v_our_firm 10978 10978 0 0.00 0 18 1 0 0
v_reserve_write_off 20932 20932 0 0.00 0 5158 7 0 0
x_lock_depdocs_on_canc_invoice 11019 11019 0 0.00 0 13435 119 0 0
x_lock_depdocs_on_canc_sup_ord 378 378 0 0.00 0 5039 327 0 0
x_make_qstorno_1000_1200 5732 5732 0 0.00 2 30289 1269 0 0
x_make_qstorno_1200_2000 5717 5717 0 0.00 3 102539 3678 0 0
x_make_qstorno_2100_3300 474761 474761 0 0.00 0 89851 166 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-10-2021 13:38:46 to 11-10-2021 13:38:50.

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:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:14 24472 8192000000 9716698720 10010607616 795083280 8.846 100 91 182 0 825393168 7836560 22473328
11:09:44 14735 8192000000 9751207536 10055372800 825683824 9.156 100 84 168 1 858793136 12548368 20560944
11:11:01 17931 8192000000 9790097040 10103767040 855531216 9.455 100 87 174 0 897166736 21180512 20455008
11:12:25 18454 8192000000 9867767360 10201567232 911180656 10.009 100 74 148 2 977539376 50616032 15742688
11:13:47 5712 8192000000 9912022320 10258292736 966029440 10.548 100 81 160 1 1029930240 46855216 17045584
11:14:56 6797 8192000000 9983033424 10338082816 1005220944 10.929 100 90 181 0 1086713104 63268016 18224144
11:16:05 2998 8192000000 10002714336 10364137472 1029064784 11.159 100 90 179 0 1111165136 64169312 17931040
11:17:11 1457 8192000000 10020636800 10385633280 1052864112 11.388 100 92 182 0 1131289264 59900864 18524288
11:18:14 2237 8192000000 10064137328 10432831488 1066997984 11.523 100 88 174 0 1168931296 83777872 18155440
11:19:20 10389 8192000000 9991844752 10402598912 1078608688 11.634 100 39 79 1 1131762688 45119664 8034336
11:20:35 19652 8192000000 9995813600 10397970432 1080384304 11.651 100 34 68 1 1127514256 39621296 7508656
11:21:57 10070 8192000000 9991797536 10397097984 1085649424 11.701 100 29 59 0 1109337520 17326656 6361440
11:23:09 9822 8192000000 10008984304 10421182464 1091718576 11.759 100 29 56 2 1126080768 28068064 6294128
11:24:27 6903 8192000000 10009055728 10403991552 1101885152 11.856 100 39 75 2 1121549200 10545104 9118944
11:25:36 36530 8192000000 10016053680 10429505536 1100425440 11.842 100 42 85 0 1141462368 32364176 8672752
11:27:14 4285 8192000000 10014706912 10404052992 1088408416 11.728 99 45 88 1 1126051808 27639456 10003936
11:28:23 7350 8192000000 9970341328 10370744320 1043577456 11.299 98 23 45 1 1071493840 23083328 4833056
11:29:31 5660 8192000000 9955652864 10330304512 1013471600 11.009 97 35 67 2 1049699088 28300272 7927216
11:30:39 9560 8192000000 9935764096 10329067520 1013792864 11.012 98 55 111 3 1038941088 12552688 12595536
11:31:49 7492 8192000000 9951618784 10350522368 1038176208 11.247 100 40 80 1 1061006256 13534512 9295536
11:32:57 10789 8192000000 9951750832 10345410560 1037698416 11.243 99 31 62 0 1062833664 17738720 7396528
11:34:08 12015 8192000000 9974246816 10377900032 1060754976 11.464 100 31 63 1 1094017072 26451456 6810640
11:35:21 32081 8192000000 9981740736 10390519808 1070502512 11.557 100 18 36 2 1099565616 25566576 3496528
11:36:57 5731 8192000000 9987131136 10389565440 1077937264 11.628 100 49 96 1 1095156192 5951888 11267040
11:38:12 17643 8192000000 10057495568 10454974464 1106948912 11.904 100 52 104 0 1159101312 41632752 10519648
11:39:40 8198 8192000000 10067182640 10471088128 1123341664 12.059 100 46 92 0 1179640320 46493184 9805472
11:40:50 46028 8192000000 10101267232 10517409792 1128849584 12.111 100 40 81 0 1217897520 80500064 8547872
11:42:40 4160 8192000000 10122781488 10542260224 1138538752 12.202 100 43 86 0 1226172896 78631920 9002224
11:43:48 16009 8192000000 10177707632 10595790848 1149872064 12.308 100 60 120 0 1283663296 120819152 12972080
11:45:10 10824 8192000000 10199378432 10624446464 1160777472 12.411 100 61 122 0 1311555632 137090464 13687696
11:46:25 10937 8192000000 10221847552 10642427904 1173146352 12.526 100 44 85 0 1328346656 145145520 10054784
11:47:44 11507 8192000000 10281201424 10700509184 1173756912 12.532 99 51 102 0 1373308032 187526592 12024528
11:48:59 8022 8192000000 10273629472 10704379904 1174177472 12.536 99 59 117 0 1376926432 189694816 13054144
11:50:12 4281 8192000000 10291188272 10700201984 1164209376 12.443 99 80 158 0 1386282464 204913088 17160000
11:51:22 5824 8192000000 10283340624 10689794048 1144979072 12.262 100 71 143 1 1384715328 223371712 16364544
11:52:33 2977 8192000000 10316802864 10725359616 1144438432 12.257 99 79 155 0 1424973264 261736880 18797952
11:53:40 3266 8192000000 10341348976 10748112896 1158478032 12.389 100 71 142 0 1438902160 263500864 16923264
11:54:50 2334 8192000000 10366648512 10761318400 1145559168 12.268 99 93 184 5 1460082336 293017600 21505568
11:55:57 6272 8192000000 10390859104 10806243328 1149677904 12.306 100 61 122 1 1490232608 325031424 15523280
11:57:06 8210 8192000000 10366324176 10801799168 1141140304 12.226 99 55 110 2 1455316128 300083616 14092208
11:58:23 10703 8192000000 10363948480 10794455040 1131803488 12.138 99 46 90 2 1442183232 298434400 11945344
11:59:36 9295 8192000000 10368847472 10808274944 1136087856 12.179 99 38 75 0 1467580768 321876784 9616128
12:00:48 17265 8192000000 10345194736 10793619456 1117588464 12.004 99 36 70 0 1450469616 323249920 9631232
12:02:07 22372 8192000000 10291796032 10713292800 1111173984 11.944 99 29 58 0 1382089856 263432064 7483808
12:03:31 15883 8192000000 10346468800 10795864064 1112107648 11.952 100 22 44 0 1445986656 327512640 6366368
12:05:28 86486 8192000000 10055344528 10527199232 1057025664 11.428 96 4 8 0 1135633888 77615136 993088
12:08:06 129888 8192000000 10136519648 10616254464 1105786304 11.893 98 11 22 0 1197255312 88974720 2494288
12:11:17 81703 8192000000 10052676192 10503069696 1121213056 12.038 100 13 26 0 1185641184 61191888 3236240
12:13:43 7555 8192000000 10085217600 10500292608 1125794880 12.082 99 54 107 2 1156011312 18660576 11555856
12:14:52 88621 8192000000 10070075344 10553483264 1136217184 12.180 100 27 54 1 1189217264 47493232 5506848
12:18:02 67110 8192000000 10081000528 10539061248 1135995776 12.178 99 25 49 1 1166443744 25790496 4657472
12:20:12 43424 8192000000 10130334592 10585522176 1153885392 12.346 100 31 62 0 1243175936 82227344 7063200
12:21:55 25707 8192000000 10158934656 10591506432 1155146736 12.358 100 50 99 0 1263092480 96650448 11295296
12:23:26 18577 8192000000 10118493408 10577293312 1153367184 12.341 100 9 18 0 1220371904 65016384 1988336
12:24:45 79708 8192000000 10060050000 10519658496 1094020400 11.781 95 22 44 0 1127099184 28286576 4792208
12:27:05 51604 8192000000 10071220720 10519293952 1130657072 12.128 99 17 34 0 1180665552 46298576 3709904
12:29:16 6327 8192000000 10077184784 10535878656 1145884896 12.271 100 25 51 3 1192310448 40748848 5676704
12:30:23 35780 8192000000 10097194416 10549260288 1148695440 12.297 100 19 38 1 1193261072 40241040 4324592
12:32:07 68418 8192000000 10059117440 10566307840 1109318128 11.926 97 5 10 0 1143762688 33389456 1055104
12:34:16 42765 8192000000 10097351856 10539192320 1108258704 11.916 97 24 48 0 1177635552 64118352 5258496
12:36:01 48490 8192000000 10115238768 10576400384 1114292624 11.973 98 35 70 1 1216845488 95030032 7522832
12:37:51 51864 8192000000 10104242288 10528940032 1081934368 11.666 95 26 50 1 1157528720 70692784 4901568
12:39:44 36369 8192000000 10073374336 10529812480 1105098400 11.886 98 9 18 0 1176548832 69719984 1730448
12:41:24 34370 8192000000 10100610208 10563166208 1122512624 12.051 99 24 48 0 1212403440 85443152 4447664
12:43:28 55552 8192000000 10059415424 10550767616 1092417184 11.766 97 25 48 1 1155798224 58934528 4446512
12:45:24 78573 8192000000 10081168480 10528296960 1120113840 12.028 99 28 56 0 1165813712 40196752 5503120
12:48:03 45790 8192000000 10046811824 10502885376 1114449760 11.975 99 12 24 0 1143407536 26827296 2130480
12:49:51 56372 8192000000 10070898816 10520506368 1099194448 11.830 97 12 24 0 1141065488 39288848 2582192
12:51:48 76786 8192000000 10035766528 10520776704 1093878752 11.780 97 13 26 0 1133822912 37015072 2929088
12:54:05 30045 8192000000 10072996016 10535145472 1099334816 11.831 98 25 50 0 1156324400 51887264 5102320
12:55:44 88044 8192000000 10153831568 10602926080 1072399520 11.575 95 26 52 2 1171694544 94029936 5265088
12:58:12 59740 8192000000 10082095344 10528161792 1103654048 11.872 98 17 34 1 1177176384 69534880 3987456
13:00:25 26504 8192000000 10079755712 10509152256 1120575632 12.032 100 54 107 0 1159937872 28477232 10885008
13:02:07 61704 8192000000 10071831680 10527567872 1114032256 11.971 98 19 38 0 1153906304 36234992 3639056
13:04:09 55239 8192000000 10089308512 10570256384 1135549024 12.174 100 28 56 0 1193881936 52800944 5531968
13:06:04 79512 8192000000 10072202000 10528423936 1146452432 12.276 100 43 86 1 1191678960 36963520 8263008
13:08:24 78670 8192000000 10124248704 10606379008 1144977232 12.262 99 12 24 0 1220239936 72622688 2640016
13:10:45 50056 8192000000 10049853280 10489073664 1117097152 12.000 97 17 32 1 1149150912 28887424 3166336
13:12:38 43308 8192000000 10122586240 10558758912 1121102688 12.037 97 24 48 1 1186450496 60352928 4994880
13:14:22 55878 8192000000 10074336560 10548539392 1084694384 11.692 95 20 42 1 1185549920 96063280 4792256
13:16:18 58126 8192000000 10058161248 10495975424 1081498880 11.662 95 26 51 1 1112692240 25525024 5668336
13:18:19 70646 8192000000 10084172288 10569838592 1105894512 11.894 97 35 70 0 1199527216 86411936 7220768
13:20:31 45367 8192000000 10113838544 10600439808 1120893216 12.035 99 33 66 2 1206981504 79593552 6494736
13:22:19 90316 8192000000 10083235808 10547032064 1088764928 11.731 96 12 24 0 1135167376 44361952 2040496
13:24:49 125435 8192000000 10089783680 10555392000 1139217936 12.208 100 22 44 0 1197037296 53360464 4458896
13:27:55 50860 8192000000 10026089840 10505682944 1065751424 11.511 94 7 14 0 1105654080 38534448 1368208
13:29:46 56975 8192000000 10109106912 10562564096 1125110784 12.075 100 14 27 0 1175789984 47591232 3087968
13:31:44 67373 8192000000 10100910112 10568339456 1124252208 12.067 99 9 18 0 1213430720 87105040 2073472
13:33:54 53071 8192000000 10063426912 10516996096 1102967232 11.866 98 16 32 0 1166455072 59986464 3501376
13:35:51 44611 8192000000 10086525088 10523394048 1081995712 11.666 96 26 51 1 1150223424 61555600 6672112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-10-2021 13:38:52 to 11-10-2021 13:39:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 146 v_add_invoice_to_stock 335544517
except 17 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 11-10-2021 13:39:08 to 11-10-2021 13:39:10.

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          242398
MON$OLDEST_ACTIVE               245347
MON$OLDEST_SNAPSHOT             245347
MON$NEXT_TRANSACTION            245347
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                       2060768
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             821
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2625 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2625 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2625 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2625 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 ---
:68: 
:69: mscs-test-161.budzdorov.ru    Mon Oct 11 13:37:15 2021
:70:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:71: 
:72: 
:73: mscs-test-161.budzdorov.ru    Mon Oct 11 13:37:22 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
:76: 
:77: mscs-test-161.budzdorov.ru    Mon Oct 11 13:37:22 2021
:78:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:79: 
--- end of diff output ---

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

11.10.21 13:39:11. 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
2021-10-11 13:39:13 No severe PSQL-related problems occured
11.10.21 13:39:15. End of report.