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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 15783608 kB
MemAvailable 30643120 kB
Buffers 20 kB
Cached 15844860 kB
SwapCached 3868 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-12 04:37:08
DTS_END                         2021-10-12 04:37:08.7060
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/34630
TRN_ID                          234599
ATT_ID                          635
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: 9123.54 23277 1094825
customer order: creation 168.14 406 20177
customer order: refuse 14.60 316 1752
order to supplier: creation 45.48 1320 5457
order to supplier: removal 3.49 113196 419
invoice (draft): creation 43.82 3191 5258
invoice (draft): removal 51.10 1000 6132
invoice accept: apply 40.87 19637 4904
invoice accept: cancel 46.67 848 5600
customer reserve: creation 3786.43 194 454372
customer reserve: removal 4629.51 66 555541
realization accept: apply 83.16 196 9979
realization accept: cancel 82.07 137 9848
payment from customer: creation 59.72 136 7166
payment from customer: removal 5.82 75 698
payment to supplier: creation 48.18 168 5781
payment to supplier: removal 3.25 66 390
service: total inventory turnovers 4.08 3092 490
service: total monetary turnovers 6.27 458 752
service: aggregate perf. data 0.86 15192 103
service: refresh index statistics 0.05 305850 6

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 12-10-2021 04:37:47 to 12-10-2021 04:37:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4768
TEST_TIME 2 4939
TEST_TIME 3 4411
TEST_TIME 4 4045
TEST_TIME 5 3892
TEST_TIME 6 4488
TEST_TIME 7 4557
TEST_TIME 8 4848
TEST_TIME 9 4967
TEST_TIME 10 5312
TEST_TIME 11 5503
TEST_TIME 12 5565
TEST_TIME 13 6064
TEST_TIME 14 6390
TEST_TIME 15 6559
TEST_TIME 16 6792
TEST_TIME 17 7132
TEST_TIME 18 7500
TEST_TIME 19 7748
TEST_TIME 20 7963
TEST_TIME 21 8101
TEST_TIME 22 8851
TEST_TIME 23 8718
TEST_TIME 24 8862
TEST_TIME 25 9350
TEST_TIME 26 10764
TEST_TIME 27 11469
TEST_TIME 28 11284
TEST_TIME 29 11001
TEST_TIME 30 10862
TEST_TIME 31 10778
TEST_TIME 32 10627
TEST_TIME 33 10469
TEST_TIME 34 10284
TEST_TIME 35 10118
TEST_TIME 36 9996
TEST_TIME 37 10120
TEST_TIME 38 10113
TEST_TIME 39 9941
TEST_TIME 40 9703
TEST_TIME 41 9641
TEST_TIME 42 9726
TEST_TIME 43 9720
TEST_TIME 44 9627
TEST_TIME 45 9566
TEST_TIME 46 9675
TEST_TIME 47 9599
TEST_TIME 48 9570
TEST_TIME 49 9540
TEST_TIME 50 9451
TEST_TIME 51 9643
TEST_TIME 52 9687
TEST_TIME 53 9672
TEST_TIME 54 9635
TEST_TIME 55 9636
TEST_TIME 56 9529
TEST_TIME 57 9565
TEST_TIME 58 9426
TEST_TIME 59 9407
TEST_TIME 60 9341
TEST_TIME 61 9280
TEST_TIME 62 9307
TEST_TIME 63 9244
TEST_TIME 64 9128
TEST_TIME 65 9270
TEST_TIME 66 9244
TEST_TIME 67 9230
TEST_TIME 68 9279
TEST_TIME 69 9281
TEST_TIME 70 9279
TEST_TIME 71 9275
TEST_TIME 72 9209
TEST_TIME 73 9331
TEST_TIME 74 9363
TEST_TIME 75 9461
TEST_TIME 76 9452
TEST_TIME 77 9461
TEST_TIME 78 9457
TEST_TIME 79 9464
TEST_TIME 80 9469
TEST_TIME 81 9431
TEST_TIME 82 9351
TEST_TIME 83 9295
TEST_TIME 84 9307
TEST_TIME 85 9271
TEST_TIME 86 9233
TEST_TIME 87 9173
TEST_TIME 88 9159
TEST_TIME 89 9116
TEST_TIME 90 9095
TEST_TIME 91 9076
TEST_TIME 92 9064
TEST_TIME 93 9062
TEST_TIME 94 9044
TEST_TIME 95 9048
TEST_TIME 96 9086
TEST_TIME 97 9098
TEST_TIME 98 9101
TEST_TIME 99 9109
TEST_TIME 100 9159
TEST_TIME 101 9218
TEST_TIME 102 9222
TEST_TIME 103 9209
TEST_TIME 104 9176
TEST_TIME 105 9112
TEST_TIME 106 9140
TEST_TIME 107 9086
TEST_TIME 108 9033
TEST_TIME 109 9090
TEST_TIME 110 9077
TEST_TIME 111 9075
TEST_TIME 112 9059
TEST_TIME 113 9041
TEST_TIME 114 9024
TEST_TIME 115 9013
TEST_TIME 116 9094
TEST_TIME 117 9115
TEST_TIME 118 9214
TEST_TIME 119 9201
TEST_TIME 120 9128

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 12-10-2021 04:37:51 to 12-10-2021 04:37:54.

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 1514016 1514016 0 0.00 0 25077 8 0 0
doc_list_biud 1514015 1514015 0 0.00 0 48129 31 0 0
sp_add_invoice_to_stock 5038 4904 134 2.65 37 251792 19638 0 134
sp_cancel_adding_invoice 5600 5600 0 0.00 5 84517 849 0 0
sp_cancel_client_order 1752 1752 0 0.00 4 8268 317 0 0
sp_cancel_customer_reserve 555541 555541 0 0.00 1 21298 66 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 3 4902 75 0 0
sp_cancel_pay_to_supplier 390 390 0 0.00 3 3909 67 0 0
sp_cancel_supplier_invoice 6141 6132 9 0.14 1 48139 1000 0 9
sp_cancel_supplier_order 419 419 0 0.00 9 1108834 113197 0 0
sp_cancel_write_off 9848 9848 0 0.00 1 13850 137 0 0
sp_client_order 20177 20177 0 0.00 3 23316 407 0 0
sp_customer_reserve 454372 454372 0 0.00 0 123841 194 0 0
sp_fill_shopping_cart 30889 30889 0 0.00 1 20872 45 0 0
sp_fill_shopping_cart_clo_res 454366 454366 0 0.00 0 27778 19 0 0
sp_get_clo_for_invoice 4895 4895 0 0.00 0 22519 421 0 0
sp_kill_cost_storno 16536 16536 0 0.00 0 24788 66 0 0
sp_kill_qstorno_ret_qs2qd 1127481 1127481 0 0.00 0 48126 36 0 0
sp_kill_qty_storno 594166 594166 0 0.00 0 48127 78 0 0
sp_lock_selected_doc 20465 20465 0 0.00 0 6596 3 0 0
sp_make_cost_storno 27821 27821 0 0.00 0 19697 69 0 0
sp_multiply_rows_for_pdistr 27821 27821 0 0.00 0 14132 40 0 0
sp_multiply_rows_for_qdistr 20177 20177 0 0.00 0 23296 227 0 0
sp_pay_from_customer 7166 7166 0 0.00 4 19409 136 0 0
sp_pay_to_supplier 5781 5781 0 0.00 5 24891 169 0 0
sp_payment_common 12947 12947 0 0.00 1 12892 34 0 0
sp_qd_handle_on_cancel_clo 1752 1752 0 0.00 0 8258 286 0 0
sp_qd_handle_on_invoice_upd_sts 10495 10495 0 0.00 0 17271 344 0 0
sp_qd_handle_on_reserve_upd_sts 9979 9979 0 0.00 0 18339 56 0 0
sp_reserve_write_off 9979 9979 0 0.00 7 24930 197 0 0
sp_supplier_invoice 5258 5258 0 0.00 7 86185 3191 0 0
sp_supplier_order 5457 5457 0 0.00 5 34741 1321 0 0
srv_aggregate_perf_data 103 103 0 0.00 2740 258068 15193 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1792 120127 48077 0 0
srv_find_qd_qs_mism 1047077 1047077 0 0.00 0 16401 7 0 0
srv_make_invnt_saldo 490 490 0 0.00 25 96688 3093 0 0
srv_make_money_saldo 752 752 0 0.00 1 10849 458 0 0
srv_recalc_idx_stat 6 6 0 0.00 195147 725974 305850 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 25969 180 0 0
t$perf-norm:sp_cancel_adding_invoice 377 377 0 0.00 1 4513 130 0 0
t$perf-norm:sp_cancel_client_order 1752 1752 0 0.00 0 11254 16 0 0
t$perf-norm:sp_cancel_customer_reserve 644 644 0 0.00 0 2182 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 654 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 390 390 0 0.00 0 15041 41 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 551 12 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 26051 1496 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 10079 13 0 0
t$perf-norm:sp_client_order 20177 20177 0 0.00 0 38573 19 0 0
t$perf-norm:sp_customer_reserve 1056 1056 0 0.00 0 4077 15 0 0
t$perf-norm:sp_pay_from_customer 7166 7166 0 0.00 0 15881 11 0 0
t$perf-norm:sp_pay_to_supplier 5781 5781 0 0.00 0 11211 13 0 0
t$perf-norm:sp_reserve_write_off 9979 9979 0 0.00 0 24702 16 0 0
t$perf-norm:sp_supplier_invoice 5258 5258 0 0.00 0 11221 25 0 0
t$perf-norm:sp_supplier_order 5457 5457 0 0.00 0 24529 19 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 3597 43 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 490 490 0 0.00 0 1909 16 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 737 5 0 0
t$perf-norm:srv_recalc_idx_stat 6 6 0 0.00 1 6 2 0 0
v_add_invoice_to_stock 10058 9790 268 2.66 0 6923 18 0 268
v_all_customers 37322 37322 0 0.00 0 26 1 0 0
v_all_suppliers 29690 29690 0 0.00 0 23 1 0 0
v_all_wares 40354 40354 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 754 754 0 0.00 0 1516 7 0 0
v_cancel_client_order 3504 3504 0 0.00 0 7285 7 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 3094 7 0 0
v_cancel_customer_reserve 1288 1288 0 0.00 0 2072 4 0 0
v_cancel_payment_to_supplier 780 780 0 0.00 0 3893 11 0 0
v_cancel_supplier_invoice 718 700 18 2.50 0 2207 17 0 18
v_cancel_supplier_order 838 838 0 0.00 0 1253 5 0 0
v_cancel_write_off 1922 1922 0 0.00 0 3331 20 0 0
v_max_id_clo_ord 5456 5456 0 0.00 0 1202 1 0 0
v_max_id_clo_res 1056 1056 0 0.00 0 4726 18 0 0
v_max_id_ord_sup 5255 5255 0 0.00 0 6784 6 0 0
v_max_non_paid_invoice 1647 1647 0 0.00 0 700 3 0 0
v_max_non_paid_realizn 3655 3655 0 0.00 0 2720 4 0 0
v_min_id_clo_ord 5456 5456 0 0.00 0 4593 10 0 0
v_min_id_clo_res 1056 1056 0 0.00 0 1336 4 0 0
v_min_id_ord_sup 5255 5255 0 0.00 0 6863 10 0 0
v_min_non_paid_invoice 5781 5781 0 0.00 0 9803 26 0 0
v_min_non_paid_realizn 7166 7166 0 0.00 0 12883 28 0 0
v_our_firm 10054 10054 0 0.00 0 448 1 0 0
v_reserve_write_off 19958 19958 0 0.00 0 7288 6 0 0
x_lock_depdocs_on_canc_invoice 11382 11382 0 0.00 0 18895 119 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 12421 356 0 0
x_make_qstorno_1000_1200 5457 5457 0 0.00 2 34737 1240 0 0
x_make_qstorno_1200_2000 5258 5258 0 0.00 3 86166 3018 0 0
x_make_qstorno_2100_3300 454372 454372 0 0.00 0 123841 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 12-10-2021 04:37:55 to 12-10-2021 04:37:59.

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:12 7 8192000000 8802625600 8815370240 7779632 0.094 3 1 2 0 7883776 68368 35776
02:08:13 39879 8192000000 9737829536 10039091200 805775584 8.955 100 85 170 0 834063040 7951488 20335968
02:09:57 25461 8192000000 9774617408 10085203968 845059824 9.351 100 86 172 1 878839200 13095072 20684304
02:11:33 19000 8192000000 9820707920 10144849920 878497408 9.685 100 77 154 0 921979232 25938464 17543360
02:12:56 10963 8192000000 9896706096 10233049088 927443584 10.169 100 78 156 0 1003728048 59277568 17006896
02:14:10 10382 8192000000 9922028080 10270113792 969707008 10.584 100 70 140 0 1043506448 59222144 14577296
02:15:24 4618 8192000000 9969359184 10332880896 1001862736 10.897 100 82 164 0 1088949120 70028960 17057424
02:16:31 3199 8192000000 10011757008 10379943936 1031155088 11.180 100 83 166 2 1124910496 77155184 16600224
02:17:36 1865 8192000000 10020590384 10388910080 1051976528 11.380 100 89 178 0 1135929984 65176048 18777408
02:18:39 21073 8192000000 10028522736 10407157760 1070845632 11.560 100 71 140 2 1146485920 61207456 14432832
02:20:01 4347 8192000000 10002305152 10390228992 1079080624 11.639 100 54 107 2 1119676992 29814672 10781696
02:21:07 12480 8192000000 9982311856 10394673152 1076833360 11.617 100 13 25 1 1111527184 32022432 2671392
02:22:27 5445 8192000000 10004629616 10406850560 1086589648 11.710 100 37 73 0 1114599088 20209952 7799488
02:23:35 8544 8192000000 9993171168 10403287040 1093442304 11.775 100 25 49 2 1109952496 10774240 5735952
02:24:48 23382 8192000000 10024774912 10424209408 1098162784 11.820 100 54 107 1 1130269024 20809456 11296784
02:26:13 5933 8192000000 10031841392 10424160256 1104918112 11.884 100 47 93 2 1139707152 24475728 10313312
02:27:22 8056 8192000000 9998711536 10407477248 1081049008 11.657 99 31 61 1 1108533856 20435888 7048960
02:28:31 5110 8192000000 9992848832 10381672448 1037678240 11.242 97 51 101 2 1068713280 19828688 11206352
02:29:38 20853 8192000000 9974059072 10372521984 1009113024 10.967 95 32 63 0 1056148800 40527904 6507872
02:31:03 5132 8192000000 9964514784 10344812544 1030370352 11.172 100 55 109 1 1060062176 18051552 11640272
02:32:11 7268 8192000000 9944428608 10321797120 1008570176 10.962 98 46 92 2 1039279216 18871728 11837312
02:33:25 41305 8192000000 9951719552 10347167744 1030591408 11.174 99 38 78 0 1058013392 19267200 8154784
02:35:10 5934 8192000000 9985021104 10383867904 1063066592 11.486 100 46 92 0 1115477808 43172352 9238864
02:36:17 4082 8192000000 9995691824 10384011264 1071235072 11.564 100 52 104 1 1099327680 15947824 12144784
02:37:25 47425 8192000000 10024362144 10420781056 1099036528 11.828 100 41 81 1 1118896512 12324928 7535056
02:39:16 52728 8192000000 10067488320 10469625856 1117597536 12.004 100 45 90 1 1168257248 40930704 9729008
02:41:36 15724 8192000000 10100766416 10515570688 1130201536 12.123 100 37 73 0 1217592208 80095552 7295120
02:42:58 26845 8192000000 10173385392 10592657408 1138483424 12.201 100 44 88 2 1276389120 128663680 9242016
02:44:30 46490 8192000000 10188492880 10617618432 1153801200 12.345 100 32 64 0 1287613616 125978160 7834256
02:46:32 13661 8192000000 10292848928 10712260608 1171928064 12.515 100 74 148 1 1392703632 204956000 15819568
02:48:00 6473 8192000000 10299513552 10716119040 1175389808 12.547 100 62 122 2 1405523664 216155744 13978112
02:49:09 1790 8192000000 10349904736 10758823936 1185947216 12.646 100 90 181 1 1451098640 245511440 19639984
02:50:13 2683 8192000000 10366937200 10781278208 1174136944 12.535 99 72 141 0 1462629232 272183840 16308448
02:51:20 2352 8192000000 10366689536 10788225024 1187122160 12.657 100 76 152 1 1472393696 268103424 17168112
02:52:25 2933 8192000000 10426191520 10837581824 1179853344 12.589 99 74 148 0 1530299264 332784720 17661200
02:53:35 3255 8192000000 10455330704 10865192960 1159089184 12.395 98 80 160 1 1534896256 357161840 18645232
02:54:41 1824 8192000000 10486852416 10895536128 1153821072 12.345 99 77 153 1 1577219744 404436464 18962208
02:55:48 2767 8192000000 10468784336 10891608064 1171765872 12.513 100 53 106 1 1570265744 385301232 13198640
02:57:04 5596 8192000000 10472489904 10882801664 1153346032 12.341 100 73 142 3 1568470736 397715744 17408960
02:58:12 6801 8192000000 10475466576 10897358848 1137377264 12.191 100 46 90 1 1570666976 421264096 12025616
02:59:21 11614 8192000000 10428018816 10926788608 1131651776 12.137 99 32 63 0 1553523744 413358528 8513440
03:00:37 28153 8192000000 10479554592 10915487744 1129836096 12.120 99 30 60 1 1555278640 417301424 8141120
03:02:19 20889 8192000000 10445430960 10888613888 1117079824 11.999 100 22 44 1 1544428880 420722576 6626480
03:03:42 32918 8192000000 10069457552 10596106240 1106265536 11.897 98 21 42 0 1332642768 221375264 5001968
03:05:16 69024 8192000000 10048119216 10493407232 1112725216 11.958 99 43 87 0 1147785104 25236704 9823184
03:07:46 56275 8192000000 10011545936 10471788544 1070209584 11.554 96 16 28 1 1102747120 29089264 3448272
03:09:48 59612 8192000000 10055203984 10499919872 1092448656 11.766 97 19 36 0 1122812032 26740416 3622960
03:11:54 107468 8192000000 10148064288 10613735424 1112528672 11.956 97 27 54 1 1208974384 90412416 6033296
03:14:42 60383 8192000000 10104207152 10549100544 1116201376 11.991 98 20 40 0 1216874240 96383328 4289536
03:16:54 118854 8192000000 10146755536 10627129344 1138499440 12.201 100 19 38 0 1261035504 118345904 4190160
03:20:05 74769 8192000000 10062318992 10559193088 1121658064 12.043 99 7 14 0 1168181520 45341472 1181984
03:22:22 95049 8192000000 10057595552 10521759744 1121169744 12.038 98 23 46 0 1189091184 61886432 6035008
03:25:02 30986 8192000000 10101554144 10548535296 1136961152 12.187 100 46 93 1 1186384992 39727760 9696080
03:26:39 40684 8192000000 10127489760 10560020480 1127630816 12.099 99 37 71 0 1195258512 60558928 7068768
03:28:22 95039 8192000000 10093308992 10620047360 1117940592 12.008 98 15 28 1 1191879008 70699680 3238736
03:31:43 55349 8192000000 10140687536 10600198144 1139962960 12.215 99 36 71 1 1203724480 57013664 6747856
03:33:43 108051 8192000000 10126571312 10625724416 1137994864 12.197 98 31 62 1 1221440432 76390208 7055360
03:36:42 41531 8192000000 10145113936 10611720192 1155872864 12.365 100 27 54 1 1241934512 80192752 5868896
03:38:27 69197 8192000000 10104106752 10616811520 1138053360 12.197 98 14 28 0 1217756672 76573520 3129792
03:40:36 64653 8192000000 10134663184 10592112640 1122603920 12.052 98 12 24 0 1216108336 91103056 2401360
03:42:42 77145 8192000000 10046214304 10499383296 1081850368 11.665 95 9 18 0 1130373440 46129456 2393616
03:44:59 86799 8192000000 10054918240 10533990400 1124288320 12.067 99 13 26 0 1169386896 42603424 2495152
03:47:31 55458 8192000000 10087823808 10549989376 1081646640 11.663 96 28 56 0 1135065024 47900800 5517584
03:49:27 40193 8192000000 10120889184 10564657152 1097225040 11.811 98 43 86 1 1201597888 94343136 10029712
03:51:10 102949 8192000000 10064383472 10572189696 1093566640 11.777 98 15 30 0 1202838624 106123792 3148192
03:53:56 62773 8192000000 10080267536 10547433472 1100125424 11.839 98 15 30 0 1141372032 38199344 3047264
03:56:07 26863 8192000000 10087865952 10525745152 1117454848 12.003 99 37 74 1 1162711184 37572400 7683936
03:57:36 119050 8192000000 10075466864 10547793920 1136086448 12.179 100 22 43 0 1186612864 45989504 4536912
04:00:37 2097 8192000000 10087291792 10510970880 1140524016 12.220 100 62 120 2 1174024240 20583008 12917216
04:01:56 28554 8192000000 10043581840 10518392832 1091835328 11.760 97 10 21 0 1116884384 23176672 1872384
04:04:18 75244 8192000000 10060274992 10533806080 1099721808 11.835 98 14 28 0 1132649136 29785264 3142064
04:06:36 51484 8192000000 10026686608 10496237568 1094159040 11.782 98 23 46 1 1125410208 26369280 4881888
04:08:30 70317 8192000000 10013442560 10484088832 1077516944 11.624 97 12 24 0 1110272368 30329824 2425600
04:10:46 24707 8192000000 10052714240 10483920896 1124928576 12.074 100 37 74 0 1158545888 26197120 7420192
04:12:11 21515 8192000000 10110419312 10551373824 1117483200 12.003 99 38 76 0 1208563552 83559232 7521120
04:13:37 28370 8192000000 10093777424 10530783232 1128211280 12.104 100 37 74 1 1177121344 41596128 7313936
04:15:07 56129 8192000000 10123379152 10567196672 1134127408 12.160 99 24 48 0 1186444064 47653360 4663296
04:17:05 82845 8192000000 10130450864 10591035392 1120665456 12.033 98 18 36 1 1196718144 72732448 3320240
04:19:30 46218 8192000000 10110797504 10540781568 1108477488 11.918 97 42 83 0 1171073408 54258064 8337856
04:21:16 120126 8192000000 10080509056 10575069184 1122887968 12.054 99 23 46 0 1205204496 77363120 4953408
04:24:28 97535 8192000000 10142494128 10617192448 1131404464 12.135 99 7 15 0 1224166368 91186128 1575776
04:27:14 42911 8192000000 10074299296 10528460800 1114633568 11.976 98 20 41 0 1160313216 41446544 4233104
04:29:03 17431 8192000000 10072285344 10509570048 1120011520 12.027 99 43 85 4 1162953792 33821440 9120832
04:30:33 43698 8192000000 10137096224 10571767808 1134283088 12.162 99 26 52 0 1209530112 69270928 5976096
04:32:16 62532 8192000000 10111732992 10625945600 1107640032 11.910 97 23 45 1 1224486992 111646704 5200256
04:34:29 71098 8192000000 10102478608 10604183552 1089968720 11.742 96 15 30 2 1200079440 106737600 3373120
04:36:41 34868 8192000000 9075552896 9319120896 228012656 2.707 21 2 4 0 235589664 7349040 227968

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 12-10-2021 04:38:01 to 12-10-2021 04:38:16.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 12-10-2021 04:38:16 to 12-10-2021 04:38:18.

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          235148
MON$OLDEST_ACTIVE               235149
MON$OLDEST_SNAPSHOT             235149
MON$NEXT_TRANSACTION            235149
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                       2042208
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             799
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 ---
--- end of diff output ---

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

12.10.21 04:38:19. 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-12 04:38:22 No severe PSQL-related problems occured
12.10.21 04:38:23. End of report.