Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.07.2022 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 15868156 kB
MemAvailable 31015164 kB
Buffers 0 kB
Cached 15828340 kB
SwapCached 2516 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.11
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 2022-07-27 22:38:26
DTS_END                         2022-07-27 22:38:26.2150
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/34364
TRN_ID                          238472
ATT_ID                          640
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: 9520.03 18345 1142403
customer order: creation 173.20 359 20784
customer order: refuse 14.63 367 1755
order to supplier: creation 46.03 1261 5523
order to supplier: removal 3.60 99767 432
invoice (draft): creation 45.83 3113 5499
invoice (draft): removal 58.21 731 6985
invoice accept: apply 40.45 17513 4854
invoice accept: cancel 52.01 759 6241
customer reserve: creation 3721.28 173 446553
customer reserve: removal 5058.36 55 607003
realization accept: apply 82.84 205 9941
realization accept: cancel 92.67 126 11120
payment from customer: creation 60.51 135 7261
payment from customer: removal 5.87 120 704
payment to supplier: creation 49.31 127 5917
payment to supplier: removal 3.59 85 431
service: total inventory turnovers 4.72 954 566
service: total monetary turnovers 6.20 46 744
service: aggregate perf. data 0.72 25248 86
service: refresh index statistics 0.03 215757 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-07-2022 22:39:12 to 27-07-2022 22:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3125
TEST_TIME 2 3957
TEST_TIME 3 3988
TEST_TIME 4 5010
TEST_TIME 5 5153
TEST_TIME 6 5492
TEST_TIME 7 5566
TEST_TIME 8 6164
TEST_TIME 9 6281
TEST_TIME 10 6710
TEST_TIME 11 6701
TEST_TIME 12 6613
TEST_TIME 13 7000
TEST_TIME 14 7145
TEST_TIME 15 7492
TEST_TIME 16 7349
TEST_TIME 17 7373
TEST_TIME 18 7373
TEST_TIME 19 7396
TEST_TIME 20 7459
TEST_TIME 21 7557
TEST_TIME 22 7893
TEST_TIME 23 7982
TEST_TIME 24 8127
TEST_TIME 25 8290
TEST_TIME 26 8424
TEST_TIME 27 8906
TEST_TIME 28 9309
TEST_TIME 29 9821
TEST_TIME 30 10082
TEST_TIME 31 10090
TEST_TIME 32 10077
TEST_TIME 33 9891
TEST_TIME 34 9934
TEST_TIME 35 10098
TEST_TIME 36 9973
TEST_TIME 37 10252
TEST_TIME 38 9994
TEST_TIME 39 9949
TEST_TIME 40 9976
TEST_TIME 41 9827
TEST_TIME 42 9740
TEST_TIME 43 9751
TEST_TIME 44 9687
TEST_TIME 45 9749
TEST_TIME 46 9778
TEST_TIME 47 9735
TEST_TIME 48 9610
TEST_TIME 49 9597
TEST_TIME 50 9561
TEST_TIME 51 9479
TEST_TIME 52 9588
TEST_TIME 53 9519
TEST_TIME 54 9476
TEST_TIME 55 9381
TEST_TIME 56 9413
TEST_TIME 57 9383
TEST_TIME 58 9341
TEST_TIME 59 9369
TEST_TIME 60 9337
TEST_TIME 61 9380
TEST_TIME 62 9545
TEST_TIME 63 9587
TEST_TIME 64 9611
TEST_TIME 65 9536
TEST_TIME 66 9694
TEST_TIME 67 9718
TEST_TIME 68 9769
TEST_TIME 69 9769
TEST_TIME 70 9766
TEST_TIME 71 9805
TEST_TIME 72 9751
TEST_TIME 73 9645
TEST_TIME 74 9619
TEST_TIME 75 9526
TEST_TIME 76 9621
TEST_TIME 77 9589
TEST_TIME 78 9524
TEST_TIME 79 9477
TEST_TIME 80 9444
TEST_TIME 81 9373
TEST_TIME 82 9305
TEST_TIME 83 9412
TEST_TIME 84 9368
TEST_TIME 85 9344
TEST_TIME 86 9376
TEST_TIME 87 9455
TEST_TIME 88 9451
TEST_TIME 89 9521
TEST_TIME 90 9542
TEST_TIME 91 9594
TEST_TIME 92 9610
TEST_TIME 93 9648
TEST_TIME 94 9651
TEST_TIME 95 9660
TEST_TIME 96 9609
TEST_TIME 97 9594
TEST_TIME 98 9529
TEST_TIME 99 9540
TEST_TIME 100 9552
TEST_TIME 101 9547
TEST_TIME 102 9492
TEST_TIME 103 9441
TEST_TIME 104 9451
TEST_TIME 105 9385
TEST_TIME 106 9418
TEST_TIME 107 9413
TEST_TIME 108 9396
TEST_TIME 109 9420
TEST_TIME 110 9388
TEST_TIME 111 9376
TEST_TIME 112 9464
TEST_TIME 113 9459
TEST_TIME 114 9447
TEST_TIME 115 9514
TEST_TIME 116 9553
TEST_TIME 117 9537
TEST_TIME 118 9513
TEST_TIME 119 9566
TEST_TIME 120 9520

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-07-2022 22:39:16 to 27-07-2022 22:39:19.

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 1558521 1558521 0 0.00 0 33419 6 0 0
doc_list_biud 1558521 1558521 0 0.00 0 21451 27 0 0
sp_add_invoice_to_stock 5005 4854 151 3.01 27 187280 17513 0 151
sp_cancel_adding_invoice 6241 6241 0 0.00 4 86318 759 0 0
sp_cancel_client_order 1755 1755 0 0.00 4 10390 368 0 0
sp_cancel_customer_reserve 607003 607003 0 0.00 0 21454 56 0 0
sp_cancel_pay_from_customer 704 704 0 0.00 2 13912 120 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 8866 85 0 0
sp_cancel_supplier_invoice 6992 6985 7 0.10 1 20430 732 0 7
sp_cancel_supplier_order 432 432 0 0.00 4 887581 99768 0 0
sp_cancel_write_off 11120 11120 0 0.00 1 19809 126 0 0
sp_client_order 20784 20784 0 0.00 3 58898 359 0 0
sp_customer_reserve 446553 446553 0 0.00 0 120844 174 0 0
sp_fill_shopping_cart 31802 31802 0 0.00 1 9755 33 0 0
sp_fill_shopping_cart_clo_res 446553 446553 0 0.00 0 17116 13 0 0
sp_get_clo_for_invoice 4854 4854 0 0.00 0 20338 309 0 0
sp_kill_cost_storno 18496 18496 0 0.00 0 16120 60 0 0
sp_kill_qstorno_ret_qs2qd 1232543 1232543 0 0.00 0 21413 30 0 0
sp_kill_qty_storno 648331 648331 0 0.00 0 21451 65 0 0
sp_lock_selected_doc 20586 20586 0 0.00 0 10135 6 0 0
sp_make_cost_storno 27973 27973 0 0.00 0 33315 70 0 0
sp_multiply_rows_for_pdistr 27973 27973 0 0.00 0 11957 37 0 0
sp_multiply_rows_for_qdistr 20784 20784 0 0.00 0 19056 163 0 0
sp_pay_from_customer 7261 7261 0 0.00 4 32862 136 0 0
sp_pay_to_supplier 5917 5917 0 0.00 4 33711 128 0 0
sp_payment_common 13178 13178 0 0.00 1 32596 34 0 0
sp_qd_handle_on_cancel_clo 1755 1755 0 0.00 0 10352 347 0 0
sp_qd_handle_on_invoice_upd_sts 11095 11095 0 0.00 0 18563 311 0 0
sp_qd_handle_on_reserve_upd_sts 9941 9941 0 0.00 0 15897 56 0 0
sp_reserve_write_off 9941 9941 0 0.00 6 33425 205 0 0
sp_supplier_invoice 5499 5499 0 0.00 6 76511 3114 0 0
sp_supplier_order 5523 5523 0 0.00 5 49497 1261 0 0
srv_aggregate_perf_data 87 86 1 1.14 2435 293995 25249 0 1
srv_fill_mon_cache_memory 65 65 0 0.00 1072 123941 41025 0 0
srv_find_qd_qs_mism 1096271 1096271 0 0.00 0 19731 5 0 0
srv_make_invnt_saldo 566 566 0 0.00 37 30331 955 0 0
srv_make_money_saldo 744 744 0 0.00 1 11310 47 0 0
srv_recalc_idx_stat 4 4 0 0.00 136493 257999 215757 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 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 4854 4854 0 0.00 0 10364 192 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 9356 144 0 0
t$perf-norm:sp_cancel_client_order 1755 1755 0 0.00 0 4631 19 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 2278 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 704 704 0 0.00 0 2636 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 596 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 411 411 0 0.00 0 1151 20 0 0
t$perf-norm:sp_cancel_supplier_order 432 432 0 0.00 0 21313 1779 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 3960 16 0 0
t$perf-norm:sp_client_order 20784 20784 0 0.00 0 9836 19 0 0
t$perf-norm:sp_customer_reserve 968 968 0 0.00 0 2021 8 0 0
t$perf-norm:sp_pay_from_customer 7261 7261 0 0.00 0 8096 11 0 0
t$perf-norm:sp_pay_to_supplier 5917 5917 0 0.00 0 7779 13 0 0
t$perf-norm:sp_reserve_write_off 9941 9941 0 0.00 0 4964 13 0 0
t$perf-norm:sp_supplier_invoice 5499 5499 0 0.00 0 5750 21 0 0
t$perf-norm:sp_supplier_order 5523 5523 0 0.00 0 9955 21 0 0
t$perf-norm:srv_aggregate_perf_data 86 86 0 0.00 0 419 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 3982 65 0 0
t$perf-norm:srv_make_invnt_saldo 566 566 0 0.00 0 793 21 0 0
t$perf-norm:srv_make_money_saldo 744 744 0 0.00 0 4580 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 158 42 0 0
v_add_invoice_to_stock 10008 9706 302 3.01 0 4423 19 0 302
v_all_customers 38108 38108 0 0.00 0 135 1 0 0
v_all_suppliers 30466 30466 0 0.00 0 26 1 0 0
v_all_wares 41568 41568 0 0.00 0 136 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 4001 14 0 0
v_cancel_client_order 3510 3510 0 0.00 0 3955 4 0 0
v_cancel_customer_prepayment 1408 1408 0 0.00 0 2999 9 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 1460 5 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 731 5 0 0
v_cancel_supplier_invoice 836 822 14 1.67 0 1573 17 0 14
v_cancel_supplier_order 864 864 0 0.00 0 2526 10 0 0
v_cancel_write_off 1970 1970 0 0.00 0 7171 17 0 0
v_max_id_clo_ord 5523 5523 0 0.00 0 435 1 0 0
v_max_id_clo_res 968 968 0 0.00 0 2024 16 0 0
v_max_id_ord_sup 5495 5495 0 0.00 0 5577 5 0 0
v_max_non_paid_invoice 1702 1702 0 0.00 0 4079 6 0 0
v_max_non_paid_realizn 3728 3728 0 0.00 0 14045 11 0 0
v_min_id_clo_ord 5523 5523 0 0.00 0 3261 5 0 0
v_min_id_clo_res 968 968 0 0.00 0 1535 3 0 0
v_min_id_ord_sup 5495 5495 0 0.00 0 6865 7 0 0
v_min_non_paid_invoice 5917 5917 0 0.00 0 11624 18 0 0
v_min_non_paid_realizn 7261 7261 0 0.00 0 11625 26 0 0
v_our_firm 10526 10526 0 0.00 0 23 1 0 0
v_reserve_write_off 19882 19882 0 0.00 0 7988 7 0 0
x_lock_depdocs_on_canc_invoice 12815 12815 0 0.00 0 12854 105 0 0
x_lock_depdocs_on_canc_sup_ord 432 432 0 0.00 0 18626 351 0 0
x_make_qstorno_1000_1200 5523 5523 0 0.00 1 49488 1190 0 0
x_make_qstorno_1200_2000 5499 5499 0 0.00 3 71355 2998 0 0
x_make_qstorno_2100_3300 446553 446553 0 0.00 0 120840 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-07-2022 22:39:20 to 27-07-2022 22:39:24.

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:08:29 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:30 19496 8192000000 9897419728 10207334400 981650032 10.700 100 87 174 0 1010647072 6112896 22884144
20:10:58 14495 8192000000 9932940336 10254127104 1016508624 11.038 100 82 164 0 1045777792 8205088 21064080
20:12:16 13744 8192000000 9969465232 10298363904 1052163120 11.381 100 84 166 0 1082453536 9725712 20564704
20:13:33 6941 8192000000 9997860144 10341228544 1092575424 11.767 100 75 151 0 1121210752 10713424 17921904
20:14:43 11635 8192000000 10053454032 10410143744 1137900000 12.196 100 78 155 1 1167796448 11544272 18352176
20:16:00 3441 8192000000 10109651152 10474258432 1200911072 12.785 100 82 164 0 1232555856 13817488 17827296
20:17:06 2374 8192000000 10155763424 10527825920 1239851520 13.145 100 89 174 1 1272128080 13994848 18281712
20:18:11 2059 8192000000 10182921824 10563829760 1270927968 13.430 100 83 166 1 1301300688 12584768 17787952
20:19:15 1510 8192000000 10211778528 10594791424 1294817152 13.648 100 87 175 1 1328091888 14618816 18655920
20:20:17 43431 8192000000 10212892304 10615328768 1314274320 13.825 100 49 98 0 1334323568 8147648 11901600
20:22:01 8598 8192000000 10205797600 10623184896 1320555920 13.882 100 18 37 1 1331344304 6550112 4238272
20:23:13 14722 8192000000 10211220272 10634514432 1330345856 13.970 100 13 28 0 1339087888 5835056 2906976
20:24:32 46828 8192000000 10222248784 10648436736 1333978080 14.003 100 29 53 1 1346273696 5313072 6982544
20:26:19 5707 8192000000 10229797232 10656083968 1340927792 14.066 100 24 48 0 1352573952 6304528 5341632
20:27:27 9709 8192000000 10220682288 10640678912 1320781408 13.884 99 14 28 0 1329547120 5553344 3212368
20:28:39 57887 8192000000 10217708208 10631421952 1309102736 13.778 99 29 57 0 1321665840 6048160 6514944
20:30:42 6978 8192000000 10163709584 10575929344 1273135520 13.450 99 21 42 0 1283792080 6042736 4613824
20:31:50 6010 8192000000 10158981488 10560266240 1245966000 13.201 98 25 50 0 1257351344 5323568 6061776
20:32:58 9285 8192000000 10130225056 10528219136 1205512848 12.827 96 14 25 0 1212863696 4986512 2364336
20:34:08 47772 8192000000 10178971024 10570481664 1255152480 13.286 99 55 108 0 1278520912 11230192 12138240
20:35:56 7231 8192000000 10185097840 10600198144 1295861344 13.658 100 22 43 1 1306308016 5864144 4582528
20:37:03 10174 8192000000 10199466576 10617028608 1311326144 13.798 100 16 32 0 1320755936 5317184 4112608
20:38:14 10687 8192000000 10221160528 10641489920 1333421216 13.998 100 22 46 0 1343633264 5319184 4892864
20:39:28 22820 8192000000 10253132944 10667532288 1348312416 14.132 100 48 93 2 1365331456 6403552 10615488
20:40:59 29902 8192000000 10267763568 10693644288 1370357696 14.330 100 29 58 0 1385243152 8303648 6581808
20:42:43 21409 8192000000 10286178864 10710237184 1380079024 14.417 100 49 96 1 1404119824 12573072 11467728
20:44:25 13514 8192000000 10313406400 10734161920 1394670880 14.548 100 53 106 1 1420657424 14050960 11935584
20:45:47 12543 8192000000 10315859008 10741620736 1400903696 14.603 100 49 98 1 1427213648 14466352 11843600
20:47:05 8197 8192000000 10319398320 10744152064 1404102768 14.632 100 60 118 0 1430932752 12590032 14239952
20:48:18 2509 8192000000 10325514864 10748792832 1404519008 14.635 100 65 126 1 1435043280 15659472 14864800
20:49:36 1671 8192000000 10311178880 10734100480 1393206704 14.534 100 59 118 0 1423026704 15567152 14252848
20:50:46 12457 8192000000 10315168656 10730700800 1368779664 14.316 99 67 134 0 1409019904 23973968 16266272
20:52:08 7005 8192000000 10339102848 10760007680 1389489216 14.501 99 60 116 2 1434972176 31863776 13619184
20:53:20 8175 8192000000 10338687072 10756296704 1389089136 14.498 99 68 136 1 1436546256 30818160 16638960
20:54:33 4969 8192000000 10324325216 10749370368 1395911472 14.559 100 49 98 0 1437642080 29345632 12384976
20:55:46 7878 8192000000 10332364816 10748542976 1389336368 14.500 100 57 109 1 1437654448 34457120 13860960
20:57:22 6598 8192000000 10333288368 10755338240 1395815584 14.558 100 51 99 0 1441061376 32350976 12894816
20:58:34 1067 8192000000 10368619712 10775621632 1406503568 14.653 100 88 174 2 1469135728 40416176 22215984
20:59:40 3626 8192000000 10313969248 10723946496 1346920400 14.120 98 58 112 0 1407023712 45038208 15065104
21:00:54 11681 8192000000 10309404288 10732351488 1357161024 14.212 99 37 72 0 1409361472 41782256 10418192
21:02:11 6276 8192000000 10334926416 10750271488 1375269136 14.374 100 56 110 2 1438649360 48310560 15069664
21:03:22 11188 8192000000 10324481088 10741436416 1374529488 14.368 100 40 79 1 1431077536 45493024 11055024
21:04:37 16448 8192000000 10346310512 10784063488 1388771440 14.495 100 37 74 0 1457491184 58035344 10684400
21:05:59 17763 8192000000 10316453808 10743304192 1355827696 14.200 98 31 61 1 1404136512 40195888 8112928
21:07:30 37090 8192000000 10292650352 10735972352 1362866752 14.263 100 13 27 0 1403974976 37397104 3711120
21:09:08 28858 8192000000 10287447104 10705182720 1360478144 14.242 100 47 93 0 1386223232 15381056 10364032
21:10:39 29095 8192000000 10281754608 10717892608 1349348016 14.142 98 20 39 0 1364682384 11175680 4158688
21:12:44 53016 8192000000 10313570000 10768396288 1375258304 14.374 99 16 32 1 1411984112 32884800 3841008
21:14:44 10741 8192000000 10323496448 10752626688 1381236800 14.428 100 44 84 1 1411790784 20204256 10349728
21:16:06 123941 8192000000 10295804288 10743422976 1351337488 14.160 97 29 57 0 1373701648 15544720 6819440
21:19:29 111652 8192000000 10319525568 10765565952 1385320736 14.464 99 34 68 0 1413336800 20130560 7885504
21:22:22 40574 8192000000 10312085552 10760900608 1402004048 14.613 100 23 46 1 1420328880 12935168 5389664
21:24:03 23348 8192000000 10301848480 10765041664 1396577008 14.565 100 18 36 1 1409615776 9114320 3924448
21:25:34 35220 8192000000 10289995568 10753507328 1390084736 14.507 100 17 34 0 1404307936 10436864 3786336
21:27:12 44743 8192000000 10301781824 10761195520 1393898976 14.541 100 15 31 0 1415456768 17637808 3919984
21:28:58 74366 8192000000 10304695088 10767458304 1396351456 14.562 100 15 30 0 1413304992 13114640 3838896
21:31:56 41795 8192000000 10308528736 10773094400 1396347376 14.562 99 20 40 0 1417051088 16098640 4605072
21:34:43 65100 8192000000 10293303456 10759434240 1374487632 14.367 98 3 6 0 1387173568 12161888 524048
21:36:57 44015 8192000000 10281217488 10735169536 1361515792 14.251 98 18 36 0 1374395616 8784912 4094912
21:38:42 38635 8192000000 10322086224 10774138880 1395824656 14.558 99 30 60 1 1420892208 19164288 5903264
21:40:21 57193 8192000000 10290473920 10751881216 1349382976 14.142 97 10 20 0 1364741264 13128704 2229584
21:42:30 26864 8192000000 10290463312 10749284352 1380173360 14.418 100 16 32 0 1400387488 16499216 3714912
21:44:04 48462 8192000000 10293369792 10762362880 1372673648 14.351 99 12 22 0 1393286048 18112288 2500112
21:46:19 55132 8192000000 10274282064 10734039040 1330707840 13.974 97 12 24 1 1345113296 11510304 2895152
21:48:42 25448 8192000000 10288089696 10726391808 1338806448 14.047 98 39 78 0 1358187456 11124208 8256800
21:50:16 119396 8192000000 10240190816 10680971264 1263462496 13.362 93 28 54 1 1279189296 9027200 6699600
21:53:19 54668 8192000000 10249356352 10707566592 1338078752 14.040 99 19 38 0 1355736928 13312800 4345376
21:55:26 56242 8192000000 10243762288 10701438976 1347946784 14.129 100 13 26 0 1360478848 9813296 2718768
21:57:23 41776 8192000000 10244560704 10693009408 1303285280 13.725 96 15 30 0 1319893712 12994192 3614240
21:59:08 68300 8192000000 10253991424 10709639168 1341570000 14.072 99 16 32 0 1359765200 14186432 4008768
22:01:30 56901 8192000000 10253481920 10722975744 1342272384 14.078 99 8 16 0 1360862960 17002144 1588432
22:03:31 102827 8192000000 10278558816 10730635264 1335060560 14.013 97 16 32 0 1354149056 15423056 3665440
22:06:14 42207 8192000000 10289947568 10737778688 1365955456 14.291 99 27 54 0 1380737248 8872128 5909664
22:08:16 57268 8192000000 10308059744 10763108352 1383919824 14.452 100 22 44 0 1402193792 13610288 4663680
22:10:43 39141 8192000000 10299079584 10749612032 1365826144 14.290 98 15 30 0 1376277984 7481744 2970096
22:12:22 47597 8192000000 10283148000 10734747648 1359168752 14.230 97 11 22 0 1371488352 10238384 2081216
22:14:10 50890 8192000000 10328017152 10766184448 1386544496 14.475 99 34 66 0 1404834576 11327328 6962752
22:16:04 89813 8192000000 10309015568 10779914240 1402306192 14.616 100 17 34 0 1425458544 18795584 4356768
22:18:39 41821 8192000000 10297895088 10767679488 1392803968 14.531 99 20 40 0 1407791296 10996304 3991024
22:20:35 77527 8192000000 10306677952 10768232448 1403556512 14.627 99 20 40 0 1420208480 11956640 4695328
22:22:56 71415 8192000000 10315412000 10780266496 1402839424 14.620 99 11 22 0 1420279920 14558288 2882208
22:25:19 71591 8192000000 10314925184 10790146048 1416677856 14.743 100 13 26 0 1431473712 11730144 3065712
22:27:31 78209 8192000000 10301459328 10762371072 1375363696 14.375 98 17 34 0 1392247248 12817280 4066272
22:30:12 51401 8192000000 10295535584 10756751360 1371876688 14.344 98 18 36 1 1390060048 14021744 4161616
22:32:10 62370 8192000000 10308775328 10774888448 1396458096 14.563 100 21 42 0 1418943120 17516288 4968736
22:34:13 60766 8192000000 10259934832 10709291008 1308419216 13.772 95 12 25 0 1333206896 22278416 2509264
22:36:14 54029 8192000000 10293841328 10738192384 1378020336 14.399 100 37 74 0 1397737488 11510080 8207072
22:38:09 27450 8192000000 8992052160 9182855168 158713376 1.900 12 2 4 0 160153136 1354688 85072

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-07-2022 22:39:26 to 27-07-2022 22:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 151 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 27-07-2022 22:39:42 to 27-07-2022 22:39:44.

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          238989
MON$OLDEST_ACTIVE               238990
MON$OLDEST_SNAPSHOT             238990
MON$NEXT_TRANSACTION            238990
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                       2047328
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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.07.22 22:39:45. 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
2022-07-27 22:39:47 No severe PSQL-related problems occured
27.07.22 22:39:49. End of report.