Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.06.2022 22:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16549616 kB
MemAvailable 31302112 kB
Buffers 0 kB
Cached 15143252 kB
SwapCached 2448 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-05 22:38:19
DTS_END                         2022-06-05 22:38:19.3490
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/58906
TRN_ID                          239814
ATT_ID                          698
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: 9174.85 20656 1100982
customer order: creation 176.98 351 21237
customer order: refuse 15.33 378 1839
order to supplier: creation 46.78 1286 5614
order to supplier: removal 3.39 96526 407
invoice (draft): creation 45.87 3885 5504
invoice (draft): removal 51.47 762 6176
invoice accept: apply 41.78 16781 5014
invoice accept: cancel 46.43 761 5572
customer reserve: creation 3848.12 166 461774
customer reserve: removal 4596.32 55 551558
realization accept: apply 86.01 161 10321
realization accept: cancel 83.77 112 10052
payment from customer: creation 60.43 97 7251
payment from customer: removal 5.96 81 715
payment to supplier: creation 50.57 110 6068
payment to supplier: removal 3.61 52 433
service: total inventory turnovers 5.01 1067 601
service: total monetary turnovers 6.21 26 745
service: aggregate perf. data 0.81 20686 97
service: refresh index statistics 0.03 269783 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-06-2022 22:39:10 to 05-06-2022 22:39:12.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3444
TEST_TIME 2 4287
TEST_TIME 3 4412
TEST_TIME 4 5103
TEST_TIME 5 5025
TEST_TIME 6 5075
TEST_TIME 7 5319
TEST_TIME 8 5263
TEST_TIME 9 5465
TEST_TIME 10 5516
TEST_TIME 11 5541
TEST_TIME 12 5755
TEST_TIME 13 5931
TEST_TIME 14 6141
TEST_TIME 15 6970
TEST_TIME 16 7223
TEST_TIME 17 7823
TEST_TIME 18 8054
TEST_TIME 19 8862
TEST_TIME 20 9239
TEST_TIME 21 9538
TEST_TIME 22 9789
TEST_TIME 23 9982
TEST_TIME 24 10096
TEST_TIME 25 10573
TEST_TIME 26 11078
TEST_TIME 27 10785
TEST_TIME 28 10502
TEST_TIME 29 10399
TEST_TIME 30 10174
TEST_TIME 31 10135
TEST_TIME 32 10102
TEST_TIME 33 10041
TEST_TIME 34 9894
TEST_TIME 35 9773
TEST_TIME 36 9639
TEST_TIME 37 9525
TEST_TIME 38 9477
TEST_TIME 39 9532
TEST_TIME 40 9576
TEST_TIME 41 9517
TEST_TIME 42 9409
TEST_TIME 43 9257
TEST_TIME 44 9372
TEST_TIME 45 9360
TEST_TIME 46 9338
TEST_TIME 47 9303
TEST_TIME 48 9325
TEST_TIME 49 9527
TEST_TIME 50 9474
TEST_TIME 51 9546
TEST_TIME 52 9636
TEST_TIME 53 9716
TEST_TIME 54 9670
TEST_TIME 55 9676
TEST_TIME 56 9701
TEST_TIME 57 9675
TEST_TIME 58 9678
TEST_TIME 59 9525
TEST_TIME 60 9421
TEST_TIME 61 9466
TEST_TIME 62 9363
TEST_TIME 63 9317
TEST_TIME 64 9377
TEST_TIME 65 9309
TEST_TIME 66 9384
TEST_TIME 67 9310
TEST_TIME 68 9367
TEST_TIME 69 9365
TEST_TIME 70 9439
TEST_TIME 71 9406
TEST_TIME 72 9384
TEST_TIME 73 9320
TEST_TIME 74 9449
TEST_TIME 75 9456
TEST_TIME 76 9412
TEST_TIME 77 9478
TEST_TIME 78 9484
TEST_TIME 79 9430
TEST_TIME 80 9430
TEST_TIME 81 9390
TEST_TIME 82 9394
TEST_TIME 83 9344
TEST_TIME 84 9285
TEST_TIME 85 9245
TEST_TIME 86 9206
TEST_TIME 87 9128
TEST_TIME 88 9188
TEST_TIME 89 9170
TEST_TIME 90 9180
TEST_TIME 91 9181
TEST_TIME 92 9144
TEST_TIME 93 9148
TEST_TIME 94 9251
TEST_TIME 95 9240
TEST_TIME 96 9237
TEST_TIME 97 9335
TEST_TIME 98 9322
TEST_TIME 99 9359
TEST_TIME 100 9382
TEST_TIME 101 9346
TEST_TIME 102 9354
TEST_TIME 103 9392
TEST_TIME 104 9333
TEST_TIME 105 9288
TEST_TIME 106 9267
TEST_TIME 107 9241
TEST_TIME 108 9227
TEST_TIME 109 9180
TEST_TIME 110 9136
TEST_TIME 111 9108
TEST_TIME 112 9132
TEST_TIME 113 9125
TEST_TIME 114 9140
TEST_TIME 115 9167
TEST_TIME 116 9126
TEST_TIME 117 9161
TEST_TIME 118 9210
TEST_TIME 119 9251
TEST_TIME 120 9179

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-06-2022 22:39:13 to 05-06-2022 22:39:17.

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 1529876 1529876 0 0.00 0 17046 6 0 0
doc_list_biud 1529870 1529870 0 0.00 0 45183 26 0 0
sp_add_invoice_to_stock 5113 5014 99 1.93 33 167934 16782 0 99
sp_cancel_adding_invoice 5572 5572 0 0.00 5 46791 762 0 0
sp_cancel_client_order 1839 1839 0 0.00 4 21742 379 0 0
sp_cancel_customer_reserve 551558 551558 0 0.00 1 30815 56 0 0
sp_cancel_pay_from_customer 715 715 0 0.00 3 6556 81 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 2 2233 53 0 0
sp_cancel_supplier_invoice 6179 6176 3 0.04 2 18249 762 0 3
sp_cancel_supplier_order 407 407 0 0.00 7 983887 96526 0 0
sp_cancel_write_off 10052 10052 0 0.00 1 16983 113 0 0
sp_client_order 21237 21237 0 0.00 3 19705 351 0 0
sp_customer_reserve 461774 461774 0 0.00 0 105003 166 0 0
sp_fill_shopping_cart 32345 32345 0 0.00 1 35577 46 0 0
sp_fill_shopping_cart_clo_res 461768 461768 0 0.00 0 14686 13 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 30827 349 0 0
sp_kill_cost_storno 16772 16772 0 0.00 0 14425 53 0 0
sp_kill_qstorno_ret_qs2qd 1119751 1119751 0 0.00 0 45181 30 0 0
sp_kill_qty_storno 590933 590933 0 0.00 0 45182 66 0 0
sp_lock_selected_doc 21257 21257 0 0.00 0 6549 4 0 0
sp_make_cost_storno 28648 28648 0 0.00 0 14581 47 0 0
sp_multiply_rows_for_pdistr 28648 28648 0 0.00 0 8948 23 0 0
sp_multiply_rows_for_qdistr 21237 21237 0 0.00 0 17203 173 0 0
sp_pay_from_customer 7251 7251 0 0.00 4 18862 97 0 0
sp_pay_to_supplier 6068 6068 0 0.00 4 16985 110 0 0
sp_payment_common 13319 13319 0 0.00 1 9696 28 0 0
sp_qd_handle_on_cancel_clo 1839 1839 0 0.00 0 21736 348 0 0
sp_qd_handle_on_invoice_upd_sts 10580 10580 0 0.00 0 19081 319 0 0
sp_qd_handle_on_reserve_upd_sts 10321 10321 0 0.00 0 19496 53 0 0
sp_reserve_write_off 10321 10321 0 0.00 6 19587 162 0 0
sp_supplier_invoice 5504 5504 0 0.00 6 92568 3885 0 0
sp_supplier_order 5614 5614 0 0.00 5 32852 1286 0 0
srv_aggregate_perf_data 98 97 1 1.02 2681 278599 20687 0 1
srv_fill_mon_cache_memory 63 63 0 0.00 2305 107346 46072 0 0
srv_find_qd_qs_mism 1053176 1053176 0 0.00 0 17045 5 0 0
srv_make_invnt_saldo 601 601 0 0.00 25 49319 1067 0 0
srv_make_money_saldo 745 745 0 0.00 1 1887 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 219820 322143 269784 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 3 3 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 5014 5014 0 0.00 0 8305 155 0 0
t$perf-norm:sp_cancel_adding_invoice 407 407 0 0.00 0 5266 81 0 0
t$perf-norm:sp_cancel_client_order 1839 1839 0 0.00 0 3535 16 0 0
t$perf-norm:sp_cancel_customer_reserve 688 688 0 0.00 0 2482 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 715 715 0 0.00 0 3294 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 3292 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 441 441 0 0.00 0 622 11 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 24616 1653 0 0
t$perf-norm:sp_cancel_write_off 998 998 0 0.00 0 5406 12 0 0
t$perf-norm:sp_client_order 21237 21237 0 0.00 0 24669 17 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 1533 8 0 0
t$perf-norm:sp_pay_from_customer 7251 7251 0 0.00 0 3219 6 0 0
t$perf-norm:sp_pay_to_supplier 6068 6068 0 0.00 0 5161 9 0 0
t$perf-norm:sp_reserve_write_off 10321 10321 0 0.00 0 16462 14 0 0
t$perf-norm:sp_supplier_invoice 5504 5504 0 0.00 0 16465 19 0 0
t$perf-norm:sp_supplier_order 5614 5614 0 0.00 0 4970 14 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 479 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 169 7 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 1709 17 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 2095 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10214 10016 198 1.93 0 4997 16 0 198
v_all_customers 38594 38594 0 0.00 0 72 0 0 0
v_all_suppliers 31126 31126 0 0.00 0 86 1 0 0
v_all_wares 42474 42474 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 814 814 0 0.00 0 2115 8 0 0
v_cancel_client_order 3678 3678 0 0.00 0 7528 6 0 0
v_cancel_customer_prepayment 1430 1430 0 0.00 0 2226 5 0 0
v_cancel_customer_reserve 1376 1376 0 0.00 0 1505 5 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 2227 5 0 0
v_cancel_supplier_invoice 888 882 6 0.67 0 814 12 0 6
v_cancel_supplier_order 814 814 0 0.00 0 290 3 0 0
v_cancel_write_off 1996 1996 0 0.00 0 7356 18 0 0
v_max_id_clo_ord 5614 5614 0 0.00 0 263 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 3255 17 0 0
v_max_id_ord_sup 5494 5494 0 0.00 0 4873 4 0 0
v_max_non_paid_invoice 1613 1613 0 0.00 0 5792 13 0 0
v_max_non_paid_realizn 3871 3871 0 0.00 0 2294 4 0 0
v_min_id_clo_ord 5614 5614 0 0.00 0 2388 6 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 5027 9 0 0
v_min_id_ord_sup 5494 5494 0 0.00 0 29374 14 0 0
v_min_non_paid_invoice 6068 6068 0 0.00 0 5289 19 0 0
v_min_non_paid_realizn 7251 7251 0 0.00 0 9693 21 0 0
v_our_firm 10640 10640 0 0.00 0 17 1 0 0
v_reserve_write_off 20642 20642 0 0.00 0 7531 5 0 0
x_lock_depdocs_on_canc_invoice 11307 11307 0 0.00 0 20022 122 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 10990 337 0 0
x_make_qstorno_1000_1200 5614 5614 0 0.00 1 32845 1203 0 0
x_make_qstorno_1200_2000 5504 5504 0 0.00 3 92000 3700 0 0
x_make_qstorno_2100_3300 461774 461774 0 0.00 0 104954 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-06-2022 22:39:18 to 05-06-2022 22:39:22.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:23 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:24 38453 8192000000 9915679168 10228633600 996917120 10.849 100 88 176 0 1025846832 6563088 22366624
20:11:11 16446 8192000000 9960476768 10289164288 1043410336 11.297 100 82 164 0 1073295936 8887312 20998288
20:12:37 9614 8192000000 10009914512 10349047808 1092466912 11.766 100 87 174 0 1124399472 10784400 21148160
20:13:52 17994 8192000000 10047911328 10397655040 1121565952 12.042 100 76 152 1 1151224064 11516256 18141856
20:15:16 8096 8192000000 10085720240 10450124800 1177004896 12.562 100 73 145 0 1205118384 11900640 16212848
20:16:25 4357 8192000000 10131832624 10503368704 1224610112 13.004 100 74 148 1 1252939584 12426240 15903232
20:17:32 2655 8192000000 10168067584 10545303552 1255801840 13.292 100 80 160 1 1287908592 13717328 18389424
20:18:36 1812 8192000000 10201214400 10582740992 1284887088 13.558 100 90 179 0 1318567776 13904848 19775840
20:19:39 35384 8192000000 10194983520 10598440960 1298334736 13.680 100 52 102 1 1319723440 10130176 11258528
20:21:15 4148 8192000000 10197644880 10611171328 1307661488 13.765 100 31 62 0 1322731376 8615200 6454688
20:22:22 4624 8192000000 10206005408 10616123392 1316647120 13.846 100 25 50 0 1328833936 6402304 5784512
20:23:28 9044 8192000000 10208461216 10628874240 1328784992 13.956 100 24 47 2 1340862672 6706624 5371056
20:24:49 55792 8192000000 10241561872 10649612288 1334287072 14.006 100 58 112 4 1353718256 4967840 14463344
20:26:45 4525 8192000000 10235107776 10653229056 1339223984 14.050 100 33 67 1 1354274880 7673696 7377200
20:27:50 7396 8192000000 10218189440 10638417920 1328876928 13.957 100 22 45 0 1340390272 6813712 4699632
20:29:00 32662 8192000000 10200129888 10613260288 1298802176 13.684 99 44 87 0 1315333680 5297472 11234032
20:30:37 7427 8192000000 10189569952 10590146560 1277756496 13.493 98 36 71 3 1293457504 7578800 8122208
20:31:47 8792 8192000000 10141679056 10547642368 1231243824 13.066 98 21 42 0 1241757520 6222096 4291600
20:32:59 48009 8192000000 10149955792 10537242624 1223502224 12.994 98 49 97 0 1239890912 4780144 11608544
20:34:47 7768 8192000000 10165992352 10569248768 1274019264 13.458 100 31 62 0 1287810272 6657664 7133344
20:35:58 7021 8192000000 10176924608 10591215616 1290749904 13.611 100 13 26 2 1300148192 5606016 3792272
20:37:07 16152 8192000000 10207018048 10617196544 1306490656 13.754 100 20 40 0 1315978544 5126432 4361456
20:38:25 93703 8192000000 10232590656 10648899584 1332193328 13.987 100 39 76 1 1347647232 6666928 8786976
20:41:03 3348 8192000000 10259786496 10675191808 1352217472 14.167 100 37 73 2 1370133216 9481600 8434144
20:42:14 38159 8192000000 10276562000 10704420864 1365397456 14.286 100 29 58 0 1386569424 14230224 6941744
20:43:57 6578 8192000000 10302608080 10726686720 1387826240 14.486 100 51 102 0 1418274832 17510656 12937936
20:45:11 16042 8192000000 10317616624 10743414784 1397235632 14.570 100 44 88 0 1429001600 21248224 10517744
20:46:50 4541 8192000000 10332617040 10762174464 1412184368 14.703 100 43 85 0 1446543936 24149856 10209712
20:48:01 5879 8192000000 10354707584 10776350720 1420259696 14.775 100 67 133 0 1462733056 26348768 16124592
20:49:13 3196 8192000000 10377968112 10798235648 1434500080 14.901 100 73 146 1 1484087696 32188848 17398768
20:50:22 2722 8192000000 10373777696 10789355520 1424373504 14.811 100 75 147 2 1477986240 35774352 17838384
20:51:29 6526 8192000000 10372323872 10790653952 1421028432 14.782 100 75 150 0 1479630336 39720384 18881520
20:52:39 5060 8192000000 10393979616 10807988224 1426028720 14.826 100 89 179 0 1495669680 47163040 22477920
20:53:50 2302 8192000000 10362466592 10778656768 1406500976 14.653 100 85 171 1 1470010432 42955136 20554320
20:54:55 2724 8192000000 10349924544 10755362816 1383304624 14.446 100 82 164 2 1449261232 44462992 21493616
20:56:01 2963 8192000000 10341643648 10763210752 1383242656 14.446 99 59 117 2 1441791104 42887376 15661072
20:57:10 9367 8192000000 10345753696 10777526272 1384061040 14.453 99 43 86 3 1442103216 46330464 11711712
20:58:24 14899 8192000000 10328697136 10766282752 1369001856 14.318 98 41 82 1 1422504704 42903392 10599456
20:59:57 12364 8192000000 10319008784 10753282048 1384623008 14.458 100 29 58 1 1430182496 37356272 8203216
21:01:14 26514 8192000000 10287758256 10732339200 1354251504 14.186 99 14 28 0 1386173824 28016352 3905968
21:03:18 23302 8192000000 10276848880 10732126208 1343893552 14.093 99 20 40 2 1387687440 38207488 5586400
21:04:50 85750 8192000000 10260106384 10693697536 1326784800 13.938 98 19 39 0 1342415248 10757568 4872880
21:07:25 82942 8192000000 10268807920 10687778816 1314900000 13.831 97 46 90 1 1335334416 10310096 10124320
21:09:52 30688 8192000000 10264145088 10701340672 1357266144 14.213 100 25 49 1 1369872976 6933200 5673632
21:11:29 43388 8192000000 10271179888 10718441472 1370097072 14.328 100 11 22 0 1381540688 9140768 2302848
21:13:13 23332 8192000000 10277289488 10724020224 1372263264 14.347 100 20 38 2 1388319920 12008912 4047744
21:14:39 37987 8192000000 10291480288 10739384320 1382325648 14.437 100 16 32 2 1403472432 17054384 4092400
21:17:05 53919 8192000000 10303309520 10753785856 1390211984 14.508 100 26 52 0 1411629632 14911808 6505840
21:19:15 39012 8192000000 10300051360 10747527168 1377714560 14.396 99 32 64 0 1395182672 10488704 6979408
21:20:55 82088 8192000000 10286772688 10751000576 1382877744 14.442 99 19 38 0 1405545264 17697184 4970336
21:23:27 65238 8192000000 10307898224 10770554880 1403144064 14.623 100 14 28 0 1417278352 10974976 3159312
21:25:42 57408 8192000000 10322312576 10788782080 1413298240 14.713 100 13 26 1 1433154080 16844432 3011408
21:28:08 38907 8192000000 10316456560 10771984384 1379946368 14.416 97 14 28 0 1394704496 11565184 3192944
21:29:47 75062 8192000000 10330693888 10785611776 1397436512 14.572 99 19 38 0 1419724784 17997504 4290768
21:32:03 58755 8192000000 10316104928 10768699392 1393772720 14.540 99 29 58 2 1410973568 10711376 6489472
21:34:04 39925 8192000000 10326313536 10771623936 1403233712 14.624 100 23 46 0 1419646080 11774848 4637520
21:35:56 32958 8192000000 10312100432 10768687104 1390215920 14.508 99 26 52 1 1408674864 12366480 6092464
21:37:58 52694 8192000000 10301507984 10755665920 1377834592 14.397 98 27 54 0 1391338704 7831104 5673008
21:39:52 31265 8192000000 10293061664 10760572928 1388596512 14.493 99 7 15 0 1402316848 12508864 1211472
21:41:24 57272 8192000000 10305399600 10771881984 1394193072 14.543 99 10 20 0 1406971568 10435712 2342784
21:43:24 47711 8192000000 10282113552 10740936704 1358727216 14.226 98 8 16 1 1374031488 13378080 1926192
21:45:20 45820 8192000000 10305370912 10738540544 1350281248 14.150 97 28 56 0 1366298992 9566032 6451712
21:47:08 77356 8192000000 10293942272 10737586176 1326278656 13.934 96 25 50 0 1344702928 12541168 5883104
21:49:30 31240 8192000000 10260620688 10696429568 1331334912 13.979 97 36 73 0 1347545568 6890128 9320528
21:51:02 73185 8192000000 10289576704 10727772160 1320668592 13.883 96 34 66 0 1339852416 11825088 7358736
21:53:17 58783 8192000000 10274312640 10701107200 1320233424 13.879 98 26 51 0 1337920832 11927200 5760208
21:55:33 78862 8192000000 10259101984 10717630464 1325903328 13.930 98 22 44 0 1345585696 15003952 4678416
21:57:52 75473 8192000000 10254290496 10698838016 1311283728 13.798 97 36 72 2 1329933280 10472080 8177472
22:01:34 76990 8192000000 10234860288 10683232256 1316345840 13.844 98 13 26 0 1331375152 11810672 3218640
22:03:52 107345 8192000000 10250342848 10704068608 1315974928 13.840 97 12 24 1 1331366608 12477792 2913888
22:06:52 79087 8192000000 10263243696 10722172928 1359113808 14.229 100 13 26 1 1371882304 9878288 2890208
22:09:20 56445 8192000000 10278372432 10728038400 1370615536 14.333 100 20 40 1 1386202080 10448256 5138288
22:11:25 65982 8192000000 10278834016 10740404224 1370417488 14.331 99 12 24 0 1389891808 16754576 2719744
22:13:37 14695 8192000000 10315863600 10754879488 1375258640 14.374 99 43 84 2 1393153504 7819184 10075680
22:14:51 77867 8192000000 10303536272 10769399808 1354962576 14.192 97 15 30 1 1378255440 19809008 3483856
22:17:23 98461 8192000000 10299634944 10761461760 1378933040 14.407 99 14 27 2 1391610896 9445536 3232320
22:20:02 47801 8192000000 10311621408 10747822080 1347988240 14.129 97 35 70 0 1371708080 15563920 8155920
22:21:54 91343 8192000000 10274808512 10724925440 1346819648 14.119 97 12 24 0 1358133584 8595904 2718032
22:24:33 63635 8192000000 10285119904 10739642368 1358311136 14.222 98 32 64 2 1376703696 11803008 6589552
22:26:37 46241 8192000000 10278168544 10745970688 1386563840 14.475 100 10 20 2 1398273856 9321344 2388672
22:28:24 66535 8192000000 10274978240 10727505920 1336690544 14.028 96 12 24 1 1351770960 12051008 3029408
22:30:41 51713 8192000000 10270858048 10733838336 1339402624 14.052 97 14 26 1 1356829360 14079184 3347552
22:33:00 67298 8192000000 10262341392 10719997952 1313554976 13.818 95 15 30 1 1325668480 8535920 3577584
22:35:07 53547 8192000000 10264396400 10716811264 1318362384 13.862 97 22 44 0 1339492848 16274448 4856016
22:37:01 80132 8192000000 9085162112 9306898432 245974256 2.915 18 11 18 2 250669232 2631232 2063744

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 99 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-06-2022 22:39:39 to 05-06-2022 22:39:41.

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          239089
MON$OLDEST_ACTIVE               240116
MON$OLDEST_SNAPSHOT             240116
MON$NEXT_TRANSACTION            240116
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                       2005568
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.33602 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33602 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33602 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33602 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 ---
:80: 
:81: mscs-test-161.budzdorov.ru    Sun Jun  5 22:38:24 2022
:82:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:83: 
:84: 
:85: mscs-test-161.budzdorov.ru    Sun Jun  5 22:38:24 2022
:86:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:87: 
:88: 
:89: mscs-test-161.budzdorov.ru    Sun Jun  5 22:38:24 2022
:90:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:91: 
--- end of diff output ---

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

05.06.22 22:39:42. 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-05 22:39:45 No severe PSQL-related problems occured
05.06.22 22:39:46. End of report.