Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.08.2021 13: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 15999348 kB
MemAvailable 30979400 kB
Buffers 0 kB
Cached 15651664 kB
SwapCached 2592 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-15 13:37:04
DTS_END                         2021-08-15 13:37:04.7260
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/35098
TRN_ID                          239369
ATT_ID                          610
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: 9330.35 17859 1119642
customer order: creation 178.61 399 21433
customer order: refuse 14.73 311 1767
order to supplier: creation 48.74 1348 5849
order to supplier: removal 3.30 125090 396
invoice (draft): creation 46.97 3435 5636
invoice (draft): removal 53.97 989 6476
invoice accept: apply 42.13 17668 5056
invoice accept: cancel 48.83 861 5859
customer reserve: creation 3841.22 176 460946
customer reserve: removal 4744.15 68 569298
realization accept: apply 86.05 193 10326
realization accept: cancel 89.77 118 10772
payment from customer: creation 60.93 110 7312
payment from customer: removal 5.92 81 710
payment to supplier: creation 50.08 140 6009
payment to supplier: removal 3.51 74 421
service: total inventory turnovers 3.82 3690 458
service: total monetary turnovers 6.71 508 805
service: aggregate perf. data 0.91 19092 109
service: refresh index statistics 0.03 182836 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 15-08-2021 13:38:00 to 15-08-2021 13:38:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8587
TEST_TIME 2 4732
TEST_TIME 3 5425
TEST_TIME 4 5671
TEST_TIME 5 5783
TEST_TIME 6 6685
TEST_TIME 7 6754
TEST_TIME 8 6967
TEST_TIME 9 6546
TEST_TIME 10 6557
TEST_TIME 11 6533
TEST_TIME 12 6619
TEST_TIME 13 6651
TEST_TIME 14 6643
TEST_TIME 15 7041
TEST_TIME 16 7205
TEST_TIME 17 7377
TEST_TIME 18 7735
TEST_TIME 19 7906
TEST_TIME 20 8224
TEST_TIME 21 8491
TEST_TIME 22 8556
TEST_TIME 23 8585
TEST_TIME 24 9063
TEST_TIME 25 9782
TEST_TIME 26 10051
TEST_TIME 27 11368
TEST_TIME 28 11483
TEST_TIME 29 11365
TEST_TIME 30 11237
TEST_TIME 31 11239
TEST_TIME 32 11104
TEST_TIME 33 11074
TEST_TIME 34 10908
TEST_TIME 35 11078
TEST_TIME 36 11210
TEST_TIME 37 11065
TEST_TIME 38 10822
TEST_TIME 39 10582
TEST_TIME 40 10445
TEST_TIME 41 10368
TEST_TIME 42 10347
TEST_TIME 43 10246
TEST_TIME 44 10156
TEST_TIME 45 10272
TEST_TIME 46 10251
TEST_TIME 47 10219
TEST_TIME 48 10148
TEST_TIME 49 10159
TEST_TIME 50 10092
TEST_TIME 51 9989
TEST_TIME 52 10005
TEST_TIME 53 9901
TEST_TIME 54 10043
TEST_TIME 55 10151
TEST_TIME 56 10114
TEST_TIME 57 10158
TEST_TIME 58 10119
TEST_TIME 59 10117
TEST_TIME 60 10122
TEST_TIME 61 10075
TEST_TIME 62 9952
TEST_TIME 63 9970
TEST_TIME 64 9877
TEST_TIME 65 9781
TEST_TIME 66 9733
TEST_TIME 67 9763
TEST_TIME 68 9747
TEST_TIME 69 9685
TEST_TIME 70 9608
TEST_TIME 71 9646
TEST_TIME 72 9595
TEST_TIME 73 9599
TEST_TIME 74 9569
TEST_TIME 75 9573
TEST_TIME 76 9643
TEST_TIME 77 9709
TEST_TIME 78 9661
TEST_TIME 79 9598
TEST_TIME 80 9559
TEST_TIME 81 9822
TEST_TIME 82 9760
TEST_TIME 83 9831
TEST_TIME 84 9741
TEST_TIME 85 9654
TEST_TIME 86 9659
TEST_TIME 87 9607
TEST_TIME 88 9565
TEST_TIME 89 9515
TEST_TIME 90 9469
TEST_TIME 91 9445
TEST_TIME 92 9531
TEST_TIME 93 9512
TEST_TIME 94 9550
TEST_TIME 95 9501
TEST_TIME 96 9485
TEST_TIME 97 9544
TEST_TIME 98 9539
TEST_TIME 99 9576
TEST_TIME 100 9542
TEST_TIME 101 9601
TEST_TIME 102 9574
TEST_TIME 103 9591
TEST_TIME 104 9535
TEST_TIME 105 9518
TEST_TIME 106 9498
TEST_TIME 107 9489
TEST_TIME 108 9447
TEST_TIME 109 9396
TEST_TIME 110 9372
TEST_TIME 111 9378
TEST_TIME 112 9369
TEST_TIME 113 9343
TEST_TIME 114 9339
TEST_TIME 115 9323
TEST_TIME 116 9319
TEST_TIME 117 9354
TEST_TIME 118 9380
TEST_TIME 119 9412
TEST_TIME 120 9335

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 15-08-2021 13:38:04 to 15-08-2021 13:38:08.

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 1546597 1546597 0 0.00 0 11003 7 0 0
doc_list_biud 1546592 1546592 0 0.00 0 49656 32 0 0
sp_add_invoice_to_stock 5211 5056 155 2.97 35 164684 17668 0 155
sp_cancel_adding_invoice 5859 5859 0 0.00 5 58405 862 0 0
sp_cancel_client_order 1767 1767 0 0.00 4 10053 311 0 0
sp_cancel_customer_reserve 569298 569298 0 0.00 1 49657 68 0 0
sp_cancel_pay_from_customer 710 710 0 0.00 3 4853 82 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 3 4522 75 0 0
sp_cancel_supplier_invoice 6490 6476 14 0.21 2 25515 989 0 14
sp_cancel_supplier_order 396 396 0 0.00 7 1251228 125090 0 0
sp_cancel_write_off 10772 10772 0 0.00 1 10685 119 0 0
sp_client_order 21433 21433 0 0.00 4 25401 399 0 0
sp_customer_reserve 460946 460946 0 0.00 0 142547 177 0 0
sp_fill_shopping_cart 32917 32917 0 0.00 1 15189 42 0 0
sp_fill_shopping_cart_clo_res 460937 460937 0 0.00 0 22563 17 0 0
sp_get_clo_for_invoice 5047 5047 0 0.00 0 37630 380 0 0
sp_kill_cost_storno 17762 17762 0 0.00 0 8399 60 0 0
sp_kill_qstorno_ret_qs2qd 1156240 1156240 0 0.00 0 49653 37 0 0
sp_kill_qty_storno 609941 609941 0 0.00 0 49656 80 0 0
sp_lock_selected_doc 21262 21262 0 0.00 0 7009 5 0 0
sp_make_cost_storno 28694 28694 0 0.00 0 10318 59 0 0
sp_multiply_rows_for_pdistr 28693 28693 0 0.00 0 8914 33 0 0
sp_multiply_rows_for_qdistr 21433 21433 0 0.00 0 17869 209 0 0
sp_pay_from_customer 7312 7312 0 0.00 4 11625 111 0 0
sp_pay_to_supplier 6009 6009 0 0.00 5 13348 140 0 0
sp_payment_common 13320 13320 0 0.00 1 13319 23 0 0
sp_qd_handle_on_cancel_clo 1767 1767 0 0.00 0 10045 279 0 0
sp_qd_handle_on_invoice_upd_sts 10906 10906 0 0.00 0 20908 347 0 0
sp_qd_handle_on_reserve_upd_sts 10326 10326 0 0.00 0 15537 62 0 0
sp_reserve_write_off 10326 10326 0 0.00 6 15562 193 0 0
sp_supplier_invoice 5636 5636 0 0.00 5 73976 3435 0 0
sp_supplier_order 5849 5849 0 0.00 5 23976 1349 0 0
srv_aggregate_perf_data 109 109 0 0.00 3224 224582 19093 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1597 144808 47273 0 0
srv_find_qd_qs_mism 1071046 1071046 0 0.00 0 10982 5 0 0
srv_make_invnt_saldo 458 458 0 0.00 31 146593 3691 0 0
srv_make_money_saldo 805 805 0 0.00 1 12515 509 0 0
srv_recalc_idx_stat 4 4 0 0.00 101791 238516 182837 0 0
t$perf-abend:sp_add_invoice_to_stock 155 155 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5056 5056 0 0.00 0 20125 159 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 7073 139 0 0
t$perf-norm:sp_cancel_client_order 1767 1767 0 0.00 0 1791 9 0 0
t$perf-norm:sp_cancel_customer_reserve 747 747 0 0.00 0 6782 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 710 710 0 0.00 0 1454 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 285 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 1753 19 0 0
t$perf-norm:sp_cancel_supplier_order 396 396 0 0.00 0 27335 1609 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 2863 8 0 0
t$perf-norm:sp_client_order 21433 21433 0 0.00 0 10897 16 0 0
t$perf-norm:sp_customer_reserve 992 992 0 0.00 0 674 4 0 0
t$perf-norm:sp_pay_from_customer 7312 7312 0 0.00 0 7910 9 0 0
t$perf-norm:sp_pay_to_supplier 6009 6009 0 0.00 0 3134 7 0 0
t$perf-norm:sp_reserve_write_off 10326 10326 0 0.00 0 27471 14 0 0
t$perf-norm:sp_supplier_invoice 5636 5636 0 0.00 0 6242 17 0 0
t$perf-norm:sp_supplier_order 5849 5849 0 0.00 0 6686 15 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 372 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 5085 80 0 0
t$perf-norm:srv_make_invnt_saldo 458 458 0 0.00 0 5188 34 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 6183 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 10404 10094 310 2.97 0 5176 16 0 310
v_all_customers 39042 39042 0 0.00 0 103 1 0 0
v_all_suppliers 31464 31464 0 0.00 0 53 1 0 0
v_all_wares 42866 42866 0 0.00 0 71 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 79 5 0 0
v_cancel_client_order 3534 3534 0 0.00 0 3074 6 0 0
v_cancel_customer_prepayment 1420 1420 0 0.00 0 2708 10 0 0
v_cancel_customer_reserve 1494 1494 0 0.00 0 3270 8 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 468 4 0 0
v_cancel_supplier_invoice 846 818 28 3.30 0 830 14 0 28
v_cancel_supplier_order 792 792 0 0.00 0 338 4 0 0
v_cancel_write_off 2060 2060 0 0.00 0 818 12 0 0
v_max_id_clo_ord 5849 5849 0 0.00 0 237 1 0 0
v_max_id_clo_res 992 992 0 0.00 0 2006 12 0 0
v_max_id_ord_sup 5635 5635 0 0.00 0 10435 7 0 0
v_max_non_paid_invoice 1760 1760 0 0.00 0 3298 4 0 0
v_max_non_paid_realizn 3893 3893 0 0.00 0 1232 3 0 0
v_min_id_clo_ord 5849 5849 0 0.00 0 3410 6 0 0
v_min_id_clo_res 992 992 0 0.00 0 1459 3 0 0
v_min_id_ord_sup 5635 5635 0 0.00 0 5517 6 0 0
v_min_non_paid_invoice 6008 6008 0 0.00 0 13316 19 0 0
v_min_non_paid_realizn 7311 7311 0 0.00 0 8865 15 0 0
v_our_firm 10660 10660 0 0.00 0 18 1 0 0
v_reserve_write_off 20652 20652 0 0.00 0 5851 7 0 0
x_lock_depdocs_on_canc_invoice 11926 11926 0 0.00 0 24687 105 0 0
x_lock_depdocs_on_canc_sup_ord 396 396 0 0.00 0 8158 318 0 0
x_make_qstorno_1000_1200 5849 5849 0 0.00 2 23964 1270 0 0
x_make_qstorno_1200_2000 5636 5636 0 0.00 2 71065 3275 0 0
x_make_qstorno_2100_3300 460946 460946 0 0.00 0 142547 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-08-2021 13:38:09 to 15-08-2021 13:38:13.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:10 10419 8192000000 9696699936 9986101248 777062208 8.663 100 90 180 0 807009472 7627376 22319888
11:09:30 13622 8192000000 9742551840 10045206528 820132144 9.100 100 90 180 0 852987136 10790320 22064672
11:10:52 18815 8192000000 9788964864 10103037952 859411760 9.494 100 79 158 0 894660864 16902912 18346192
11:12:14 10484 8192000000 9854041552 10182176768 905417088 9.952 100 78 156 1 959062320 36350832 17294400
11:13:31 9906 8192000000 9930139040 10270400512 952165072 10.412 100 85 169 1 1035793760 66418048 17210640
11:14:45 2819 8192000000 9948040240 10296926208 987136320 10.754 100 84 168 0 1066279248 62060752 17082176
11:15:51 2771 8192000000 9988185824 10346733568 1018145840 11.054 100 89 178 0 1103333312 67378656 17808816
11:16:56 2192 8192000000 10010418864 10382778368 1039990864 11.265 100 82 165 1 1123423840 66575440 16857536
11:18:00 2200 8192000000 10034494368 10402930688 1058261520 11.440 100 92 184 0 1145827968 68702464 18863984
11:19:03 2605 8192000000 10020007472 10396090368 1063671200 11.492 100 82 164 0 1133915168 53632288 16611680
11:20:06 28664 8192000000 10000013152 10382331904 1071465872 11.566 100 67 138 0 1121233520 34279264 15488384
11:21:35 7916 8192000000 9994215712 10388709376 1076925616 11.618 100 41 80 3 1109582992 23129168 9528208
11:22:45 5150 8192000000 10000077056 10397761536 1084020784 11.686 100 44 87 1 1121066464 27833296 9212384
11:23:51 10727 8192000000 9999739104 10412986368 1089503888 11.738 100 27 54 4 1112325520 17103312 5718320
11:25:04 15746 8192000000 10015932944 10404761600 1094510480 11.786 100 65 131 3 1116893712 8579120 13804112
11:26:22 3730 8192000000 9995321088 10392711168 1073756224 11.588 99 40 80 0 1106972768 23302544 9914000
11:27:29 12565 8192000000 9996043520 10393718784 1054085920 11.400 97 30 60 2 1077383504 16203920 7093664
11:28:45 20256 8192000000 9971483632 10365313024 1019438592 11.067 96 28 55 1 1044925616 19024704 6462320
11:30:41 6179 8192000000 9963555040 10341265408 983054848 10.714 95 50 98 2 1008169712 14873392 10241472
11:31:48 5547 8192000000 9935295872 10320850944 1023433248 11.105 100 40 79 2 1041482544 8774320 9274976
11:32:57 3147 8192000000 9938130176 10321149952 1028101168 11.150 100 56 112 1 1058434736 16499072 13834496
11:34:00 61892 8192000000 9950088608 10349580288 1031992464 11.188 99 37 73 1 1067697168 28754000 6950704
11:36:03 9019 8192000000 9987944896 10392809472 1060097872 11.457 100 37 68 2 1094241616 26672896 7470848
11:37:13 21691 8192000000 10007915248 10395942912 1085134960 11.696 100 51 100 0 1114946048 18874256 10936832
11:38:46 3033 8192000000 10057476800 10449240064 1109455840 11.927 100 64 125 3 1163305824 39477936 14372048
11:39:51 61753 8192000000 10061899824 10476748800 1114752096 11.977 100 50 100 0 1188556128 63251744 10552288
11:42:02 8073 8192000000 10116302432 10516357120 1119765936 12.025 100 65 128 3 1217468928 84059088 13643904
11:43:14 20307 8192000000 10112730672 10526519296 1122430816 12.050 99 59 116 0 1220060432 85550096 12079520
11:44:40 3832 8192000000 10172137008 10596352000 1148060928 12.291 100 54 108 1 1281550976 121725984 11764064
11:45:53 5054 8192000000 10161442592 10575998976 1143803472 12.251 99 49 99 0 1268962976 114614736 10544768
11:47:01 5963 8192000000 10234858048 10650906624 1160313792 12.406 100 55 110 1 1345990352 173589344 12087216
11:48:23 5986 8192000000 10274086784 10685628416 1169593008 12.493 100 58 116 1 1376738224 194253968 12891248
11:49:33 1596 8192000000 10303228912 10708295680 1165379632 12.454 99 78 156 2 1405672544 222119840 18173072
11:50:37 3296 8192000000 10360844016 10773848064 1177401680 12.566 100 76 150 0 1469135600 274733584 17000336
11:51:50 2724 8192000000 10383623760 10792095744 1171117504 12.507 99 63 126 0 1478010832 291561456 15331872
11:52:56 3251 8192000000 10409344896 10820943872 1172759632 12.523 100 72 144 1 1511452528 321253296 17439600
11:54:03 5249 8192000000 10460550656 10868350976 1177364016 12.566 100 80 159 0 1564471680 368590016 18517648
11:55:16 5032 8192000000 10471513952 10884206592 1151360256 12.322 99 68 135 0 1564895376 397067184 16467936
11:56:29 3324 8192000000 10481200816 10892677120 1166369008 12.463 100 68 135 2 1585936928 402843760 16724160
11:57:38 4147 8192000000 10492386720 10935410688 1149803200 12.308 99 59 118 0 1585641008 421208544 14629264
11:58:47 6494 8192000000 10490648048 10922184704 1144705328 12.260 99 38 76 0 1583825520 429179984 9940208
12:00:02 9354 8192000000 10504802960 10924720128 1112064992 11.952 98 49 98 2 1585561616 461100800 12395824
12:01:17 13719 8192000000 10530269408 10990436352 1114387568 11.974 100 36 72 1 1641429344 516666352 10375424
12:02:51 31879 8192000000 10347916384 10842025984 1114346672 11.974 100 24 46 1 1475769184 354979984 6442528
12:04:53 58473 8192000000 10063478480 10554847232 1102436704 11.861 99 4 8 1 1189002352 85726256 839392
12:06:52 92418 8192000000 10049853184 10484654080 1033762656 11.205 92 26 53 0 1061401536 22497248 5141632
12:09:25 107035 8192000000 10119141888 10560548864 1121385264 12.040 99 19 38 0 1182275088 57001040 3888784
12:12:17 44878 8192000000 10111739872 10584055808 1130444816 12.126 100 31 62 1 1218322336 81424032 6453488
12:14:11 144808 8192000000 10105276096 10538082304 1137922832 12.196 99 31 60 3 1199097488 53988112 7186544
12:17:48 40597 8192000000 10113424704 10557329408 1135567520 12.174 99 21 42 0 1201110800 61606768 3936512
12:19:30 21742 8192000000 10128469472 10564935680 1154637312 12.353 100 37 75 0 1225889664 62634848 8617504
12:20:53 65513 8192000000 10135850896 10621255680 1140509792 12.220 98 23 46 0 1251107728 105403328 5194608
12:23:03 58678 8192000000 10118954400 10578964480 1135365968 12.172 98 19 38 2 1206775232 67477840 3931424
12:25:08 101473 8192000000 10069388288 10545905664 1100414528 11.842 96 12 24 0 1167657360 64564224 2678608
12:28:04 50782 8192000000 10135052704 10583830528 1137809360 12.195 99 22 44 0 1187619632 45264592 4545680
12:30:14 20755 8192000000 10153154384 10591141888 1154496432 12.352 100 29 59 1 1252795792 91828608 6470752
12:31:38 61620 8192000000 10097248192 10544402432 1118496576 12.013 98 12 24 0 1219210976 98111152 2603248
12:33:40 84848 8192000000 10084068544 10558738432 1080323520 11.651 94 11 22 1 1143094784 60527216 2244048
12:36:08 53067 8192000000 10073474688 10503659520 1093819552 11.779 97 54 106 0 1162162240 58048288 10294400
12:38:02 16843 8192000000 10058596976 10493739008 1104145184 11.877 98 33 65 1 1134208336 23579360 6483792
12:39:21 117816 8192000000 10033539008 10477121536 1090215808 11.745 97 28 56 0 1154674224 58279856 6178560
12:42:19 47517 8192000000 10090694640 10530037760 1131702272 12.137 100 25 48 0 1195263408 57855440 5705696
12:44:07 64966 8192000000 10014158128 10503766016 1047682768 11.338 94 16 32 0 1121751264 70535280 3533216
12:46:16 40148 8192000000 10050922800 10496675840 1097000544 11.809 98 23 46 0 1134605776 32567600 5037632
12:48:18 92570 8192000000 10050947936 10496393216 1077991072 11.628 97 10 20 0 1148196784 67965264 2240448
12:50:50 46266 8192000000 10103917440 10552438784 1129800720 12.119 100 27 54 1 1193324112 57577968 5945424
12:52:37 58371 8192000000 10129787632 10593370112 1115152528 11.981 99 15 30 0 1198262544 79768160 3341856
12:54:36 125290 8192000000 10131482368 10573156352 1082923104 11.675 95 16 31 0 1162324480 76178816 3222560
12:57:41 73410 8192000000 10091922272 10602790912 1139377808 12.210 100 22 44 0 1291725536 147379664 4968064
12:59:55 110333 8192000000 10068597600 10543271936 1135075040 12.169 99 19 38 0 1199822240 60427136 4320064
13:03:04 72906 8192000000 10085340944 10570805248 1141608272 12.231 100 25 50 1 1207016624 59664416 5743936
13:05:17 64523 8192000000 10096378096 10552610816 1101056864 11.848 96 14 27 1 1158223312 53947520 3218928
13:07:25 68857 8192000000 10119999808 10607747072 1142994464 12.244 99 14 28 0 1235500624 89374624 3131536
13:09:45 48421 8192000000 10088874144 10536902656 1137129136 12.189 99 21 43 1 1180353120 38519888 4704096
13:12:01 46445 8192000000 10097812080 10536165376 1128190432 12.104 98 34 68 1 1171476272 35689104 7596736
13:13:52 59250 8192000000 10096120608 10596519936 1140811808 12.223 99 16 32 0 1185331744 41570544 2949392
13:15:52 84471 8192000000 10093810496 10538733568 1122862416 12.054 97 19 38 0 1181684128 55068208 3753504
13:18:17 45786 8192000000 10092928496 10610778112 1132709600 12.147 99 12 24 0 1201722880 66705920 2307360
13:20:10 21642 8192000000 10100396128 10556502016 1138104304 12.198 99 26 49 2 1182803312 39406704 5292304
13:21:38 120641 8192000000 10067677344 10528673792 1125612848 12.080 98 30 60 0 1208265024 76238032 6414144
13:24:41 51502 8192000000 10062468960 10531086336 1129020992 12.112 99 11 22 1 1164349760 33261808 2066960
13:26:38 56970 8192000000 10105759728 10568093696 1132619152 12.146 99 12 24 0 1169972272 34882080 2471040
13:28:35 48347 8192000000 10061194144 10540589056 1097084016 11.810 97 13 24 1 1133755440 34160880 2510544
13:30:24 44312 8192000000 10129810656 10575015936 1110193424 11.934 98 27 55 1 1165403200 49625120 5584656
13:32:37 61436 8192000000 10151139312 10593419264 1081207888 11.659 95 24 48 1 1193276192 106960256 5108048
13:34:38 73170 8192000000 10081573488 10533199872 1090370768 11.746 97 21 42 0 1222772960 127196176 5206016
13:36:52 30773 8192000000 8980771184 9233027072 135334224 1.625 12 5 6 2 167250512 30761504 1154784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 15-08-2021 13:38:15 to 15-08-2021 13:38:30.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-08-2021 13:38:31 to 15-08-2021 13:38:33.

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          240159
MON$OLDEST_ACTIVE               240488
MON$OLDEST_SNAPSHOT             240488
MON$NEXT_TRANSACTION            240488
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                       2065376
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             810
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 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 ---
:72: 
:73: mscs-test-161.budzdorov.ru    Sun Aug 15 13:37:24 2021
:74:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:75: 
--- end of diff output ---

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

15.08.21 13:38:34. 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-15 13:38:36 No severe PSQL-related problems occured
15.08.21 13:38:37. End of report.