Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 04.08.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 16496608 kB
MemAvailable 31069804 kB
Buffers 0 kB
Cached 15203076 kB
SwapCached 2580 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-08-04 22:37:44
DTS_END                         2021-08-04 22:37:44.8240
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59084
TRN_ID                          240349
ATT_ID                          704
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: 9072.33 19757 1088679
customer order: creation 175.04 355 21005
customer order: refuse 15.02 364 1802
order to supplier: creation 45.64 1331 5477
order to supplier: removal 3.10 109136 372
invoice (draft): creation 45.30 3589 5436
invoice (draft): removal 49.07 892 5888
invoice accept: apply 42.47 18072 5096
invoice accept: cancel 45.07 926 5408
customer reserve: creation 3940.23 177 472828
customer reserve: removal 4415.87 59 529904
realization accept: apply 84.19 205 10103
realization accept: cancel 79.23 124 9508
payment from customer: creation 60.13 144 7215
payment from customer: removal 5.58 115 670
payment to supplier: creation 50.46 156 6055
payment to supplier: removal 3.48 92 417
service: total inventory turnovers 4.78 1192 574
service: total monetary turnovers 6.67 41 800
service: aggregate perf. data 0.98 15112 117
service: refresh index statistics 0.03 243053 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 04-08-2021 22:39:30 to 04-08-2021 22:39:33.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2971
TEST_TIME 2 3588
TEST_TIME 3 3400
TEST_TIME 4 3919
TEST_TIME 5 3960
TEST_TIME 6 4592
TEST_TIME 7 4519
TEST_TIME 8 4469
TEST_TIME 9 4735
TEST_TIME 10 4951
TEST_TIME 11 5500
TEST_TIME 12 5570
TEST_TIME 13 6245
TEST_TIME 14 6418
TEST_TIME 15 6528
TEST_TIME 16 7147
TEST_TIME 17 7396
TEST_TIME 18 7767
TEST_TIME 19 8106
TEST_TIME 20 8197
TEST_TIME 21 8946
TEST_TIME 22 9031
TEST_TIME 23 8923
TEST_TIME 24 8944
TEST_TIME 25 8818
TEST_TIME 26 9019
TEST_TIME 27 9407
TEST_TIME 28 9339
TEST_TIME 29 9358
TEST_TIME 30 10267
TEST_TIME 31 10708
TEST_TIME 32 10604
TEST_TIME 33 10350
TEST_TIME 34 10134
TEST_TIME 35 10237
TEST_TIME 36 10106
TEST_TIME 37 9936
TEST_TIME 38 9939
TEST_TIME 39 9839
TEST_TIME 40 9700
TEST_TIME 41 9656
TEST_TIME 42 9510
TEST_TIME 43 9531
TEST_TIME 44 9460
TEST_TIME 45 9357
TEST_TIME 46 9298
TEST_TIME 47 9147
TEST_TIME 48 9176
TEST_TIME 49 9162
TEST_TIME 50 9105
TEST_TIME 51 9040
TEST_TIME 52 8957
TEST_TIME 53 8941
TEST_TIME 54 8984
TEST_TIME 55 8934
TEST_TIME 56 8945
TEST_TIME 57 9014
TEST_TIME 58 9005
TEST_TIME 59 9143
TEST_TIME 60 9250
TEST_TIME 61 9254
TEST_TIME 62 9286
TEST_TIME 63 9371
TEST_TIME 64 9407
TEST_TIME 65 9377
TEST_TIME 66 9331
TEST_TIME 67 9401
TEST_TIME 68 9488
TEST_TIME 69 9362
TEST_TIME 70 9373
TEST_TIME 71 9349
TEST_TIME 72 9296
TEST_TIME 73 9310
TEST_TIME 74 9312
TEST_TIME 75 9239
TEST_TIME 76 9190
TEST_TIME 77 9146
TEST_TIME 78 9127
TEST_TIME 79 9188
TEST_TIME 80 9217
TEST_TIME 81 9182
TEST_TIME 82 9190
TEST_TIME 83 9244
TEST_TIME 84 9222
TEST_TIME 85 9223
TEST_TIME 86 9235
TEST_TIME 87 9260
TEST_TIME 88 9322
TEST_TIME 89 9302
TEST_TIME 90 9322
TEST_TIME 91 9367
TEST_TIME 92 9275
TEST_TIME 93 9224
TEST_TIME 94 9234
TEST_TIME 95 9225
TEST_TIME 96 9183
TEST_TIME 97 9139
TEST_TIME 98 9104
TEST_TIME 99 9065
TEST_TIME 100 9053
TEST_TIME 101 9053
TEST_TIME 102 9029
TEST_TIME 103 9018
TEST_TIME 104 9054
TEST_TIME 105 9097
TEST_TIME 106 9070
TEST_TIME 107 9084
TEST_TIME 108 9093
TEST_TIME 109 9097
TEST_TIME 110 9117
TEST_TIME 111 9209
TEST_TIME 112 9273
TEST_TIME 113 9250
TEST_TIME 114 9219
TEST_TIME 115 9159
TEST_TIME 116 9138
TEST_TIME 117 9107
TEST_TIME 118 9061
TEST_TIME 119 9027
TEST_TIME 120 9076

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 04-08-2021 22:39:34 to 04-08-2021 22:39:37.

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 1524333 1524333 0 0.00 0 23576 6 0 0
doc_list_biud 1524331 1524331 0 0.00 0 40634 27 0 0
sp_add_invoice_to_stock 5221 5096 125 2.39 37 203817 18073 0 125
sp_cancel_adding_invoice 5408 5408 0 0.00 5 65393 927 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 11613 365 0 0
sp_cancel_customer_reserve 529904 529904 0 0.00 1 39553 60 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 7504 116 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 2 6010 92 0 0
sp_cancel_supplier_invoice 5896 5888 8 0.13 2 15521 892 0 8
sp_cancel_supplier_order 372 372 0 0.00 7 949616 109136 0 0
sp_cancel_write_off 9508 9508 0 0.00 1 14179 124 0 0
sp_client_order 21005 21005 0 0.00 3 26388 355 0 0
sp_customer_reserve 472828 472828 0 0.00 0 56205 177 0 0
sp_fill_shopping_cart 31917 31917 0 0.00 1 20878 44 0 0
sp_fill_shopping_cart_clo_res 472825 472825 0 0.00 0 16258 16 0 0
sp_get_clo_for_invoice 5091 5091 0 0.00 0 27626 350 0 0
sp_kill_cost_storno 16003 16003 0 0.00 0 13269 61 0 0
sp_kill_qstorno_ret_qs2qd 1075576 1075576 0 0.00 0 39549 33 0 0
sp_kill_qty_storno 568076 568076 0 0.00 0 40633 72 0 0
sp_lock_selected_doc 20979 20979 0 0.00 0 9253 6 0 0
sp_make_cost_storno 28464 28464 0 0.00 0 23208 64 0 0
sp_multiply_rows_for_pdistr 28464 28464 0 0.00 0 18024 36 0 0
sp_multiply_rows_for_qdistr 21005 21005 0 0.00 0 16216 154 0 0
sp_pay_from_customer 7215 7215 0 0.00 4 18033 145 0 0
sp_pay_to_supplier 6055 6055 0 0.00 4 16246 157 0 0
sp_payment_common 13270 13270 0 0.00 1 12273 41 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 11605 344 0 0
sp_qd_handle_on_invoice_upd_sts 10499 10499 0 0.00 0 40631 361 0 0
sp_qd_handle_on_reserve_upd_sts 10103 10103 0 0.00 0 18608 66 0 0
sp_reserve_write_off 10103 10103 0 0.00 6 21923 205 0 0
sp_supplier_invoice 5436 5436 0 0.00 6 96018 3589 0 0
sp_supplier_order 5477 5477 0 0.00 5 61441 1331 0 0
srv_aggregate_perf_data 117 117 0 0.00 2528 136106 15113 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1859 160008 51615 0 0
srv_find_qd_qs_mism 1039027 1039027 0 0.00 0 11038 5 0 0
srv_make_invnt_saldo 574 574 0 0.00 29 115063 1193 0 0
srv_make_money_saldo 800 800 0 0.00 1 12564 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 127102 326942 243054 0 0
t$perf-abend:sp_add_invoice_to_stock 125 125 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5096 5096 0 0.00 0 21394 177 0 0
t$perf-norm:sp_cancel_adding_invoice 441 441 0 0.00 0 4722 152 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 2344 15 0 0
t$perf-norm:sp_cancel_customer_reserve 700 700 0 0.00 0 595 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 6389 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 2288 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 365 365 0 0.00 0 4067 31 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 39132 1752 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 4155 9 0 0
t$perf-norm:sp_client_order 21005 21005 0 0.00 0 35913 21 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 10148 17 0 0
t$perf-norm:sp_pay_from_customer 7215 7215 0 0.00 0 16819 18 0 0
t$perf-norm:sp_pay_to_supplier 6055 6055 0 0.00 0 29542 15 0 0
t$perf-norm:sp_reserve_write_off 10103 10103 0 0.00 0 8005 13 0 0
t$perf-norm:sp_supplier_invoice 5436 5436 0 0.00 0 8313 27 0 0
t$perf-norm:sp_supplier_order 5477 5477 0 0.00 0 8793 20 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 813 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 293 7 0 0
t$perf-norm:srv_make_invnt_saldo 574 574 0 0.00 0 13303 51 0 0
t$perf-norm:srv_make_money_saldo 800 800 0 0.00 0 13654 40 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 13 8 0 0
v_add_invoice_to_stock 10432 10182 250 2.39 0 7620 19 0 250
v_all_customers 38020 38020 0 0.00 0 103 1 0 0
v_all_suppliers 30346 30346 0 0.00 0 103 1 0 0
v_all_wares 42010 42010 0 0.00 0 52 1 0 0
v_cancel_adding_invoice 882 882 0 0.00 0 1890 10 0 0
v_cancel_client_order 3604 3604 0 0.00 0 881 3 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 4529 14 0 0
v_cancel_customer_reserve 1400 1400 0 0.00 0 2203 4 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 3484 10 0 0
v_cancel_supplier_invoice 746 730 16 2.14 0 4588 23 0 16
v_cancel_supplier_order 744 744 0 0.00 0 859 7 0 0
v_cancel_write_off 2036 2036 0 0.00 0 6154 20 0 0
v_max_id_clo_ord 5477 5477 0 0.00 0 1616 2 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 3574 21 0 0
v_max_id_ord_sup 5435 5435 0 0.00 0 4587 3 0 0
v_max_non_paid_invoice 1794 1794 0 0.00 0 294 2 0 0
v_max_non_paid_realizn 3891 3891 0 0.00 0 3457 7 0 0
v_min_id_clo_ord 5477 5477 0 0.00 0 3172 7 0 0
v_min_id_clo_res 1001 1001 0 0.00 0 2465 6 0 0
v_min_id_ord_sup 5435 5435 0 0.00 0 5959 11 0 0
v_min_non_paid_invoice 6055 6055 0 0.00 0 12268 26 0 0
v_min_non_paid_realizn 7215 7215 0 0.00 0 10250 27 0 0
v_our_firm 10638 10638 0 0.00 0 20 1 0 0
v_reserve_write_off 20206 20206 0 0.00 0 6655 7 0 0
x_lock_depdocs_on_canc_invoice 10931 10931 0 0.00 0 30765 131 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 7722 381 0 0
x_make_qstorno_1000_1200 5477 5477 0 0.00 1 61418 1253 0 0
x_make_qstorno_1200_2000 5436 5436 0 0.00 3 96011 3414 0 0
x_make_qstorno_2100_3300 472828 472828 0 0.00 0 56162 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 04-08-2021 22:39:38 to 04-08-2021 22:39:42.

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:48 7 8192000000 8802630672 8814170112 8572016 0.104 2 1 2 0 8691424 56848 62560
20:08:49 35768 8192000000 10373067360 10689183744 1422975488 14.799 100 93 186 0 1459506160 6239936 30290736
20:10:29 26566 8192000000 10430481120 10760167424 1478210448 15.286 100 84 168 0 1513888832 8689056 26989328
20:11:59 17104 8192000000 10497263776 10838929408 1541235872 15.834 100 75 150 0 1573649808 8843088 23570848
20:13:20 12229 8192000000 10561098256 10923139072 1620677680 16.516 100 69 136 2 1651531408 10423568 20430160
20:14:37 6926 8192000000 10637983024 11007864832 1694691008 17.141 100 77 154 2 1730163776 13162976 22309792
20:15:51 4641 8192000000 10689969360 11067453440 1752899280 17.626 100 81 160 0 1789158016 13916656 22342080
20:16:58 4180 8192000000 10736133328 11116945408 1794937456 17.972 100 78 156 0 1829407584 12868944 21601184
20:18:05 1857 8192000000 10782937136 11168940032 1840523296 18.345 100 90 180 2 1879987760 14055840 25408624
20:19:08 1968 8192000000 10815793488 11205873664 1870407312 18.588 100 93 186 1 1909942992 13750288 25785392
20:20:14 11939 8192000000 10804646912 11214225408 1881388784 18.676 100 60 119 2 1910696912 12384688 16923440
20:21:31 7168 8192000000 10799308048 11225604096 1889021568 18.738 100 26 50 1 1902224528 5901360 7301600
20:22:44 9880 8192000000 10804257408 11236655104 1898714288 18.816 100 17 34 1 1910114000 6069168 5330544
20:23:54 16413 8192000000 10826722768 11257315328 1919979264 18.987 100 39 75 3 1936549872 4713232 11857376
20:25:15 16953 8192000000 10837167888 11270619136 1922919984 19.010 100 42 85 3 1941339184 6113056 12306144
20:26:36 6507 8192000000 10810069120 11245318144 1898540304 18.815 99 21 42 2 1910264896 5909344 5815248
20:27:46 5313 8192000000 10814636144 11251367936 1900183536 18.828 100 29 58 2 1914746912 5743264 8820112
20:28:52 7529 8192000000 10767762416 11198599168 1857300704 18.481 100 13 25 1 1866781104 5826608 3653792
20:30:01 39210 8192000000 10706713120 11125350400 1597548128 16.318 86 31 62 1 1610956576 5236688 8171760
20:31:40 8201 8192000000 10715406720 11123208192 1756764160 17.658 97 28 55 1 1770647984 5936400 7947424
20:32:50 7298 8192000000 10739734896 11161014272 1823585456 18.207 100 30 61 2 1838665200 6850272 8229472
20:34:00 8819 8192000000 10753175088 11165986816 1791259536 17.942 97 34 64 2 1805335728 5123792 8952400
20:35:09 12418 8192000000 10756772256 11182587904 1842416256 18.360 100 33 62 1 1857456320 6577568 8462496
20:36:23 5779 8192000000 10790412512 11217620992 1872446416 18.604 100 27 54 2 1886134368 6419712 7268240
20:37:35 4656 8192000000 10816891296 11249799168 1906979472 18.882 100 34 68 2 1922301536 5905712 9416352
20:38:44 7571 8192000000 10867180560 11280769024 1927991552 19.051 100 52 104 1 1951422368 8293104 15137712
20:40:02 67915 8192000000 10859748576 11306409984 1940452896 19.150 100 27 55 2 1958166240 9275840 8437504
20:42:14 109383 8192000000 10901737216 11352256512 1971994192 19.401 100 23 46 0 1994294768 15040512 7260064
20:45:07 25149 8192000000 10930323520 11365785600 1975611136 19.430 99 45 90 2 2011855216 22415920 13828160
20:46:39 7652 8192000000 10967175856 11396272128 2013205648 19.727 100 55 110 1 2048599168 19058208 16335312
20:47:48 6860 8192000000 10967082176 11398111232 2013325264 19.728 100 60 120 1 2055960304 24594672 18040368
20:49:05 7281 8192000000 10958187680 11394830336 2005138864 19.663 100 53 106 1 2043921280 23277040 15505376
20:50:17 7331 8192000000 10979316896 11409473536 2005443376 19.666 99 68 136 2 2055559024 29706816 20408832
20:51:33 10447 8192000000 10948040240 11377033216 1991245584 19.554 100 65 130 0 2034704928 24350960 19108384
20:52:49 2508 8192000000 10958270112 11371483136 1973450384 19.413 99 74 148 0 2032963632 36689968 22823280
20:53:54 2250 8192000000 10978552288 11395223552 1994557856 19.580 100 84 164 1 2057231008 37959008 24714144
20:55:03 5089 8192000000 10962012240 11381493760 1969793088 19.384 99 68 135 1 2028008992 37462576 20753328
20:56:16 7949 8192000000 10907316288 11331477504 1918239168 18.973 99 54 108 0 1976101136 40193040 17668928
20:57:32 1858 8192000000 10968436880 11377545216 1969398416 19.381 100 93 181 2 2050002624 51953120 28651088
20:58:41 3035 8192000000 10928002832 11350990848 1942646128 19.168 100 62 124 3 2005931952 43959616 19326208
20:59:47 22772 8192000000 10899240096 11335991296 1901231216 18.836 97 36 72 3 1949983792 37570768 11181808
21:01:16 14623 8192000000 10898177664 11338842112 1904476784 18.862 98 29 56 2 1959081888 45167824 9437280
21:02:51 25607 8192000000 10934654400 11377246208 1950172288 19.228 100 25 50 2 2010123552 51291760 8659504
21:04:21 19890 8192000000 10937508512 11374358528 1946795712 19.201 100 30 59 2 2016600544 59199152 10605680
21:05:42 28989 8192000000 10920971904 11374469120 1951043712 19.235 100 23 46 0 2011580000 52184160 8352128
21:07:14 45611 8192000000 10898592400 11352350720 1910224480 18.908 98 13 26 0 1950942384 36381296 4336608
21:09:00 21627 8192000000 10899356720 11318128640 1919399904 18.982 98 44 86 0 1937779872 6694912 11685056
21:10:29 68722 8192000000 10880471136 11318996992 1917848144 18.970 99 36 72 0 1943486320 14818912 10819264
21:12:40 95749 8192000000 10863909504 11320524800 1907691728 18.888 98 29 57 2 1922678704 7692896 7294080
21:15:20 51179 8192000000 10876505280 11343720448 1955646912 19.271 100 16 29 2 1972709024 12859568 4202544
21:17:50 34211 8192000000 10883154464 11344773120 1946408416 19.198 99 29 59 0 1964143296 9463840 8271040
21:19:26 85207 8192000000 10900208208 11362467840 1971885360 19.400 100 29 58 0 1995147792 14872256 8390176
21:21:53 72459 8192000000 10903631632 11369078784 1974477312 19.421 100 26 51 1 1989430432 7284096 7669024
21:24:15 29261 8192000000 10900421776 11368304640 1981917840 19.480 100 16 32 0 1995892752 8862640 5112272
21:26:02 25370 8192000000 10910634480 11377356800 1974044656 19.418 99 35 68 0 1990058384 6299680 9714048
21:27:37 32673 8192000000 10913199696 11384803328 1977519520 19.445 99 8 16 0 1986441760 6440832 2481408
21:29:10 65184 8192000000 10916643824 11393871872 2001710816 19.636 100 12 24 1 2015786560 10611024 3464720
21:31:30 61165 8192000000 10926830352 11401572352 1990258112 19.546 99 13 26 0 2004000048 9926032 3815904
21:33:31 56381 8192000000 10916985568 11384131584 1945657168 19.192 97 12 24 1 1966195984 15308160 5230656
21:35:29 78192 8192000000 10939108640 11430768640 2003407088 19.650 100 32 64 0 2037523264 24919872 9196304
21:37:47 100548 8192000000 10932961152 11402878976 1943123536 19.172 97 13 25 1 1960419680 13497168 3798976
21:40:29 90226 8192000000 10896791600 11355914240 1851738944 18.436 93 14 28 0 1867838528 12033744 4065840
21:43:04 10775 8192000000 10880814032 11320340480 1899102208 18.819 98 59 118 0 1927826656 12652448 16072000
21:44:16 46891 8192000000 10855890128 11324579840 1879494160 18.661 97 33 66 2 1917462016 28719120 9248736
21:46:07 95582 8192000000 10847983008 11297361920 1851392592 18.433 96 14 28 2 1872153536 16935408 3825536
21:48:45 36715 8192000000 10832297744 11305914368 1913779472 18.937 100 17 34 3 1929565936 10969712 4816752
21:50:22 81715 8192000000 10833874320 11317530624 1885474608 18.709 98 8 16 0 1905167344 17103664 2589072
21:52:45 65817 8192000000 10841872336 11311931392 1902103264 18.843 98 19 38 1 1917320864 10181760 5035840
21:54:57 77066 8192000000 10845018192 11310387200 1889937216 18.745 98 30 59 1 1915992704 16634224 9421264
21:57:20 45300 8192000000 10854817808 11323793408 1887064352 18.722 97 11 22 1 1901387088 11181920 3140816
21:59:21 75832 8192000000 10863949088 11338940416 1903856096 18.857 98 15 30 0 1921590976 13240464 4494416
22:01:37 16538 8192000000 10842774784 11296800768 1886583360 18.718 98 38 75 2 1904690208 8131632 9975216
22:02:57 70915 8192000000 10828580208 11287298048 1835105776 18.301 96 16 32 1 1849654800 10168304 4380720
22:05:09 160007 8192000000 10884847296 11340623872 1894948368 18.786 98 27 54 1 1921751968 19777600 7026000
22:08:49 112314 8192000000 10895910896 11352866816 1950674992 19.232 99 24 46 5 1971004752 13612688 6717072
22:11:43 53822 8192000000 10881956416 11361107968 1969356976 19.380 100 17 34 1 1982620272 8694048 4569248
22:13:37 66660 8192000000 10892902944 11374129152 1958782992 19.296 99 5 10 1 1966326000 6287056 1255952
22:15:44 72940 8192000000 10911741504 11398561792 1989057856 19.536 100 13 26 3 2001569616 8816144 3695616
22:17:57 45531 8192000000 10921244992 11402141696 1999275328 19.617 100 11 22 1 2010213456 8190560 2747568
22:19:43 72589 8192000000 10913393344 11390676992 1968072592 19.370 98 18 36 1 1985520592 11951168 5496832
22:21:58 83105 8192000000 10899479952 11371651072 1896573952 18.799 95 15 30 0 1910827600 10144640 4109008
22:24:26 60816 8192000000 10922579808 11385077760 1938483632 19.135 98 26 52 1 1961082464 14948128 7650704
22:26:27 89812 8192000000 10897345792 11364995072 1904583104 18.863 96 25 51 1 1924849824 13377200 6889520
22:28:57 108399 8192000000 10866823840 11356635136 1825847920 18.225 93 4 8 0 1845109424 18363216 898288
22:31:46 116407 8192000000 10858701424 11307884544 1877483248 18.645 96 32 63 1 1896575728 10392096 8700384
22:34:45 65331 8192000000 10842228704 11312570368 1898435776 18.814 98 10 20 0 1910767424 9271760 3059888
22:36:51 71272 8192000000 9295844608 9580113920 436746384 5.061 23 5 10 0 445164960 7225616 1192960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 04-08-2021 22:39:44 to 04-08-2021 22:39:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 125 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 04-08-2021 22:40:00 to 04-08-2021 22:40:02.

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          240610
MON$OLDEST_ACTIVE               240611
MON$OLDEST_SNAPSHOT             240611
MON$NEXT_TRANSACTION            240611
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                       2018304
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:180: 
:181: mscs-test-161.budzdorov.ru    Wed Aug  4 22:38:10 2021
:182:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:183: 
:184: 
:185: mscs-test-161.budzdorov.ru    Wed Aug  4 22:38:10 2021
:186:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:187: 
--- end of diff output ---

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

04.08.21 22:40:03. 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-08-04 22:40:05 No severe PSQL-related problems occured
04.08.21 22:40:06. End of report.