Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.12.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 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16449440 kB
MemAvailable 31468132 kB
Buffers 0 kB
Cached 15194388 kB
SwapCached 0 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-12-05 22:37:56
DTS_END                         2021-12-05 22:37:56.0210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_AGGREGATE_PERF_DATA(83:5) ==> TMP_AGGREGATE_PERF_DATA_AUTOGEN(720:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53728
TRN_ID                          242564
ATT_ID                          725
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: 9282.70 19155 1113924
customer order: creation 176.67 349 21200
customer order: refuse 15.32 332 1838
order to supplier: creation 46.33 1233 5559
order to supplier: removal 3.39 119751 407
invoice (draft): creation 46.31 3296 5557
invoice (draft): removal 53.30 879 6396
invoice accept: apply 42.70 16363 5124
invoice accept: cancel 48.27 851 5792
customer reserve: creation 3869.02 163 464282
customer reserve: removal 4668.13 69 560175
realization accept: apply 85.71 182 10285
realization accept: cancel 94.60 99 11352
payment from customer: creation 61.30 124 7356
payment from customer: removal 5.73 71 687
payment to supplier: creation 49.78 131 5974
payment to supplier: removal 3.31 96 397
service: total inventory turnovers 5.21 1120 625
service: total monetary turnovers 6.73 33 808
service: aggregate perf. data 0.88 19211 106
service: refresh index statistics 0.03 218738 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-12-2021 22:38:38 to 05-12-2021 22:38:40.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2874
TEST_TIME 2 3459
TEST_TIME 3 4170
TEST_TIME 4 5453
TEST_TIME 5 5550
TEST_TIME 6 5386
TEST_TIME 7 5405
TEST_TIME 8 5360
TEST_TIME 9 5742
TEST_TIME 10 6454
TEST_TIME 11 7042
TEST_TIME 12 6962
TEST_TIME 13 7120
TEST_TIME 14 7221
TEST_TIME 15 7733
TEST_TIME 16 7753
TEST_TIME 17 7676
TEST_TIME 18 7915
TEST_TIME 19 7717
TEST_TIME 20 7995
TEST_TIME 21 8075
TEST_TIME 22 8393
TEST_TIME 23 8273
TEST_TIME 24 8401
TEST_TIME 25 8357
TEST_TIME 26 8432
TEST_TIME 27 8937
TEST_TIME 28 9113
TEST_TIME 29 9062
TEST_TIME 30 9487
TEST_TIME 31 10909
TEST_TIME 32 11163
TEST_TIME 33 11075
TEST_TIME 34 11067
TEST_TIME 35 10888
TEST_TIME 36 10840
TEST_TIME 37 10665
TEST_TIME 38 10564
TEST_TIME 39 10508
TEST_TIME 40 10315
TEST_TIME 41 10255
TEST_TIME 42 10166
TEST_TIME 43 10371
TEST_TIME 44 10217
TEST_TIME 45 10185
TEST_TIME 46 10061
TEST_TIME 47 9976
TEST_TIME 48 9873
TEST_TIME 49 9820
TEST_TIME 50 9824
TEST_TIME 51 9826
TEST_TIME 52 9757
TEST_TIME 53 9705
TEST_TIME 54 9589
TEST_TIME 55 9494
TEST_TIME 56 9413
TEST_TIME 57 9388
TEST_TIME 58 9361
TEST_TIME 59 9509
TEST_TIME 60 9479
TEST_TIME 61 9402
TEST_TIME 62 9477
TEST_TIME 63 9467
TEST_TIME 64 9490
TEST_TIME 65 9473
TEST_TIME 66 9510
TEST_TIME 67 9530
TEST_TIME 68 9569
TEST_TIME 69 9541
TEST_TIME 70 9593
TEST_TIME 71 9551
TEST_TIME 72 9582
TEST_TIME 73 9579
TEST_TIME 74 9635
TEST_TIME 75 9568
TEST_TIME 76 9452
TEST_TIME 77 9477
TEST_TIME 78 9491
TEST_TIME 79 9407
TEST_TIME 80 9441
TEST_TIME 81 9391
TEST_TIME 82 9339
TEST_TIME 83 9294
TEST_TIME 84 9300
TEST_TIME 85 9244
TEST_TIME 86 9296
TEST_TIME 87 9254
TEST_TIME 88 9209
TEST_TIME 89 9286
TEST_TIME 90 9258
TEST_TIME 91 9292
TEST_TIME 92 9366
TEST_TIME 93 9361
TEST_TIME 94 9378
TEST_TIME 95 9354
TEST_TIME 96 9418
TEST_TIME 97 9408
TEST_TIME 98 9389
TEST_TIME 99 9417
TEST_TIME 100 9422
TEST_TIME 101 9429
TEST_TIME 102 9465
TEST_TIME 103 9387
TEST_TIME 104 9378
TEST_TIME 105 9318
TEST_TIME 106 9339
TEST_TIME 107 9323
TEST_TIME 108 9268
TEST_TIME 109 9256
TEST_TIME 110 9233
TEST_TIME 111 9195
TEST_TIME 112 9148
TEST_TIME 113 9174
TEST_TIME 114 9205
TEST_TIME 115 9174
TEST_TIME 116 9172
TEST_TIME 117 9206
TEST_TIME 118 9259
TEST_TIME 119 9280
TEST_TIME 120 9293

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-12-2021 22:38:42 to 05-12-2021 22:38:45.

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 1543537 1543537 0 0.00 0 15594 6 0 0
doc_list_biud 1543535 1543535 0 0.00 0 46617 31 0 0
sp_add_invoice_to_stock 5241 5124 117 2.23 55 207655 16363 0 117
sp_cancel_adding_invoice 5792 5792 0 0.00 5 53207 851 0 0
sp_cancel_client_order 1838 1838 0 0.00 4 22097 333 0 0
sp_cancel_customer_reserve 560175 560175 0 0.00 1 24250 69 0 0
sp_cancel_pay_from_customer 687 687 0 0.00 2 5989 71 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 2 4733 97 0 0
sp_cancel_supplier_invoice 6403 6396 7 0.10 1 15542 880 0 7
sp_cancel_supplier_order 407 407 0 0.00 7 1230073 119751 0 0
sp_cancel_write_off 11352 11352 0 0.00 1 8879 100 0 0
sp_client_order 21200 21200 0 0.00 4 16195 349 0 0
sp_customer_reserve 464282 464282 0 0.00 0 63929 163 0 0
sp_fill_shopping_cart 32311 32311 0 0.00 1 33086 41 0 0
sp_fill_shopping_cart_clo_res 464272 464272 0 0.00 0 10851 12 0 0
sp_get_clo_for_invoice 5109 5109 0 0.00 0 17574 298 0 0
sp_kill_cost_storno 18228 18228 0 0.00 0 15318 56 0 0
sp_kill_qstorno_ret_qs2qd 1138505 1138505 0 0.00 0 19519 37 0 0
sp_kill_qty_storno 601354 601354 0 0.00 0 46616 79 0 0
sp_lock_selected_doc 21290 21290 0 0.00 0 8361 6 0 0
sp_make_cost_storno 28724 28724 0 0.00 0 13885 52 0 0
sp_multiply_rows_for_pdistr 28724 28724 0 0.00 0 15573 33 0 0
sp_multiply_rows_for_qdistr 21200 21200 0 0.00 0 16181 163 0 0
sp_pay_from_customer 7356 7356 0 0.00 4 15599 125 0 0
sp_pay_to_supplier 5974 5974 0 0.00 4 13908 131 0 0
sp_payment_common 13330 13330 0 0.00 1 11939 22 0 0
sp_qd_handle_on_cancel_clo 1838 1838 0 0.00 0 22090 303 0 0
sp_qd_handle_on_invoice_upd_sts 10901 10901 0 0.00 0 46615 337 0 0
sp_qd_handle_on_reserve_upd_sts 10285 10285 0 0.00 0 16667 66 0 0
sp_reserve_write_off 10285 10285 0 0.00 6 17304 182 0 0
sp_supplier_invoice 5557 5557 0 0.00 6 87712 3297 0 0
sp_supplier_order 5559 5559 0 0.00 5 30168 1233 0 0
srv_aggregate_perf_data 107 106 1 0.93 3019 191119 19212 0 1
srv_fill_mon_cache_memory 61 61 0 0.00 1236 149197 48077 0 0
srv_find_qd_qs_mism 1065241 1065241 0 0.00 0 10295 4 0 0
srv_make_invnt_saldo 625 625 0 0.00 29 73462 1121 0 0
srv_make_money_saldo 808 808 0 0.00 0 3690 33 0 0
srv_recalc_idx_stat 4 4 0 0.00 86733 353736 218738 0 0
t$perf-abend:sp_add_invoice_to_stock 117 117 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 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 5124 5124 0 0.00 0 25958 187 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5508 89 0 0
t$perf-norm:sp_cancel_client_order 1838 1838 0 0.00 0 2532 12 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4017 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 687 687 0 0.00 0 5258 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 940 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 429 429 0 0.00 0 738 12 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 23130 1851 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 2237 15 0 0
t$perf-norm:sp_client_order 21200 21200 0 0.00 0 13245 20 0 0
t$perf-norm:sp_customer_reserve 1025 1025 0 0.00 0 1060 6 0 0
t$perf-norm:sp_pay_from_customer 7356 7356 0 0.00 0 13441 10 0 0
t$perf-norm:sp_pay_to_supplier 5974 5974 0 0.00 0 4500 8 0 0
t$perf-norm:sp_reserve_write_off 10285 10285 0 0.00 0 16872 11 0 0
t$perf-norm:sp_supplier_invoice 5557 5557 0 0.00 0 6808 18 0 0
t$perf-norm:sp_supplier_order 5559 5559 0 0.00 0 5708 15 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 576 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 2487 46 0 0
t$perf-norm:srv_make_invnt_saldo 625 625 0 0.00 0 821 17 0 0
t$perf-norm:srv_make_money_saldo 808 808 0 0.00 0 2771 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10450 10216 234 2.23 0 7564 23 0 234
v_all_customers 38776 38776 0 0.00 0 17 1 0 0
v_all_suppliers 30866 30866 0 0.00 0 16 1 0 0
v_all_wares 42400 42400 0 0.00 0 80 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 151 5 0 0
v_cancel_client_order 3676 3676 0 0.00 0 3878 8 0 0
v_cancel_customer_prepayment 1374 1374 0 0.00 0 2669 9 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 278 4 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 2834 9 0 0
v_cancel_supplier_invoice 872 858 14 1.60 0 3969 21 0 14
v_cancel_supplier_order 814 814 0 0.00 0 1743 7 0 0
v_cancel_write_off 2054 2054 0 0.00 0 1650 12 0 0
v_max_id_clo_ord 5558 5558 0 0.00 0 225 1 0 0
v_max_id_clo_res 1025 1025 0 0.00 0 1571 14 0 0
v_max_id_ord_sup 5551 5551 0 0.00 0 5535 6 0 0
v_max_non_paid_invoice 1650 1650 0 0.00 0 2481 4 0 0
v_max_non_paid_realizn 3899 3899 0 0.00 0 4230 8 0 0
v_min_id_clo_ord 5558 5558 0 0.00 0 2680 5 0 0
v_min_id_clo_res 1025 1025 0 0.00 0 2295 9 0 0
v_min_id_ord_sup 5551 5551 0 0.00 0 5692 6 0 0
v_min_non_paid_invoice 5974 5974 0 0.00 0 11933 15 0 0
v_min_non_paid_realizn 7356 7356 0 0.00 0 9388 15 0 0
v_our_firm 10538 10538 0 0.00 0 16 1 0 0
v_reserve_write_off 20570 20570 0 0.00 0 3955 7 0 0
x_lock_depdocs_on_canc_invoice 11759 11759 0 0.00 0 17295 130 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 13829 423 0 0
x_make_qstorno_1000_1200 5559 5559 0 0.00 1 30161 1150 0 0
x_make_qstorno_1200_2000 5557 5557 0 0.00 3 82189 3140 0 0
x_make_qstorno_2100_3300 464282 464282 0 0.00 0 63892 149 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-12-2021 22:38:46 to 05-12-2021 22:38:50.

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:59 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:00 18537 8192000000 9891506368 10202918912 973882928 10.625 100 87 174 0 1002488384 5813600 22791856
20:10:21 26827 8192000000 9949077104 10273398784 1030339184 11.172 100 90 180 0 1061697520 8456896 22901440
20:11:54 22311 8192000000 9977696448 10310455296 1056130544 11.419 100 88 176 0 1088433472 10353744 21949184
20:13:20 10551 8192000000 10042070304 10391699456 1124210416 12.067 100 78 156 0 1153258016 10910112 18137488
20:14:38 9310 8192000000 10096686496 10459340800 1179946800 12.590 100 82 164 3 1211342336 12994752 18400784
20:15:51 3820 8192000000 10123810720 10496446464 1217006832 12.934 100 78 155 1 1246871728 12649200 17215696
20:16:57 3726 8192000000 10159875712 10539261952 1248988272 13.229 100 74 146 1 1277002432 12195488 15818672
20:18:04 1845 8192000000 10193283152 10578780160 1280927472 13.521 100 86 172 1 1314108816 14559088 18622256
20:19:06 16456 8192000000 10200521456 10596511744 1298215168 13.679 100 66 131 0 1324407504 10324896 15867440
20:20:24 4070 8192000000 10208883296 10616250368 1314580096 13.828 100 41 82 1 1331555680 8009232 8966352
20:21:29 13074 8192000000 10211972800 10628075520 1323875424 13.912 100 18 36 1 1333876816 6358384 3643008
20:22:46 55123 8192000000 10219224096 10642714624 1329377968 13.962 100 43 84 2 1345461392 5427328 10656096
20:24:44 3684 8192000000 10237388800 10652831744 1337750576 14.037 100 49 98 0 1358002400 9445312 10806512
20:25:50 11314 8192000000 10226268864 10650804224 1338262544 14.042 100 16 30 1 1347375152 5694656 3417952
20:27:04 59477 8192000000 10218931360 10632691712 1297423824 13.672 97 35 70 1 1310733872 5303440 8006608
20:29:04 6420 8192000000 10201854256 10616729600 1296216112 13.661 99 17 34 1 1305373200 5284080 3873008
20:30:12 7199 8192000000 10148876768 10548363264 1222217888 12.982 95 16 32 1 1231364224 5197456 3948880
20:31:20 14043 8192000000 10116935376 10508038144 1146168032 12.274 92 24 48 1 1157080768 4847472 6065264
20:32:34 16703 8192000000 10123938816 10523103232 1203661728 12.810 96 25 50 2 1214785936 5379872 5744336
20:33:52 7824 8192000000 10174837008 10581929984 1279891248 13.512 100 25 50 0 1292429168 6716544 5821376
20:35:01 8130 8192000000 10187436528 10600140800 1299407968 13.690 100 24 47 1 1310638000 5382368 5847664
20:36:10 62663 8192000000 10209435360 10622550016 1312364064 13.808 100 29 56 2 1325290480 5080880 7845536
20:38:17 8201 8192000000 10242433456 10652291072 1337610576 14.036 100 49 95 1 1354361984 6296640 10454768
20:39:30 42570 8192000000 10259214240 10678775808 1357917904 14.219 100 45 89 1 1379634272 10960528 10755840
20:41:19 19578 8192000000 10289404064 10709827584 1374051424 14.363 100 50 99 3 1399800224 14905552 10843248
20:42:41 19360 8192000000 10292826496 10715029504 1383520096 14.448 100 52 102 2 1411689408 16354896 11814416
20:44:12 17507 8192000000 10304564288 10734178304 1394665376 14.547 100 40 80 2 1424593216 20463856 9463984
20:45:34 6634 8192000000 10313194592 10732314624 1387529104 14.484 99 61 119 1 1420463712 19199824 13734784
20:46:48 2407 8192000000 10340701472 10753404928 1402060048 14.613 100 77 152 2 1444496576 24696864 17739664
20:47:52 1235 8192000000 10328596336 10736926720 1380979312 14.425 98 86 172 1 1428231376 26939392 20312672
20:48:57 6331 8192000000 10333007440 10752053248 1396671120 14.565 100 76 152 2 1441146320 26252896 18222304
20:50:07 4476 8192000000 10327342512 10741899264 1385066208 14.462 99 63 126 0 1433284672 31202464 17016000
20:51:15 4192 8192000000 10348134784 10770911232 1403422832 14.625 100 63 126 2 1452423728 32893776 16107120
20:52:23 3011 8192000000 10317044704 10726404096 1358329840 14.222 99 87 172 1 1415191856 35537728 21324288
20:53:35 7886 8192000000 10297399232 10712264704 1348201696 14.131 99 57 113 1 1399235664 36710352 14323616
20:55:07 14917 8192000000 10329960432 10745860096 1354886208 14.191 98 48 96 2 1415546944 47596256 13064480
20:56:27 9477 8192000000 10321382160 10734161920 1360939808 14.246 99 60 119 0 1423643184 47479120 15224256
20:57:50 14138 8192000000 10337825872 10766295040 1381960464 14.434 100 44 87 1 1447787648 52988224 12838960
20:59:12 3411 8192000000 10360148384 10765082624 1386624944 14.476 100 85 171 3 1457887536 49316096 21946496
21:00:20 5064 8192000000 10370796800 10790199296 1407799504 14.664 100 63 126 3 1478158992 53967200 16392288
21:01:30 8981 8192000000 10374830848 10800218112 1409898032 14.683 100 50 98 0 1482899344 59234560 13766752
21:02:46 1347 8192000000 10376907648 10793312256 1398872240 14.585 100 68 135 1 1479214704 61302384 19040080
21:03:51 17821 8192000000 10390134096 10815635456 1406780144 14.655 100 41 81 1 1491315616 72172896 12362576
21:05:35 16968 8192000000 10351470736 10788487168 1391595968 14.520 100 23 46 2 1460417472 61437360 7384144
21:07:05 22431 8192000000 10337671520 10771701760 1350864832 14.155 100 27 54 1 1448650192 89058896 8726464
21:08:43 29620 8192000000 10258408704 10707939328 1340484448 14.062 99 16 32 0 1383111456 38873536 3753472
21:10:13 57109 8192000000 10268059648 10713251840 1345493872 14.107 99 40 80 1 1369586496 14603824 9488800
21:12:34 76595 8192000000 10275803360 10727088128 1352200896 14.167 98 22 42 2 1365889696 8764400 4924400
21:14:50 90394 8192000000 10299711200 10745462784 1382249248 14.437 100 33 65 4 1401237872 11505360 7483264
21:17:23 68871 8192000000 10287408752 10748497920 1383991408 14.452 100 12 24 0 1401777472 14960736 2825328
21:19:36 90541 8192000000 10287635120 10760138752 1375488672 14.376 99 14 28 0 1400932992 21953584 3490736
21:22:06 75979 8192000000 10295742592 10763706368 1396845488 14.567 100 15 30 0 1412520368 11898320 3776560
21:25:09 71284 8192000000 10295413648 10760364032 1402469456 14.617 100 13 26 0 1413909888 8322864 3117568
21:28:01 52537 8192000000 10305111680 10772217856 1403242608 14.624 100 34 67 1 1419097440 8128640 7726192
21:30:36 51946 8192000000 10306800064 10776166400 1406628240 14.654 100 8 16 1 1423000544 14639168 1733136
21:32:28 54284 8192000000 10318435360 10783215616 1413507424 14.715 100 19 38 0 1436052752 17506160 5039168
21:34:33 101891 8192000000 10296467344 10780008448 1385761904 14.468 98 19 38 0 1411972768 21654336 4556528
21:37:21 73254 8192000000 10295051568 10752200704 1330026192 13.967 94 16 32 0 1340863600 7724848 3112560
21:40:01 91556 8192000000 10288486144 10751033344 1304112000 13.733 93 15 30 2 1324511200 17149632 3249568
21:42:32 29671 8192000000 10306599728 10748276736 1363784208 14.271 98 32 65 0 1381956560 11039904 7132448
21:44:03 126142 8192000000 10273275888 10732621824 1270832416 13.429 92 23 46 1 1286308752 9983760 5492576
21:47:10 82681 8192000000 10284410000 10735640576 1338831072 14.047 97 15 30 0 1353771648 11679968 3260608
21:49:33 66546 8192000000 10286426464 10734067712 1362177504 14.257 99 16 32 3 1386428048 20587408 3663136
21:51:39 96673 8192000000 10269581248 10722893824 1344413248 14.097 98 17 34 2 1361060448 13195072 3452128
21:54:33 105714 8192000000 10255556864 10686025728 1297851552 13.676 95 13 26 0 1308764720 8415520 2497648
21:57:21 59599 8192000000 10230358240 10678960128 1314606640 13.828 97 19 38 0 1329770832 10639856 4524336
21:59:24 53159 8192000000 10270615120 10721013760 1364780576 14.280 100 11 22 0 1376964000 9641376 2542048
22:01:24 64962 8192000000 10261397920 10723237888 1354639984 14.189 99 10 20 4 1376824288 19292992 2891312
22:03:47 81700 8192000000 10262879616 10716475392 1350826384 14.155 99 12 22 0 1362946576 10294864 1825328
22:06:09 76465 8192000000 10274129360 10719436800 1349600688 14.144 98 20 40 1 1369194864 14526816 5067360
22:08:26 49678 8192000000 10280074736 10729443328 1358404816 14.223 98 19 38 3 1377128752 14255600 4468336
22:10:19 42335 8192000000 10287684000 10735902720 1369538720 14.323 99 25 50 1 1383814896 8972864 5303312
22:12:03 56808 8192000000 10307721968 10756464640 1394439264 14.545 100 28 56 0 1413955728 13083792 6432672
22:14:00 56479 8192000000 10311276368 10751741952 1372812736 14.352 98 26 52 0 1388069808 9656608 5600464
22:16:01 70801 8192000000 10304316032 10748248064 1372315936 14.348 98 22 44 1 1387111232 10086288 4709008
22:18:12 149197 8192000000 10255395360 10718244864 1233264256 13.084 88 6 12 0 1247748608 12831808 1652544
22:22:30 41264 8192000000 10254959024 10699972608 1330401424 13.971 98 24 48 0 1347188752 10744144 6043184
22:24:14 42649 8192000000 10241194896 10698866688 1343019136 14.085 99 9 18 0 1357642304 12682400 1940768
22:27:47 81821 8192000000 10261530976 10721075200 1348476928 14.134 99 10 20 0 1360139072 9541088 2121056
22:30:09 55219 8192000000 10251777280 10710564864 1322542352 13.900 97 12 24 0 1338832592 13592992 2697248
22:32:18 58914 8192000000 10282697152 10732945408 1372745008 14.352 100 22 44 0 1391959264 13925712 5288544
22:34:19 79497 8192000000 10280595440 10737446912 1345067232 14.103 98 8 16 1 1356875120 9841472 1966416
22:36:55 60868 8192000000 10214480656 10662891520 1274948800 13.467 93 15 30 1 1290117696 11784160 3384736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 05-12-2021 22:38:52 to 05-12-2021 22:39:06.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 117 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-12-2021 22:39:07 to 05-12-2021 22:39:09.

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

05.12.21 22:39:10. 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-12-05 22:39:13 No severe PSQL-related problems occured
05.12.21 22:39:14. End of report.