Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.07.2022 16: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 16053432 kB
MemAvailable 30998064 kB
Buffers 0 kB
Cached 15649936 kB
SwapCached 2512 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-30 16:38:29
DTS_END                         2022-07-30 16:38:29.1320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/48628
TRN_ID                          245276
ATT_ID                          704
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: 9179.98 21032 1101598
customer order: creation 177.19 366 21263
customer order: refuse 15.21 377 1825
order to supplier: creation 47.36 1292 5683
order to supplier: removal 3.51 90002 421
invoice (draft): creation 46.68 3181 5602
invoice (draft): removal 51.27 724 6152
invoice accept: apply 43.20 18367 5184
invoice accept: cancel 46.17 843 5540
customer reserve: creation 3922.24 185 470669
customer reserve: removal 4529.98 55 543598
realization accept: apply 86.50 180 10380
realization accept: cancel 75.19 118 9023
payment from customer: creation 62.06 103 7447
payment from customer: removal 6.11 88 733
payment to supplier: creation 50.99 129 6119
payment to supplier: removal 3.49 71 419
service: total inventory turnovers 4.89 1064 587
service: total monetary turnovers 6.98 23 837
service: aggregate perf. data 0.93 21264 112
service: refresh index statistics 0.03 282208 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-07-2022 16:39:39 to 30-07-2022 16:39:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4001
TEST_TIME 2 6684
TEST_TIME 3 6326
TEST_TIME 4 5618
TEST_TIME 5 5354
TEST_TIME 6 5515
TEST_TIME 7 6029
TEST_TIME 8 6048
TEST_TIME 9 6094
TEST_TIME 10 5797
TEST_TIME 11 5909
TEST_TIME 12 6028
TEST_TIME 13 6453
TEST_TIME 14 6732
TEST_TIME 15 7041
TEST_TIME 16 7117
TEST_TIME 17 7223
TEST_TIME 18 7455
TEST_TIME 19 7761
TEST_TIME 20 8492
TEST_TIME 21 8397
TEST_TIME 22 8794
TEST_TIME 23 9406
TEST_TIME 24 9829
TEST_TIME 25 10156
TEST_TIME 26 10215
TEST_TIME 27 10762
TEST_TIME 28 10612
TEST_TIME 29 10958
TEST_TIME 30 10883
TEST_TIME 31 10659
TEST_TIME 32 10401
TEST_TIME 33 10218
TEST_TIME 34 10371
TEST_TIME 35 10193
TEST_TIME 36 10100
TEST_TIME 37 10057
TEST_TIME 38 10025
TEST_TIME 39 9919
TEST_TIME 40 9790
TEST_TIME 41 9775
TEST_TIME 42 9652
TEST_TIME 43 9618
TEST_TIME 44 9654
TEST_TIME 45 9745
TEST_TIME 46 9669
TEST_TIME 47 9657
TEST_TIME 48 9599
TEST_TIME 49 9649
TEST_TIME 50 9650
TEST_TIME 51 9738
TEST_TIME 52 9675
TEST_TIME 53 9717
TEST_TIME 54 9716
TEST_TIME 55 9721
TEST_TIME 56 9793
TEST_TIME 57 9810
TEST_TIME 58 9837
TEST_TIME 59 9822
TEST_TIME 60 9855
TEST_TIME 61 9804
TEST_TIME 62 9751
TEST_TIME 63 9634
TEST_TIME 64 9575
TEST_TIME 65 9675
TEST_TIME 66 9625
TEST_TIME 67 9545
TEST_TIME 68 9505
TEST_TIME 69 9412
TEST_TIME 70 9393
TEST_TIME 71 9400
TEST_TIME 72 9403
TEST_TIME 73 9392
TEST_TIME 74 9354
TEST_TIME 75 9356
TEST_TIME 76 9393
TEST_TIME 77 9533
TEST_TIME 78 9520
TEST_TIME 79 9498
TEST_TIME 80 9513
TEST_TIME 81 9534
TEST_TIME 82 9581
TEST_TIME 83 9569
TEST_TIME 84 9574
TEST_TIME 85 9490
TEST_TIME 86 9463
TEST_TIME 87 9434
TEST_TIME 88 9397
TEST_TIME 89 9401
TEST_TIME 90 9385
TEST_TIME 91 9340
TEST_TIME 92 9290
TEST_TIME 93 9262
TEST_TIME 94 9265
TEST_TIME 95 9244
TEST_TIME 96 9278
TEST_TIME 97 9324
TEST_TIME 98 9348
TEST_TIME 99 9380
TEST_TIME 100 9383
TEST_TIME 101 9395
TEST_TIME 102 9386
TEST_TIME 103 9379
TEST_TIME 104 9375
TEST_TIME 105 9432
TEST_TIME 106 9447
TEST_TIME 107 9499
TEST_TIME 108 9429
TEST_TIME 109 9415
TEST_TIME 110 9356
TEST_TIME 111 9331
TEST_TIME 112 9321
TEST_TIME 113 9260
TEST_TIME 114 9244
TEST_TIME 115 9237
TEST_TIME 116 9239
TEST_TIME 117 9240
TEST_TIME 118 9219
TEST_TIME 119 9232
TEST_TIME 120 9182

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-07-2022 16:39:42 to 30-07-2022 16:39:45.

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 1537494 1537494 0 0.00 0 26302 7 0 0
doc_list_biud 1537487 1537487 0 0.00 0 23867 25 0 0
sp_add_invoice_to_stock 5314 5184 130 2.44 25 249197 18368 0 130
sp_cancel_adding_invoice 5540 5540 0 0.00 3 64609 843 0 0
sp_cancel_client_order 1825 1825 0 0.00 4 23115 377 0 0
sp_cancel_customer_reserve 543598 543598 0 0.00 0 17567 55 0 0
sp_cancel_pay_from_customer 733 733 0 0.00 2 4536 88 0 0
sp_cancel_pay_to_supplier 419 419 0 0.00 3 4170 71 0 0
sp_cancel_supplier_invoice 6161 6152 9 0.14 1 23893 724 0 9
sp_cancel_supplier_order 421 421 0 0.00 9 1071316 90002 0 0
sp_cancel_write_off 9023 9023 0 0.00 1 21597 118 0 0
sp_client_order 21263 21263 0 0.00 3 29040 367 0 0
sp_customer_reserve 470669 470669 0 0.00 0 49309 186 0 0
sp_fill_shopping_cart 32547 32547 0 0.00 1 18566 39 0 0
sp_fill_shopping_cart_clo_res 470665 470665 0 0.00 0 20973 13 0 0
sp_get_clo_for_invoice 5180 5180 0 0.00 0 27277 269 0 0
sp_kill_cost_storno 15715 15715 0 0.00 0 6663 51 0 0
sp_kill_qstorno_ret_qs2qd 1102792 1102792 0 0.00 0 23865 30 0 0
sp_kill_qty_storno 582115 582115 0 0.00 0 23866 65 0 0
sp_lock_selected_doc 21626 21626 0 0.00 0 7912 6 0 0
sp_make_cost_storno 29126 29126 0 0.00 0 21066 53 0 0
sp_multiply_rows_for_pdistr 29124 29124 0 0.00 0 5965 27 0 0
sp_multiply_rows_for_qdistr 21263 21263 0 0.00 0 14378 168 0 0
sp_pay_from_customer 7447 7447 0 0.00 4 23687 103 0 0
sp_pay_to_supplier 6119 6119 0 0.00 4 21299 129 0 0
sp_payment_common 13563 13563 0 0.00 1 5415 22 0 0
sp_qd_handle_on_cancel_clo 1825 1825 0 0.00 0 23110 345 0 0
sp_qd_handle_on_invoice_upd_sts 10720 10720 0 0.00 0 23667 318 0 0
sp_qd_handle_on_reserve_upd_sts 10376 10376 0 0.00 0 12067 61 0 0
sp_reserve_write_off 10380 10380 0 0.00 6 18332 180 0 0
sp_supplier_invoice 5602 5602 0 0.00 6 90976 3181 0 0
sp_supplier_order 5683 5683 0 0.00 5 66074 1292 0 0
srv_aggregate_perf_data 113 112 1 0.88 2562 311164 21264 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 816 112376 42233 0 0
srv_find_qd_qs_mism 1052445 1052445 0 0.00 0 26294 6 0 0
srv_make_invnt_saldo 587 587 0 0.00 33 25680 1065 0 0
srv_make_money_saldo 837 837 0 0.00 1 420 23 0 0
srv_recalc_idx_stat 4 4 0 0.00 122001 410354 282209 0 0
t$perf-abend:sp_add_invoice_to_stock 130 130 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 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 5184 5184 0 0.00 0 16532 160 0 0
t$perf-norm:sp_cancel_adding_invoice 436 436 0 0.00 0 12358 120 0 0
t$perf-norm:sp_cancel_client_order 1825 1825 0 0.00 0 2124 14 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 1963 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 733 733 0 0.00 0 1437 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 419 419 0 0.00 0 4057 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 444 444 0 0.00 0 2259 14 0 0
t$perf-norm:sp_cancel_supplier_order 421 421 0 0.00 0 21494 1696 0 0
t$perf-norm:sp_cancel_write_off 1077 1077 0 0.00 0 4775 15 0 0
t$perf-norm:sp_client_order 21263 21263 0 0.00 0 8446 18 0 0
t$perf-norm:sp_customer_reserve 1002 1002 0 0.00 0 20302 24 0 0
t$perf-norm:sp_pay_from_customer 7447 7447 0 0.00 0 8518 14 0 0
t$perf-norm:sp_pay_to_supplier 6119 6119 0 0.00 0 5028 11 0 0
t$perf-norm:sp_reserve_write_off 10380 10380 0 0.00 0 9156 13 0 0
t$perf-norm:sp_supplier_invoice 5602 5602 0 0.00 0 9321 22 0 0
t$perf-norm:sp_supplier_order 5683 5683 0 0.00 0 4460 16 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 3801 40 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 587 587 0 0.00 0 1239 20 0 0
t$perf-norm:srv_make_money_saldo 837 837 0 0.00 0 4322 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10606 10346 260 2.45 0 23833 26 0 260
v_all_customers 38642 38642 0 0.00 0 101 1 0 0
v_all_suppliers 31340 31340 0 0.00 0 26 1 0 0
v_all_wares 42526 42526 0 0.00 0 108 1 0 0
v_cancel_adding_invoice 872 872 0 0.00 0 1564 11 0 0
v_cancel_client_order 3650 3650 0 0.00 0 2225 7 0 0
v_cancel_customer_prepayment 1466 1466 0 0.00 0 1925 6 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 825 4 0 0
v_cancel_payment_to_supplier 838 838 0 0.00 0 1939 5 0 0
v_cancel_supplier_invoice 906 888 18 1.98 0 18126 38 0 18
v_cancel_supplier_order 842 842 0 0.00 0 1282 10 0 0
v_cancel_write_off 2154 2154 0 0.00 0 7066 21 0 0
v_max_id_clo_ord 5683 5683 0 0.00 0 341 1 0 0
v_max_id_clo_res 1002 1002 0 0.00 0 6095 16 0 0
v_max_id_ord_sup 5601 5601 0 0.00 0 3428 5 0 0
v_max_non_paid_invoice 1730 1730 0 0.00 0 263 2 0 0
v_max_non_paid_realizn 4193 4193 0 0.00 0 2012 5 0 0
v_min_id_clo_ord 5683 5683 0 0.00 0 3840 9 0 0
v_min_id_clo_res 1002 1002 0 0.00 0 6100 16 0 0
v_min_id_ord_sup 5601 5601 0 0.00 0 7065 10 0 0
v_min_non_paid_invoice 6116 6116 0 0.00 0 4444 16 0 0
v_min_non_paid_realizn 7445 7445 0 0.00 0 3786 13 0 0
v_our_firm 10388 10388 0 0.00 0 20 1 0 0
v_reserve_write_off 20752 20752 0 0.00 0 18294 8 0 0
x_lock_depdocs_on_canc_invoice 11248 11248 0 0.00 0 23483 124 0 0
x_lock_depdocs_on_canc_sup_ord 421 421 0 0.00 0 16224 363 0 0
x_make_qstorno_1000_1200 5683 5683 0 0.00 1 65930 1216 0 0
x_make_qstorno_1200_2000 5602 5602 0 0.00 3 90125 3034 0 0
x_make_qstorno_2100_3300 470669 470669 0 0.00 0 38166 170 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 30-07-2022 16:39:46 to 30-07-2022 16:39:51.

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:32 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:34 18612 8192000000 9910397824 10221883392 997154288 10.851 100 91 179 0 1026800640 6103040 23543312
14:10:57 20000 8192000000 9950774608 10272161792 1029551488 11.164 100 86 172 0 1060394016 8603968 22238560
14:12:37 17720 8192000000 9993954096 10326532096 1074078416 11.591 100 75 150 0 1102690880 9876064 18736400
14:13:58 10714 8192000000 10022867936 10371588096 1114789856 11.978 100 77 152 1 1143668528 10910976 17967696
14:15:26 4653 8192000000 10088623008 10450251776 1178331264 12.575 100 77 151 0 1207575920 12081024 17163632
14:16:33 4657 8192000000 10128797296 10497470464 1220973616 12.971 100 84 168 0 1253166592 13794576 18398400
14:17:42 1705 8192000000 10166668224 10541912064 1252365760 13.260 100 87 170 0 1283957712 13129040 18462912
14:18:45 1755 8192000000 10192464112 10577420288 1282702608 13.538 100 75 149 0 1311327760 12710112 15915040
14:19:50 1505 8192000000 10216717168 10600931328 1300793984 13.702 100 85 168 0 1332605456 13192048 18619424
14:20:53 1410 8192000000 10221173440 10614472704 1318492208 13.863 100 70 134 1 1343332272 10791712 14048352
14:21:55 5209 8192000000 10212928080 10623328256 1324140736 13.914 100 36 72 0 1339897984 7804464 7952784
14:23:03 6776 8192000000 10216416656 10634153984 1332143536 13.987 100 20 40 0 1343096416 6814624 4138256
14:24:13 9306 8192000000 10229284784 10648080384 1338101952 14.040 100 21 43 0 1352455952 9944944 4409056
14:25:25 21154 8192000000 10245888656 10659319808 1340311680 14.060 100 47 92 0 1356011856 5537248 10162928
14:26:47 6878 8192000000 10235954016 10660085760 1344418432 14.097 100 31 60 0 1358040448 7176224 6445792
14:27:54 6660 8192000000 10213055984 10636632064 1311711376 13.802 98 24 49 0 1323113712 5579472 5822864
14:29:05 59755 8192000000 10198608960 10604658688 1268689120 13.410 96 32 64 0 1281718896 5844608 7185168
14:31:06 4590 8192000000 10173783856 10577993728 1264787024 13.374 99 33 66 1 1283418560 11096048 7535488
14:32:16 8006 8192000000 10127012800 10525904896 1200276384 12.779 96 22 42 1 1210706000 5526976 4902640
14:33:27 8679 8192000000 10132456448 10535149568 1233942016 13.090 99 16 33 0 1242843344 5498768 3402560
14:34:37 24587 8192000000 10132806272 10536431616 1210407680 12.873 96 26 52 0 1226101088 9657424 6035984
14:36:02 5328 8192000000 10169884272 10582556672 1286438272 13.572 100 24 49 1 1297486832 5439728 5608832
14:37:08 7583 8192000000 10183142752 10596847616 1294460656 13.645 100 27 54 1 1306736096 5655344 6620096
14:38:16 17806 8192000000 10221006992 10636402688 1318632048 13.864 100 21 40 0 1332268800 9646800 3989952
14:39:36 50486 8192000000 10246985136 10657595392 1339429696 14.052 100 45 89 0 1357407312 7947536 10030080
14:41:33 15541 8192000000 10265403520 10683736064 1361369280 14.250 100 52 105 1 1382123296 9461456 11292560
14:42:53 3841 8192000000 10295661600 10710368256 1377068608 14.390 100 52 104 2 1408921776 19968160 11885008
14:44:01 16454 8192000000 10313879984 10732314624 1392651216 14.530 100 60 120 0 1427507440 20852288 14003936
14:45:26 3998 8192000000 10325009664 10743824384 1397488912 14.573 100 56 107 1 1435244432 24527280 13228240
14:46:32 14153 8192000000 10320608256 10741149696 1397939920 14.577 99 55 108 0 1437794832 27157408 12697504
14:47:56 20129 8192000000 10323526960 10752188416 1397411936 14.572 99 28 56 0 1434540528 30208960 6919632
14:49:32 4673 8192000000 10349164208 10768678912 1409457120 14.679 100 54 106 0 1457173664 34622512 13094032
14:50:41 2960 8192000000 10354905792 10773041152 1402289184 14.615 99 64 126 0 1457868576 40723984 14855408
14:51:49 1893 8192000000 10334888048 10752753664 1371492848 14.340 97 70 136 2 1426625184 39426256 15706080
14:52:55 2391 8192000000 10337419920 10754060288 1387055280 14.480 100 66 131 1 1446864736 43405792 16403664
14:54:04 15338 8192000000 10327303200 10739761152 1372870048 14.353 100 63 126 2 1432387008 43403888 16113072
14:55:26 814 8192000000 10336374352 10738278400 1369503984 14.323 100 89 174 0 1437934704 46723152 21707568
14:56:32 12453 8192000000 10341979232 10753609728 1374102080 14.364 100 57 110 0 1442202912 53213696 14887136
14:57:51 3261 8192000000 10356292624 10763497472 1383496080 14.448 100 83 166 0 1453043648 48538624 21008944
14:59:00 11860 8192000000 10318633296 10745524224 1372548288 14.350 100 38 77 0 1426221248 42550512 11122448
15:00:32 7079 8192000000 10329437248 10751209472 1369455936 14.322 100 52 104 0 1437863184 54385056 14022192
15:01:43 14336 8192000000 10303753136 10726277120 1361191168 14.248 100 51 99 2 1416856896 42656080 13009648
15:03:04 17597 8192000000 10281383008 10725003264 1346632400 14.117 99 26 52 0 1392445616 39025248 6787968
15:04:26 25333 8192000000 10278710288 10723438592 1356105232 14.202 100 15 30 0 1395283232 35007968 4170032
15:05:53 27104 8192000000 10271947872 10713706496 1357831936 14.218 100 17 35 1 1379201376 17078624 4290816
15:07:30 31613 8192000000 10272087616 10727206912 1347547376 14.125 99 14 28 1 1367940640 17022560 3370704
15:09:02 84552 8192000000 10298018752 10731945984 1359202560 14.230 99 32 59 0 1375835120 10463168 6169392
15:12:08 92003 8192000000 10285132832 10750504960 1359369344 14.232 98 15 31 0 1385879744 23335120 3175280
15:14:43 22823 8192000000 10297375552 10732982272 1380857936 14.424 100 24 48 0 1394178640 7961664 5359040
15:16:09 20555 8192000000 10302519184 10748166144 1392736224 14.530 100 32 65 0 1410600352 10787232 7076896
15:17:35 16552 8192000000 10306965056 10756726784 1386687808 14.476 99 40 74 1 1410280096 14696720 8895568
15:19:16 50707 8192000000 10298916800 10759794688 1382027936 14.435 99 11 22 0 1397857648 13313936 2515776
15:21:07 40399 8192000000 10302518304 10753204224 1382318896 14.437 99 17 34 0 1402072384 15422160 4331328
15:23:06 27238 8192000000 10298645456 10760499200 1397719040 14.575 100 17 35 0 1416564608 14183616 4661952
15:24:33 68538 8192000000 10291323920 10761912320 1365203856 14.284 98 12 24 0 1382930464 15132592 2594016
15:26:42 35967 8192000000 10284336384 10739466240 1369746224 14.325 98 34 68 1 1389630896 12336016 7548656
15:28:20 63470 8192000000 10288977408 10744029184 1340320896 14.060 95 13 26 0 1355440288 12177520 2941872
15:30:44 38022 8192000000 10297254448 10746847232 1387030640 14.479 100 30 60 0 1403412704 9824912 6557152
15:32:22 73267 8192000000 10298158928 10752729088 1341647376 14.072 96 18 33 0 1359362144 13881712 3833056
15:34:36 44227 8192000000 10278270336 10737250304 1337007536 14.030 96 18 36 0 1353426800 12342880 4076384
15:36:22 43478 8192000000 10276723760 10717843456 1352593152 14.171 98 30 61 0 1369927536 10649232 6685152
15:38:06 72093 8192000000 10294964672 10747494400 1358286912 14.222 98 27 54 0 1383610672 19452912 5870848
15:40:53 45287 8192000000 10279116672 10710659072 1299390176 13.690 94 18 36 1 1310869456 7879680 3599600
15:42:46 29215 8192000000 10294994992 10726383616 1372308688 14.348 100 43 86 1 1394153584 11979328 9865568
15:44:16 71287 8192000000 10258616224 10710466560 1301360096 13.708 95 12 24 0 1313830272 9939120 2531056
15:46:27 79915 8192000000 10256713072 10705051648 1346241472 14.114 98 11 22 0 1357182832 8301728 2639632
15:49:12 61129 8192000000 10264811872 10719080448 1330163600 13.969 97 8 16 0 1347659824 15912336 1583888
15:51:23 43178 8192000000 10285064288 10729463808 1372769424 14.352 100 13 26 0 1385331008 9581056 2980528
15:53:30 89626 8192000000 10292247664 10751389696 1359482512 14.233 98 14 28 0 1378537312 15703984 3350816
15:57:10 55276 8192000000 10297649200 10740371456 1370226928 14.329 99 18 36 0 1385168480 10918272 4023280
15:59:10 110854 8192000000 10280214784 10735140864 1315977472 13.840 95 17 35 1 1339436560 19181600 4277488
16:02:01 110448 8192000000 10271516528 10723684352 1318498640 13.863 96 13 26 1 1338083600 17188320 2396640
16:05:16 107635 8192000000 10281937824 10728054784 1327650144 13.946 96 26 52 0 1344724992 11563376 5511472
16:08:03 43293 8192000000 10278843136 10741059584 1392288576 14.526 100 11 21 0 1400104576 5465696 2350304
16:09:49 48777 8192000000 10288789952 10750115840 1397419088 14.572 100 6 12 0 1404451632 6057104 975440
16:11:38 39406 8192000000 10309437088 10766897152 1407340928 14.660 100 13 26 0 1418465616 8391296 2733392
16:13:42 88686 8192000000 10310412832 10777509888 1397705152 14.575 99 14 28 0 1417852480 16753520 3393808
16:16:20 66017 8192000000 10292812336 10746433536 1340677072 14.064 96 17 34 0 1356819568 12226336 3916160
16:18:27 31791 8192000000 10305987872 10745438208 1383217296 14.445 99 45 90 2 1402067008 7870048 10979664
16:20:02 36451 8192000000 10302715968 10735009792 1344515168 14.098 97 36 72 0 1368058592 15846048 7697376
16:21:43 14359 8192000000 10290227296 10722885632 1365454272 14.286 100 41 79 2 1385904736 11441888 9008576
16:22:59 112376 8192000000 10285378000 10740109312 1305902304 13.749 95 17 34 0 1322049856 12791440 3356112
16:25:59 76156 8192000000 10269053344 10709987328 1325115744 13.923 97 40 80 1 1347443328 13066192 9261392
16:28:16 40852 8192000000 10266176528 10724470784 1350253232 14.150 99 11 22 0 1366216128 13678176 2284720
16:29:57 58509 8192000000 10264769584 10729148416 1365264864 14.285 100 4 8 0 1381186544 15181216 740464
16:31:56 51873 8192000000 10278607920 10740891648 1363050784 14.265 99 6 12 0 1381762000 17290832 1420384
16:33:48 84419 8192000000 10252879264 10712682496 1327501472 13.945 97 13 26 0 1341413728 11146976 2765280
16:36:13 36015 8192000000 10263610272 10706669568 1323561728 13.909 97 25 50 0 1336114304 6867040 5685536
16:37:55 39458 8192000000 9175100656 9434628096 339278352 3.976 25 5 8 0 343168272 2948752 941168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-07-2022 16:39:52 to 30-07-2022 16:40:07.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 130 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-07-2022 16:40:08 to 30-07-2022 16:40:10.

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          245276
MON$OLDEST_ACTIVE               245540
MON$OLDEST_SNAPSHOT             245540
MON$NEXT_TRANSACTION            245540
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                       2025632
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

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

Database validation

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

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

--- start of diff output ---
:498: 
:499: mscs-test-161.budzdorov.ru    Sat Jul 30 16:38:37 2022
:500:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:501: 
:502: 
:503: mscs-test-161.budzdorov.ru    Sat Jul 30 16:38:37 2022
:504:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:505: 
:506: 
:507: mscs-test-161.budzdorov.ru    Sat Jul 30 16:38:37 2022
:508:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:509: 
:510: 
:511: mscs-test-161.budzdorov.ru    Sat Jul 30 16:38:37 2022
:512:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:513: 
:514: 
:515: mscs-test-161.budzdorov.ru    Sat Jul 30 16:38:37 2022
:516:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:517: 
:518: 
:519: mscs-test-161.budzdorov.ru    Sat Jul 30 16:38:40 2022
:520:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:521: 
--- end of diff output ---

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

30.07.22 16:40:11. 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-30 16:40:14 No severe PSQL-related problems occured
30.07.22 16:40:15. End of report.