Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.12.2021 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 16705180 kB
MemAvailable 31435368 kB
Buffers 20 kB
Cached 14907156 kB
SwapCached 0 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-12-09 04:37:10
DTS_END                         2021-12-09 04:37:10.7510
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/34252
TRN_ID                          226110
ATT_ID                          605
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: 8884.03 21893 1066083
customer order: creation 164.75 413 19770
customer order: refuse 14.50 303 1740
order to supplier: creation 42.80 1487 5136
order to supplier: removal 3.57 104625 428
invoice (draft): creation 44.54 3411 5345
invoice (draft): removal 51.12 917 6134
invoice accept: apply 38.97 17346 4676
invoice accept: cancel 45.84 691 5501
customer reserve: creation 3687.83 168 442540
customer reserve: removal 4514.35 66 541722
realization accept: apply 79.84 181 9581
realization accept: cancel 71.96 126 8635
payment from customer: creation 57.47 102 6896
payment from customer: removal 5.66 60 679
payment to supplier: creation 46.98 105 5638
payment to supplier: removal 3.38 110 405
service: total inventory turnovers 4.02 3313 482
service: total monetary turnovers 5.64 384 677
service: aggregate perf. data 0.78 18996 94
service: refresh index statistics 0.03 285064 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-12-2021 04:38:30 to 09-12-2021 04:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4661
TEST_TIME 2 4618
TEST_TIME 3 4755
TEST_TIME 4 5087
TEST_TIME 5 4941
TEST_TIME 6 4747
TEST_TIME 7 4784
TEST_TIME 8 4602
TEST_TIME 9 4845
TEST_TIME 10 5050
TEST_TIME 11 5528
TEST_TIME 12 6085
TEST_TIME 13 6012
TEST_TIME 14 6477
TEST_TIME 15 6698
TEST_TIME 16 7117
TEST_TIME 17 7486
TEST_TIME 18 8169
TEST_TIME 19 8374
TEST_TIME 20 8657
TEST_TIME 21 8938
TEST_TIME 22 9756
TEST_TIME 23 10770
TEST_TIME 24 11081
TEST_TIME 25 11245
TEST_TIME 26 11216
TEST_TIME 27 11068
TEST_TIME 28 10736
TEST_TIME 29 10572
TEST_TIME 30 10463
TEST_TIME 31 10394
TEST_TIME 32 10292
TEST_TIME 33 10129
TEST_TIME 34 9965
TEST_TIME 35 9809
TEST_TIME 36 9754
TEST_TIME 37 9596
TEST_TIME 38 9445
TEST_TIME 39 9588
TEST_TIME 40 9674
TEST_TIME 41 9756
TEST_TIME 42 9727
TEST_TIME 43 9725
TEST_TIME 44 9708
TEST_TIME 45 9741
TEST_TIME 46 9857
TEST_TIME 47 9851
TEST_TIME 48 9758
TEST_TIME 49 9840
TEST_TIME 50 9876
TEST_TIME 51 9694
TEST_TIME 52 9587
TEST_TIME 53 9542
TEST_TIME 54 9492
TEST_TIME 55 9366
TEST_TIME 56 9295
TEST_TIME 57 9304
TEST_TIME 58 9284
TEST_TIME 59 9306
TEST_TIME 60 9312
TEST_TIME 61 9280
TEST_TIME 62 9261
TEST_TIME 63 9289
TEST_TIME 64 9406
TEST_TIME 65 9398
TEST_TIME 66 9384
TEST_TIME 67 9433
TEST_TIME 68 9409
TEST_TIME 69 9524
TEST_TIME 70 9552
TEST_TIME 71 9548
TEST_TIME 72 9445
TEST_TIME 73 9468
TEST_TIME 74 9391
TEST_TIME 75 9364
TEST_TIME 76 9288
TEST_TIME 77 9218
TEST_TIME 78 9183
TEST_TIME 79 9221
TEST_TIME 80 9181
TEST_TIME 81 9177
TEST_TIME 82 9212
TEST_TIME 83 9200
TEST_TIME 84 9192
TEST_TIME 85 9222
TEST_TIME 86 9251
TEST_TIME 87 9256
TEST_TIME 88 9229
TEST_TIME 89 9195
TEST_TIME 90 9187
TEST_TIME 91 9385
TEST_TIME 92 9401
TEST_TIME 93 9302
TEST_TIME 94 9267
TEST_TIME 95 9207
TEST_TIME 96 9164
TEST_TIME 97 9188
TEST_TIME 98 9142
TEST_TIME 99 9132
TEST_TIME 100 9129
TEST_TIME 101 9122
TEST_TIME 102 9145
TEST_TIME 103 9142
TEST_TIME 104 9137
TEST_TIME 105 9170
TEST_TIME 106 9121
TEST_TIME 107 9138
TEST_TIME 108 9170
TEST_TIME 109 9178
TEST_TIME 110 9168
TEST_TIME 111 9108
TEST_TIME 112 9080
TEST_TIME 113 9051
TEST_TIME 114 9032
TEST_TIME 115 9001
TEST_TIME 116 9002
TEST_TIME 117 9009
TEST_TIME 118 8972
TEST_TIME 119 8946
TEST_TIME 120 8886

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-12-2021 04:38:34 to 09-12-2021 04:38:37.

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 1474966 1474966 0 0.00 0 19665 6 0 0
doc_list_biud 1474965 1474965 0 0.00 0 51183 30 0 0
sp_add_invoice_to_stock 4784 4676 108 2.25 48 226042 17347 0 108
sp_cancel_adding_invoice 5501 5501 0 0.00 6 53863 692 0 0
sp_cancel_client_order 1740 1740 0 0.00 5 19299 303 0 0
sp_cancel_customer_reserve 541722 541722 0 0.00 1 21352 66 0 0
sp_cancel_pay_from_customer 679 679 0 0.00 3 3579 61 0 0
sp_cancel_pay_to_supplier 405 405 0 0.00 3 15913 110 0 0
sp_cancel_supplier_invoice 6144 6134 10 0.16 2 51212 918 0 10
sp_cancel_supplier_order 428 428 0 0.00 9 1059793 104625 0 0
sp_cancel_write_off 8635 8635 0 0.00 1 8593 127 0 0
sp_client_order 19770 19770 0 0.00 4 66253 414 0 0
sp_customer_reserve 442540 442540 0 0.00 0 105991 168 0 0
sp_fill_shopping_cart 30227 30227 0 0.00 1 16095 40 0 0
sp_fill_shopping_cart_clo_res 442535 442535 0 0.00 0 12637 16 0 0
sp_get_clo_for_invoice 4671 4671 0 0.00 0 34943 431 0 0
sp_kill_cost_storno 15220 15220 0 0.00 0 8226 57 0 0
sp_kill_qstorno_ret_qs2qd 1098641 1098641 0 0.00 0 51182 36 0 0
sp_kill_qty_storno 578412 578412 0 0.00 0 51183 76 0 0
sp_lock_selected_doc 19911 19911 0 0.00 0 8344 5 0 0
sp_make_cost_storno 26786 26786 0 0.00 0 19526 50 0 0
sp_multiply_rows_for_pdistr 26786 26786 0 0.00 0 11061 26 0 0
sp_multiply_rows_for_qdistr 19770 19770 0 0.00 0 65139 219 0 0
sp_pay_from_customer 6896 6896 0 0.00 4 19270 102 0 0
sp_pay_to_supplier 5638 5638 0 0.00 5 20600 105 0 0
sp_payment_common 12534 12534 0 0.00 1 20527 17 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 19293 270 0 0
sp_qd_handle_on_invoice_upd_sts 10172 10172 0 0.00 0 17208 296 0 0
sp_qd_handle_on_reserve_upd_sts 9581 9581 0 0.00 0 17506 73 0 0
sp_reserve_write_off 9581 9581 0 0.00 7 19602 181 0 0
sp_supplier_invoice 5345 5345 0 0.00 8 87801 3411 0 0
sp_supplier_order 5136 5136 0 0.00 6 78603 1487 0 0
srv_aggregate_perf_data 94 94 0 0.00 3020 270759 18997 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 814 217957 53652 0 0
srv_find_qd_qs_mism 1019893 1019893 0 0.00 0 17850 6 0 0
srv_make_invnt_saldo 482 482 0 0.00 54 119100 3313 0 0
srv_make_money_saldo 677 677 0 0.00 1 16084 385 0 0
srv_recalc_idx_stat 4 4 0 0.00 162826 537665 285065 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4677 4677 0 0.00 0 15553 148 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 3476 64 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 1632 9 0 0
t$perf-norm:sp_cancel_customer_reserve 662 662 0 0.00 0 10613 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 679 679 0 0.00 0 1968 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 405 405 0 0.00 0 2146 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 551 10 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 18822 1285 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 5648 13 0 0
t$perf-norm:sp_client_order 19770 19770 0 0.00 0 15733 15 0 0
t$perf-norm:sp_customer_reserve 933 933 0 0.00 0 1364 3 0 0
t$perf-norm:sp_pay_from_customer 6896 6896 0 0.00 0 5597 7 0 0
t$perf-norm:sp_pay_to_supplier 5638 5638 0 0.00 0 4499 7 0 0
t$perf-norm:sp_reserve_write_off 9581 9581 0 0.00 0 11877 12 0 0
t$perf-norm:sp_supplier_invoice 5345 5345 0 0.00 0 9438 20 0 0
t$perf-norm:sp_supplier_order 5136 5136 0 0.00 0 6839 12 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 132 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 195 5 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 165 3 0 0
t$perf-norm:srv_make_money_saldo 677 677 0 0.00 0 7788 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9558 9342 216 2.25 0 7114 15 0 216
v_all_customers 35842 35842 0 0.00 0 18 1 0 0
v_all_suppliers 29292 29292 0 0.00 0 12 1 0 0
v_all_wares 39540 39540 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 75 5 0 0
v_cancel_client_order 3480 3480 0 0.00 0 1578 4 0 0
v_cancel_customer_prepayment 1358 1358 0 0.00 0 911 7 0 0
v_cancel_customer_reserve 1324 1324 0 0.00 0 1370 4 0 0
v_cancel_payment_to_supplier 810 810 0 0.00 0 7680 14 0 0
v_cancel_supplier_invoice 762 742 20 2.62 0 2215 15 0 20
v_cancel_supplier_order 856 856 0 0.00 0 1519 5 0 0
v_cancel_write_off 1948 1948 0 0.00 0 6145 18 0 0
v_max_id_clo_ord 5129 5129 0 0.00 0 304 1 0 0
v_max_id_clo_res 933 933 0 0.00 0 4873 21 0 0
v_max_id_ord_sup 5327 5327 0 0.00 0 7151 8 0 0
v_max_non_paid_invoice 1448 1448 0 0.00 0 527 2 0 0
v_max_non_paid_realizn 3749 3749 0 0.00 0 3336 4 0 0
v_min_id_clo_ord 5129 5129 0 0.00 0 6856 7 0 0
v_min_id_clo_res 933 933 0 0.00 0 7890 30 0 0
v_min_id_ord_sup 5327 5327 0 0.00 0 3796 7 0 0
v_min_non_paid_invoice 5638 5638 0 0.00 0 20524 12 0 0
v_min_non_paid_realizn 6896 6896 0 0.00 0 4909 11 0 0
v_our_firm 9992 9992 0 0.00 0 16 1 0 0
v_reserve_write_off 19162 19162 0 0.00 0 9657 4 0 0
x_lock_depdocs_on_canc_invoice 11264 11264 0 0.00 0 20523 107 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 8241 270 0 0
x_make_qstorno_1000_1200 5136 5136 0 0.00 2 78586 1424 0 0
x_make_qstorno_1200_2000 5345 5345 0 0.00 3 86927 3252 0 0
x_make_qstorno_2100_3300 442539 442539 0 0.00 0 105988 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-12-2021 04:38:38 to 09-12-2021 04:38:42.

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:14 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:15 26070 8192000000 9716534432 10012311552 786048080 8.755 100 86 172 1 814846560 7501056 21297424
02:09:45 12769 8192000000 9758418928 10062512128 830139584 9.201 100 88 176 0 863077904 11615792 21322528
02:11:05 11616 8192000000 9800713216 10115399680 875120064 9.651 100 91 182 0 913750080 17534528 21095488
02:12:22 16986 8192000000 9850789440 10181144576 908683360 9.984 100 85 170 0 967048048 39646144 18718544
02:13:43 10014 8192000000 9905823184 10254774272 946740928 10.359 100 70 140 0 1017214768 55905280 14568560
02:14:57 5858 8192000000 9942771744 10294415360 987284224 10.755 100 79 157 1 1055866736 52204768 16377744
02:16:04 3248 8192000000 9982270336 10346745856 1013514272 11.009 100 70 140 0 1103217488 75546560 14156656
02:17:17 2483 8192000000 10027535264 10394263552 1042929216 11.293 100 88 173 0 1138202640 77284864 17988560
02:18:23 1462 8192000000 10046298992 10412613632 1059008560 11.447 100 94 187 1 1151089056 73360640 18719856
02:19:25 2648 8192000000 10015898464 10397143040 1069032400 11.543 100 83 164 1 1123268896 37061120 17175376
02:20:31 3950 8192000000 9986943376 10382282752 1074097664 11.591 100 31 60 1 1097136784 16182448 6856672
02:21:36 37824 8192000000 9996062928 10390114304 1080282432 11.650 100 40 79 0 1107261072 18476880 8501760
02:23:15 10152 8192000000 10019386832 10422390784 1084777152 11.693 100 48 93 2 1112217712 17288368 10152192
02:24:27 4290 8192000000 9998218160 10394779648 1088457328 11.728 100 28 56 1 1108832112 13462912 6911872
02:25:32 6857 8192000000 10006088560 10414841856 1096142464 11.801 100 25 49 1 1116742560 15533312 5066784
02:26:43 26167 8192000000 10003201920 10415099904 1102824672 11.864 100 19 38 2 1128415824 21200816 4390336
02:28:11 3093 8192000000 10018646976 10420064256 1084184608 11.687 99 49 98 2 1115630208 19296368 12149232
02:29:15 7148 8192000000 9976708544 10374123520 1034724928 11.214 97 44 88 0 1067722224 23071824 9925472
02:30:25 50360 8192000000 9927848224 10317787136 990435168 10.786 95 22 44 0 1020179056 24372976 5370912
02:32:16 3666 8192000000 9961872736 10340061184 1015679728 11.030 99 49 99 0 1060999744 34323120 10996896
02:33:22 5965 8192000000 9958664432 10340323328 1030228528 11.171 99 32 62 1 1077752032 40364688 7158816
02:34:28 6569 8192000000 9977056144 10372812800 1053882768 11.398 100 46 93 2 1085963616 22176192 9904656
02:35:35 52440 8192000000 9991085632 10386460672 1066143680 11.515 100 34 68 1 1097280592 24160480 6976432
02:37:35 18336 8192000000 10007209024 10399899648 1093528592 11.776 100 51 102 1 1113652448 9133488 10990368
02:39:11 15137 8192000000 10066174000 10466082816 1117357072 12.002 100 46 92 1 1183512544 56842768 9312704
02:40:31 37874 8192000000 10123163728 10527862784 1129656256 12.118 100 53 106 2 1222710480 81912960 11141264
02:42:24 36405 8192000000 10182571200 10595774464 1156449920 12.370 100 33 66 3 1289017024 124940144 7626960
02:44:06 20111 8192000000 10252394144 10670432256 1168279984 12.481 100 48 97 1 1355019472 175785072 10954416
02:45:30 9045 8192000000 10284449472 10701463552 1179092304 12.582 100 45 90 0 1386819920 197648816 10078800
02:46:44 7915 8192000000 10329673280 10748178432 1192738496 12.709 100 52 104 3 1442718528 237984336 11995696
02:47:56 7984 8192000000 10385981184 10822410240 1204079168 12.814 100 58 114 1 1488741744 271164336 13498240
02:49:14 813 8192000000 10440372768 10859429888 1216033360 12.925 100 87 174 0 1541751936 305642688 20075888
02:50:21 4823 8192000000 10441627600 10869317632 1210656480 12.875 100 55 109 0 1543828000 320184688 12986832
02:51:31 3441 8192000000 10479947040 10909466624 1213544896 12.902 100 68 136 0 1583102688 353359840 16197952
02:52:39 5523 8192000000 10501399168 10929700864 1226847488 13.025 100 68 137 0 1601532304 358023008 16661808
02:53:49 18655 8192000000 10491361376 10999848960 1197232400 12.751 99 43 85 0 1627230480 418904016 11094064
02:55:33 21664 8192000000 10435506064 10899492864 1169025520 12.488 98 28 56 1 1517159728 340618960 7515248
02:56:55 24971 8192000000 10429808720 10876157952 1160854656 12.411 100 28 56 0 1536381952 367254000 8273296
02:58:23 20457 8192000000 10401023536 10853466112 1159753296 12.401 100 23 46 0 1522270112 355961760 6555056
02:59:46 65819 8192000000 10178869024 10670702592 1071742320 11.569 94 15 31 0 1292259888 216987792 3529776
03:01:56 36829 8192000000 10121456000 10592948224 1114256256 11.973 98 9 18 0 1213863296 97305984 2301056
03:03:35 63736 8192000000 10066387104 10483433472 1072337808 11.574 95 28 56 1 1110118688 32204160 5576720
03:05:50 217956 8192000000 10045665136 10465865728 981440576 10.698 88 36 71 1 1022709552 33428576 7840400
03:10:30 71471 8192000000 10040116048 10474704896 1082844496 11.675 97 20 40 2 1125010288 38238992 3926800
03:12:43 32118 8192000000 10058597264 10499141632 1094265040 11.783 99 12 24 0 1151663904 54799168 2599696
03:14:17 52920 8192000000 10105848560 10542645248 1096316704 11.803 97 19 38 0 1169140592 68956960 3866928
03:16:10 70868 8192000000 10104491968 10570031104 1106377840 11.898 99 21 42 1 1232926928 121665360 4883728
03:18:24 73393 8192000000 10057314992 10512076800 1103742448 11.873 98 23 46 0 1147119904 38560352 4817104
03:20:38 15700 8192000000 10070530688 10494709760 1116482144 11.994 99 60 116 2 1170302496 41015472 12804880
03:21:54 32026 8192000000 10099297392 10536321024 1128194272 12.104 100 32 63 1 1203037824 68425856 6417696
03:23:29 99292 8192000000 10066993296 10527772672 1116924336 11.998 98 11 21 1 1155465392 36285008 2256048
03:26:08 23396 8192000000 10129995280 10583805952 1153412816 12.342 100 20 40 0 1234560224 76583488 4563920
03:27:35 127223 8192000000 10081195408 10517368832 1144749968 12.260 99 26 50 2 1176372864 26791600 4831296
03:30:43 35257 8192000000 10088147088 10553085952 1147489568 12.286 100 8 16 0 1193699168 44643424 1566176
03:32:45 2304 8192000000 10075139120 10557272064 1154874048 12.355 100 21 44 1 1190436048 30864160 4697840
03:33:48 51222 8192000000 10080895392 10578878464 1142931328 12.243 99 11 21 0 1206791696 61347408 2512960
03:35:40 49952 8192000000 10077743968 10554949632 1156022096 12.366 100 22 44 0 1219635952 58280224 5333632
03:37:32 58134 8192000000 10101696656 10566107136 1127032704 12.093 97 19 38 1 1183118544 51745408 4340432
03:39:32 54362 8192000000 10100562368 10567479296 1124574304 12.070 98 26 52 0 1193763536 64026448 5162784
03:41:26 80371 8192000000 10081622096 10542600192 1134224496 12.161 99 12 24 0 1193766128 56730160 2811472
03:43:48 39469 8192000000 10089784704 10556063744 1111778096 11.949 97 22 44 1 1170686800 54350800 4557904
03:45:37 35266 8192000000 10127224928 10617573376 1132295344 12.143 99 26 52 1 1231743104 94148032 5299728
03:47:13 102999 8192000000 10085199344 10550013952 1034120752 11.208 91 13 26 0 1063802752 27166896 2515104
03:49:57 105693 8192000000 10044322064 10512097280 1075031408 11.600 96 31 61 1 1159191104 77320240 6839456
03:52:47 44673 8192000000 10048101936 10480975872 1104271264 11.878 100 9 18 0 1156739904 50577248 1891392
03:56:18 95001 8192000000 10078651184 10539393024 1110087472 11.933 99 6 12 0 1186285520 74855376 1342672
03:59:13 54356 8192000000 10060558752 10525638656 1100921280 11.846 98 19 38 0 1173545632 68808272 3816080
04:01:08 56090 8192000000 10070753168 10505584640 1082816656 11.674 98 32 64 2 1138478544 49255344 6406544
04:03:09 52466 8192000000 10060288304 10519425024 1100755008 11.845 99 18 36 0 1165375760 61051216 3569536
04:05:19 60587 8192000000 10087750336 10508873728 1084880672 11.694 96 27 54 0 1152008352 61573008 5554672
04:07:24 71390 8192000000 10078137488 10646138880 1071845120 11.570 96 16 32 0 1214873808 139738528 3290160
04:09:37 190316 8192000000 10080490304 10555056128 1125517632 12.079 100 13 26 0 1192788000 64484640 2785728
04:13:48 79124 8192000000 10050940816 10542403584 1106659216 11.901 98 6 12 0 1166308128 58883184 765728
04:16:07 39639 8192000000 10093231856 10549919744 1138317568 12.200 100 23 46 0 1174968224 31538560 5112096
04:18:06 73092 8192000000 10104451632 10598985728 1139225120 12.208 99 23 46 0 1219969024 75553056 5190848
04:20:19 35592 8192000000 10103123856 10527588352 1139681104 12.213 99 55 105 1 1190678784 39917312 11080368
04:21:57 90092 8192000000 10105708128 10532655104 1134975280 12.168 99 19 38 0 1189249520 50638800 3635440
04:24:28 29385 8192000000 10096671344 10529632256 1123782832 12.063 98 35 70 1 1180614032 49660432 7170768
04:26:00 74693 8192000000 10079039136 10526924800 1125824064 12.082 98 20 40 0 1168785920 38970800 3991056
04:28:51 105456 8192000000 10107814272 10545037312 1117819856 12.006 98 35 70 0 1172248384 47797264 6631264
04:31:38 86557 8192000000 10092543488 10558058496 1130849504 12.129 99 13 26 0 1204536096 70507536 3179056
04:34:05 66529 8192000000 10062646208 10525274112 1131323936 12.134 99 17 33 0 1188788240 54265024 3199280
04:36:15 63096 8192000000 9446426848 9793241088 550299472 6.294 48 7 14 0 577055712 25402064 1354176

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 09-12-2021 04:38:44 to 09-12-2021 04:38:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-12-2021 04:38:59 to 09-12-2021 04:39:01.

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          227060
MON$OLDEST_ACTIVE               227061
MON$OLDEST_SNAPSHOT             227061
MON$NEXT_TRANSACTION            227061
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                       2023296
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2676 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2676 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2676 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2676 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 ---
:93: 
:94: mscs-test-161.budzdorov.ru    Thu Dec  9 04:37:24 2021
:95:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:96: 
--- end of diff output ---

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

09.12.21 04:39:02. 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-12-09 04:39:05 No severe PSQL-related problems occured
09.12.21 04:39:06. End of report.