Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 06.09.2021 22: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 16261680 kB
MemAvailable 30907252 kB
Buffers 0 kB
Cached 15452260 kB
SwapCached 340 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 3.0.8
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-09-06 22:37:48
DTS_END                         2021-09-06 22:37:48.0090
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(57:17)
IP                              ::1/44094
TRN_ID                          239625
ATT_ID                          624
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: 8997.32 21593 1079678
customer order: creation 172.93 357 20751
customer order: refuse 14.56 351 1747
order to supplier: creation 47.48 1333 5697
order to supplier: removal 3.38 94258 405
invoice (draft): creation 45.97 3253 5516
invoice (draft): removal 50.13 843 6015
invoice accept: apply 42.66 18953 5119
invoice accept: cancel 45.43 828 5452
customer reserve: creation 3848.28 193 461793
customer reserve: removal 4431.89 55 531827
realization accept: apply 83.63 181 10035
realization accept: cancel 81.00 116 9720
payment from customer: creation 58.70 134 7044
payment from customer: removal 5.89 76 707
payment to supplier: creation 49.01 157 5881
payment to supplier: removal 3.52 113 422
service: total inventory turnovers 4.81 1095 577
service: total monetary turnovers 7.19 27 863
service: aggregate perf. data 0.86 17391 103
service: refresh index statistics 0.03 292152 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 06-09-2021 22:39:43 to 06-09-2021 22:39:45.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4815
TEST_TIME 2 5000
TEST_TIME 3 5239
TEST_TIME 4 5061
TEST_TIME 5 5251
TEST_TIME 6 5315
TEST_TIME 7 6083
TEST_TIME 8 5819
TEST_TIME 9 5820
TEST_TIME 10 6156
TEST_TIME 11 6156
TEST_TIME 12 6233
TEST_TIME 13 6406
TEST_TIME 14 6851
TEST_TIME 15 6852
TEST_TIME 16 7228
TEST_TIME 17 7271
TEST_TIME 18 7560
TEST_TIME 19 8034
TEST_TIME 20 8214
TEST_TIME 21 8687
TEST_TIME 22 8550
TEST_TIME 23 8984
TEST_TIME 24 9371
TEST_TIME 25 9693
TEST_TIME 26 10191
TEST_TIME 27 10163
TEST_TIME 28 10409
TEST_TIME 29 10376
TEST_TIME 30 10211
TEST_TIME 31 10277
TEST_TIME 32 10329
TEST_TIME 33 10243
TEST_TIME 34 10170
TEST_TIME 35 9887
TEST_TIME 36 9932
TEST_TIME 37 10004
TEST_TIME 38 9790
TEST_TIME 39 9750
TEST_TIME 40 9734
TEST_TIME 41 9675
TEST_TIME 42 9734
TEST_TIME 43 9614
TEST_TIME 44 9615
TEST_TIME 45 9532
TEST_TIME 46 9612
TEST_TIME 47 9500
TEST_TIME 48 9364
TEST_TIME 49 9322
TEST_TIME 50 9458
TEST_TIME 51 9328
TEST_TIME 52 9318
TEST_TIME 53 9257
TEST_TIME 54 9239
TEST_TIME 55 9290
TEST_TIME 56 9211
TEST_TIME 57 9338
TEST_TIME 58 9473
TEST_TIME 59 9389
TEST_TIME 60 9399
TEST_TIME 61 9564
TEST_TIME 62 9613
TEST_TIME 63 9652
TEST_TIME 64 9652
TEST_TIME 65 9674
TEST_TIME 66 9720
TEST_TIME 67 9804
TEST_TIME 68 9772
TEST_TIME 69 9644
TEST_TIME 70 9641
TEST_TIME 71 9583
TEST_TIME 72 9553
TEST_TIME 73 9566
TEST_TIME 74 9506
TEST_TIME 75 9452
TEST_TIME 76 9419
TEST_TIME 77 9325
TEST_TIME 78 9267
TEST_TIME 79 9202
TEST_TIME 80 9262
TEST_TIME 81 9307
TEST_TIME 82 9377
TEST_TIME 83 9388
TEST_TIME 84 9321
TEST_TIME 85 9384
TEST_TIME 86 9424
TEST_TIME 87 9376
TEST_TIME 88 9361
TEST_TIME 89 9373
TEST_TIME 90 9415
TEST_TIME 91 9429
TEST_TIME 92 9433
TEST_TIME 93 9368
TEST_TIME 94 9295
TEST_TIME 95 9289
TEST_TIME 96 9244
TEST_TIME 97 9201
TEST_TIME 98 9156
TEST_TIME 99 9180
TEST_TIME 100 9186
TEST_TIME 101 9121
TEST_TIME 102 9161
TEST_TIME 103 9145
TEST_TIME 104 9131
TEST_TIME 105 9183
TEST_TIME 106 9153
TEST_TIME 107 9155
TEST_TIME 108 9175
TEST_TIME 109 9183
TEST_TIME 110 9194
TEST_TIME 111 9209
TEST_TIME 112 9212
TEST_TIME 113 9225
TEST_TIME 114 9151
TEST_TIME 115 9142
TEST_TIME 116 9102
TEST_TIME 117 9112
TEST_TIME 118 9083
TEST_TIME 119 9043
TEST_TIME 120 9002

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 06-09-2021 22:39:47 to 06-09-2021 22:39:50.

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 1508873 1508873 0 0.00 0 21494 6 0 0
doc_list_biud 1508873 1508873 0 0.00 0 32738 26 0 0
sp_add_invoice_to_stock 5262 5119 143 2.71 31 191681 18953 0 143
sp_cancel_adding_invoice 5452 5452 0 0.00 5 110835 829 0 0
sp_cancel_client_order 1747 1747 0 0.00 4 13373 352 0 0
sp_cancel_customer_reserve 531827 531827 0 0.00 1 32745 56 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 2 3071 76 0 0
sp_cancel_pay_to_supplier 422 422 0 0.00 3 15609 114 0 0
sp_cancel_supplier_invoice 6025 6015 10 0.16 2 15574 843 0 10
sp_cancel_supplier_order 405 405 0 0.00 9 956124 94258 0 0
sp_cancel_write_off 9720 9720 0 0.00 1 18894 116 0 0
sp_client_order 20751 20751 0 0.00 3 16307 358 0 0
sp_customer_reserve 461793 461793 0 0.00 0 47165 193 0 0
sp_fill_shopping_cart 31960 31960 0 0.00 1 20363 40 0 0
sp_fill_shopping_cart_clo_res 461786 461786 0 0.00 0 11578 13 0 0
sp_get_clo_for_invoice 5112 5112 0 0.00 0 30465 295 0 0
sp_kill_cost_storno 16301 16301 0 0.00 0 9051 57 0 0
sp_kill_qstorno_ret_qs2qd 1079794 1079794 0 0.00 0 32666 31 0 0
sp_kill_qty_storno 570313 570313 0 0.00 0 32736 67 0 0
sp_lock_selected_doc 20922 20922 0 0.00 0 8624 6 0 0
sp_make_cost_storno 28072 28072 0 0.00 0 21444 53 0 0
sp_multiply_rows_for_pdistr 28072 28072 0 0.00 0 21467 33 0 0
sp_multiply_rows_for_qdistr 20751 20751 0 0.00 0 13014 153 0 0
sp_pay_from_customer 7044 7044 0 0.00 4 14786 135 0 0
sp_pay_to_supplier 5881 5881 0 0.00 5 21475 158 0 0
sp_payment_common 12925 12925 0 0.00 1 14773 35 0 0
sp_qd_handle_on_cancel_clo 1747 1747 0 0.00 0 11819 319 0 0
sp_qd_handle_on_invoice_upd_sts 10564 10564 0 0.00 0 17523 325 0 0
sp_qd_handle_on_reserve_upd_sts 10035 10035 0 0.00 0 15518 55 0 0
sp_reserve_write_off 10035 10035 0 0.00 6 17719 182 0 0
sp_supplier_invoice 5516 5516 0 0.00 6 67136 3254 0 0
sp_supplier_order 5697 5697 0 0.00 6 41292 1333 0 0
srv_aggregate_perf_data 103 103 0 0.00 2940 275666 17391 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1500 140116 44674 0 0
srv_find_qd_qs_mism 1032568 1032568 0 0.00 0 12586 6 0 0
srv_make_invnt_saldo 577 577 0 0.00 29 91583 1096 0 0
srv_make_money_saldo 863 863 0 0.00 1 2250 27 0 0
srv_recalc_idx_stat 4 4 0 0.00 105678 549960 292152 0 0
t$perf-abend:sp_add_invoice_to_stock 143 143 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5119 5119 0 0.00 0 16343 169 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 5846 106 0 0
t$perf-norm:sp_cancel_client_order 1747 1747 0 0.00 0 2469 11 0 0
t$perf-norm:sp_cancel_customer_reserve 621 621 0 0.00 0 5067 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 1303 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 422 422 0 0.00 0 743 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 385 385 0 0.00 0 926 17 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 16372 1430 0 0
t$perf-norm:sp_cancel_write_off 1053 1053 0 0.00 0 3064 7 0 0
t$perf-norm:sp_client_order 20751 20751 0 0.00 0 8673 20 0 0
t$perf-norm:sp_customer_reserve 1016 1016 0 0.00 0 7928 29 0 0
t$perf-norm:sp_pay_from_customer 7044 7044 0 0.00 0 5124 10 0 0
t$perf-norm:sp_pay_to_supplier 5881 5881 0 0.00 0 3575 8 0 0
t$perf-norm:sp_reserve_write_off 10035 10035 0 0.00 0 7743 12 0 0
t$perf-norm:sp_supplier_invoice 5516 5516 0 0.00 0 8312 21 0 0
t$perf-norm:sp_supplier_order 5697 5697 0 0.00 0 4027 14 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 335 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2376 44 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1573 24 0 0
t$perf-norm:srv_make_money_saldo 863 863 0 0.00 0 1540 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 10 5 0 0
v_add_invoice_to_stock 10504 10218 286 2.72 0 10785 19 0 286
v_all_customers 37484 37484 0 0.00 0 13 1 0 0
v_all_suppliers 30562 30562 0 0.00 0 193 1 0 0
v_all_wares 41500 41500 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 416 6 0 0
v_cancel_client_order 3494 3494 0 0.00 0 12596 8 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 2975 5 0 0
v_cancel_customer_reserve 1242 1242 0 0.00 0 1970 5 0 0
v_cancel_payment_to_supplier 844 844 0 0.00 0 15104 28 0 0
v_cancel_supplier_invoice 790 770 20 2.53 0 3886 21 0 20
v_cancel_supplier_order 810 810 0 0.00 0 690 4 0 0
v_cancel_write_off 2106 2106 0 0.00 0 7201 22 0 0
v_max_id_clo_ord 5696 5696 0 0.00 0 989 2 0 0
v_max_id_clo_res 1016 1016 0 0.00 0 1539 6 0 0
v_max_id_ord_sup 5514 5514 0 0.00 0 2543 4 0 0
v_max_non_paid_invoice 1810 1810 0 0.00 0 1192 4 0 0
v_max_non_paid_realizn 3932 3932 0 0.00 0 6836 4 0 0
v_min_id_clo_ord 5696 5696 0 0.00 0 4134 6 0 0
v_min_id_clo_res 1016 1016 0 0.00 0 9465 17 0 0
v_min_id_ord_sup 5514 5514 0 0.00 0 6785 5 0 0
v_min_non_paid_invoice 5881 5881 0 0.00 0 7831 19 0 0
v_min_non_paid_realizn 7044 7044 0 0.00 0 11698 23 0 0
v_our_firm 10240 10240 0 0.00 0 16 1 0 0
v_reserve_write_off 20070 20070 0 0.00 0 16445 9 0 0
x_lock_depdocs_on_canc_invoice 11082 11082 0 0.00 0 11247 114 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 30528 392 0 0
x_make_qstorno_1000_1200 5697 5697 0 0.00 1 41277 1243 0 0
x_make_qstorno_1200_2000 5516 5516 0 0.00 3 67124 3117 0 0
x_make_qstorno_2100_3300 461793 461793 0 0.00 0 47164 178 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 06-09-2021 22:39:51 to 06-09-2021 22:39:55.

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
20:07:51 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:52 30607 8192000000 10356854928 10672451584 1407030624 14.658 100 88 174 1 1442082640 6103984 28948032
20:10:27 19269 8192000000 10422399408 10749874176 1469764560 15.212 100 89 178 0 1506176048 7592704 28818784
20:11:55 16164 8192000000 10470206816 10814504960 1537898304 15.805 100 83 166 0 1572826208 9097872 25830032
20:13:15 8647 8192000000 10551794768 10906849280 1619424432 16.505 100 73 146 2 1651310160 10205328 21680400
20:14:24 8918 8192000000 10649301696 11019120640 1708695104 17.258 100 76 152 2 1742675936 12115216 21865616
20:15:38 5387 8192000000 10712543280 11088793600 1774971216 17.808 100 83 166 3 1810937216 12402592 23563408
20:16:46 4830 8192000000 10763116448 11145117696 1820300928 18.180 100 90 180 2 1860140816 14391520 25448368
20:17:53 2454 8192000000 10792444160 11181662208 1851993888 18.438 100 77 155 2 1885436480 12124144 21318448
20:18:57 3116 8192000000 10820569168 11209818112 1875976768 18.633 100 86 171 0 1912701792 12616304 24108720
20:20:04 13402 8192000000 10827556096 11218882560 1884479744 18.701 100 67 136 2 1912188160 8819296 18889120
20:21:20 6632 8192000000 10797834128 11221983232 1884849232 18.704 100 28 56 2 1900169136 6668192 8651712
20:22:28 6522 8192000000 10800521600 11231690752 1894378672 18.781 100 23 46 1 1907586976 6656032 6552272
20:23:39 47958 8192000000 10856855280 11257806848 1908874880 18.898 100 49 93 1 1929263776 5736048 14652848
20:25:28 6597 8192000000 10819247712 11259273216 1908731600 18.896 100 29 58 3 1923792864 6988176 8073088
20:26:36 10589 8192000000 10817651696 11254431744 1909662432 18.904 100 30 60 2 1924123616 6325488 8135696
20:27:47 34989 8192000000 10797437888 11230998528 1844113760 18.374 97 18 36 1 1855340848 5030960 6196128
20:29:22 6093 8192000000 10737984368 11160035328 1786191088 17.900 97 40 80 1 1804842064 7045440 11605536
20:30:29 9382 8192000000 10741933440 11154452480 1790895568 17.939 98 42 84 2 1808584352 5713776 11975008
20:31:40 70492 8192000000 10721428336 11145555968 1727941600 17.418 95 30 61 0 1741724880 5204032 8579248
20:33:52 4706 8192000000 10745950864 11167850496 1822402240 18.197 99 41 82 4 1839691712 5796768 11492704
20:34:57 9859 8192000000 10772425664 11201155072 1859268096 18.497 100 25 50 4 1871416368 5229632 6918640
20:36:10 7393 8192000000 10790631472 11218378752 1861053680 18.512 99 22 44 2 1873745072 5236880 7454512
20:37:18 16841 8192000000 10846030960 11248377856 1897709808 18.808 100 78 158 3 1927434288 6457968 23266512
20:38:37 11919 8192000000 10858117264 11283234816 1933071856 19.091 100 66 132 2 1961297024 9354288 18870880
20:39:52 10522 8192000000 10880829888 11305746432 1950135440 19.228 100 63 125 0 1979347184 11623872 17587872
20:41:09 7843 8192000000 10908105504 11334336512 1966765408 19.360 100 50 98 0 1993863152 13063120 14034624
20:42:20 44143 8192000000 10920634960 11353010176 1979406544 19.460 100 49 98 1 2010868992 16939632 14522816
20:44:09 16353 8192000000 10938547456 11371245568 1996719872 19.597 100 52 102 2 2029808192 17771296 15317024
20:45:32 15063 8192000000 10958257936 11392045056 2007497472 19.682 100 52 104 1 2042447968 19852560 15097936
20:46:53 11729 8192000000 10968828272 11408261120 2017669600 19.762 100 58 116 1 2061143328 25641296 17832432
20:48:13 4215 8192000000 10971686976 11394142208 2008685136 19.691 100 68 135 1 2051654992 23374832 19595024
20:49:22 3352 8192000000 10993233072 11417186304 2025703824 19.825 100 86 169 2 2076677488 26113024 24860640
20:50:31 1704 8192000000 10992175952 11409670144 2018759952 19.770 100 85 171 1 2073420848 28879040 25781856
20:51:38 3033 8192000000 10961587472 11386888192 1986052736 19.513 99 76 151 0 2037531968 28779808 22699424
20:52:44 3097 8192000000 10989195232 11407978496 2009371024 19.697 100 84 167 1 2066948848 32339200 25238624
20:53:52 3402 8192000000 10956818112 11377758208 1981224912 19.474 100 70 137 1 2036783888 34117456 21441520
20:55:00 1498 8192000000 10969694112 11378065408 1979826528 19.463 100 92 186 1 2049168400 40740128 28601744
20:56:04 3877 8192000000 10966065328 11374010368 1975332928 19.428 100 80 159 0 2042044128 41261648 25449552
20:57:10 11081 8192000000 10931029008 11371147264 1962061824 19.322 100 41 82 1 2014407584 38320208 14025552
20:58:27 18980 8192000000 10902173248 11349319680 1954420400 19.262 100 33 66 2 2005543728 39017872 12105456
20:59:54 6002 8192000000 10907868256 11325788160 1928184272 19.052 100 66 129 2 1985715200 37157120 20373808
21:01:05 19444 8192000000 10888861376 11348787200 1938636960 19.136 100 25 50 1 1981665280 34140880 8887440
21:02:28 33839 8192000000 10884374224 11347296256 1933433872 19.094 100 22 44 3 1971990912 30950160 7606880
21:04:07 37151 8192000000 10862568240 11327160320 1911567712 18.919 99 7 14 1 1937404144 23447680 2388752
21:05:46 19962 8192000000 10860362144 11321245696 1917608640 18.968 99 18 36 1 1930686640 8391088 4686912
21:07:56 40927 8192000000 10889595072 11349614592 1960406160 19.309 100 23 46 1 1977247888 10995248 5846480
21:10:10 39989 8192000000 10876902272 11332255744 1933903792 19.098 99 27 54 2 1955364880 14330048 7131040
21:11:52 92294 8192000000 10912794256 11348123648 1923674784 19.016 98 36 73 3 1944471920 10928208 9868928
21:14:27 40813 8192000000 10887055568 11355627520 1945158528 19.188 99 24 48 2 1963168112 11762896 6246688
21:16:14 59882 8192000000 10912378544 11359162368 1965822272 19.352 100 46 91 2 1991590096 12477600 13290224
21:18:17 50673 8192000000 10902279664 11373072384 1971467872 19.397 100 12 24 1 1987175024 12193312 3513840
21:20:12 68691 8192000000 10904267568 11377926144 1966996304 19.362 99 14 28 0 1986824944 15491536 4337104
21:22:44 38635 8192000000 10899410320 11378372608 1976421488 19.436 100 16 30 1 1996463600 15134224 4907888
21:24:40 46724 8192000000 10913570512 11387248640 1994289296 19.578 100 22 44 0 2013624208 12789664 6545248
21:26:58 38073 8192000000 10911121712 11392008192 1989767120 19.542 100 14 28 2 2006644144 13202448 3674576
21:28:36 80703 8192000000 10906878960 11389992960 1990591648 19.548 100 16 30 1 2005632528 10259040 4781840
21:31:03 49122 8192000000 10915573968 11379445760 1962391600 19.325 98 28 56 2 1980604912 9729904 8483408
21:32:55 31941 8192000000 10898708480 11358056448 1946937984 19.202 98 17 34 0 1961610832 9967152 4705696
21:34:27 35150 8192000000 10891279760 11366510592 1950689536 19.232 99 11 21 1 1966095216 12392800 3012880
21:36:05 34327 8192000000 10927089488 11370692608 1939485840 19.143 98 39 78 1 1961518448 10907200 11125408
21:37:46 72694 8192000000 10876144096 11350056960 1886182688 18.715 96 18 36 2 1908659168 17345136 5131344
21:40:03 67027 8192000000 10878325040 11333885952 1891666736 18.759 97 21 42 0 1909571504 12077024 5827744
21:42:12 44877 8192000000 10867565264 11313311744 1858895232 18.494 95 30 60 1 1877814640 10838656 8080752
21:44:04 93768 8192000000 10879507504 11362533376 1895477648 18.790 97 16 32 0 1924333808 23845280 5010880
21:46:45 73432 8192000000 10872623264 11338928128 1883623248 18.694 97 17 34 0 1894015808 5626240 4766320
21:49:02 37877 8192000000 10859758432 11318001664 1909388016 18.902 99 30 60 1 1927788640 10204720 8195904
21:50:58 63342 8192000000 10846742512 11321892864 1892991920 18.770 98 12 24 0 1905163904 8876288 3295696
21:53:02 40992 8192000000 10881497872 11348303872 1935286432 19.109 99 24 48 0 1958526816 16573520 6666864
21:55:15 54516 8192000000 10894029232 11369324544 1959850944 19.305 100 17 34 0 1986050016 20744208 5454864
21:57:39 74029 8192000000 10882507504 11370258432 1950086960 19.227 100 15 30 1 1974942048 20677840 4177248
21:59:53 62977 8192000000 10892278752 11372789760 1927416400 19.046 98 17 33 1 1940280576 8436672 4427504
22:01:57 73468 8192000000 10889093440 11372793856 1953753152 19.256 99 17 34 0 1974396080 15514448 5128480
22:04:11 69262 8192000000 10888772784 11355217920 1915772864 18.953 97 14 28 0 1930913472 11494736 3645872
22:06:24 82866 8192000000 10896220688 11380531200 1918282464 18.973 97 17 32 1 1936405568 14022784 4100320
22:08:48 63855 8192000000 10911103552 11382964224 1963472464 19.334 99 9 18 0 1983616768 18274736 1869568
22:11:07 82306 8192000000 10910522576 11379175424 1964059568 19.338 99 28 56 0 1981553168 9954432 7539168
22:13:30 70494 8192000000 10907826240 11391156224 1988300352 19.530 100 7 14 0 1998901552 8607488 1993712
22:15:41 67439 8192000000 10930488864 11411001344 1981447936 19.476 99 20 39 0 1999848640 13729440 4671264
22:17:49 54140 8192000000 10945822576 11421933568 2012713184 19.723 100 23 46 1 2029906336 10133824 7059328
22:19:51 73584 8192000000 10931327952 11396263936 1928943952 19.058 96 18 36 0 1944517248 10583824 4989472
22:22:08 71611 8192000000 10902338240 11368005632 1839552016 18.337 92 14 28 0 1854961744 11533632 3876096
22:24:21 38985 8192000000 10889755984 11357696000 1918434496 18.974 98 29 58 1 1945255392 18743440 8077456
22:26:06 44402 8192000000 10889390416 11355201536 1916024512 18.955 98 22 44 0 1932369248 10342960 6001776
22:28:24 106124 8192000000 10828873376 11294822400 1741105296 17.528 90 15 30 0 1755491008 10120464 4265248
22:31:10 140114 8192000000 10822328416 11278888960 1825343328 18.221 95 27 53 2 1841322320 8003968 7975024
22:35:37 54036 8192000000 10853783440 11331514368 1905208864 18.868 98 11 22 1 1918521440 10122064 3190512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 06-09-2021 22:39:57 to 06-09-2021 22:40:12.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 06-09-2021 22:40:13 to 06-09-2021 22:40:15.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          237101
MON$OLDEST_ACTIVE               239815
MON$OLDEST_SNAPSHOT             239815
MON$NEXT_TRANSACTION            239815
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-03 16:41:55.3470
MON$PAGES                       2022720
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.8.33491 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33491 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33491 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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 ---
:280: 
:281: mscs-test-161.budzdorov.ru    Mon Sep  6 22:37:50 2021
:282:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:283: 
:284: 
:285: mscs-test-161.budzdorov.ru    Mon Sep  6 22:37:55 2021
:286:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:287: 
:288: 
:289: mscs-test-161.budzdorov.ru    Mon Sep  6 22:37:57 2021
:290:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:291: 
:292: 
:293: mscs-test-161.budzdorov.ru    Mon Sep  6 22:37:57 2021
:294:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:295: 
--- end of diff output ---

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

06.09.21 22:40:16. There are 3 logs in /var/tmp/logs-oltp30 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-09-06 22:40:18 No severe PSQL-related problems occured
06.09.21 22:40:19. End of report.