Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.07.2022 19:39
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 16272240 kB
MemAvailable 31082520 kB
Buffers 0 kB
Cached 15421200 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-13 19:38:23
DTS_END                         2022-07-13 19:38:23.6350
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/42924
TRN_ID                          246490
ATT_ID                          737
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: 9232.12 20013 1107854
customer order: creation 176.53 343 21183
customer order: refuse 14.66 315 1759
order to supplier: creation 45.96 1251 5515
order to supplier: removal 3.43 101317 412
invoice (draft): creation 45.72 3353 5486
invoice (draft): removal 50.87 775 6104
invoice accept: apply 43.13 17651 5176
invoice accept: cancel 45.91 781 5509
customer reserve: creation 3958.98 177 475077
customer reserve: removal 4544.65 61 545358
realization accept: apply 85.13 182 10216
realization accept: cancel 83.52 111 10022
payment from customer: creation 61.14 112 7337
payment from customer: removal 5.96 99 715
payment to supplier: creation 50.57 137 6068
payment to supplier: removal 3.53 101 423
service: total inventory turnovers 4.86 997 583
service: total monetary turnovers 6.69 20 803
service: aggregate perf. data 0.87 18863 104
service: refresh index statistics 0.03 253606 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-07-2022 19:39:36 to 13-07-2022 19:39:38.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4321
TEST_TIME 2 3962
TEST_TIME 3 4344
TEST_TIME 4 4607
TEST_TIME 5 4576
TEST_TIME 6 5246
TEST_TIME 7 5008
TEST_TIME 8 5129
TEST_TIME 9 5094
TEST_TIME 10 5247
TEST_TIME 11 5380
TEST_TIME 12 5443
TEST_TIME 13 5806
TEST_TIME 14 5933
TEST_TIME 15 6330
TEST_TIME 16 6547
TEST_TIME 17 6665
TEST_TIME 18 6873
TEST_TIME 19 7119
TEST_TIME 20 7583
TEST_TIME 21 7864
TEST_TIME 22 7921
TEST_TIME 23 8608
TEST_TIME 24 8746
TEST_TIME 25 9398
TEST_TIME 26 9920
TEST_TIME 27 9728
TEST_TIME 28 10083
TEST_TIME 29 10150
TEST_TIME 30 10020
TEST_TIME 31 9886
TEST_TIME 32 9640
TEST_TIME 33 9643
TEST_TIME 34 9648
TEST_TIME 35 9547
TEST_TIME 36 9546
TEST_TIME 37 9514
TEST_TIME 38 9455
TEST_TIME 39 9448
TEST_TIME 40 9339
TEST_TIME 41 9365
TEST_TIME 42 9476
TEST_TIME 43 9395
TEST_TIME 44 9333
TEST_TIME 45 9434
TEST_TIME 46 9299
TEST_TIME 47 9211
TEST_TIME 48 9184
TEST_TIME 49 9105
TEST_TIME 50 9142
TEST_TIME 51 9181
TEST_TIME 52 9345
TEST_TIME 53 9306
TEST_TIME 54 9346
TEST_TIME 55 9482
TEST_TIME 56 9445
TEST_TIME 57 9406
TEST_TIME 58 9650
TEST_TIME 59 9606
TEST_TIME 60 9642
TEST_TIME 61 9669
TEST_TIME 62 9525
TEST_TIME 63 9458
TEST_TIME 64 9409
TEST_TIME 65 9418
TEST_TIME 66 9336
TEST_TIME 67 9301
TEST_TIME 68 9236
TEST_TIME 69 9242
TEST_TIME 70 9281
TEST_TIME 71 9196
TEST_TIME 72 9237
TEST_TIME 73 9208
TEST_TIME 74 9219
TEST_TIME 75 9204
TEST_TIME 76 9253
TEST_TIME 77 9206
TEST_TIME 78 9225
TEST_TIME 79 9358
TEST_TIME 80 9325
TEST_TIME 81 9340
TEST_TIME 82 9370
TEST_TIME 83 9338
TEST_TIME 84 9442
TEST_TIME 85 9453
TEST_TIME 86 9536
TEST_TIME 87 9508
TEST_TIME 88 9415
TEST_TIME 89 9399
TEST_TIME 90 9339
TEST_TIME 91 9313
TEST_TIME 92 9271
TEST_TIME 93 9262
TEST_TIME 94 9284
TEST_TIME 95 9269
TEST_TIME 96 9250
TEST_TIME 97 9246
TEST_TIME 98 9303
TEST_TIME 99 9297
TEST_TIME 100 9304
TEST_TIME 101 9321
TEST_TIME 102 9359
TEST_TIME 103 9360
TEST_TIME 104 9369
TEST_TIME 105 9396
TEST_TIME 106 9407
TEST_TIME 107 9474
TEST_TIME 108 9456
TEST_TIME 109 9379
TEST_TIME 110 9359
TEST_TIME 111 9338
TEST_TIME 112 9291
TEST_TIME 113 9260
TEST_TIME 114 9288
TEST_TIME 115 9261
TEST_TIME 116 9237
TEST_TIME 117 9204
TEST_TIME 118 9276
TEST_TIME 119 9282
TEST_TIME 120 9232

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-07-2022 19:39:40 to 13-07-2022 19:39:43.

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 1546879 1546879 0 0.00 0 17422 6 0 0
doc_list_biud 1546879 1546879 0 0.00 0 39284 27 0 0
sp_add_invoice_to_stock 5315 5176 139 2.61 28 154310 17651 0 139
sp_cancel_adding_invoice 5509 5509 0 0.00 4 58729 782 0 0
sp_cancel_client_order 1759 1759 0 0.00 3 13074 316 0 0
sp_cancel_customer_reserve 545358 545358 0 0.00 0 17753 61 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 3 6550 99 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 2 7917 101 0 0
sp_cancel_supplier_invoice 6115 6104 11 0.17 1 17749 776 0 11
sp_cancel_supplier_order 412 412 0 0.00 11 1016513 101318 0 0
sp_cancel_write_off 10022 10022 0 0.00 1 10326 111 0 0
sp_client_order 21183 21183 0 0.00 3 27314 344 0 0
sp_customer_reserve 475077 475077 0 0.00 0 53109 177 0 0
sp_fill_shopping_cart 32179 32179 0 0.00 1 19538 35 0 0
sp_fill_shopping_cart_clo_res 475075 475075 0 0.00 0 20451 15 0 0
sp_get_clo_for_invoice 5174 5174 0 0.00 0 17761 293 0 0
sp_kill_cost_storno 16669 16669 0 0.00 0 10321 53 0 0
sp_kill_qstorno_ret_qs2qd 1107254 1107254 0 0.00 0 17747 33 0 0
sp_kill_qty_storno 584554 584554 0 0.00 0 39283 70 0 0
sp_lock_selected_doc 21267 21267 0 0.00 0 8507 6 0 0
sp_make_cost_storno 28795 28795 0 0.00 0 13327 58 0 0
sp_multiply_rows_for_pdistr 28795 28795 0 0.00 0 7686 28 0 0
sp_multiply_rows_for_qdistr 21183 21183 0 0.00 0 27301 162 0 0
sp_pay_from_customer 7337 7337 0 0.00 4 16744 112 0 0
sp_pay_to_supplier 6068 6068 0 0.00 5 17189 138 0 0
sp_payment_common 13405 13405 0 0.00 2 7049 29 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 13069 292 0 0
sp_qd_handle_on_invoice_upd_sts 10683 10683 0 0.00 0 39283 306 0 0
sp_qd_handle_on_reserve_upd_sts 10216 10216 0 0.00 0 9528 55 0 0
sp_reserve_write_off 10216 10216 0 0.00 6 13887 183 0 0
sp_supplier_invoice 5486 5486 0 0.00 5 101514 3354 0 0
sp_supplier_order 5515 5515 0 0.00 4 41655 1252 0 0
srv_aggregate_perf_data 105 104 1 0.95 2628 256968 18864 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 1728 114115 44653 0 0
srv_find_qd_qs_mism 1058448 1058448 0 0.00 0 17421 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 21 29988 997 0 0
srv_make_money_saldo 803 803 0 0.00 1 1227 21 0 0
srv_recalc_idx_stat 4 4 0 0.00 97888 334150 253606 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 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-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5176 5176 0 0.00 0 14786 156 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 4650 99 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 1442 9 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 1890 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 307 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 538 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 420 420 0 0.00 0 2408 25 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 27455 1666 0 0
t$perf-norm:sp_cancel_write_off 1006 1006 0 0.00 0 14935 38 0 0
t$perf-norm:sp_client_order 21183 21183 0 0.00 0 11215 16 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 2099 5 0 0
t$perf-norm:sp_pay_from_customer 7337 7337 0 0.00 0 10358 16 0 0
t$perf-norm:sp_pay_to_supplier 6068 6068 0 0.00 0 6485 11 0 0
t$perf-norm:sp_reserve_write_off 10216 10216 0 0.00 0 8551 12 0 0
t$perf-norm:sp_supplier_invoice 5486 5486 0 0.00 0 8861 18 0 0
t$perf-norm:sp_supplier_order 5515 5515 0 0.00 0 6660 18 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 187 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 197 6 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 3232 22 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 4088 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10620 10342 278 2.61 0 6920 21 0 278
v_all_customers 38508 38508 0 0.00 0 20 0 0 0
v_all_suppliers 30742 30742 0 0.00 0 17 1 0 0
v_all_wares 42366 42366 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 2306 9 0 0
v_cancel_client_order 3518 3518 0 0.00 0 1928 4 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 6507 14 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 2264 8 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 490 4 0 0
v_cancel_supplier_invoice 862 840 22 2.55 0 8668 26 0 22
v_cancel_supplier_order 824 824 0 0.00 0 3786 12 0 0
v_cancel_write_off 2012 2012 0 0.00 0 5673 13 0 0
v_max_id_clo_ord 5514 5514 0 0.00 0 1542 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 3130 13 0 0
v_max_id_ord_sup 5482 5482 0 0.00 0 2535 4 0 0
v_max_non_paid_invoice 1693 1693 0 0.00 0 382 3 0 0
v_max_non_paid_realizn 4019 4019 0 0.00 0 4247 7 0 0
v_min_id_clo_ord 5514 5514 0 0.00 0 19482 12 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 7168 12 0 0
v_min_id_ord_sup 5482 5482 0 0.00 0 10826 11 0 0
v_min_non_paid_invoice 6068 6068 0 0.00 0 6817 20 0 0
v_min_non_paid_realizn 7337 7337 0 0.00 0 7042 23 0 0
v_our_firm 10494 10494 0 0.00 0 13 1 0 0
v_reserve_write_off 20432 20432 0 0.00 0 8685 9 0 0
x_lock_depdocs_on_canc_invoice 11193 11193 0 0.00 0 16093 118 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 5882 331 0 0
x_make_qstorno_1000_1200 5515 5515 0 0.00 1 41638 1184 0 0
x_make_qstorno_1200_2000 5486 5486 0 0.00 2 101455 3223 0 0
x_make_qstorno_2100_3300 475077 475077 0 0.00 0 53107 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-07-2022 19:39:44 to 13-07-2022 19:39:48.

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
17:08:27 1 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:28 28666 8192000000 9905006656 10213773312 979000656 10.674 100 88 174 2 1007840640 6123216 22716768
17:11:07 12230 8192000000 9934627360 10253438976 1015843488 11.032 100 89 178 0 1047730512 8975968 22911056
17:12:26 15221 8192000000 9978099936 10308456448 1052678592 11.386 100 85 170 0 1084436656 10779568 20978496
17:13:44 8186 8192000000 10006218928 10350313472 1095091936 11.791 100 70 139 0 1122511264 10754032 16665296
17:14:59 4953 8192000000 10072261632 10435080192 1168399232 12.482 100 75 146 1 1196853872 11719264 16735376
17:16:06 3338 8192000000 10121571680 10487787520 1207975328 12.850 100 84 165 2 1240314016 14262960 18075728
17:17:11 2407 8192000000 10155497456 10531106816 1247399824 13.214 100 83 162 0 1277287504 12182080 17705600
17:18:16 1703 8192000000 10192390128 10569834496 1277351552 13.489 100 88 176 1 1310891408 13538016 20001840
17:19:18 1767 8192000000 10200287056 10584432640 1290323088 13.607 100 80 160 1 1320342080 11985024 18033968
17:20:21 40595 8192000000 10184555664 10602377216 1305191984 13.742 100 40 82 0 1322142192 7916848 9033360
17:22:04 3140 8192000000 10213076432 10619142144 1313881264 13.821 100 40 77 1 1329714544 7638064 8195216
17:23:09 10199 8192000000 10205745504 10625310720 1320966192 13.885 100 19 38 0 1331043344 6178896 3898256
17:24:22 27180 8192000000 10208653488 10634227712 1327329952 13.943 100 29 58 1 1341333296 6164048 7839296
17:26:31 5983 8192000000 10219510448 10637582336 1327548528 13.945 100 30 59 0 1340479504 6702256 6228720
17:27:38 8633 8192000000 10204203984 10619011072 1293808752 13.639 98 19 32 2 1306404656 9202032 3393872
17:28:50 6368 8192000000 10181422480 10591805440 1274882848 13.466 98 23 47 0 1286537664 5809184 5845632
17:29:59 47731 8192000000 10131711088 10526945280 1050808848 11.368 81 18 36 2 1059235552 4575936 3850768
17:31:47 6484 8192000000 10148697296 10544070656 1226791072 13.024 97 24 48 1 1241864656 9605856 5467728
17:32:54 8288 8192000000 10152280848 10552360960 1228133472 13.037 97 24 48 0 1243357488 9611472 5612544
17:34:04 21455 8192000000 10173122592 10589581312 1286461072 13.572 100 28 56 0 1298799840 5374272 6964496
17:35:26 12939 8192000000 10185496784 10596798464 1297746688 13.675 100 21 42 0 1308211776 5735888 4729200
17:36:42 6731 8192000000 10202208384 10614636544 1307082800 13.760 100 30 60 0 1319274960 5208768 6983392
17:37:49 13791 8192000000 10211645808 10622758912 1313318000 13.816 100 17 34 0 1322217632 4912208 3987424
17:39:10 16308 8192000000 10245088928 10657271808 1345208688 14.104 100 37 71 2 1359232656 6303728 7720240
17:40:34 56568 8192000000 10248838224 10663649280 1343015600 14.085 99 56 112 0 1365175952 8825920 13334432
17:42:37 30572 8192000000 10274763728 10693079040 1363665904 14.270 100 39 78 1 1382041808 9421824 8954080
17:44:18 36926 8192000000 10279563152 10709348352 1361405744 14.250 98 34 66 1 1387751168 17947024 8398400
17:46:08 9776 8192000000 10313541696 10735407104 1396450960 14.563 100 45 89 0 1424853552 17701872 10700720
17:47:22 24826 8192000000 10314480640 10737143808 1396874432 14.567 100 39 80 0 1428557392 21791984 9890976
17:48:54 6374 8192000000 10335312576 10756644864 1407022016 14.657 100 59 117 1 1447865648 26436752 14406880
17:50:05 12943 8192000000 10335406144 10763911168 1400068608 14.596 99 45 90 0 1440756272 29067632 11620032
17:51:28 22585 8192000000 10314620480 10737831936 1374764592 14.370 99 52 104 0 1418231296 30190320 13276384
17:52:55 6283 8192000000 10321679024 10738454528 1382072208 14.435 100 56 112 0 1430706240 34593392 14040640
17:54:09 1726 8192000000 10347924816 10759700480 1399280656 14.589 100 76 151 3 1453074464 35012944 18780864
17:55:16 3106 8192000000 10332922368 10746998784 1384207008 14.454 99 59 118 0 1437196368 38115344 14874016
17:56:26 4700 8192000000 10351408336 10763993088 1397320048 14.571 100 72 144 1 1458555520 43502944 17732528
17:57:37 2982 8192000000 10368026672 10781577216 1403890032 14.630 100 80 156 1 1472855536 49121520 19843984
17:58:47 9908 8192000000 10339258512 10755047424 1381424064 14.429 99 48 94 0 1435554784 41048848 13081872
18:00:16 7925 8192000000 10291373088 10713059328 1345794448 14.110 99 37 74 0 1399109104 42910640 10404016
18:01:31 38382 8192000000 10286996800 10726318080 1355052640 14.193 99 26 52 1 1396890112 34603024 7234448
18:03:15 43428 8192000000 10271985056 10706149376 1330890304 13.975 99 16 32 0 1371633536 36125968 4617264
18:04:59 36631 8192000000 10285490880 10708529152 1325791504 13.929 97 39 77 1 1347705600 13174912 8739184
18:06:57 77339 8192000000 10255262992 10696777728 1307415968 13.763 97 12 24 0 1319478032 9368592 2693472
18:09:19 73644 8192000000 10291206640 10724065280 1371085696 14.337 100 28 56 0 1384003536 7434048 5483792
18:11:43 78939 8192000000 10272318768 10722295808 1353193376 14.176 99 25 50 0 1383004736 23818400 5992960
18:14:13 9162 8192000000 10275990640 10718990336 1370358048 14.330 100 34 68 0 1389405504 11240464 7806992
18:15:24 16504 8192000000 10283224256 10722295808 1355086880 14.193 98 45 88 3 1376763552 11565680 10110992
18:16:42 94022 8192000000 10291673696 10749841408 1378394224 14.402 99 16 32 0 1395757440 13250912 4112304
18:19:20 58622 8192000000 10283404944 10740129792 1385087760 14.462 100 20 41 0 1404936560 14476912 5371888
18:21:31 38323 8192000000 10296742224 10746122240 1392056576 14.524 100 29 58 0 1409135904 10330656 6748672
18:23:13 62161 8192000000 10285992624 10764144640 1377398560 14.393 99 4 6 0 1391669312 13728064 542688
18:25:25 57811 8192000000 10279303296 10738708480 1366524208 14.296 98 15 31 0 1381294752 11879296 2891248
18:27:34 33922 8192000000 10321261760 10766073856 1383482576 14.448 99 47 93 0 1409594000 14792064 11319360
18:29:08 53162 8192000000 10295594656 10748375040 1366979008 14.300 98 33 64 0 1390951456 15748848 8223600
18:31:10 44624 8192000000 10311765024 10748280832 1359566704 14.233 98 34 64 0 1375372784 8213888 7592192
18:32:55 100386 8192000000 10286893632 10742181888 1357088352 14.211 98 13 26 0 1375027760 15097408 2842000
18:35:40 45116 8192000000 10311829200 10774536192 1383215728 14.445 99 28 56 0 1409597776 19942480 6439568
18:37:35 110343 8192000000 10296988560 10757050368 1315633936 13.837 94 11 22 0 1328275296 10314672 2326688
18:40:25 74519 8192000000 10291344752 10742554624 1369031568 14.318 98 32 62 0 1381500800 5717696 6751536
18:44:22 40528 8192000000 10279515536 10744442880 1380159152 14.418 100 6 12 0 1395672288 14386512 1126624
18:47:15 53766 8192000000 10276327104 10730283008 1334416384 14.007 97 18 36 1 1351526736 12935152 4175200
18:49:09 61582 8192000000 10285391728 10737897472 1357295088 14.213 98 31 62 0 1381871920 17295440 7281392
18:51:54 67684 8192000000 10259467696 10717933568 1330581872 13.972 98 15 30 0 1348181648 14016688 3583088
18:54:02 69051 8192000000 10247557552 10691633152 1273686304 13.455 94 21 42 1 1294330912 15930704 4713904
18:56:19 50038 8192000000 10265280864 10708246528 1333959488 14.003 99 26 52 0 1357390512 17775904 5655120
18:58:12 30970 8192000000 10274152640 10711994368 1331793520 13.983 98 26 52 0 1345589120 8240608 5554992
18:59:44 68434 8192000000 10283882768 10733690880 1357191376 14.212 99 34 67 0 1376881488 12320272 7369840
19:01:53 31772 8192000000 10279023808 10741006336 1375548288 14.377 100 19 38 0 1393345760 13403680 4393792
19:03:29 25818 8192000000 10294516432 10744426496 1375574832 14.377 99 33 66 0 1397930832 14449072 7906928
19:04:55 114115 8192000000 10281347648 10723254272 1324812208 13.920 96 28 56 0 1339431392 8556128 6063056
19:07:49 41375 8192000000 10274103680 10721738752 1354072240 14.184 99 22 44 0 1371495328 12793600 4629488
19:09:52 57299 8192000000 10277566928 10739695616 1383249056 14.446 100 12 23 0 1399138384 13658928 2230400
19:11:54 26622 8192000000 10268803104 10743119872 1362149488 14.257 99 4 8 0 1374854880 12111552 593840
19:13:23 18857 8192000000 10279021696 10731442176 1358120608 14.220 98 28 53 0 1370320544 5966576 6233360
19:14:43 7694 8192000000 10295189280 10743349248 1390771440 14.513 100 55 108 0 1409515952 7259664 11484848
19:15:51 66081 8192000000 10283780864 10744594432 1348808576 14.137 97 20 40 2 1364956352 11791968 4355808
19:17:57 32875 8192000000 10294982496 10738941952 1371029616 14.336 99 34 68 0 1388074080 9324752 7719712
19:19:35 56147 8192000000 10287333184 10746363904 1370020032 14.327 99 29 58 1 1386441904 10013696 6408176
19:21:32 36239 8192000000 10288273920 10742976512 1360307280 14.240 98 32 64 0 1379634240 12347840 6979120
19:23:09 84106 8192000000 10265085360 10710581248 1277026368 13.486 92 15 30 0 1293294864 13046256 3222240
19:25:36 95347 8192000000 10274115744 10735808512 1316780320 13.848 96 5 10 0 1333009088 15185872 1042896
19:28:39 42576 8192000000 10277737664 10722164736 1354883696 14.191 99 32 65 1 1369121744 7257616 6980432
19:30:22 38396 8192000000 10269755056 10728632320 1374577008 14.368 100 8 14 0 1387028720 10835696 1616016
19:32:01 64004 8192000000 10239135792 10690220032 1296541408 13.664 95 8 17 1 1311653408 13273360 1838640
19:34:06 72943 8192000000 10270165936 10730901504 1359537888 14.233 100 12 24 0 1377806224 15495168 2773168
19:36:19 58200 8192000000 10228991648 10678247424 1267988176 13.403 94 17 34 0 1285877840 13755744 4133920

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-07-2022 19:39:50 to 13-07-2022 19:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 139 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 13-07-2022 19:40:06 to 13-07-2022 19:40:08.

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          244532
MON$OLDEST_ACTIVE               246665
MON$OLDEST_SNAPSHOT             246665
MON$NEXT_TRANSACTION            246665
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                       2010496
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.33608 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33608 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33608 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33608 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 ---
:1035: 
:1036: mscs-test-161.budzdorov.ru    Wed Jul 13 19:38:32 2022
:1037:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1038: 
--- end of diff output ---

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

13.07.22 19:40:09. 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-13 19:40:11 No severe PSQL-related problems occured
13.07.22 19:40:12. End of report.