Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2021 16: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 14826872 kB
MemAvailable 30730472 kB
Buffers 8 kB
Cached 16828744 kB
SwapCached 3072 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-27 16:37:53
DTS_END                         2021-09-27 16:37:53.6390
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/57490
TRN_ID                          240877
ATT_ID                          649
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: 9377.73 24409 1125328
customer order: creation 174.22 356 20906
customer order: refuse 14.37 348 1724
order to supplier: creation 45.30 1304 5436
order to supplier: removal 3.39 113808 407
invoice (draft): creation 45.42 3580 5450
invoice (draft): removal 53.72 883 6446
invoice accept: apply 41.52 18721 4982
invoice accept: cancel 49.00 839 5880
customer reserve: creation 3836.48 185 460377
customer reserve: removal 4814.31 63 577717
realization accept: apply 82.18 180 9861
realization accept: cancel 86.55 119 10386
payment from customer: creation 60.97 132 7316
payment from customer: removal 5.65 78 678
payment to supplier: creation 48.93 140 5871
payment to supplier: removal 3.50 68 420
service: total inventory turnovers 4.55 1144 546
service: total monetary turnovers 6.94 29 833
service: aggregate perf. data 0.74 21958 89
service: refresh index statistics 0.03 324244 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-09-2021 16:39:05 to 27-09-2021 16:39:07.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2874
TEST_TIME 2 4886
TEST_TIME 3 4635
TEST_TIME 4 4734
TEST_TIME 5 5147
TEST_TIME 6 4852
TEST_TIME 7 5001
TEST_TIME 8 5522
TEST_TIME 9 5830
TEST_TIME 10 6192
TEST_TIME 11 6375
TEST_TIME 12 6495
TEST_TIME 13 6515
TEST_TIME 14 6585
TEST_TIME 15 6963
TEST_TIME 16 7096
TEST_TIME 17 7355
TEST_TIME 18 7538
TEST_TIME 19 7808
TEST_TIME 20 8099
TEST_TIME 21 8163
TEST_TIME 22 8392
TEST_TIME 23 8530
TEST_TIME 24 8654
TEST_TIME 25 8778
TEST_TIME 26 9201
TEST_TIME 27 9137
TEST_TIME 28 9573
TEST_TIME 29 9792
TEST_TIME 30 9780
TEST_TIME 31 10444
TEST_TIME 32 11308
TEST_TIME 33 11044
TEST_TIME 34 10851
TEST_TIME 35 10706
TEST_TIME 36 10606
TEST_TIME 37 10428
TEST_TIME 38 10373
TEST_TIME 39 10374
TEST_TIME 40 10361
TEST_TIME 41 10255
TEST_TIME 42 10234
TEST_TIME 43 10151
TEST_TIME 44 10269
TEST_TIME 45 10215
TEST_TIME 46 10064
TEST_TIME 47 9902
TEST_TIME 48 9833
TEST_TIME 49 9743
TEST_TIME 50 9619
TEST_TIME 51 9548
TEST_TIME 52 9643
TEST_TIME 53 9593
TEST_TIME 54 9619
TEST_TIME 55 9561
TEST_TIME 56 9519
TEST_TIME 57 9403
TEST_TIME 58 9353
TEST_TIME 59 9370
TEST_TIME 60 9286
TEST_TIME 61 9360
TEST_TIME 62 9365
TEST_TIME 63 9358
TEST_TIME 64 9343
TEST_TIME 65 9387
TEST_TIME 66 9419
TEST_TIME 67 9408
TEST_TIME 68 9491
TEST_TIME 69 9524
TEST_TIME 70 9573
TEST_TIME 71 9591
TEST_TIME 72 9585
TEST_TIME 73 9637
TEST_TIME 74 9547
TEST_TIME 75 9485
TEST_TIME 76 9397
TEST_TIME 77 9445
TEST_TIME 78 9434
TEST_TIME 79 9361
TEST_TIME 80 9340
TEST_TIME 81 9297
TEST_TIME 82 9293
TEST_TIME 83 9295
TEST_TIME 84 9275
TEST_TIME 85 9301
TEST_TIME 86 9323
TEST_TIME 87 9292
TEST_TIME 88 9334
TEST_TIME 89 9361
TEST_TIME 90 9375
TEST_TIME 91 9419
TEST_TIME 92 9480
TEST_TIME 93 9471
TEST_TIME 94 9444
TEST_TIME 95 9518
TEST_TIME 96 9470
TEST_TIME 97 9427
TEST_TIME 98 9418
TEST_TIME 99 9367
TEST_TIME 100 9350
TEST_TIME 101 9366
TEST_TIME 102 9333
TEST_TIME 103 9312
TEST_TIME 104 9270
TEST_TIME 105 9220
TEST_TIME 106 9230
TEST_TIME 107 9226
TEST_TIME 108 9317
TEST_TIME 109 9315
TEST_TIME 110 9307
TEST_TIME 111 9337
TEST_TIME 112 9362
TEST_TIME 113 9364
TEST_TIME 114 9342
TEST_TIME 115 9407
TEST_TIME 116 9415
TEST_TIME 117 9460
TEST_TIME 118 9453
TEST_TIME 119 9416
TEST_TIME 120 9381
TEST_TIME 121 9303

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-09-2021 16:39:09 to 27-09-2021 16:39:12.

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 1551786 1551786 0 0.00 0 29516 6 0 0
doc_list_biud 1551786 1551786 0 0.00 0 43233 29 0 0
sp_add_invoice_to_stock 5150 4982 168 3.26 31 150281 18722 0 168
sp_cancel_adding_invoice 5880 5880 0 0.00 5 52990 839 0 0
sp_cancel_client_order 1724 1724 0 0.00 4 19976 349 0 0
sp_cancel_customer_reserve 577717 577717 0 0.00 1 29931 63 0 0
sp_cancel_pay_from_customer 678 678 0 0.00 3 3940 79 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 3151 68 0 0
sp_cancel_supplier_invoice 6460 6446 14 0.21 2 43282 884 0 14
sp_cancel_supplier_order 407 407 0 0.00 6 1134742 113809 0 0
sp_cancel_write_off 10386 10386 0 0.00 1 9500 119 0 0
sp_client_order 20906 20906 0 0.00 3 25946 357 0 0
sp_customer_reserve 460377 460377 0 0.00 0 56791 186 0 0
sp_fill_shopping_cart 31780 31780 0 0.00 1 9948 41 0 0
sp_fill_shopping_cart_clo_res 460371 460371 0 0.00 0 14949 15 0 0
sp_get_clo_for_invoice 4976 4976 0 0.00 0 33413 313 0 0
sp_kill_cost_storno 17364 17364 0 0.00 0 14424 59 0 0
sp_kill_qstorno_ret_qs2qd 1172673 1172673 0 0.00 0 43231 34 0 0
sp_kill_qty_storno 617397 617397 0 0.00 0 43232 73 0 0
sp_lock_selected_doc 20554 20554 0 0.00 0 7933 6 0 0
sp_make_cost_storno 28024 28024 0 0.00 0 29292 55 0 0
sp_multiply_rows_for_pdistr 28024 28024 0 0.00 0 10097 30 0 0
sp_multiply_rows_for_qdistr 20906 20906 0 0.00 0 22223 170 0 0
sp_pay_from_customer 7316 7316 0 0.00 4 11120 133 0 0
sp_pay_to_supplier 5871 5871 0 0.00 5 12775 140 0 0
sp_payment_common 13187 13187 0 0.00 1 11076 43 0 0
sp_qd_handle_on_cancel_clo 1724 1724 0 0.00 0 19968 318 0 0
sp_qd_handle_on_invoice_upd_sts 10856 10856 0 0.00 0 16422 329 0 0
sp_qd_handle_on_reserve_upd_sts 9861 9861 0 0.00 0 12149 51 0 0
sp_reserve_write_off 9861 9861 0 0.00 7 17796 181 0 0
sp_supplier_invoice 5450 5450 0 0.00 6 69469 3581 0 0
sp_supplier_order 5436 5436 0 0.00 5 41717 1305 0 0
srv_aggregate_perf_data 90 89 1 1.11 3273 234096 21958 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 2744 135541 45894 0 0
srv_find_qd_qs_mism 1077458 1077458 0 0.00 0 14521 5 0 0
srv_make_invnt_saldo 546 546 0 0.00 31 31273 1144 0 0
srv_make_money_saldo 833 833 0 0.00 1 2059 29 0 0
srv_recalc_idx_stat 3 3 0 0.00 195025 513556 324244 0 0
t$perf-abend:sp_add_invoice_to_stock 168 168 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4982 4982 0 0.00 0 14216 190 0 0
t$perf-norm:sp_cancel_adding_invoice 417 417 0 0.00 0 4906 121 0 0
t$perf-norm:sp_cancel_client_order 1724 1724 0 0.00 0 1679 12 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 1733 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 678 678 0 0.00 0 905 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 5097 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 381 381 0 0.00 0 732 13 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 24608 1786 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 3067 8 0 0
t$perf-norm:sp_client_order 20906 20906 0 0.00 0 12329 19 0 0
t$perf-norm:sp_customer_reserve 970 970 0 0.00 0 4090 10 0 0
t$perf-norm:sp_pay_from_customer 7316 7316 0 0.00 0 7084 13 0 0
t$perf-norm:sp_pay_to_supplier 5871 5871 0 0.00 0 9301 12 0 0
t$perf-norm:sp_reserve_write_off 9861 9861 0 0.00 0 4399 12 0 0
t$perf-norm:sp_supplier_invoice 5450 5450 0 0.00 0 6650 17 0 0
t$perf-norm:sp_supplier_order 5436 5436 0 0.00 0 10950 16 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 1424 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 10 1 0 0
t$perf-norm:srv_make_invnt_saldo 546 546 0 0.00 0 1355 15 0 0
t$perf-norm:srv_make_money_saldo 833 833 0 0.00 0 1767 14 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 2 18 8 0 0
v_add_invoice_to_stock 10288 9952 336 3.26 0 6099 21 0 336
v_all_customers 38462 38462 0 0.00 0 186 1 0 0
v_all_suppliers 30450 30450 0 0.00 0 142 1 0 0
v_all_wares 41806 41806 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 834 834 0 0.00 0 1677 9 0 0
v_cancel_client_order 3448 3448 0 0.00 0 2886 7 0 0
v_cancel_customer_prepayment 1356 1356 0 0.00 0 3931 11 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 3690 6 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 3142 9 0 0
v_cancel_supplier_invoice 790 762 28 3.54 0 6491 26 0 28
v_cancel_supplier_order 814 814 0 0.00 0 2586 7 0 0
v_cancel_write_off 2036 2036 0 0.00 0 5759 23 0 0
v_max_id_clo_ord 5436 5436 0 0.00 0 8203 4 0 0
v_max_id_clo_res 970 970 0 0.00 0 2472 14 0 0
v_max_id_ord_sup 5441 5441 0 0.00 0 5476 5 0 0
v_max_non_paid_invoice 1523 1523 0 0.00 0 529 3 0 0
v_max_non_paid_realizn 3830 3830 0 0.00 0 5089 7 0 0
v_min_id_clo_ord 5436 5436 0 0.00 0 9494 16 0 0
v_min_id_clo_res 970 970 0 0.00 0 4545 10 0 0
v_min_id_ord_sup 5441 5441 0 0.00 0 5278 8 0 0
v_min_non_paid_invoice 5871 5871 0 0.00 0 10037 31 0 0
v_min_non_paid_realizn 7316 7316 0 0.00 0 10050 29 0 0
v_our_firm 10316 10316 0 0.00 0 20 1 0 0
v_reserve_write_off 19722 19722 0 0.00 0 6578 9 0 0
x_lock_depdocs_on_canc_invoice 11945 11945 0 0.00 0 18146 125 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 15283 419 0 0
x_make_qstorno_1000_1200 5436 5436 0 0.00 2 41592 1217 0 0
x_make_qstorno_1200_2000 5450 5450 0 0.00 3 69436 3436 0 0
x_make_qstorno_2100_3300 460377 460377 0 0.00 0 56790 168 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2021 16:39:13 to 27-09-2021 16:39:17.

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
14:07:57 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:58 34120 8192000000 10393188624 10711777280 1422772016 14.797 100 88 174 1 1456116032 5604624 27739392
14:10:34 18470 8192000000 10439134480 10770477056 1496252912 15.443 100 97 194 1 1535878528 8578432 31047184
14:11:55 15060 8192000000 10488474128 10833293312 1542290960 15.843 100 83 166 2 1577627952 9012032 26324960
14:13:15 9951 8192000000 10558739072 10920771584 1623148608 16.537 100 74 148 0 1654910480 9648368 22113504
14:14:27 11759 8192000000 10650582512 11023474688 1706519344 17.240 100 73 144 2 1738900592 11055872 21325376
14:15:43 5946 8192000000 10731885888 11109523456 1786187472 17.900 100 89 176 1 1824722880 14394320 24141088
14:16:51 1900 8192000000 10769751760 11150159872 1815168976 18.138 100 77 152 3 1849559312 13111296 21279040
14:17:54 1659 8192000000 10801294432 11185344512 1854502336 18.459 100 95 191 3 1896204272 14812432 26889504
14:18:57 2096 8192000000 10811298128 11211915264 1874039856 18.617 100 68 136 0 1906719200 13217616 19461728
14:20:00 29282 8192000000 10824917728 11223080960 1883368192 18.692 100 61 121 3 1906771312 6631376 16771744
14:21:30 4775 8192000000 10799236160 11222138880 1881705520 18.679 100 25 50 0 1896306880 7837312 6764048
14:22:38 7610 8192000000 10797665984 11230969856 1890295616 18.748 100 17 33 2 1901358240 6663232 4399392
14:23:48 6739 8192000000 10810941536 11243077632 1899266992 18.820 100 18 36 2 1910308000 6190448 4850560
14:25:01 46407 8192000000 10820645280 11255459840 1910259008 18.909 100 33 66 1 1925054432 5182080 9613344
14:26:47 9033 8192000000 10821615232 11254480896 1903276048 18.853 100 23 46 1 1915555744 5762928 6516768
14:27:58 5446 8192000000 10769795008 11200774144 1835437360 18.304 98 26 52 1 1848402432 5515920 7449152
14:29:04 9657 8192000000 10751091920 11175399424 1816488608 18.149 98 15 30 0 1826019248 5227664 4302976
14:30:17 24575 8192000000 10702189872 11120787456 1742801040 17.542 96 38 76 1 1760505808 6077088 11627680
14:31:46 3963 8192000000 10713756720 11130257408 1785930640 17.898 99 36 70 3 1806734992 10427840 10376512
14:32:56 7801 8192000000 10736625328 11156807680 1821608160 18.191 100 27 54 1 1835599312 6410832 7580320
14:34:04 11155 8192000000 10753496992 11186028544 1844000800 18.373 100 25 50 2 1857504928 5465152 8038976
14:35:20 23087 8192000000 10760276704 11194298368 1857301952 18.481 100 46 93 2 1875802816 5607216 12893648
14:36:45 6472 8192000000 10781544048 11212075008 1871026704 18.593 100 33 65 1 1887534256 6682576 9824976
14:37:51 19791 8192000000 10841358240 11255615488 1906658992 18.880 100 37 74 0 1923403600 5333632 11410976
14:39:14 12758 8192000000 10870227696 11290656768 1931502432 19.079 100 56 111 0 1957955712 10768384 15684896
14:40:32 66524 8192000000 10870113088 11314085888 1951701632 19.240 100 39 79 1 1973391968 9653120 12037216
14:42:44 36859 8192000000 10899122688 11339362304 1963780256 19.336 100 40 80 2 1994554400 18716496 12057648
14:44:28 10111 8192000000 10908120848 11348295680 1973484848 19.413 100 37 73 1 1998394048 13541360 11367840
14:45:40 9672 8192000000 10919099872 11355361280 1974510416 19.421 100 47 94 1 2008928848 19681696 14736736
14:46:54 19394 8192000000 10955537472 11385573376 1984784928 19.503 99 60 120 0 2025406016 22664688 17956400
14:48:20 10908 8192000000 10959169376 11390898176 2003145872 19.648 100 52 104 0 2043222000 24497312 15578816
14:49:36 7109 8192000000 10971096528 11399274496 2009979680 19.701 100 75 151 0 2062663136 30451600 22231856
14:50:47 7077 8192000000 10976991696 11406225408 2010882080 19.708 100 69 138 1 2065554320 33710896 20961344
14:52:00 9883 8192000000 10972426448 11402047488 1999194592 19.616 100 62 124 1 2056261792 37690576 19376624
14:53:13 5791 8192000000 10972535920 11392086016 1987440896 19.524 100 78 156 2 2050204752 38865712 23898144
14:54:24 10640 8192000000 10954444672 11384606720 1979119024 19.458 100 63 126 0 2043039872 43884816 20036032
14:55:41 4902 8192000000 10956804400 11375525888 1964384816 19.341 100 65 130 1 2037657712 53174960 20097936
14:56:52 2743 8192000000 10938432064 11346522112 1944254688 19.181 99 82 163 0 2019642640 49606096 25781856
14:58:01 26209 8192000000 10925839280 11370631168 1953746976 19.256 100 48 96 3 2023095536 52907152 16441408
14:59:33 4847 8192000000 10948856912 11387445248 1971660352 19.399 100 44 88 0 2032628384 46274240 14693792
15:00:42 7002 8192000000 10932022896 11367194624 1937581296 19.127 98 46 92 1 2000720080 48313296 14825488
15:01:56 27514 8192000000 10967278752 11403452416 1975893424 19.432 100 46 93 0 2050207488 58664672 15649392
15:03:31 15901 8192000000 10940117200 11389071360 1955604400 19.271 99 37 74 1 2026284624 57774352 12905872
15:04:54 13850 8192000000 10967560192 11410108416 1982063184 19.481 100 43 86 1 2054115904 58079856 13972864
15:06:37 27731 8192000000 10921032064 11371966464 1935680640 19.112 99 25 50 1 1993470288 48667856 9121792
15:08:07 33282 8192000000 10930541360 11388329984 1903360112 18.853 98 24 48 1 1981348480 69509472 8478896
15:09:45 132259 8192000000 10867237648 11322413056 1896282080 18.796 97 13 26 2 1912450704 12557488 3611136
15:13:00 47425 8192000000 10907661728 11365507072 1968701088 19.375 100 19 38 0 1990801184 15878864 6221232
15:15:15 69622 8192000000 10909877840 11383439360 1972510448 19.405 100 13 26 0 1999500016 22850880 4138688
15:17:35 78443 8192000000 10928175584 11390377984 1993818768 19.574 100 19 38 1 2012056208 12816896 5420544
15:19:55 86891 8192000000 10936169952 11410092032 2002892992 19.646 100 22 43 3 2027112096 18164784 6054320
15:22:45 84947 8192000000 10903637856 11376332800 1954272064 19.260 98 22 44 2 1970998960 10539376 6187520
15:25:13 71767 8192000000 10914657120 11384242176 1976552000 19.437 99 26 51 1 1992152320 8354400 7245920
15:27:47 22171 8192000000 10930387072 11400802304 2007398000 19.681 100 36 71 1 2028863344 11415456 10049888
15:29:09 59104 8192000000 10926601120 11414036480 2010870576 19.708 100 23 46 2 2031936480 12731424 8334480
15:31:09 58864 8192000000 10928069360 11409731584 2013020080 19.725 100 11 22 0 2026611040 10399136 3191824
15:33:10 75302 8192000000 10917454864 11408310272 1999560464 19.619 100 12 25 0 2019345312 16824192 2960656
15:35:36 54877 8192000000 10894077088 11374919680 1928618800 19.056 97 5 10 0 1936785168 6865120 1301248
15:38:02 63611 8192000000 10899580720 11389255680 1956714720 19.280 98 21 42 1 1973772720 10865264 6192736
15:40:19 58441 8192000000 10889850432 11363418112 1950971936 19.234 98 14 28 1 1962478784 8095616 3411232
15:42:21 40140 8192000000 10901646096 11364913152 1923757200 19.017 97 24 48 2 1939918256 9620016 6541040
15:44:10 36569 8192000000 10912812592 11374915584 1977247376 19.443 100 45 90 1 2002999424 12823568 12928480
15:45:51 63687 8192000000 10911353088 11387109376 1961678624 19.319 99 20 39 0 1983158624 15881168 5598832
15:48:09 76067 8192000000 10858728128 11311157248 1851783696 18.437 95 18 32 2 1870916048 14531584 4600768
15:50:31 135541 8192000000 10862046016 11312676864 1817563824 18.158 93 31 62 0 1848495152 22627984 8303344
15:54:03 42843 8192000000 10842703472 11314221056 1863674560 18.533 97 20 40 1 1888816784 19928832 5213392
15:55:50 53711 8192000000 10837262704 11306487808 1901148352 18.836 99 6 12 0 1909423136 6800736 1474048
15:58:18 68435 8192000000 10848482400 11331989504 1930429296 19.070 100 7 14 0 1943275696 10596560 2249840
16:00:38 52285 8192000000 10845471552 11306205184 1892226608 18.764 99 17 34 0 1906186576 9733424 4226544
16:02:43 23530 8192000000 10821278752 11284213760 1867161408 18.561 97 34 68 0 1884982240 8222960 9597872
16:04:09 77896 8192000000 10870109216 11340189696 1922918928 19.010 99 19 38 1 1943490912 14717824 5854160
16:06:31 67489 8192000000 10894610000 11380953088 1935511808 19.111 99 14 28 1 1956981104 17259712 4209584
16:08:39 47196 8192000000 10911871264 11376967680 1959134608 19.299 99 29 58 3 1980564352 13507744 7922000
16:10:28 40570 8192000000 10941113440 11400368128 1999830192 19.621 100 40 78 0 2025516080 14475456 11210432
16:12:11 79462 8192000000 10901971456 11354062848 1900368800 18.829 96 21 42 0 1923231728 16863792 5999136
16:14:31 80790 8192000000 10903078864 11383472128 1982807744 19.487 100 32 65 0 2002824096 11040624 8975728
16:16:58 22641 8192000000 10924699184 11377184768 1977149808 19.442 99 38 76 1 1994774448 7362464 10262176
16:18:21 61453 8192000000 10907798768 11390246912 1970689216 19.391 99 14 28 0 1985427648 11103792 3634640
16:20:22 50693 8192000000 10911754176 11392606208 1975347776 19.428 99 12 24 0 1988589600 10180512 3061312
16:22:13 71042 8192000000 10876279824 11357970432 1939905392 19.146 98 15 30 2 1955123856 10236656 4981808
16:25:04 50496 8192000000 10877722496 11360903168 1910835536 18.913 97 6 12 0 1922665456 10508720 1321200
16:27:03 16008 8192000000 10903546880 11353460736 1948059712 19.211 99 49 95 1 1970634496 8361664 14213120
16:28:19 70600 8192000000 10883668432 11360477184 1928787392 19.057 98 20 40 0 1943945232 9626384 5531456
16:30:40 41111 8192000000 10909804464 11367534592 1938980096 19.139 98 26 52 0 1960183888 14135904 7067888
16:32:25 43166 8192000000 10904118096 11375157248 1912093168 18.923 97 30 60 2 1938953632 18678064 8182400
16:34:11 102397 8192000000 10848502384 11309662208 1773463952 17.796 91 11 22 1 1786532512 10206944 2861616
16:36:54 67105 8192000000 9171489232 9428414464 333419664 3.910 18 4 8 1 341338352 7052624 866064

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-09-2021 16:39:19 to 27-09-2021 16:39:34.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 168 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2021 16:39:35 to 27-09-2021 16:39:37.

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          241162
MON$OLDEST_ACTIVE               241163
MON$OLDEST_SNAPSHOT             241163
MON$NEXT_TRANSACTION            241163
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                       2037408
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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'

27.09.21 16:39:38. 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-27 16:39:40 No severe PSQL-related problems occured
27.09.21 16:39:41. End of report.