Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.06.2022 16: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 16270484 kB
MemAvailable 31244216 kB
Buffers 0 kB
Cached 15474596 kB
SwapCached 2496 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.10
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-06-25 16:38:26
DTS_END                         2022-06-25 16:38:26.9210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(139:30) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51872
TRN_ID                          240682
ATT_ID                          713
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: 9155.81 20676 1098697
customer order: creation 168.40 356 20208
customer order: refuse 15.10 366 1812
order to supplier: creation 44.15 1382 5298
order to supplier: removal 3.48 99548 417
invoice (draft): creation 44.31 3390 5317
invoice (draft): removal 51.43 885 6171
invoice accept: apply 41.69 19381 5003
invoice accept: cancel 45.96 822 5515
customer reserve: creation 3875.10 191 465012
customer reserve: removal 4580.21 58 549625
realization accept: apply 82.22 182 9866
realization accept: cancel 75.44 121 9053
payment from customer: creation 59.18 121 7101
payment from customer: removal 5.58 92 670
payment to supplier: creation 47.85 135 5742
payment to supplier: removal 3.46 86 415
service: total inventory turnovers 4.74 1201 569
service: total monetary turnovers 6.74 34 809
service: aggregate perf. data 0.75 26438 90
service: refresh index statistics 0.03 258740 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-06-2022 16:40:51 to 25-06-2022 16:40:53.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6222
TEST_TIME 2 5783
TEST_TIME 3 5727
TEST_TIME 4 5380
TEST_TIME 5 5755
TEST_TIME 6 5998
TEST_TIME 7 5980
TEST_TIME 8 5904
TEST_TIME 9 6484
TEST_TIME 10 6573
TEST_TIME 11 6895
TEST_TIME 12 6988
TEST_TIME 13 6980
TEST_TIME 14 7051
TEST_TIME 15 7580
TEST_TIME 16 7414
TEST_TIME 17 7565
TEST_TIME 18 7586
TEST_TIME 19 8105
TEST_TIME 20 8095
TEST_TIME 21 8334
TEST_TIME 22 8278
TEST_TIME 23 8252
TEST_TIME 24 8494
TEST_TIME 25 8657
TEST_TIME 26 8616
TEST_TIME 27 8445
TEST_TIME 28 8896
TEST_TIME 29 9558
TEST_TIME 30 9660
TEST_TIME 31 10646
TEST_TIME 32 10373
TEST_TIME 33 10236
TEST_TIME 34 10196
TEST_TIME 35 10131
TEST_TIME 36 9984
TEST_TIME 37 9928
TEST_TIME 38 9779
TEST_TIME 39 9920
TEST_TIME 40 9865
TEST_TIME 41 9808
TEST_TIME 42 9718
TEST_TIME 43 9632
TEST_TIME 44 9517
TEST_TIME 45 9414
TEST_TIME 46 9575
TEST_TIME 47 9482
TEST_TIME 48 9407
TEST_TIME 49 9395
TEST_TIME 50 9351
TEST_TIME 51 9378
TEST_TIME 52 9311
TEST_TIME 53 9283
TEST_TIME 54 9185
TEST_TIME 55 9145
TEST_TIME 56 9114
TEST_TIME 57 9106
TEST_TIME 58 9135
TEST_TIME 59 9289
TEST_TIME 60 9251
TEST_TIME 61 9266
TEST_TIME 62 9336
TEST_TIME 63 9394
TEST_TIME 64 9464
TEST_TIME 65 9489
TEST_TIME 66 9493
TEST_TIME 67 9446
TEST_TIME 68 9388
TEST_TIME 69 9598
TEST_TIME 70 9591
TEST_TIME 71 9496
TEST_TIME 72 9410
TEST_TIME 73 9354
TEST_TIME 74 9313
TEST_TIME 75 9358
TEST_TIME 76 9308
TEST_TIME 77 9276
TEST_TIME 78 9189
TEST_TIME 79 9208
TEST_TIME 80 9200
TEST_TIME 81 9204
TEST_TIME 82 9200
TEST_TIME 83 9212
TEST_TIME 84 9287
TEST_TIME 85 9292
TEST_TIME 86 9308
TEST_TIME 87 9391
TEST_TIME 88 9356
TEST_TIME 89 9386
TEST_TIME 90 9460
TEST_TIME 91 9429
TEST_TIME 92 9348
TEST_TIME 93 9302
TEST_TIME 94 9327
TEST_TIME 95 9275
TEST_TIME 96 9250
TEST_TIME 97 9219
TEST_TIME 98 9187
TEST_TIME 99 9175
TEST_TIME 100 9161
TEST_TIME 101 9170
TEST_TIME 102 9190
TEST_TIME 103 9224
TEST_TIME 104 9204
TEST_TIME 105 9275
TEST_TIME 106 9288
TEST_TIME 107 9304
TEST_TIME 108 9266
TEST_TIME 109 9316
TEST_TIME 110 9350
TEST_TIME 111 9384
TEST_TIME 112 9347
TEST_TIME 113 9276
TEST_TIME 114 9316
TEST_TIME 115 9272
TEST_TIME 116 9241
TEST_TIME 117 9221
TEST_TIME 118 9201
TEST_TIME 119 9225
TEST_TIME 120 9159

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-06-2022 16:40:55 to 25-06-2022 16:40:58.

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 1527676 1527676 0 0.00 0 25557 6 0 0
doc_list_biud 1527674 1527674 0 0.00 0 67127 27 0 0
sp_add_invoice_to_stock 5198 5003 195 3.75 60 210860 19382 0 195
sp_cancel_adding_invoice 5515 5515 0 0.00 5 62745 823 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 11182 366 0 0
sp_cancel_customer_reserve 549625 549625 0 0.00 1 67129 59 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 3471 92 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 3072 87 0 0
sp_cancel_supplier_invoice 6178 6171 7 0.11 2 24361 885 0 7
sp_cancel_supplier_order 417 417 0 0.00 6 906599 99549 0 0
sp_cancel_write_off 9053 9053 0 0.00 1 14716 121 0 0
sp_client_order 20208 20208 0 0.00 4 28102 356 0 0
sp_customer_reserve 465012 465012 0 0.00 0 99786 192 0 0
sp_fill_shopping_cart 30809 30809 0 0.00 1 14824 39 0 0
sp_fill_shopping_cart_clo_res 465008 465008 0 0.00 0 10867 14 0 0
sp_get_clo_for_invoice 4999 4999 0 0.00 0 27363 309 0 0
sp_kill_cost_storno 15653 15653 0 0.00 0 25546 60 0 0
sp_kill_qstorno_ret_qs2qd 1114891 1114891 0 0.00 0 66925 32 0 0
sp_kill_qty_storno 587458 587458 0 0.00 0 67127 70 0 0
sp_lock_selected_doc 20657 20657 0 0.00 0 6315 5 0 0
sp_make_cost_storno 27708 27708 0 0.00 0 17787 60 0 0
sp_multiply_rows_for_pdistr 27707 27707 0 0.00 0 10382 31 0 0
sp_multiply_rows_for_qdistr 20208 20208 0 0.00 0 11113 156 0 0
sp_pay_from_customer 7101 7101 0 0.00 4 10367 121 0 0
sp_pay_to_supplier 5742 5742 0 0.00 5 15290 136 0 0
sp_payment_common 12841 12841 0 0.00 1 10423 28 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 11175 342 0 0
sp_qd_handle_on_invoice_upd_sts 10514 10514 0 0.00 0 20259 319 0 0
sp_qd_handle_on_reserve_upd_sts 9866 9866 0 0.00 0 28075 61 0 0
sp_reserve_write_off 9866 9866 0 0.00 6 28155 183 0 0
sp_supplier_invoice 5317 5317 0 0.00 4 89078 3391 0 0
sp_supplier_order 5298 5298 0 0.00 5 87118 1383 0 0
srv_aggregate_perf_data 90 90 0 0.00 2613 346638 26438 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1447 147722 48088 0 0
srv_find_qd_qs_mism 1050611 1050611 0 0.00 0 14282 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 53 91518 1202 0 0
srv_make_money_saldo 809 809 0 0.00 0 8284 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 145206 331951 258741 0 0
t$perf-abend:sp_add_invoice_to_stock 195 195 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-norm:sp_add_invoice_to_stock 5004 5004 0 0.00 0 18381 192 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 8867 131 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 2998 14 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 5947 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 790 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 161 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 884 12 0 0
t$perf-norm:sp_cancel_supplier_order 417 417 0 0.00 0 29713 1569 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 1627 10 0 0
t$perf-norm:sp_client_order 20208 20208 0 0.00 0 10664 19 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 3075 12 0 0
t$perf-norm:sp_pay_from_customer 7101 7101 0 0.00 0 4787 10 0 0
t$perf-norm:sp_pay_to_supplier 5742 5742 0 0.00 0 18042 16 0 0
t$perf-norm:sp_reserve_write_off 9866 9866 0 0.00 0 10671 11 0 0
t$perf-norm:sp_supplier_invoice 5317 5317 0 0.00 0 13082 26 0 0
t$perf-norm:sp_supplier_order 5298 5298 0 0.00 0 5741 18 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 117 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 735 15 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 1688 26 0 0
t$perf-norm:srv_make_money_saldo 809 809 0 0.00 0 674 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 2 2 0 0
v_add_invoice_to_stock 10388 9998 390 3.75 0 10279 18 0 390
v_all_customers 36882 36882 0 0.00 0 21 1 0 0
v_all_suppliers 29688 29688 0 0.00 0 17 1 0 0
v_all_wares 40414 40414 0 0.00 0 14 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 1958 9 0 0
v_cancel_client_order 3624 3624 0 0.00 0 1927 5 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 3143 12 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 5673 10 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 173 3 0 0
v_cancel_supplier_invoice 770 756 14 1.81 0 585 14 0 14
v_cancel_supplier_order 834 834 0 0.00 0 2172 8 0 0
v_cancel_write_off 2036 2036 0 0.00 0 3829 14 0 0
v_max_id_clo_ord 5295 5295 0 0.00 0 413 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2354 14 0 0
v_max_id_ord_sup 5305 5305 0 0.00 0 2995 3 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 10418 11 0 0
v_max_non_paid_realizn 3801 3801 0 0.00 0 4259 6 0 0
v_min_id_clo_ord 5295 5295 0 0.00 0 1777 5 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 652 2 0 0
v_min_id_ord_sup 5305 5305 0 0.00 0 4032 5 0 0
v_min_non_paid_invoice 5740 5740 0 0.00 0 7215 21 0 0
v_min_non_paid_realizn 7101 7101 0 0.00 0 10335 20 0 0
v_our_firm 10132 10132 0 0.00 0 16 1 0 0
v_reserve_write_off 19731 19731 0 0.00 0 5652 7 0 0
x_lock_depdocs_on_canc_invoice 11308 11308 0 0.00 0 19173 115 0 0
x_lock_depdocs_on_canc_sup_ord 417 417 0 0.00 0 5769 343 0 0
x_make_qstorno_1000_1200 5298 5298 0 0.00 1 87101 1298 0 0
x_make_qstorno_1200_2000 5317 5317 0 0.00 2 89070 3256 0 0
x_make_qstorno_2100_3300 465012 465012 0 0.00 0 99784 175 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-06-2022 16:40:59 to 25-06-2022 16:41:03.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:08:30 4 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:31 23423 8192000000 9913867840 10228387840 998339728 10.862 100 81 162 1 1025281952 5809504 21132720
14:10:57 21346 8192000000 9965575424 10292359168 1048282896 11.344 100 88 176 0 1078601824 8489632 21829296
14:12:24 8628 8192000000 9993091216 10325438464 1075472224 11.604 100 87 174 0 1106760224 9971952 21316048
14:13:45 12430 8192000000 10020422176 10363949056 1105309456 11.888 100 79 158 0 1135909136 11520224 19079456
14:15:00 7801 8192000000 10072048576 10429501440 1157733072 12.382 100 70 142 1 1185862544 11801664 16327808
14:16:12 6192 8192000000 10126818160 10497253376 1219236432 12.955 100 76 152 0 1248285552 11930112 17119008
14:17:19 2659 8192000000 10154211232 10532139008 1243428992 13.178 100 74 148 1 1273071984 13734736 15908256
14:18:23 2969 8192000000 10195040368 10576359424 1281973136 13.531 100 79 158 1 1313053024 13493440 17586448
14:19:29 7375 8192000000 10207274768 10595037184 1295560304 13.655 100 67 135 1 1320923712 10003808 15359600
14:20:39 5488 8192000000 10206911472 10604625920 1308120832 13.769 100 61 120 3 1328662752 6854624 13687296
14:21:45 4439 8192000000 10192533584 10606923776 1309226704 13.779 100 26 50 0 1322183344 7104960 5851680
14:22:52 6202 8192000000 10209555792 10623438848 1321090752 13.887 100 24 47 0 1333037856 5943264 6003840
14:24:00 10941 8192000000 10217373152 10633080832 1329067408 13.959 100 16 32 0 1338528176 5559168 3901600
14:25:14 59635 8192000000 10249616768 10656358400 1335643776 14.018 100 47 95 1 1352847376 5292832 11910768
14:27:14 4804 8192000000 10242536288 10654240768 1338288496 14.042 100 28 56 1 1351559312 7402480 5868336
14:28:20 6855 8192000000 10222006064 10636562432 1310563584 13.791 99 22 44 0 1325857712 10361280 4932848
14:29:29 10028 8192000000 10145212896 10545823744 1200989168 12.786 93 18 36 3 1210515392 5139552 4386672
14:30:39 10597 8192000000 10138377856 10540072960 1212482464 12.892 96 28 56 0 1225049200 6458096 6108640
14:31:50 4666 8192000000 10133964352 10534281216 1241033792 13.156 99 26 52 1 1254030432 7291568 5705072
14:32:56 9482 8192000000 10141963408 10543382528 1240775584 13.153 99 37 74 2 1255673728 5130672 9767472
14:34:06 19487 8192000000 10165487680 10570452992 1261924240 13.348 99 25 47 1 1273206176 5504752 5777184
14:35:26 28839 8192000000 10182171936 10589683712 1284184016 13.551 100 23 45 0 1294547168 5450560 4912592
14:36:59 5064 8192000000 10201105872 10613178368 1301032768 13.705 100 49 98 1 1319370624 6327376 12010480
14:38:06 6099 8192000000 10213017456 10623602688 1313499616 13.818 100 22 43 1 1323572816 4953632 5119568
14:39:17 25365 8192000000 10253739904 10662215680 1343214416 14.086 100 62 119 2 1365659968 9310288 13135264
14:40:58 26115 8192000000 10257231440 10681286656 1356019760 14.202 100 38 74 1 1373494720 8346384 9128576
14:42:31 17135 8192000000 10281424176 10700353536 1367969904 14.309 100 58 116 4 1396234864 15525904 12739056
14:44:02 3399 8192000000 10283733600 10699489280 1368917184 14.317 100 60 120 1 1396781904 13637824 14226896
14:45:10 2713 8192000000 10295698256 10711044096 1369070656 14.319 99 62 123 0 1398933936 15489024 14374256
14:46:20 14443 8192000000 10309150016 10728603648 1388746656 14.495 100 61 122 1 1419059744 15984912 14328176
14:47:38 19741 8192000000 10287773120 10708385792 1353230896 14.177 98 44 88 2 1382664800 19594048 9839856
14:49:04 22494 8192000000 10319145440 10738163712 1390999376 14.515 100 56 112 1 1423987216 19333600 13654240
14:50:31 12105 8192000000 10302207584 10727030784 1389258176 14.499 100 57 112 0 1416145744 13759520 13128048
14:51:46 2115 8192000000 10311839728 10721345536 1378686320 14.405 100 91 183 1 1418161696 17897232 21578144
14:52:51 4089 8192000000 10287421968 10693517312 1343831280 14.092 99 66 130 1 1381228608 21193824 16203504
14:53:59 10817 8192000000 10304088320 10707996672 1363182224 14.266 99 81 162 3 1404833088 22262336 19388528
14:55:15 9471 8192000000 10295127680 10710069248 1353375456 14.178 99 51 100 1 1392513152 26598304 12539392
14:56:36 3578 8192000000 10335064032 10735218688 1372596864 14.350 100 92 181 2 1432780416 37038080 23145472
14:57:46 1442 8192000000 10343246720 10744639488 1375275408 14.374 99 89 176 3 1435050656 38300544 21474704
14:58:51 2753 8192000000 10329281392 10729213952 1370247296 14.329 100 80 161 0 1431717248 40696128 20773824
15:00:01 22571 8192000000 10322497792 10744782848 1379962720 14.416 100 38 76 1 1429390752 38466608 10961424
15:01:30 5891 8192000000 10348623472 10756075520 1385053744 14.462 100 64 128 0 1447245056 45713024 16478288
15:02:42 18403 8192000000 10314611424 10741301248 1357661536 14.216 99 42 84 0 1417595776 48446768 11487472
15:04:17 24213 8192000000 10325553168 10759520256 1375981888 14.381 100 27 54 0 1427541568 44010928 7548752
15:05:57 21689 8192000000 10313832432 10748620800 1355729152 14.199 100 26 52 0 1425188816 61259664 8200000
15:07:24 33069 8192000000 10290074480 10738503680 1348383392 14.133 100 24 48 1 1416644384 60435696 7825296
15:09:14 20525 8192000000 10263458608 10697236480 1351979440 14.165 99 32 62 4 1378359616 19404272 6975904
15:10:36 105973 8192000000 10268673904 10715148288 1351846800 14.164 98 24 46 1 1364332976 7902944 4583232
15:13:25 69613 8192000000 10295435152 10753466368 1388334912 14.491 100 21 41 1 1404903008 11670256 4897840
15:15:38 90629 8192000000 10300581200 10752393216 1379994272 14.416 99 11 20 1 1400766080 18400112 2371696
15:18:09 102295 8192000000 10300841088 10759741440 1390662848 14.512 100 9 18 1 1406850064 14393488 1793728
15:20:55 63931 8192000000 10310853616 10766561280 1403914496 14.630 100 27 54 2 1425024304 13513696 7596112
15:24:30 54339 8192000000 10305044688 10771435520 1404974384 14.639 100 13 26 2 1415947040 8155168 2817488
15:26:53 17576 8192000000 10330136464 10785153024 1415379216 14.732 100 44 88 0 1441445536 16519184 9547136
15:28:11 28438 8192000000 10303635792 10761682944 1394985232 14.550 100 46 92 0 1424186688 18009584 11191872
15:29:41 64884 8192000000 10288096304 10747899904 1387564400 14.484 99 14 28 0 1399240528 8594576 3081552
15:31:52 58097 8192000000 10295184624 10765623296 1392742496 14.530 99 12 24 0 1405310000 9849056 2718448
15:33:52 48278 8192000000 10313257472 10768420864 1392357552 14.527 99 18 36 0 1404172400 7871024 3943824
15:35:46 67157 8192000000 10301128608 10765434880 1350373040 14.151 96 13 26 0 1372501264 18976864 3151360
15:37:56 41147 8192000000 10275835200 10728304640 1334354032 14.006 96 11 22 0 1349098368 12345600 2398736
15:39:37 39611 8192000000 10283301920 10725982208 1337604880 14.036 97 33 66 0 1358667136 13799584 7262672
15:41:18 67161 8192000000 10285459264 10742374400 1349446832 14.143 98 13 26 0 1370281648 18027232 2807584
15:43:26 69866 8192000000 10242181904 10676240384 1221526688 12.976 89 26 52 1 1238934240 11682912 5724640
15:45:40 45375 8192000000 10290039904 10721423360 1317806912 13.857 97 44 88 0 1340513744 12577728 10129104
15:47:31 137780 8192000000 10257673264 10713047040 1290239792 13.606 95 12 24 0 1312777664 19952768 2585104
15:50:52 49539 8192000000 10235925056 10683224064 1316073936 13.841 98 19 38 1 1333758672 13568272 4116464
15:53:28 72536 8192000000 10246021216 10711015424 1333455440 13.998 99 5 10 0 1347864016 13130496 1278080
15:55:41 48490 8192000000 10243964880 10693586944 1320738128 13.883 97 18 36 0 1337663552 12536160 4389264
15:57:38 59305 8192000000 10263378384 10713264128 1345575440 14.108 99 6 12 0 1359610576 12745552 1289584
15:59:42 23317 8192000000 10268479968 10708865024 1340731776 14.064 98 47 94 1 1362805520 10887584 11186160
16:01:10 55403 8192000000 10270708320 10721837056 1359840800 14.236 100 22 44 0 1378844160 13756352 5247008
16:03:12 42564 8192000000 10292180640 10739859456 1370880880 14.335 100 37 74 0 1392926016 14229104 7816032
16:05:03 70366 8192000000 10275043264 10718691328 1300006272 13.695 95 15 30 1 1328182240 24641872 3534096
16:07:14 127058 8192000000 10291484272 10751385600 1338266384 14.042 97 17 34 1 1357824208 15571600 3986224
16:10:22 122647 8192000000 10283643904 10730729472 1368506432 14.314 99 29 58 0 1386716608 12123072 6087104
16:13:25 90182 8192000000 10273643184 10736099328 1358840672 14.227 98 13 26 1 1369041824 6985824 3215328
16:15:56 85180 8192000000 10288364864 10746159104 1367750736 14.307 98 19 38 1 1384240672 11789664 4700272
16:18:29 47385 8192000000 10312585472 10766561280 1391274960 14.517 99 24 48 2 1409944384 12731616 5937808
16:20:17 54093 8192000000 10294028384 10758586368 1363324256 14.267 98 19 38 1 1389036032 21411760 4300016
16:22:27 66104 8192000000 10302983744 10762924032 1360516384 14.242 97 16 32 0 1373365856 9266272 3583200
16:24:34 111127 8192000000 10307133696 10750099456 1311822352 13.803 94 10 21 0 1323166704 9222176 2122176
16:27:32 86239 8192000000 10280902032 10740064256 1327164400 13.942 96 12 22 1 1341880320 12498112 2217808
16:29:58 147722 8192000000 10278224000 10730369024 1343591360 14.090 98 12 24 0 1362200000 15900080 2708560
16:33:28 2174 8192000000 10276661728 10742833152 1372309728 14.348 100 10 20 0 1391845360 17511920 2023712
16:34:43 50302 8192000000 10248829776 10710835200 1338437008 14.043 98 14 28 0 1356265424 14500064 3328352
16:36:33 73307 8192000000 10256740640 10711691264 1331345664 13.979 97 14 28 0 1344387776 9810960 3231152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-06-2022 16:41:05 to 25-06-2022 16:41:20.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 195 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 25-06-2022 16:41:21 to 25-06-2022 16:41:23.

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          237567
MON$OLDEST_ACTIVE               240881
MON$OLDEST_SNAPSHOT             240881
MON$NEXT_TRANSACTION            240881
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                       2029984
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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'

25.06.22 16:41:24. 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-06-25 16:41:26 No severe PSQL-related problems occured
25.06.22 16:41:27. End of report.