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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 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 16267832 kB
MemAvailable 30846696 kB
Buffers 0 kB
Cached 15410548 kB
SwapCached 396 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-08 22:37:48
DTS_END                         2021-09-08 22:37:48.1870
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/47838
TRN_ID                          246010
ATT_ID                          697
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: 9205.58 16343 1104670
customer order: creation 183.46 345 22015
customer order: refuse 15.53 317 1863
order to supplier: creation 48.09 1227 5771
order to supplier: removal 3.35 101715 402
invoice (draft): creation 48.43 2970 5812
invoice (draft): removal 50.77 788 6092
invoice accept: apply 43.14 18275 5177
invoice accept: cancel 45.36 750 5443
customer reserve: creation 3986.01 183 478321
customer reserve: removal 4481.21 61 537745
realization accept: apply 88.75 164 10650
realization accept: cancel 77.05 103 9246
payment from customer: creation 61.19 105 7343
payment from customer: removal 5.95 85 714
payment to supplier: creation 51.18 112 6141
payment to supplier: removal 3.62 59 434
service: total inventory turnovers 5.09 907 611
service: total monetary turnovers 6.63 22 796
service: aggregate perf. data 0.75 23717 90
service: refresh index statistics 0.03 174951 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-09-2021 22:38:49 to 08-09-2021 22:38:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2539
TEST_TIME 2 2632
TEST_TIME 3 4449
TEST_TIME 4 4701
TEST_TIME 5 4383
TEST_TIME 6 4292
TEST_TIME 7 4694
TEST_TIME 8 4503
TEST_TIME 9 4847
TEST_TIME 10 4844
TEST_TIME 11 4970
TEST_TIME 12 4951
TEST_TIME 13 4925
TEST_TIME 14 5499
TEST_TIME 15 5896
TEST_TIME 16 6188
TEST_TIME 17 6470
TEST_TIME 18 6877
TEST_TIME 19 7363
TEST_TIME 20 7560
TEST_TIME 21 8059
TEST_TIME 22 8225
TEST_TIME 23 9130
TEST_TIME 24 9565
TEST_TIME 25 9778
TEST_TIME 26 10321
TEST_TIME 27 10267
TEST_TIME 28 10253
TEST_TIME 29 10071
TEST_TIME 30 10265
TEST_TIME 31 10256
TEST_TIME 32 10075
TEST_TIME 33 9884
TEST_TIME 34 9802
TEST_TIME 35 9676
TEST_TIME 36 9592
TEST_TIME 37 9552
TEST_TIME 38 9461
TEST_TIME 39 9544
TEST_TIME 40 9525
TEST_TIME 41 9577
TEST_TIME 42 9529
TEST_TIME 43 9462
TEST_TIME 44 9302
TEST_TIME 45 9335
TEST_TIME 46 9231
TEST_TIME 47 9307
TEST_TIME 48 9247
TEST_TIME 49 9332
TEST_TIME 50 9300
TEST_TIME 51 9260
TEST_TIME 52 9427
TEST_TIME 53 9405
TEST_TIME 54 9425
TEST_TIME 55 9432
TEST_TIME 56 9459
TEST_TIME 57 9463
TEST_TIME 58 9607
TEST_TIME 59 9663
TEST_TIME 60 9646
TEST_TIME 61 9548
TEST_TIME 62 9493
TEST_TIME 63 9432
TEST_TIME 64 9435
TEST_TIME 65 9356
TEST_TIME 66 9338
TEST_TIME 67 9308
TEST_TIME 68 9226
TEST_TIME 69 9239
TEST_TIME 70 9212
TEST_TIME 71 9179
TEST_TIME 72 9243
TEST_TIME 73 9288
TEST_TIME 74 9278
TEST_TIME 75 9353
TEST_TIME 76 9347
TEST_TIME 77 9272
TEST_TIME 78 9309
TEST_TIME 79 9395
TEST_TIME 80 9455
TEST_TIME 81 9484
TEST_TIME 82 9462
TEST_TIME 83 9399
TEST_TIME 84 9360
TEST_TIME 85 9309
TEST_TIME 86 9344
TEST_TIME 87 9302
TEST_TIME 88 9241
TEST_TIME 89 9221
TEST_TIME 90 9186
TEST_TIME 91 9196
TEST_TIME 92 9192
TEST_TIME 93 9206
TEST_TIME 94 9185
TEST_TIME 95 9236
TEST_TIME 96 9274
TEST_TIME 97 9236
TEST_TIME 98 9247
TEST_TIME 99 9237
TEST_TIME 100 9257
TEST_TIME 101 9277
TEST_TIME 102 9313
TEST_TIME 103 9340
TEST_TIME 104 9354
TEST_TIME 105 9345
TEST_TIME 106 9290
TEST_TIME 107 9288
TEST_TIME 108 9276
TEST_TIME 109 9279
TEST_TIME 110 9232
TEST_TIME 111 9207
TEST_TIME 112 9168
TEST_TIME 113 9112
TEST_TIME 114 9159
TEST_TIME 115 9196
TEST_TIME 116 9183
TEST_TIME 117 9176
TEST_TIME 118 9204
TEST_TIME 119 9252
TEST_TIME 120 9209

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-09-2021 22:38:53 to 08-09-2021 22:38:56.

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 1547264 1547264 0 0.00 0 18364 6 0 0
doc_list_biud 1547264 1547264 0 0.00 0 43984 27 0 0
sp_add_invoice_to_stock 5314 5177 137 2.57 27 178057 18275 0 137
sp_cancel_adding_invoice 5443 5443 0 0.00 4 66864 750 0 0
sp_cancel_client_order 1863 1863 0 0.00 5 9978 317 0 0
sp_cancel_customer_reserve 537745 537745 0 0.00 1 21153 61 0 0
sp_cancel_pay_from_customer 714 714 0 0.00 3 5813 85 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 4890 59 0 0
sp_cancel_supplier_invoice 6103 6092 11 0.18 2 17909 788 0 11
sp_cancel_supplier_order 402 402 0 0.00 9 1113707 101715 0 0
sp_cancel_write_off 9246 9246 0 0.00 1 7936 103 0 0
sp_client_order 22015 22015 0 0.00 4 13357 346 0 0
sp_customer_reserve 478321 478321 0 0.00 0 78424 183 0 0
sp_fill_shopping_cart 33580 33580 0 0.00 1 13058 34 0 0
sp_fill_shopping_cart_clo_res 478315 478315 0 0.00 0 18213 15 0 0
sp_get_clo_for_invoice 5169 5169 0 0.00 0 24387 259 0 0
sp_kill_cost_storno 15837 15837 0 0.00 0 5939 49 0 0
sp_kill_qstorno_ret_qs2qd 1091230 1091230 0 0.00 0 21719 33 0 0
sp_kill_qty_storno 576610 576610 0 0.00 0 43981 70 0 0
sp_lock_selected_doc 21895 21895 0 0.00 0 11477 6 0 0
sp_make_cost_storno 29303 29303 0 0.00 0 18338 49 0 0
sp_multiply_rows_for_pdistr 29303 29303 0 0.00 0 10192 27 0 0
sp_multiply_rows_for_qdistr 22015 22015 0 0.00 0 10663 174 0 0
sp_pay_from_customer 7343 7343 0 0.00 4 12670 106 0 0
sp_pay_to_supplier 6141 6141 0 0.00 5 17637 113 0 0
sp_payment_common 13484 13484 0 0.00 1 12685 27 0 0
sp_qd_handle_on_cancel_clo 1863 1863 0 0.00 0 9971 294 0 0
sp_qd_handle_on_invoice_upd_sts 10612 10612 0 0.00 0 43974 288 0 0
sp_qd_handle_on_reserve_upd_sts 10650 10650 0 0.00 0 16965 57 0 0
sp_reserve_write_off 10650 10650 0 0.00 6 16978 165 0 0
sp_supplier_invoice 5812 5812 0 0.00 6 81599 2970 0 0
sp_supplier_order 5771 5771 0 0.00 4 35265 1228 0 0
srv_aggregate_perf_data 90 90 0 0.00 3347 440000 23718 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1186 113132 43980 0 0
srv_find_qd_qs_mism 1054628 1054628 0 0.00 0 16609 6 0 0
srv_make_invnt_saldo 611 611 0 0.00 27 69619 908 0 0
srv_make_money_saldo 796 796 0 0.00 1 2107 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 77488 278248 174951 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5177 5177 0 0.00 0 14550 162 0 0
t$perf-norm:sp_cancel_adding_invoice 432 432 0 0.00 0 6034 85 0 0
t$perf-norm:sp_cancel_client_order 1863 1863 0 0.00 0 9190 27 0 0
t$perf-norm:sp_cancel_customer_reserve 706 706 0 0.00 0 1856 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 714 714 0 0.00 0 642 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 3654 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 2123 18 0 0
t$perf-norm:sp_cancel_supplier_order 402 402 0 0.00 0 16271 1629 0 0
t$perf-norm:sp_cancel_write_off 1115 1115 0 0.00 0 6023 14 0 0
t$perf-norm:sp_client_order 22015 22015 0 0.00 0 11244 18 0 0
t$perf-norm:sp_customer_reserve 1083 1083 0 0.00 0 3860 11 0 0
t$perf-norm:sp_pay_from_customer 7343 7343 0 0.00 0 6109 14 0 0
t$perf-norm:sp_pay_to_supplier 6141 6141 0 0.00 0 17437 19 0 0
t$perf-norm:sp_reserve_write_off 10650 10650 0 0.00 0 13484 18 0 0
t$perf-norm:sp_supplier_invoice 5812 5812 0 0.00 0 8454 19 0 0
t$perf-norm:sp_supplier_order 5771 5771 0 0.00 0 5297 16 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 612 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 56 1 0 0
t$perf-norm:srv_make_invnt_saldo 611 611 0 0.00 0 1052 16 0 0
t$perf-norm:srv_make_money_saldo 796 796 0 0.00 0 3361 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 58 17 0 0
v_add_invoice_to_stock 10608 10334 274 2.58 0 8939 20 0 274
v_all_customers 39438 39438 0 0.00 0 13 1 0 0
v_all_suppliers 32076 32076 0 0.00 0 24 1 0 0
v_all_wares 44028 44028 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 864 864 0 0.00 0 3727 10 0 0
v_cancel_client_order 3726 3726 0 0.00 0 3614 5 0 0
v_cancel_customer_prepayment 1428 1428 0 0.00 0 3472 9 0 0
v_cancel_customer_reserve 1412 1412 0 0.00 0 1743 6 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 4876 10 0 0
v_cancel_supplier_invoice 848 826 22 2.59 0 2778 18 0 22
v_cancel_supplier_order 804 804 0 0.00 0 2884 13 0 0
v_cancel_write_off 2230 2230 0 0.00 0 2490 11 0 0
v_max_id_clo_ord 5767 5767 0 0.00 0 432 1 0 0
v_max_id_clo_res 1083 1083 0 0.00 0 2576 14 0 0
v_max_id_ord_sup 5796 5796 0 0.00 0 3851 2 0 0
v_max_non_paid_invoice 1666 1666 0 0.00 0 402 3 0 0
v_max_non_paid_realizn 4051 4051 0 0.00 0 1379 4 0 0
v_min_id_clo_ord 5767 5767 0 0.00 0 2355 6 0 0
v_min_id_clo_res 1083 1083 0 0.00 0 6289 8 0 0
v_min_id_ord_sup 5796 5796 0 0.00 0 7410 6 0 0
v_min_non_paid_invoice 6141 6141 0 0.00 0 12640 15 0 0
v_min_non_paid_realizn 7343 7343 0 0.00 0 12530 19 0 0
v_our_firm 11174 11174 0 0.00 0 36 1 0 0
v_reserve_write_off 21298 21298 0 0.00 0 3301 5 0 0
x_lock_depdocs_on_canc_invoice 11122 11122 0 0.00 0 16302 110 0 0
x_lock_depdocs_on_canc_sup_ord 402 402 0 0.00 0 3650 298 0 0
x_make_qstorno_1000_1200 5771 5771 0 0.00 1 35255 1157 0 0
x_make_qstorno_1200_2000 5812 5812 0 0.00 4 81576 2847 0 0
x_make_qstorno_2100_3300 478321 478321 0 0.00 0 78367 167 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-09-2021 22:38:57 to 08-09-2021 22:39:02.

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 2 8192000000 8799415648 8810168320 5422784 0.066 1 1 2 0 5540832 55488 62560
20:08:52 21475 8192000000 10393765664 10714214400 1449942048 15.037 100 94 188 0 1486563584 6501712 30119824
20:10:18 26147 8192000000 10467703104 10803261440 1515073200 15.607 100 91 182 1 1553779136 9105984 29599952
20:11:51 11547 8192000000 10518006832 10862747648 1568830192 16.072 100 86 172 0 1606463520 10735136 26898192
20:13:13 15922 8192000000 10577087120 10938834944 1635662256 16.643 100 77 152 1 1668875216 11131408 22081552
20:14:31 10475 8192000000 10632918832 11002974208 1695765968 17.150 100 77 155 0 1730127232 12057664 22303600
20:15:48 5084 8192000000 10700252320 11084296192 1771600912 17.780 100 74 148 0 1804683920 12061616 21021392
20:16:54 3162 8192000000 10762146336 11152998400 1824590512 18.215 100 79 158 0 1860586160 13764320 22231328
20:17:59 1717 8192000000 10805286608 11193585664 1863253696 18.530 100 87 172 0 1901441200 12707824 25479680
20:19:02 8761 8192000000 10827863968 11217055744 1885583408 18.710 100 76 149 3 1917291536 11669120 20039008
20:20:13 13261 8192000000 10835597376 11232501760 1892857600 18.769 100 72 145 1 1923277952 9236192 21184160
20:21:29 2170 8192000000 10830334672 11246866432 1904103232 18.859 100 54 106 2 1931325872 11772384 15450256
20:22:33 5854 8192000000 10830134304 11242614784 1910352608 18.909 100 40 81 1 1927520736 5102512 12065616
20:23:40 13817 8192000000 10815377200 11248742400 1915050368 18.947 100 27 55 0 1929119968 5569216 8500384
20:24:58 3514 8192000000 10858124512 11267330048 1920453728 18.990 100 69 136 3 1949985728 10171936 19360064
20:26:02 4597 8192000000 10834609552 11260502016 1913955296 18.938 100 32 64 3 1930219872 6954048 9310528
20:27:08 57304 8192000000 10819361520 11249926144 1862654096 18.525 97 23 47 3 1874193216 4808832 6730288
20:29:08 3359 8192000000 10773447968 11200012288 1837916784 18.324 98 42 82 3 1856066768 6117584 12032400
20:30:12 8944 8192000000 10723902352 11144278016 1728594208 17.424 94 33 66 1 1744634448 6143808 9896432
20:31:24 59496 8192000000 10653134560 11057729536 1601050304 16.348 88 23 45 1 1611748144 4490448 6207392
20:33:24 8611 8192000000 10747455392 11169837056 1817068016 18.154 99 32 64 0 1831703264 5771552 8863696
20:34:35 9043 8192000000 10770127424 11201667072 1860751344 18.509 100 21 42 2 1872283344 5655792 5876208
20:35:44 36528 8192000000 10786593216 11220897792 1875351568 18.628 100 13 25 3 1884839440 4981536 4506336
20:37:34 6001 8192000000 10815347776 11245473792 1904415088 18.862 100 27 53 1 1916822960 4878144 7529728
20:38:45 3584 8192000000 10865355744 11276644352 1926681296 19.040 100 55 110 0 1951164032 9319168 15163568
20:39:56 22998 8192000000 10878263696 11302969344 1943397984 19.174 100 51 100 2 1971034368 13703392 13932992
20:41:23 8997 8192000000 10897681776 11323600896 1958776240 19.296 100 58 116 4 1990759360 15380896 16602224
20:42:43 60280 8192000000 10886677728 11329462272 1956919264 19.282 100 31 62 1 1983778592 17304432 9554896
20:45:11 7051 8192000000 10929421232 11363577856 1983771632 19.495 100 49 98 1 2019922880 21264944 14886304
20:46:31 9702 8192000000 10953602592 11386183680 2003364960 19.649 100 51 102 3 2042551200 23737888 15448352
20:47:47 1186 8192000000 10985066304 11416158208 2023265600 19.806 100 65 129 0 2068862432 26359536 19237296
20:48:53 3963 8192000000 10968222896 11395039232 2010312096 19.704 100 68 136 1 2058439952 27840368 20287488
20:50:02 8460 8192000000 10979972736 11412131840 2001545968 19.635 99 63 125 1 2056082368 35141216 19395184
20:51:14 2095 8192000000 10982220736 11402940416 2006930032 19.677 100 86 171 3 2072149024 39610640 25608352
20:52:20 5098 8192000000 10969049536 11394428928 1985716896 19.510 100 68 136 1 2057536304 49920000 21899408
20:53:31 4252 8192000000 10967583408 11383644160 1976260896 19.435 99 75 149 1 2047127568 47854608 23012064
20:54:41 5635 8192000000 10981467248 11397476352 1987585040 19.525 100 77 154 2 2059688128 47476304 24626784
20:55:55 12037 8192000000 10976729232 11416276992 1981522128 19.477 99 41 82 1 2047434048 52196592 13715328
20:57:15 17057 8192000000 10950348800 11395842048 1945805136 19.193 98 36 72 1 2006978592 48735696 12437760
20:58:34 15119 8192000000 10923275568 11370586112 1925335872 19.030 99 33 66 0 1997111984 59738128 12037984
20:59:52 15401 8192000000 10896384688 11345661952 1904414384 18.862 98 29 58 1 1973413232 58724384 10274464
21:01:13 21534 8192000000 10866491792 11317399552 1897335888 18.805 98 22 44 1 1936100768 31147904 7616976
21:02:36 55326 8192000000 10862286880 11330994176 1876281504 18.635 98 7 14 1 1908965248 30266992 2416752
21:04:32 58589 8192000000 10889232768 11338522624 1924186176 19.020 99 23 46 0 1943639648 12082416 7371056
21:06:37 77248 8192000000 10885375136 11351965696 1888837312 18.736 97 6 12 0 1910121072 19580832 1702928
21:08:57 24487 8192000000 10887616656 11329003520 1940568336 19.151 99 50 101 0 1962682448 8353792 13760320
21:10:28 31648 8192000000 10892212832 11345186816 1955272480 19.268 100 33 67 0 1973074080 8324208 9477392
21:12:13 113132 8192000000 10890438096 11349155840 1933853712 19.098 98 30 60 0 1950859728 8479472 8526544
21:15:14 51668 8192000000 10923581984 11372118016 1951946128 19.242 98 33 66 1 1976254992 13877424 10431440
21:17:06 27476 8192000000 10917853152 11388018688 1991369408 19.555 100 17 34 0 2011183584 14606656 5207520
21:18:35 34536 8192000000 10905544592 11377807360 1968307872 19.372 99 21 42 0 1984195248 10138528 5748848
21:20:22 88149 8192000000 10911200224 11378737152 1961577216 19.319 99 16 32 0 1980681504 14821616 4282672
21:22:57 62222 8192000000 10911161984 11386728448 1968559344 19.374 99 9 18 1 1981321200 10257072 2504784
21:25:00 50432 8192000000 10940791744 11397611520 1982729008 19.486 99 24 46 0 1997082448 7998928 6354512
21:26:54 39563 8192000000 10938555728 11401707520 1984887760 19.503 99 24 46 2 2005968592 13603728 7477104
21:29:17 67723 8192000000 10921107952 11397165056 1987522512 19.524 100 13 26 0 2006327136 15344112 3460512
21:31:25 23833 8192000000 10924634832 11365670912 1912580336 18.927 96 20 40 0 1926513904 7867024 6066544
21:32:50 28676 8192000000 10894985040 11352367104 1958413136 19.293 99 31 62 2 1978667376 11797984 8456256
21:34:22 74889 8192000000 10905146800 11377086464 1943879184 19.178 99 7 14 0 1967463408 21794144 1790080
21:36:44 29297 8192000000 10911494576 11366629376 1960122992 19.307 99 43 86 2 1983764336 10211296 13430048
21:38:19 97171 8192000000 10875285744 11329765376 1902113280 18.843 97 34 68 0 1919944000 7119648 10711072
21:40:58 1294 8192000000 10888451840 11341209600 1948932496 19.218 100 38 77 0 1971534144 11769744 10831904
21:42:00 37078 8192000000 10834143824 11307036672 1876357488 18.636 97 7 14 0 1888202816 9464720 2380608
21:43:54 66882 8192000000 10846678928 11308675072 1851798896 18.437 96 14 28 0 1861765024 5891376 4074752
21:46:11 61095 8192000000 10838432560 11318755328 1886468736 18.717 98 11 22 0 1897914976 8208000 3238240
21:48:15 51272 8192000000 10851832976 11313782784 1872749344 18.607 96 17 34 2 1887945904 9947504 5249056
21:50:07 62572 8192000000 10851112880 11327139840 1875310384 18.627 97 13 26 0 1896130064 17022096 3797584
21:52:18 87533 8192000000 10855065904 11318755328 1863917312 18.535 97 9 18 0 1876837568 10268992 2651264
21:54:51 41767 8192000000 10869604544 11319128064 1910012144 18.907 99 47 94 2 1939748608 15539808 14196656
21:56:33 61542 8192000000 10866114416 11340251136 1931820992 19.081 100 14 28 0 1955318144 19320752 4176400
21:58:41 63378 8192000000 10880123104 11334729728 1903477248 18.854 98 27 55 1 1924231920 13386320 7368352
22:00:45 86786 8192000000 10867241744 11338371072 1942670000 19.168 100 25 50 0 1963286576 13121520 7495056
22:04:17 64121 8192000000 10866814512 11352670208 1950558656 19.231 100 11 22 0 1962293856 8615824 3119376
22:06:21 55330 8192000000 10882953968 11356647424 1944185536 19.180 99 15 30 0 1956793472 7778784 4829152
22:08:54 69290 8192000000 10906816640 11384877056 1979655104 19.462 100 14 28 2 1991253696 7508416 4090176
22:11:04 43318 8192000000 10901843040 11362807808 1953427312 19.254 98 14 27 0 1965945600 8943424 3574864
22:12:49 93009 8192000000 10888965552 11370381312 1888914368 18.737 95 12 24 1 1902134752 9999744 3220640
22:15:26 36471 8192000000 10910859280 11371139072 1922967408 19.011 97 28 56 0 1940215616 9667344 7580864
22:17:04 34570 8192000000 10939276192 11402616832 1995688256 19.589 100 49 98 0 2023196288 13794256 13713776
22:18:46 64229 8192000000 10909255312 11379740672 1927150672 19.044 97 14 28 0 1945606944 14397664 4058608
22:20:52 34439 8192000000 10914556816 11384774656 1980434032 19.468 100 21 42 1 1996131296 10092320 5604944
22:22:39 56075 8192000000 10873760576 11346235392 1925754144 19.033 98 27 52 1 1946967072 13323712 7889216
22:24:45 74904 8192000000 10859606656 11323920384 1865008912 18.544 95 19 38 0 1877688112 7524448 5154752
22:27:01 84988 8192000000 10881973440 11362729984 1956187808 19.276 100 16 32 2 1973886256 13240016 4458432
22:29:26 53105 8192000000 10899665824 11377078272 1948643072 19.216 99 20 40 1 1976310448 22107968 5559408
22:31:19 66947 8192000000 10874692976 11367698432 1911403760 18.918 98 11 22 0 1930680176 15950368 3326048
22:33:26 68706 8192000000 10872992752 11342311424 1840208688 18.343 94 22 44 0 1860446032 13779968 6457376
22:35:36 60772 8192000000 10872239952 11334139904 1908652656 18.896 99 21 42 1 1934952288 19921824 6377808
22:37:42 16622 8192000000 9024362144 9222828032 189455088 2.260 10 2 2 1 193657216 3789056 413072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-09-2021 22:39:03 to 08-09-2021 22:39:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-09-2021 22:39:19 to 08-09-2021 22:39:21.

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          247270
MON$OLDEST_ACTIVE               247271
MON$OLDEST_SNAPSHOT             247271
MON$NEXT_TRANSACTION            247271
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                       2016000
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.33493 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33493 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33493 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33493 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 ---
--- end of diff output ---

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

08.09.21 22:39:22. 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-08 22:39:25 No severe PSQL-related problems occured
08.09.21 22:39:26. End of report.