Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2023 01:40
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 15449552 kB
MemAvailable 30397668 kB
Buffers 0 kB
Cached 16289836 kB
SwapCached 4428 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 2023-01-08 01:38:36
DTS_END                         2023-01-08 01:38:36.2510
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/56294
TRN_ID                          234617
ATT_ID                          697
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9088.73 21310 1090647
customer order: creation 165.39 369 19847
customer order: refuse 14.35 381 1722
order to supplier: creation 43.54 1246 5225
order to supplier: removal 3.44 109318 413
invoice (draft): creation 44.20 3490 5304
invoice (draft): removal 53.07 844 6368
invoice accept: apply 40.19 17714 4823
invoice accept: cancel 48.19 771 5783
customer reserve: creation 3708.53 176 445023
customer reserve: removal 4679.38 63 561526
realization accept: apply 80.59 193 9671
realization accept: cancel 84.13 111 10096
payment from customer: creation 56.65 117 6798
payment from customer: removal 5.40 103 648
payment to supplier: creation 46.78 137 5614
payment to supplier: removal 3.31 78 397
service: total inventory turnovers 4.43 1181 531
service: total monetary turnovers 6.38 29 765
service: aggregate perf. data 0.74 23406 89
service: refresh index statistics 0.03 266466 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 08-01-2023 01:40:32 to 08-01-2023 01:40:35.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8639
TEST_TIME 2 5713
TEST_TIME 3 5319
TEST_TIME 4 5451
TEST_TIME 5 5409
TEST_TIME 6 5517
TEST_TIME 7 5716
TEST_TIME 8 5731
TEST_TIME 9 5382
TEST_TIME 10 5356
TEST_TIME 11 5304
TEST_TIME 12 5322
TEST_TIME 13 5649
TEST_TIME 14 5797
TEST_TIME 15 6282
TEST_TIME 16 6437
TEST_TIME 17 6435
TEST_TIME 18 6517
TEST_TIME 19 6851
TEST_TIME 20 7120
TEST_TIME 21 7050
TEST_TIME 22 7462
TEST_TIME 23 7643
TEST_TIME 24 8245
TEST_TIME 25 8774
TEST_TIME 26 8868
TEST_TIME 27 9091
TEST_TIME 28 10097
TEST_TIME 29 10497
TEST_TIME 30 10356
TEST_TIME 31 10844
TEST_TIME 32 10695
TEST_TIME 33 10456
TEST_TIME 34 10258
TEST_TIME 35 10219
TEST_TIME 36 10013
TEST_TIME 37 9877
TEST_TIME 38 9791
TEST_TIME 39 9707
TEST_TIME 40 9678
TEST_TIME 41 9555
TEST_TIME 42 9462
TEST_TIME 43 9414
TEST_TIME 44 9438
TEST_TIME 45 9322
TEST_TIME 46 9329
TEST_TIME 47 9371
TEST_TIME 48 9240
TEST_TIME 49 9158
TEST_TIME 50 9228
TEST_TIME 51 9176
TEST_TIME 52 9151
TEST_TIME 53 9054
TEST_TIME 54 9019
TEST_TIME 55 9072
TEST_TIME 56 9123
TEST_TIME 57 9090
TEST_TIME 58 9048
TEST_TIME 59 9122
TEST_TIME 60 9101
TEST_TIME 61 9299
TEST_TIME 62 9265
TEST_TIME 63 9379
TEST_TIME 64 9387
TEST_TIME 65 9393
TEST_TIME 66 9355
TEST_TIME 67 9335
TEST_TIME 68 9329
TEST_TIME 69 9337
TEST_TIME 70 9238
TEST_TIME 71 9273
TEST_TIME 72 9213
TEST_TIME 73 9203
TEST_TIME 74 9197
TEST_TIME 75 9133
TEST_TIME 76 9107
TEST_TIME 77 9075
TEST_TIME 78 9034
TEST_TIME 79 9104
TEST_TIME 80 9077
TEST_TIME 81 9111
TEST_TIME 82 9140
TEST_TIME 83 9111
TEST_TIME 84 9126
TEST_TIME 85 9151
TEST_TIME 86 9225
TEST_TIME 87 9252
TEST_TIME 88 9222
TEST_TIME 89 9221
TEST_TIME 90 9334
TEST_TIME 91 9355
TEST_TIME 92 9355
TEST_TIME 93 9263
TEST_TIME 94 9266
TEST_TIME 95 9235
TEST_TIME 96 9212
TEST_TIME 97 9148
TEST_TIME 98 9117
TEST_TIME 99 9116
TEST_TIME 100 9072
TEST_TIME 101 9046
TEST_TIME 102 9009
TEST_TIME 103 8981
TEST_TIME 104 8942
TEST_TIME 105 8946
TEST_TIME 106 8937
TEST_TIME 107 9011
TEST_TIME 108 9007
TEST_TIME 109 9002
TEST_TIME 110 8992
TEST_TIME 111 9030
TEST_TIME 112 9003
TEST_TIME 113 9031
TEST_TIME 114 9047
TEST_TIME 115 9105
TEST_TIME 116 9125
TEST_TIME 117 9158
TEST_TIME 118 9170
TEST_TIME 119 9165
TEST_TIME 120 9093

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 08-01-2023 01:40:36 to 08-01-2023 01:40:39.

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 1502225 1502225 0 0.00 0 24790 6 0 0
doc_list_biud 1502224 1502224 0 0.00 0 52433 30 0 0
sp_add_invoice_to_stock 4922 4823 99 2.01 36 146254 17715 0 99
sp_cancel_adding_invoice 5783 5783 0 0.00 3 54203 771 0 0
sp_cancel_client_order 1722 1722 0 0.00 4 12083 382 0 0
sp_cancel_customer_reserve 561526 561526 0 0.00 0 21750 63 0 0
sp_cancel_pay_from_customer 648 648 0 0.00 2 5850 104 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 3 4626 79 0 0
sp_cancel_supplier_invoice 6379 6368 11 0.17 1 52503 844 0 11
sp_cancel_supplier_order 413 413 0 0.00 6 1235770 109319 0 0
sp_cancel_write_off 10096 10096 0 0.00 1 16401 111 0 0
sp_client_order 19847 19847 0 0.00 3 36836 369 0 0
sp_customer_reserve 445023 445023 0 0.00 0 52882 176 0 0
sp_fill_shopping_cart 30364 30364 0 0.00 1 13483 44 0 0
sp_fill_shopping_cart_clo_res 445013 445013 0 0.00 0 15530 14 0 0
sp_get_clo_for_invoice 4812 4812 0 0.00 0 20676 290 0 0
sp_kill_cost_storno 16924 16924 0 0.00 0 15442 55 0 0
sp_kill_qstorno_ret_qs2qd 1139929 1139929 0 0.00 0 52432 34 0 0
sp_kill_qty_storno 600391 600391 0 0.00 0 52432 73 0 0
sp_lock_selected_doc 20070 20070 0 0.00 0 6404 5 0 0
sp_make_cost_storno 26895 26895 0 0.00 0 22856 61 0 0
sp_multiply_rows_for_pdistr 26895 26895 0 0.00 0 8488 29 0 0
sp_multiply_rows_for_qdistr 19847 19847 0 0.00 0 17136 158 0 0
sp_pay_from_customer 6798 6798 0 0.00 4 27303 117 0 0
sp_pay_to_supplier 5614 5614 0 0.00 4 12962 138 0 0
sp_payment_common 12412 12412 0 0.00 1 27296 32 0 0
sp_qd_handle_on_cancel_clo 1722 1722 0 0.00 0 12077 340 0 0
sp_qd_handle_on_invoice_upd_sts 10595 10595 0 0.00 0 27812 321 0 0
sp_qd_handle_on_reserve_upd_sts 9671 9671 0 0.00 0 25014 57 0 0
sp_reserve_write_off 9671 9671 0 0.00 5 25047 194 0 0
sp_supplier_invoice 5304 5304 0 0.00 6 111123 3491 0 0
sp_supplier_order 5225 5225 0 0.00 5 120185 1246 0 0
srv_aggregate_perf_data 89 89 0 0.00 2860 375918 23407 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 994 187486 44803 0 0
srv_find_qd_qs_mism 1044589 1044589 0 0.00 0 24788 5 0 0
srv_make_invnt_saldo 531 531 0 0.00 41 105192 1181 0 0
srv_make_money_saldo 765 765 0 0.00 0 1466 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 116247 361996 266466 0 0
t$perf-abend:sp_add_invoice_to_stock 99 99 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4823 4823 0 0.00 0 40482 204 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 18467 154 0 0
t$perf-norm:sp_cancel_client_order 1722 1722 0 0.00 0 4307 14 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1924 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 648 648 0 0.00 0 546 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 1555 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 4111 18 0 0
t$perf-norm:sp_cancel_supplier_order 413 413 0 0.00 0 18132 1516 0 0
t$perf-norm:sp_cancel_write_off 934 934 0 0.00 0 2401 12 0 0
t$perf-norm:sp_client_order 19847 19847 0 0.00 0 8995 16 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 5261 12 0 0
t$perf-norm:sp_pay_from_customer 6798 6798 0 0.00 0 9290 14 0 0
t$perf-norm:sp_pay_to_supplier 5614 5614 0 0.00 0 10587 12 0 0
t$perf-norm:sp_reserve_write_off 9671 9671 0 0.00 0 26958 15 0 0
t$perf-norm:sp_supplier_invoice 5304 5304 0 0.00 0 8850 20 0 0
t$perf-norm:sp_supplier_order 5225 5225 0 0.00 0 6994 15 0 0
t$perf-norm:srv_aggregate_perf_data 89 89 0 0.00 0 412 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 3152 53 0 0
t$perf-norm:srv_make_invnt_saldo 531 531 0 0.00 0 2817 30 0 0
t$perf-norm:srv_make_money_saldo 765 765 0 0.00 0 8066 31 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 9820 9622 198 2.01 0 4188 20 0 198
v_all_customers 36054 36054 0 0.00 0 32 0 0 0
v_all_suppliers 29236 29236 0 0.00 0 71 1 0 0
v_all_wares 39692 39692 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 2554 12 0 0
v_cancel_client_order 3444 3444 0 0.00 0 5420 9 0 0
v_cancel_customer_prepayment 1296 1296 0 0.00 0 2946 13 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 2239 9 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 3478 16 0 0
v_cancel_supplier_invoice 828 806 22 2.65 0 9433 30 0 22
v_cancel_supplier_order 826 826 0 0.00 0 3670 19 0 0
v_cancel_write_off 1868 1868 0 0.00 0 4412 17 0 0
v_max_id_clo_ord 5224 5224 0 0.00 0 3878 2 0 0
v_max_id_clo_res 954 954 0 0.00 0 1091 8 0 0
v_max_id_ord_sup 5294 5294 0 0.00 0 2307 3 0 0
v_max_non_paid_invoice 1514 1514 0 0.00 0 3162 7 0 0
v_max_non_paid_realizn 3563 3563 0 0.00 0 3158 5 0 0
v_min_id_clo_ord 5224 5224 0 0.00 0 7534 11 0 0
v_min_id_clo_res 954 954 0 0.00 0 1443 5 0 0
v_min_id_ord_sup 5294 5294 0 0.00 0 7571 15 0 0
v_min_non_paid_invoice 5614 5614 0 0.00 0 12031 21 0 0
v_min_non_paid_realizn 6798 6798 0 0.00 0 27293 25 0 0
v_our_firm 10108 10108 0 0.00 0 79 1 0 0
v_reserve_write_off 19342 19342 0 0.00 0 4885 8 0 0
x_lock_depdocs_on_canc_invoice 11748 11748 0 0.00 0 20602 124 0 0
x_lock_depdocs_on_canc_sup_ord 413 413 0 0.00 0 6343 359 0 0
x_make_qstorno_1000_1200 5225 5225 0 0.00 2 120162 1162 0 0
x_make_qstorno_1200_2000 5304 5304 0 0.00 3 106000 3333 0 0
x_make_qstorno_2100_3300 445023 445023 0 0.00 0 52878 160 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 08-01-2023 01:40:40 to 08-01-2023 01:40:45.

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
23:08:40 3 8192000000 8800954688 8813121536 6805552 0.083 4 1 2 0 6907808 59472 42784
23:09:40 31366 8192000000 9890609632 10197573632 966176240 10.549 100 89 178 0 995592464 5521648 23894576
23:11:19 16853 8192000000 9947869600 10265944064 1025817712 11.128 100 84 168 0 1055292544 7793824 21681008
23:12:41 5337 8192000000 9985373760 10314567680 1067370832 11.527 100 88 176 0 1099031488 9786656 21874000
23:13:49 13568 8192000000 10024530992 10371141632 1117124656 12.000 100 80 156 0 1146082112 10199856 18757600
23:15:07 6064 8192000000 10071366592 10424737792 1155832384 12.364 100 86 172 0 1188927952 13059904 20035664
23:16:17 3473 8192000000 10116511504 10477256704 1196196432 12.741 100 89 178 0 1230753680 14414320 20142928
23:17:23 1733 8192000000 10147696288 10517725184 1231910048 13.072 100 81 160 1 1263480688 13600304 17970336
23:18:26 1964 8192000000 10183856176 10561081344 1268808592 13.411 100 87 174 0 1301341760 13672256 18860912
23:19:30 1413 8192000000 10206614816 10592665600 1289158048 13.597 100 87 174 1 1324418736 16288464 18972224
23:20:33 16136 8192000000 10210473472 10600955904 1300947408 13.704 100 68 133 0 1325655792 9701216 15007168
23:21:50 2862 8192000000 10202960448 10607677440 1305876864 13.749 100 36 72 0 1322490112 8886496 7726752
23:22:55 7320 8192000000 10199931344 10610728960 1313155328 13.815 100 20 40 0 1323705760 5838096 4712336
23:24:03 9817 8192000000 10200640128 10619109376 1319321632 13.871 100 18 35 0 1328240432 5029840 3888960
23:25:15 69604 8192000000 10211713984 10631540736 1322811312 13.902 100 25 50 1 1334499264 5715616 5972336
23:27:28 5516 8192000000 10226873024 10641248256 1327185456 13.942 100 31 62 1 1339572176 5741472 6645248
23:28:41 4786 8192000000 10203052112 10614857728 1289341168 13.598 98 37 71 1 1303562352 6369600 7851584
23:29:47 29501 8192000000 10158663552 10563747840 1222639760 12.986 95 23 46 0 1233144592 4607696 5897136
23:31:39 5602 8192000000 10161831280 10563817472 1236288080 13.112 97 32 64 0 1250206704 6400880 7517744
23:32:46 8075 8192000000 10141584384 10543857664 1216278960 12.927 96 16 32 0 1225066992 5301984 3486048
23:33:54 9365 8192000000 10155832464 10559635456 1232956544 13.081 97 20 39 1 1243407424 5163376 5287504
23:35:07 44143 8192000000 10184067680 10593579008 1287245504 13.579 100 35 70 2 1301766304 5710096 8810704
23:36:53 6219 8192000000 10202613984 10614886400 1304870784 13.740 100 27 52 0 1316011072 5381056 5759232
23:38:01 13446 8192000000 10209533456 10627444736 1316104208 13.841 100 18 36 0 1325418544 5491744 3822592
23:39:21 35698 8192000000 10241295024 10658566144 1340378896 14.061 100 33 63 0 1353482000 6146176 6956928
23:41:04 7219 8192000000 10272110592 10689449984 1359820656 14.236 100 52 104 0 1382962992 12355904 10786432
23:42:21 11402 8192000000 10283853456 10701111296 1360278784 14.240 99 49 97 0 1386562976 15476512 10807680
23:43:41 10433 8192000000 10298005504 10717556736 1381433408 14.429 100 51 102 0 1410223568 16729696 12060464
23:44:58 15979 8192000000 10294116112 10720194560 1380856128 14.424 100 44 86 3 1408761568 16878976 11026464
23:46:38 17630 8192000000 10309424800 10737369088 1396600640 14.565 100 36 69 0 1422135584 17574688 7960256
23:47:57 11674 8192000000 10328430448 10748923904 1394226240 14.544 99 52 103 1 1433779104 27289776 12263088
23:49:34 4129 8192000000 10335879552 10753740800 1402230864 14.615 100 59 114 0 1447255888 30029584 14995440
23:50:53 3558 8192000000 10357716240 10771980288 1407011600 14.657 99 69 138 1 1461146560 37283200 16851760
23:51:58 6445 8192000000 10313584496 10728566784 1350117680 14.149 97 57 112 0 1400338816 36240896 13980240
23:53:10 8411 8192000000 10326574832 10740633600 1374702912 14.369 99 60 120 0 1427884672 37871328 15310432
23:54:22 2245 8192000000 10341789776 10748715008 1380429104 14.420 99 72 144 1 1440791904 42300656 18062144
23:55:29 2367 8192000000 10341706288 10742906880 1381103712 14.426 100 82 165 1 1445168528 43094256 20970560
23:56:41 7643 8192000000 10356444912 10760454144 1381581248 14.431 99 79 158 1 1453278176 51675296 20021632
23:57:56 993 8192000000 10392226416 10797060096 1411410336 14.696 100 90 182 1 1493939136 58912048 23616752
23:59:03 7792 8192000000 10379805552 10799321088 1404014576 14.631 99 53 105 1 1478083616 59487648 14581392
00:00:19 3974 8192000000 10379895664 10801926144 1406611952 14.654 100 48 96 1 1484763952 64663664 13488336
00:01:26 14414 8192000000 10340608160 10761502720 1368639984 14.315 99 46 88 1 1436362336 55777504 11944848
00:02:47 28680 8192000000 10320383168 10759462912 1339851136 14.056 98 34 68 0 1418435456 68798976 9785344
00:04:35 18824 8192000000 10311733472 10747039744 1360507312 14.242 100 30 60 1 1424333376 55084656 8741408
00:05:56 17427 8192000000 10316456448 10760740864 1346072096 14.112 99 31 59 1 1421413824 66532560 8809168
00:07:16 68009 8192000000 10294812816 10733219840 1347667888 14.126 99 17 34 0 1401372928 48398368 5306672
00:09:25 39468 8192000000 10267724384 10707902464 1362345856 14.258 100 12 24 0 1375354896 10465504 2543536
00:11:09 64887 8192000000 10266011920 10707955712 1348055376 14.130 98 46 91 0 1366524464 8584320 9884768
00:14:35 57029 8192000000 10282354640 10732662784 1377076432 14.390 100 15 30 0 1391042384 10777168 3188784
00:16:33 38967 8192000000 10269116560 10715938816 1363649472 14.270 99 11 21 0 1374604272 8538512 2416288
00:18:37 16165 8192000000 10282251248 10723819520 1364109168 14.274 99 24 48 0 1377685936 7936048 5640720
00:19:55 52803 8192000000 10285864144 10732306432 1381626480 14.431 100 21 42 0 1397632336 11301648 4704208
00:21:56 69072 8192000000 10298794480 10759372800 1391958656 14.523 100 9 18 0 1414564976 20500240 2106080
00:24:06 49597 8192000000 10295635360 10754674688 1393725024 14.539 100 14 27 0 1414534784 17864688 2945072
00:25:58 87127 8192000000 10294525856 10749976576 1393779984 14.540 100 22 42 1 1408340624 9044944 5515696
00:30:04 68038 8192000000 10295906832 10768846848 1403395008 14.625 100 9 18 0 1419498672 14416688 1686976
00:32:41 64109 8192000000 10302068208 10780098560 1394554384 14.546 99 8 16 0 1407429440 11311040 1564016
00:34:46 43050 8192000000 10281054880 10739822592 1330594976 13.973 95 14 28 0 1341124400 7339344 3190080
00:36:37 58714 8192000000 10285702688 10746191872 1374632944 14.369 99 17 34 0 1390082704 11741536 3708224
00:39:11 83157 8192000000 10293131824 10762010624 1367531072 14.305 98 17 34 0 1386406288 15259120 3616096
00:41:52 42538 8192000000 10281623264 10724810752 1347166560 14.122 98 25 50 1 1362121776 8272752 6682464
00:43:47 40663 8192000000 10289966224 10730369024 1343255504 14.087 97 33 66 0 1358709872 8188240 7266128
00:45:30 7868 8192000000 10298515696 10737004544 1379621648 14.413 100 44 88 0 1400042048 10208624 10211776
00:46:40 24754 8192000000 10257314944 10696675328 1317213200 13.851 97 31 60 0 1333526864 9771632 6542032
00:48:07 84020 8192000000 10268056944 10717908992 1356666992 14.207 99 41 82 1 1374626768 8174880 9784896
00:50:42 40576 8192000000 10277176272 10737065984 1371506864 14.341 100 23 44 0 1390384032 13900064 4977104
00:52:27 59811 8192000000 10257972816 10717548544 1351519264 14.161 99 10 20 0 1364055136 9977808 2558064
00:54:53 83268 8192000000 10261534512 10712059904 1339904704 14.057 98 22 44 0 1360934976 15298656 5731616
00:57:16 54900 8192000000 10247567504 10709458944 1322619136 13.900 97 11 22 0 1341191776 15682352 2890288
00:59:13 115561 8192000000 10263553392 10723315712 1310045104 13.786 96 14 28 0 1330027424 16391712 3590608
01:02:09 40504 8192000000 10278409760 10724560896 1370313888 14.330 100 33 65 1 1389078720 11196368 7568464
01:03:54 112479 8192000000 10248755216 10703605760 1287568192 13.582 94 14 28 0 1307354848 16825632 2961024
01:06:47 18910 8192000000 10287908288 10717073408 1354807760 14.191 100 49 93 0 1373740832 8204704 10728368
01:08:07 56248 8192000000 10284843328 10747707392 1371096688 14.337 100 19 38 1 1395331760 19578192 4656880
01:10:05 187486 8192000000 10286590160 10750201856 1361248208 14.249 98 20 40 0 1379860816 14054960 4557648
01:14:13 56373 8192000000 10267957968 10731798528 1367998816 14.309 99 8 16 1 1382667936 12515648 2153472
01:16:28 58576 8192000000 10280500512 10740609024 1382486352 14.439 100 9 18 0 1397372640 12516864 2369424
01:18:28 58884 8192000000 10275984080 10738417664 1375719056 14.378 99 22 44 1 1388521312 8021376 4780880
01:20:27 87207 8192000000 10287175344 10751893504 1377745744 14.396 99 16 32 1 1388000304 7002688 3251872
01:22:55 63123 8192000000 10290593872 10757332992 1367500992 14.305 98 12 24 0 1378164720 7667728 2996000
01:25:13 55710 8192000000 10285763104 10744258560 1386996512 14.479 100 13 26 0 1400510192 10361040 3152640
01:27:11 63176 8192000000 10301842688 10760986624 1396752512 14.566 100 14 28 1 1408096640 8207552 3136576
01:29:23 44290 8192000000 10285603456 10743455744 1355239744 14.195 97 22 44 0 1372599808 12367984 4992080
01:31:09 39962 8192000000 10297283024 10746339328 1341044304 14.067 96 24 48 0 1359566224 11742992 6778928
01:32:50 88064 8192000000 10298279440 10765062144 1366738880 14.298 98 26 52 0 1387000528 14014080 6247568
01:35:34 54494 8192000000 10291672640 10738958336 1288094960 13.587 93 17 35 0 1303938032 12155856 3687216
01:37:30 85895 8192000000 9195832272 9458630656 348666400 4.082 26 9 16 1 351885072 1808544 1410128

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-01-2023 01:40:46 to 08-01-2023 01:41:02.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2023 01:41:03 to 08-01-2023 01:41:05.

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          235064
MON$OLDEST_ACTIVE               235065
MON$OLDEST_SNAPSHOT             235065
MON$NEXT_TRANSACTION            235065
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                       2015488
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.33652 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33652 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33652 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

08.01.23 01:41:06. 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
2023-01-08 01:41:08 No severe PSQL-related problems occured
08.01.23 01:41:09. End of report.