Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.08.2021 10:39
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 16324816 kB
MemAvailable 30989816 kB
Buffers 0 kB
Cached 15412288 kB
SwapCached 1308 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-08-26 10:37:07
DTS_END                         2021-08-26 10:37:07.4400
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/36848
TRN_ID                          232281
ATT_ID                          585
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: 8748.41 22626 1049809
customer order: creation 169.89 409 20387
customer order: refuse 14.55 317 1746
order to supplier: creation 45.18 1469 5422
order to supplier: removal 3.27 116617 392
invoice (draft): creation 45.25 3557 5430
invoice (draft): removal 49.33 998 5920
invoice accept: apply 40.48 19122 4858
invoice accept: cancel 43.93 872 5271
customer reserve: creation 3710.60 190 445272
customer reserve: removal 4337.31 69 520477
realization accept: apply 82.73 189 9927
realization accept: cancel 80.63 123 9675
payment from customer: creation 58.46 121 7015
payment from customer: removal 5.76 77 691
payment to supplier: creation 47.36 132 5683
payment to supplier: removal 3.11 86 373
service: total inventory turnovers 3.85 3287 462
service: total monetary turnovers 5.77 605 692
service: aggregate perf. data 0.93 12556 112
service: refresh index statistics 0.03 291719 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-08-2021 10:39:29 to 26-08-2021 10:39:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6945
TEST_TIME 2 7147
TEST_TIME 3 7079
TEST_TIME 4 6525
TEST_TIME 5 6376
TEST_TIME 6 6228
TEST_TIME 7 6222
TEST_TIME 8 6191
TEST_TIME 9 6463
TEST_TIME 10 6490
TEST_TIME 11 6365
TEST_TIME 12 6837
TEST_TIME 13 6962
TEST_TIME 14 7115
TEST_TIME 15 7685
TEST_TIME 16 8031
TEST_TIME 17 8478
TEST_TIME 18 8444
TEST_TIME 19 8618
TEST_TIME 20 9038
TEST_TIME 21 9187
TEST_TIME 22 9158
TEST_TIME 23 9212
TEST_TIME 24 9434
TEST_TIME 25 9854
TEST_TIME 26 11449
TEST_TIME 27 11309
TEST_TIME 28 11018
TEST_TIME 29 10809
TEST_TIME 30 10632
TEST_TIME 31 10531
TEST_TIME 32 10431
TEST_TIME 33 10423
TEST_TIME 34 10232
TEST_TIME 35 10037
TEST_TIME 36 9945
TEST_TIME 37 9791
TEST_TIME 38 9604
TEST_TIME 39 9665
TEST_TIME 40 9501
TEST_TIME 41 9453
TEST_TIME 42 9510
TEST_TIME 43 9439
TEST_TIME 44 9320
TEST_TIME 45 9316
TEST_TIME 46 9228
TEST_TIME 47 9206
TEST_TIME 48 9205
TEST_TIME 49 9306
TEST_TIME 50 9287
TEST_TIME 51 9298
TEST_TIME 52 9363
TEST_TIME 53 9329
TEST_TIME 54 9309
TEST_TIME 55 9309
TEST_TIME 56 9309
TEST_TIME 57 9309
TEST_TIME 58 9265
TEST_TIME 59 9195
TEST_TIME 60 9117
TEST_TIME 61 9072
TEST_TIME 62 9001
TEST_TIME 63 8954
TEST_TIME 64 8952
TEST_TIME 65 9054
TEST_TIME 66 8986
TEST_TIME 67 8972
TEST_TIME 68 9012
TEST_TIME 69 9053
TEST_TIME 70 9055
TEST_TIME 71 9092
TEST_TIME 72 9129
TEST_TIME 73 9080
TEST_TIME 74 9126
TEST_TIME 75 9106
TEST_TIME 76 9119
TEST_TIME 77 9147
TEST_TIME 78 9053
TEST_TIME 79 9030
TEST_TIME 80 8947
TEST_TIME 81 8936
TEST_TIME 82 8889
TEST_TIME 83 8841
TEST_TIME 84 8844
TEST_TIME 85 8812
TEST_TIME 86 8842
TEST_TIME 87 8800
TEST_TIME 88 8802
TEST_TIME 89 8811
TEST_TIME 90 8872
TEST_TIME 91 8919
TEST_TIME 92 8958
TEST_TIME 93 8990
TEST_TIME 94 8992
TEST_TIME 95 8969
TEST_TIME 96 9013
TEST_TIME 97 8985
TEST_TIME 98 8917
TEST_TIME 99 8855
TEST_TIME 100 8835
TEST_TIME 101 8818
TEST_TIME 102 8793
TEST_TIME 103 8736
TEST_TIME 104 8742
TEST_TIME 105 8753
TEST_TIME 106 8761
TEST_TIME 107 8780
TEST_TIME 108 8802
TEST_TIME 109 8798
TEST_TIME 110 8820
TEST_TIME 111 8811
TEST_TIME 112 8787
TEST_TIME 113 8834
TEST_TIME 114 8847
TEST_TIME 115 8937
TEST_TIME 116 8924
TEST_TIME 117 8853
TEST_TIME 118 8854
TEST_TIME 119 8826
TEST_TIME 120 8757

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-08-2021 10:39:33 to 26-08-2021 10:39:36.

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 1462113 1462113 0 0.00 0 21480 7 0 0
doc_list_biud 1462113 1462113 0 0.00 0 36223 31 0 0
sp_add_invoice_to_stock 4982 4858 124 2.48 19 192452 19123 0 124
sp_cancel_adding_invoice 5271 5271 0 0.00 5 83833 873 0 0
sp_cancel_client_order 1746 1746 0 0.00 5 7163 318 0 0
sp_cancel_customer_reserve 520477 520477 0 0.00 1 36229 70 0 0
sp_cancel_pay_from_customer 691 691 0 0.00 3 7013 78 0 0
sp_cancel_pay_to_supplier 373 373 0 0.00 3 5820 87 0 0
sp_cancel_supplier_invoice 5925 5920 5 0.08 2 22751 998 0 5
sp_cancel_supplier_order 392 392 0 0.00 7 1219698 116617 0 0
sp_cancel_write_off 9675 9675 0 0.00 1 12526 124 0 0
sp_client_order 20387 20387 0 0.00 4 26870 409 0 0
sp_customer_reserve 445272 445272 0 0.00 0 42634 191 0 0
sp_fill_shopping_cart 31239 31239 0 0.00 1 20047 52 0 0
sp_fill_shopping_cart_clo_res 445257 445257 0 0.00 0 16540 15 0 0
sp_get_clo_for_invoice 4833 4833 0 0.00 0 34101 370 0 0
sp_kill_cost_storno 16010 16010 0 0.00 0 20863 58 0 0
sp_kill_qstorno_ret_qs2qd 1056941 1056941 0 0.00 0 36196 38 0 0
sp_kill_qty_storno 558241 558241 0 0.00 0 36223 81 0 0
sp_lock_selected_doc 20533 20533 0 0.00 0 8704 6 0 0
sp_make_cost_storno 27458 27458 0 0.00 0 18164 58 0 0
sp_multiply_rows_for_pdistr 27458 27458 0 0.00 0 7510 33 0 0
sp_multiply_rows_for_qdistr 20387 20387 0 0.00 0 21108 205 0 0
sp_pay_from_customer 7015 7015 0 0.00 4 15781 122 0 0
sp_pay_to_supplier 5683 5683 0 0.00 5 18170 133 0 0
sp_payment_common 12698 12698 0 0.00 1 13182 29 0 0
sp_qd_handle_on_cancel_clo 1746 1746 0 0.00 0 6937 288 0 0
sp_qd_handle_on_invoice_upd_sts 10104 10104 0 0.00 0 31598 347 0 0
sp_qd_handle_on_reserve_upd_sts 9927 9927 0 0.00 0 9380 55 0 0
sp_reserve_write_off 9927 9927 0 0.00 7 15398 189 0 0
sp_supplier_invoice 5430 5430 0 0.00 7 88771 3558 0 0
sp_supplier_order 5422 5422 0 0.00 6 71123 1470 0 0
srv_aggregate_perf_data 112 112 0 0.00 2736 103083 12556 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1015 161587 50462 0 0
srv_find_qd_qs_mism 1003303 1003303 0 0.00 0 21480 6 0 0
srv_make_invnt_saldo 462 462 0 0.00 39 104624 3288 0 0
srv_make_money_saldo 692 692 0 0.00 1 16677 605 0 0
srv_recalc_idx_stat 4 4 0 0.00 134827 426688 291719 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4858 4858 0 0.00 0 20178 142 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 1 9343 115 0 0
t$perf-norm:sp_cancel_client_order 1746 1746 0 0.00 0 3498 11 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 2322 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 691 691 0 0.00 0 658 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 373 373 0 0.00 0 7485 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 5010 23 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 14316 1436 0 0
t$perf-norm:sp_cancel_write_off 1042 1042 0 0.00 0 6741 17 0 0
t$perf-norm:sp_client_order 20387 20387 0 0.00 0 8178 15 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 1741 5 0 0
t$perf-norm:sp_pay_from_customer 7015 7015 0 0.00 0 6593 8 0 0
t$perf-norm:sp_pay_to_supplier 5683 5683 0 0.00 0 8950 8 0 0
t$perf-norm:sp_reserve_write_off 9927 9927 0 0.00 0 8405 8 0 0
t$perf-norm:sp_supplier_invoice 5430 5430 0 0.00 0 7980 18 0 0
t$perf-norm:sp_supplier_order 5422 5422 0 0.00 0 3016 11 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 1073 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 239 6 0 0
t$perf-norm:srv_make_invnt_saldo 462 462 0 0.00 0 378 6 0 0
t$perf-norm:srv_make_money_saldo 692 692 0 0.00 0 7064 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 5 0 0
v_add_invoice_to_stock 9914 9666 248 2.50 0 10373 17 0 248
v_all_customers 37558 37558 0 0.00 0 66 1 0 0
v_all_suppliers 30068 30068 0 0.00 0 21 1 0 0
v_all_wares 40774 40774 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 7794 21 0 0
v_cancel_client_order 3492 3492 0 0.00 0 6856 7 0 0
v_cancel_customer_prepayment 1382 1382 0 0.00 0 664 4 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 5418 9 0 0
v_cancel_payment_to_supplier 746 746 0 0.00 0 2876 9 0 0
v_cancel_supplier_invoice 826 816 10 1.21 0 1278 12 0 10
v_cancel_supplier_order 784 784 0 0.00 0 2440 6 0 0
v_cancel_write_off 2084 2084 0 0.00 0 666 11 0 0
v_max_id_clo_ord 5422 5422 0 0.00 0 1305 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 3461 12 0 0
v_max_id_ord_sup 5430 5430 0 0.00 0 7264 5 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 655 2 0 0
v_max_non_paid_realizn 3663 3663 0 0.00 0 1527 3 0 0
v_min_id_clo_ord 5422 5422 0 0.00 0 7494 7 0 0
v_min_id_clo_res 990 990 0 0.00 0 1742 11 0 0
v_min_id_ord_sup 5430 5430 0 0.00 0 9153 11 0 0
v_min_non_paid_invoice 5683 5683 0 0.00 0 6994 19 0 0
v_min_non_paid_realizn 7015 7015 0 0.00 0 7421 23 0 0
v_our_firm 9920 9920 0 0.00 0 19 1 0 0
v_reserve_write_off 19854 19854 0 0.00 0 10403 6 0 0
x_lock_depdocs_on_canc_invoice 10783 10783 0 0.00 0 19723 104 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 9194 317 0 0
x_make_qstorno_1000_1200 5422 5422 0 0.00 2 71116 1395 0 0
x_make_qstorno_1200_2000 5430 5430 0 0.00 4 88449 3336 0 0
x_make_qstorno_2100_3300 445272 445272 0 0.00 0 42632 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-08-2021 10:39:37 to 26-08-2021 10:39:41.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:10 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 28509 8192000000 9716203136 10011021312 789958688 8.794 100 82 164 0 817985312 7722176 20304448
08:09:41 28563 8192000000 9757823888 10064588800 831441920 9.214 100 90 180 0 865287760 11832800 22013040
08:11:16 21122 8192000000 9804258512 10127077376 870075168 9.601 100 84 167 1 908535088 19041536 19418384
08:12:42 12722 8192000000 9852407552 10187202560 915861232 10.055 100 74 147 1 962602608 30728512 16012864
08:14:03 9313 8192000000 9917647248 10262024192 963170368 10.520 100 80 160 1 1029614624 50015856 16428400
08:15:14 3321 8192000000 9986505376 10341175296 1003358960 10.911 100 82 164 0 1095841680 75712912 16769808
08:16:20 2379 8192000000 10024030224 10385866752 1027870960 11.148 100 90 178 2 1133341920 87293520 18177440
08:17:26 2228 8192000000 10032817248 10400489472 1051838832 11.378 100 86 173 1 1141478624 72250320 17389472
08:18:29 6919 8192000000 10034248512 10419408896 1068178560 11.535 100 87 172 1 1152807632 66770144 17858928
08:19:42 3096 8192000000 10007350976 10407550976 1072453120 11.576 100 38 76 0 1134323504 53635216 8235168
08:20:46 5361 8192000000 9998032064 10397900800 1078623280 11.634 100 36 72 1 1115619536 29508784 7487472
08:21:52 45920 8192000000 9991622688 10397818880 1080757200 11.655 100 35 70 0 1110280512 22225072 7298240
08:23:43 4683 8192000000 10000585744 10396585984 1086108160 11.706 100 35 66 3 1129528912 36123136 7297616
08:24:49 7425 8192000000 10006207904 10417606656 1086364448 11.708 99 35 72 1 1118370832 24393792 7612592
08:26:00 3853 8192000000 10014692096 10410901504 1098523232 11.824 100 47 91 2 1132898704 24632112 9743360
08:27:06 30881 8192000000 10010754032 10425135104 1095318480 11.793 100 21 42 0 1130640240 31217200 4104560
08:28:40 5596 8192000000 9987100000 10377048064 1057694512 11.434 98 33 65 1 1085781632 21609200 6477920
08:29:48 8058 8192000000 9959011632 10346225664 1010828832 10.983 97 34 69 0 1036967232 19037696 7100704
08:30:56 57719 8192000000 9931365520 10300080128 988761136 10.769 97 65 124 3 1012299920 10022480 13516304
08:32:55 7163 8192000000 9941635232 10333884416 1023241712 11.103 100 38 77 1 1063855136 32074928 8538496
08:34:05 4813 8192000000 9953070096 10340048896 1045869648 11.321 100 56 115 3 1068789920 10660464 12259808
08:35:12 10923 8192000000 9970177728 10386984960 1052461360 11.384 100 25 50 3 1086992784 28654240 5877184
08:36:24 51230 8192000000 9983035840 10383462400 1067769504 11.531 100 30 60 0 1104600160 30073712 6756944
08:38:23 3848 8192000000 10022175680 10413846528 1093981424 11.780 100 61 122 1 1133571280 26434128 13155728
08:39:48 7200 8192000000 10107237536 10514169856 1123135008 12.057 100 63 125 0 1222284944 85514656 13635280
08:40:59 4823 8192000000 10117891200 10521280512 1130367568 12.125 100 58 114 0 1225488640 82661584 12459488
08:42:11 22420 8192000000 10151032800 10568318976 1141252784 12.227 100 45 90 0 1262578720 111474944 9850992
08:43:36 17055 8192000000 10188993776 10597728256 1153861216 12.346 100 50 99 1 1298336992 133487424 10988352
08:44:56 17011 8192000000 10215597568 10637836288 1163322496 12.434 100 40 80 1 1334514720 161892192 9300032
08:46:20 19006 8192000000 10262974800 10685751296 1174745136 12.541 100 53 106 1 1364020544 176715072 12560336
08:47:42 7815 8192000000 10283060400 10702790656 1187761984 12.663 100 55 109 1 1389362848 189255088 12345776
08:48:54 6236 8192000000 10347316320 10764681216 1190063792 12.684 100 69 137 2 1450877472 245527984 15285696
08:50:04 5955 8192000000 10371117472 10783182848 1191250608 12.695 100 80 155 0 1472955776 264181456 17523712
08:51:16 16590 8192000000 10390310992 10803531776 1197607504 12.754 100 72 144 1 1489078752 274501712 16969536
08:52:51 5699 8192000000 10367835056 10807218176 1193783632 12.719 100 61 122 0 1471957824 262829888 15344304
08:54:01 1010 8192000000 10394832768 10805141504 1191563024 12.698 100 85 170 0 1489988592 278118672 20306896
08:55:11 6422 8192000000 10371424368 10788921344 1159114608 12.395 99 52 104 1 1461840816 288913728 13812480
08:56:20 7259 8192000000 10377844304 10806153216 1165221312 12.452 100 46 93 0 1490119040 312964528 11933200
08:57:36 9196 8192000000 10399726576 10861936640 1162061744 12.423 100 52 103 2 1491286000 315784624 13439632
08:58:49 15195 8192000000 10385830736 10832605184 1147138288 12.283 99 30 60 2 1475113744 320277600 7697856
09:00:11 23331 8192000000 10430427952 10873987072 1163871680 12.440 100 31 62 1 1513999296 341827920 8299696
09:01:54 18678 8192000000 10420377344 10858663936 1113553776 11.966 99 27 54 1 1513728400 392983632 7190992
09:03:14 24443 8192000000 10078008384 10590023680 1089229664 11.735 98 21 41 1 1284555216 189831648 5493904
09:04:39 87491 8192000000 10033963072 10453958656 1079592080 11.644 97 51 102 1 1126387856 35709648 11086128
09:07:17 54873 8192000000 10067543920 10512179200 1064284048 11.497 96 41 80 0 1137958336 64775728 8898560
09:09:13 79551 8192000000 10043443536 10549768192 1092509152 11.767 98 12 24 1 1145622096 50627680 2485264
09:11:34 72542 8192000000 10075974848 10511024128 1092377840 11.765 97 17 34 0 1148383632 52502112 3503680
09:13:48 80099 8192000000 10101623440 10539622400 1127550384 12.098 100 26 52 0 1216877968 83250320 6077264
09:16:31 65565 8192000000 10100533040 10527252480 1093131664 11.772 96 24 48 1 1167454592 69477104 4845824
09:18:43 73073 8192000000 10063157824 10568708096 1130819888 12.129 100 11 22 0 1205654128 72407104 2427136
09:20:57 64100 8192000000 10065293008 10530914304 1122288848 12.049 99 14 28 0 1153781312 28480848 3011616
09:23:02 29235 8192000000 10089449616 10521690112 1142257552 12.237 100 49 98 1 1173799664 21351008 10191104
09:24:35 79410 8192000000 10117971600 10570350592 1138339104 12.200 99 22 44 1 1205919520 62609872 4970544
09:26:55 55952 8192000000 10123132144 10595516416 1155989584 12.366 100 26 52 0 1210947120 49512288 5445248
09:28:52 64433 8192000000 10098014368 10566184960 1161917344 12.421 100 26 52 0 1200287200 32362256 6007600
09:30:59 41852 8192000000 10093614432 10543927296 1164069216 12.441 100 32 64 1 1211627024 41028096 6529712
09:33:01 112233 8192000000 10095197024 10539491328 1123152880 12.057 96 21 42 3 1164812304 37923408 3736016
09:35:57 94327 8192000000 10056582176 10513498112 1098581968 11.824 95 22 44 0 1133408192 30402240 4423984
09:38:57 69455 8192000000 10109529536 10564526080 1132953472 12.149 98 16 32 1 1228897520 92573664 3370384
09:41:14 68558 8192000000 10141723744 10631307264 1144439504 12.257 100 8 16 1 1216233232 70283248 1510480
09:43:36 63294 8192000000 10102748112 10541789184 1100191264 11.839 96 25 51 0 1164581648 58756784 5633600
09:45:52 78642 8192000000 10069865440 10527944704 1046299744 11.325 92 11 22 2 1087857904 38781328 2776832
09:48:32 93787 8192000000 10046076560 10482323456 1084523472 11.691 96 6 12 0 1138007808 52012144 1472192
09:51:14 88729 8192000000 10055085152 10486468608 1073603024 11.586 95 23 46 1 1116840400 38064560 5172816
09:53:45 101316 8192000000 10043336784 10496245760 1079695200 11.645 96 17 34 0 1130525104 47142960 3686944
09:56:58 42269 8192000000 10064024336 10482728960 1088331824 11.727 98 49 99 1 1141296976 43054256 9910896
09:58:41 64714 8192000000 10058138720 10510319616 1085675872 11.702 97 18 36 0 1162269248 72884160 3709216
10:00:46 50113 8192000000 10033977472 10506215424 1106457248 11.899 100 11 22 0 1163006240 53324992 3224000
10:03:00 60769 8192000000 10031469984 10478542848 1069599792 11.548 96 15 29 2 1102575984 29443760 3532432
10:05:05 74026 8192000000 10115309264 10572333056 1120570064 12.032 100 22 44 0 1218771648 92712864 5488720
10:07:21 63081 8192000000 10091684608 10554576896 1107112048 11.905 98 16 32 1 1161056192 50533584 3410560
10:09:30 161587 8192000000 10071880992 10545623040 1131303760 12.134 99 22 44 0 1221167936 85225024 4639152
10:13:19 46686 8192000000 10105339296 10578817024 1149509424 12.305 100 35 70 0 1246548832 89265168 7774240
10:15:10 104181 8192000000 10098415168 10553880576 1154065200 12.348 100 35 69 0 1206232160 44835920 7331040
10:18:00 52422 8192000000 10088714304 10572914688 1156273424 12.368 100 7 14 0 1217666704 60093424 1299856
10:19:56 22944 8192000000 10088920352 10591412224 1152362272 12.332 100 7 14 0 1202926272 49141184 1422816
10:21:19 78439 8192000000 10134743088 10607763456 1113245920 11.963 96 19 38 0 1205314384 87954224 4114240
10:23:38 58407 8192000000 10128797856 10571448320 1138390224 12.200 99 33 64 1 1234473536 89580672 6502640
10:26:07 41453 8192000000 10092828672 10535886848 1125062176 12.075 98 19 38 1 1168327552 39201168 4064208
10:27:49 50402 8192000000 10093765984 10534625280 1099384112 11.832 96 19 38 1 1141140512 37269920 4486480
10:29:44 74665 8192000000 10105729488 10560401408 1066757728 11.521 93 17 34 0 1165786752 95094992 3934032
10:32:00 50202 8192000000 10072659040 10614894592 1110747936 11.939 98 13 26 0 1216600272 103263392 2588944
10:34:18 57751 8192000000 10062165392 10490880000 1099702528 11.835 97 51 102 2 1143674848 32859280 11113040
10:36:17 65551 8192000000 9812788800 10237427712 893220208 9.831 79 7 14 0 930063280 35553408 1289664

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-08-2021 10:39:43 to 26-08-2021 10:39:58.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-08-2021 10:39:59 to 26-08-2021 10:40: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          232253
MON$OLDEST_ACTIVE               232459
MON$OLDEST_SNAPSHOT             232459
MON$NEXT_TRANSACTION            232459
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                       2027872
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2573 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2573 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2573 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2573 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Aug 26 10:37:34 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

26.08.21 10:40: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-08-26 10:40:05 No severe PSQL-related problems occured
26.08.21 10:40:06. End of report.