Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.05.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 16472884 kB
MemAvailable 31423624 kB
Buffers 0 kB
Cached 15269848 kB
SwapCached 2544 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-05-25 19:38:21
DTS_END                         2022-05-25 19:38:21.6210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/57100
TRN_ID                          237395
ATT_ID                          634
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: 9175.67 24838 1101080
customer order: creation 170.08 357 20409
customer order: refuse 14.80 342 1776
order to supplier: creation 44.72 1350 5366
order to supplier: removal 3.32 107115 398
invoice (draft): creation 46.19 3470 5543
invoice (draft): removal 50.99 864 6119
invoice accept: apply 41.71 19016 5005
invoice accept: cancel 46.66 708 5599
customer reserve: creation 3883.70 187 466044
customer reserve: removal 4582.38 61 549886
realization accept: apply 82.57 176 9908
realization accept: cancel 80.45 107 9654
payment from customer: creation 58.95 111 7074
payment from customer: removal 5.36 101 643
payment to supplier: creation 48.42 125 5810
payment to supplier: removal 3.51 78 421
service: total inventory turnovers 4.81 1070 577
service: total monetary turnovers 6.27 41 752
service: aggregate perf. data 0.77 22076 92
service: refresh index statistics 0.03 339402 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-05-2022 19:39:53 to 25-05-2022 19:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2850
TEST_TIME 2 3398
TEST_TIME 3 3596
TEST_TIME 4 3905
TEST_TIME 5 4379
TEST_TIME 6 5110
TEST_TIME 7 5655
TEST_TIME 8 5581
TEST_TIME 9 5635
TEST_TIME 10 5847
TEST_TIME 11 6421
TEST_TIME 12 7120
TEST_TIME 13 7399
TEST_TIME 14 7863
TEST_TIME 15 8182
TEST_TIME 16 8478
TEST_TIME 17 8649
TEST_TIME 18 8385
TEST_TIME 19 8888
TEST_TIME 20 8886
TEST_TIME 21 8820
TEST_TIME 22 9207
TEST_TIME 23 9416
TEST_TIME 24 9460
TEST_TIME 25 9525
TEST_TIME 26 9397
TEST_TIME 27 9470
TEST_TIME 28 10035
TEST_TIME 29 11572
TEST_TIME 30 11257
TEST_TIME 31 10979
TEST_TIME 32 10862
TEST_TIME 33 10693
TEST_TIME 34 10617
TEST_TIME 35 10616
TEST_TIME 36 10467
TEST_TIME 37 10302
TEST_TIME 38 10152
TEST_TIME 39 10196
TEST_TIME 40 10178
TEST_TIME 41 10033
TEST_TIME 42 9902
TEST_TIME 43 9781
TEST_TIME 44 9676
TEST_TIME 45 9691
TEST_TIME 46 9619
TEST_TIME 47 9609
TEST_TIME 48 9555
TEST_TIME 49 9544
TEST_TIME 50 9532
TEST_TIME 51 9539
TEST_TIME 52 9602
TEST_TIME 53 9659
TEST_TIME 54 9585
TEST_TIME 55 9756
TEST_TIME 56 9770
TEST_TIME 57 9673
TEST_TIME 58 9684
TEST_TIME 59 9785
TEST_TIME 60 9806
TEST_TIME 61 9775
TEST_TIME 62 9843
TEST_TIME 63 9739
TEST_TIME 64 9653
TEST_TIME 65 9579
TEST_TIME 66 9541
TEST_TIME 67 9492
TEST_TIME 68 9485
TEST_TIME 69 9537
TEST_TIME 70 9507
TEST_TIME 71 9455
TEST_TIME 72 9460
TEST_TIME 73 9463
TEST_TIME 74 9518
TEST_TIME 75 9491
TEST_TIME 76 9595
TEST_TIME 77 9624
TEST_TIME 78 9624
TEST_TIME 79 9636
TEST_TIME 80 9724
TEST_TIME 81 9677
TEST_TIME 82 9634
TEST_TIME 83 9607
TEST_TIME 84 9551
TEST_TIME 85 9586
TEST_TIME 86 9526
TEST_TIME 87 9493
TEST_TIME 88 9443
TEST_TIME 89 9448
TEST_TIME 90 9402
TEST_TIME 91 9361
TEST_TIME 92 9439
TEST_TIME 93 9401
TEST_TIME 94 9357
TEST_TIME 95 9354
TEST_TIME 96 9355
TEST_TIME 97 9309
TEST_TIME 98 9414
TEST_TIME 99 9418
TEST_TIME 100 9361
TEST_TIME 101 9421
TEST_TIME 102 9387
TEST_TIME 103 9413
TEST_TIME 104 9413
TEST_TIME 105 9490
TEST_TIME 106 9479
TEST_TIME 107 9479
TEST_TIME 108 9468
TEST_TIME 109 9422
TEST_TIME 110 9381
TEST_TIME 111 9387
TEST_TIME 112 9334
TEST_TIME 113 9304
TEST_TIME 114 9297
TEST_TIME 115 9285
TEST_TIME 116 9276
TEST_TIME 117 9266
TEST_TIME 118 9233
TEST_TIME 119 9239
TEST_TIME 120 9177

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-05-2022 19:39:57 to 25-05-2022 19:40:00.

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 1532160 1532160 0 0.00 0 18945 6 0 0
doc_list_biud 1532158 1532158 0 0.00 0 30966 28 0 0
sp_add_invoice_to_stock 5127 5005 122 2.37 37 185272 19016 0 122
sp_cancel_adding_invoice 5599 5599 0 0.00 4 63637 708 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 7509 342 0 0
sp_cancel_customer_reserve 549886 549886 0 0.00 1 30968 61 0 0
sp_cancel_pay_from_customer 643 643 0 0.00 2 9433 102 0 0
sp_cancel_pay_to_supplier 421 421 0 0.00 2 9924 78 0 0
sp_cancel_supplier_invoice 6127 6119 8 0.13 1 16362 864 0 8
sp_cancel_supplier_order 398 398 0 0.00 15 1031561 107116 0 0
sp_cancel_write_off 9654 9654 0 0.00 1 12816 107 0 0
sp_client_order 20409 20409 0 0.00 3 77464 357 0 0
sp_customer_reserve 466044 466044 0 0.00 0 51067 187 0 0
sp_fill_shopping_cart 31304 31304 0 0.00 1 13567 43 0 0
sp_fill_shopping_cart_clo_res 466037 466037 0 0.00 0 17361 16 0 0
sp_get_clo_for_invoice 4997 4997 0 0.00 0 24215 368 0 0
sp_kill_cost_storno 16317 16317 0 0.00 0 9919 54 0 0
sp_kill_qstorno_ret_qs2qd 1115943 1115943 0 0.00 0 30914 34 0 0
sp_kill_qty_storno 588336 588336 0 0.00 0 30965 72 0 0
sp_lock_selected_doc 20530 20530 0 0.00 0 11076 5 0 0
sp_make_cost_storno 27789 27789 0 0.00 0 18939 58 0 0
sp_multiply_rows_for_pdistr 27788 27788 0 0.00 0 8179 26 0 0
sp_multiply_rows_for_qdistr 20409 20409 0 0.00 0 77435 159 0 0
sp_pay_from_customer 7074 7074 0 0.00 4 16642 112 0 0
sp_pay_to_supplier 5810 5810 0 0.00 4 14945 125 0 0
sp_payment_common 12884 12884 0 0.00 1 11170 30 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 7504 313 0 0
sp_qd_handle_on_invoice_upd_sts 10595 10595 0 0.00 0 15825 333 0 0
sp_qd_handle_on_reserve_upd_sts 9908 9908 0 0.00 0 11649 54 0 0
sp_reserve_write_off 9908 9908 0 0.00 6 18957 177 0 0
sp_supplier_invoice 5543 5543 0 0.00 7 77418 3471 0 0
sp_supplier_order 5366 5366 0 0.00 4 74846 1351 0 0
srv_aggregate_perf_data 92 92 0 0.00 2543 479321 22077 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 2329 143423 48194 0 0
srv_find_qd_qs_mism 1053282 1053282 0 0.00 0 13036 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 36 53401 1071 0 0
srv_make_money_saldo 752 752 0 0.00 1 13565 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 202575 507204 339402 0 0
t$perf-abend:sp_add_invoice_to_stock 122 122 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5006 5006 0 0.00 0 11416 168 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 2126 80 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 5072 17 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 551 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 643 643 0 0.00 0 4026 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 421 421 0 0.00 0 142 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 2331 25 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 33122 1575 0 0
t$perf-norm:sp_cancel_write_off 961 961 0 0.00 0 5389 12 0 0
t$perf-norm:sp_client_order 20409 20409 0 0.00 0 16917 19 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 5308 11 0 0
t$perf-norm:sp_pay_from_customer 7074 7074 0 0.00 0 17984 13 0 0
t$perf-norm:sp_pay_to_supplier 5810 5810 0 0.00 0 5470 14 0 0
t$perf-norm:sp_reserve_write_off 9908 9908 0 0.00 0 8066 10 0 0
t$perf-norm:sp_supplier_invoice 5543 5543 0 0.00 0 6672 14 0 0
t$perf-norm:sp_supplier_order 5366 5366 0 0.00 0 20338 20 0 0
t$perf-norm:srv_aggregate_perf_data 92 92 0 0.00 0 13 0 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 109 2 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1670 19 0 0
t$perf-norm:srv_make_money_saldo 752 752 0 0.00 0 7274 22 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 10234 9990 244 2.38 0 10491 21 0 244
v_all_customers 36882 36882 0 0.00 0 22 1 0 0
v_all_suppliers 30060 30060 0 0.00 0 181 1 0 0
v_all_wares 40816 40816 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 2805 14 0 0
v_cancel_client_order 3552 3552 0 0.00 0 6069 8 0 0
v_cancel_customer_prepayment 1286 1286 0 0.00 0 3601 7 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 4941 10 0 0
v_cancel_payment_to_supplier 842 842 0 0.00 0 1098 5 0 0
v_cancel_supplier_invoice 808 792 16 1.98 0 4772 19 0 16
v_cancel_supplier_order 796 796 0 0.00 0 1275 6 0 0
v_cancel_write_off 1922 1922 0 0.00 0 4821 13 0 0
v_max_id_clo_ord 5356 5356 0 0.00 0 2476 2 0 0
v_max_id_clo_res 981 981 0 0.00 0 1723 12 0 0
v_max_id_ord_sup 5538 5538 0 0.00 0 1988 2 0 0
v_max_non_paid_invoice 1674 1674 0 0.00 0 2700 5 0 0
v_max_non_paid_realizn 3881 3881 0 0.00 0 5382 5 0 0
v_min_id_clo_ord 5356 5356 0 0.00 0 5341 13 0 0
v_min_id_clo_res 981 981 0 0.00 0 10456 26 0 0
v_min_id_ord_sup 5538 5538 0 0.00 0 4639 8 0 0
v_min_non_paid_invoice 5810 5810 0 0.00 0 10929 17 0 0
v_min_non_paid_realizn 7074 7074 0 0.00 0 11163 28 0 0
v_our_firm 10320 10320 0 0.00 0 17 1 0 0
v_reserve_write_off 19816 19816 0 0.00 0 5408 6 0 0
x_lock_depdocs_on_canc_invoice 11322 11322 0 0.00 0 10475 112 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4236 333 0 0
x_make_qstorno_1000_1200 5366 5366 0 0.00 1 74798 1261 0 0
x_make_qstorno_1200_2000 5543 5543 0 0.00 3 77409 3319 0 0
x_make_qstorno_2100_3300 466044 466044 0 0.00 0 51065 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-05-2022 19:40:01 to 25-05-2022 19:40:05.

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:25 14 8192000000 8806999344 8820658176 12231984 0.149 3 3 6 0 12447168 84832 130352
17:09:27 30153 8192000000 9906848960 10218950656 983402848 10.717 100 90 180 0 1012619920 5660256 23556816
17:11:03 26256 8192000000 9951852800 10273624064 1033965872 11.207 100 94 188 0 1065987152 7998704 24022576
17:12:33 18440 8192000000 9989003936 10319396864 1067846448 11.532 100 91 182 0 1100544880 9688000 23010432
17:13:55 11895 8192000000 10040466768 10393923584 1129614880 12.118 100 78 155 1 1159579520 10767968 19196672
17:15:13 8675 8192000000 10093270624 10454048768 1178202320 12.573 100 82 164 1 1209755136 12502432 19050384
17:16:24 5494 8192000000 10127856672 10497986560 1216448880 12.929 100 81 160 0 1248467360 13756064 18262416
17:17:32 3682 8192000000 10163806464 10541842432 1250116224 13.239 100 78 156 0 1281527264 14018688 17392352
17:18:38 2342 8192000000 10188114624 10570743808 1272117824 13.441 100 86 171 2 1303993600 12700032 19175744
17:19:41 1875 8192000000 10220558128 10601795584 1292666336 13.629 100 91 181 2 1326551200 12736448 21148416
17:20:45 1872 8192000000 10215686912 10602557440 1299423712 13.690 100 84 168 1 1329138688 10554608 19160368
17:21:47 9627 8192000000 10196760128 10602872832 1305501072 13.745 100 30 56 2 1319338992 6853520 6984400
17:23:01 8333 8192000000 10203338224 10611376128 1313926160 13.822 100 27 53 2 1326534864 6649712 5958992
17:24:11 5246 8192000000 10211534720 10625994752 1322069936 13.895 100 27 54 2 1334975760 6764096 6141728
17:25:21 73022 8192000000 10228778768 10644672512 1330065664 13.968 100 33 66 3 1343816016 5438064 8312288
17:27:34 13100 8192000000 10222171168 10646847488 1322822672 13.902 99 19 38 0 1333193168 5323344 5047152
17:28:48 11551 8192000000 10177601168 10595491840 1243520704 13.179 95 22 45 0 1254264112 5915568 4827840
17:30:00 22521 8192000000 10164032448 10567335936 1200723312 12.783 93 17 34 1 1209549408 4935360 3890736
17:31:23 20397 8192000000 10113130048 10505793536 1124615664 12.071 89 24 48 2 1135336432 5023936 5696832
17:32:48 6603 8192000000 10146715312 10543460352 1209896592 12.868 96 26 52 1 1225847184 9004704 6945888
17:33:56 9547 8192000000 10184985952 10584997888 1282396720 13.535 100 27 54 0 1293765088 5050080 6318288
17:35:09 19961 8192000000 10204806736 10608869376 1305164240 13.742 100 26 50 3 1317084032 5388240 6531552
17:37:01 8992 8192000000 10207563264 10616418304 1307321824 13.762 100 35 70 2 1322031760 7425840 7284096
17:38:11 9992 8192000000 10223212992 10637086720 1326819552 13.938 100 16 32 0 1335374224 5030688 3523984
17:39:29 19382 8192000000 10252669952 10666455040 1339878640 14.056 100 44 88 1 1360720000 10193232 10648128
17:40:58 19498 8192000000 10278549936 10695024640 1364359920 14.276 100 38 76 1 1389723824 16736672 8627232
17:42:18 33797 8192000000 10296915920 10710261760 1375200368 14.374 100 46 90 2 1405165872 18801728 11163776
17:44:03 6146 8192000000 10302796720 10728992768 1388554416 14.493 100 41 82 0 1418328480 20084448 9689616
17:45:29 6619 8192000000 10322858544 10745196544 1398369232 14.580 100 63 126 2 1434813232 22268640 14175360
17:46:38 5725 8192000000 10334407536 10753077248 1403228928 14.624 100 62 122 1 1439527536 22830352 13468256
17:47:48 2680 8192000000 10324548336 10746597376 1398447552 14.581 100 56 111 0 1434412592 22867712 13097328
17:48:54 14802 8192000000 10344896272 10770173952 1407537696 14.662 100 64 127 1 1458860784 35586240 15736848
17:50:16 13643 8192000000 10334871440 10758631424 1397458208 14.572 100 61 122 1 1440923904 28119424 15346272
17:51:32 2327 8192000000 10342668512 10757861376 1396232816 14.561 100 89 176 1 1447517408 29263328 22021264
17:52:37 7274 8192000000 10333166736 10757017600 1394863376 14.549 99 56 111 0 1437353952 28872816 13617760
17:53:48 11722 8192000000 10348313408 10773143552 1408208544 14.668 100 54 108 0 1453360496 31688608 13463344
17:55:06 3372 8192000000 10333866864 10758184960 1395069744 14.551 100 56 113 0 1439415936 30463040 13883152
17:56:16 16398 8192000000 10315354192 10733457408 1340828560 14.065 97 42 83 2 1383443040 31082080 11532400
17:57:35 12333 8192000000 10311464096 10739441664 1371234288 14.338 99 30 61 0 1415821760 36726416 7861056
17:58:50 9431 8192000000 10318183424 10738688000 1355911728 14.201 98 45 87 2 1404391664 36388160 12091776
18:00:04 6734 8192000000 10337264352 10744406016 1367804992 14.307 99 57 114 0 1429333168 45453392 16074784
18:01:17 11095 8192000000 10323926880 10751672320 1383148944 14.445 100 33 66 0 1432482608 39979648 9354016
18:02:30 8369 8192000000 10330278848 10760536064 1385928000 14.470 100 33 66 0 1435413248 40308656 9176592
18:03:43 13635 8192000000 10333413184 10763141120 1387504560 14.484 100 38 75 0 1442484496 44885840 10094096
18:05:26 14113 8192000000 10323428112 10752262144 1352095360 14.166 99 30 60 0 1417277616 56208384 8973872
18:06:59 58832 8192000000 10261477456 10715783168 1335612288 14.018 98 21 42 0 1390789088 49405600 5771200
18:08:59 55200 8192000000 10277732544 10703286272 1356414672 14.205 100 58 116 0 1376528208 7044448 13069088
18:10:55 50019 8192000000 10287261312 10734776320 1378438336 14.403 100 34 68 0 1397760400 11515184 7806880
18:12:46 48923 8192000000 10284411632 10746863616 1383760416 14.450 100 10 20 1 1397438912 11407184 2271312
18:14:36 69587 8192000000 10292787808 10731520000 1355999392 14.201 98 11 22 1 1366528592 7909616 2619584
18:17:06 69806 8192000000 10295479472 10745155584 1372444352 14.349 99 15 30 0 1387394480 11947056 3003072
18:19:25 66093 8192000000 10274989856 10734804992 1371568720 14.341 99 15 30 1 1381868960 6784736 3515504
18:21:31 109163 8192000000 10297843072 10759221248 1386322336 14.473 100 34 69 1 1411320064 17273216 7724512
18:24:22 67366 8192000000 10264443456 10723254272 1336446848 14.025 97 14 28 0 1353089552 13198640 3444064
18:26:51 60856 8192000000 10273856400 10738814976 1362630912 14.261 99 11 22 0 1376099024 10907504 2560608
18:28:55 54428 8192000000 10289503840 10746871808 1371667888 14.342 99 23 46 0 1389201136 12271504 5261744
18:30:51 45395 8192000000 10284936336 10735878144 1343817424 14.092 97 29 58 0 1364742848 14296016 6629408
18:33:03 56189 8192000000 10288146016 10758852608 1374475968 14.367 99 35 69 0 1398928288 16382384 8069936
18:35:55 22682 8192000000 10305844400 10747121664 1370001008 14.327 99 47 94 1 1392841056 12859344 9980704
18:37:23 130867 8192000000 10282050000 10731995136 1255035904 13.284 91 13 27 0 1269409472 11468304 2905264
18:40:39 91608 8192000000 10257348704 10706907136 1336900304 14.029 98 25 50 1 1356201520 14056224 5244992
18:43:19 49665 8192000000 10272840048 10726416384 1363989776 14.273 99 31 62 0 1386502640 15341664 7171200
18:45:15 75309 8192000000 10276748960 10731827200 1352459328 14.170 99 11 22 1 1369084944 14387232 2238384
18:47:31 48332 8192000000 10273292288 10708414464 1335621680 14.018 98 30 59 1 1350399728 8167168 6610880
18:49:20 25319 8192000000 10281147808 10715512832 1338278816 14.042 98 48 96 0 1358155664 8753440 11123408
18:50:48 57859 8192000000 10277864752 10730545152 1359848928 14.236 99 20 40 1 1378532864 14121552 4562384
18:52:46 102167 8192000000 10283299936 10742452224 1318291696 13.861 95 9 18 1 1337004320 16639104 2073520
18:55:32 143422 8192000000 10285067152 10742382592 1314509040 13.827 96 10 20 0 1335565040 18724096 2331904
18:58:55 127503 8192000000 10279241904 10728951808 1330496912 13.972 97 12 24 1 1353916672 20819792 2599968
19:02:03 40080 8192000000 10272377152 10718904320 1344664928 14.099 98 23 46 1 1355819664 6578112 4576624
19:03:44 50240 8192000000 10276369152 10729734144 1365527776 14.287 100 26 52 0 1383237328 12162832 5546720
19:05:38 56932 8192000000 10289615056 10739535872 1354561136 14.188 98 18 36 2 1371730432 12892352 4276944
19:07:41 75192 8192000000 10288019200 10743021568 1373485632 14.358 99 23 46 0 1393388160 13712512 6190016
19:10:51 58024 8192000000 10268318192 10727665664 1365636048 14.288 99 18 35 1 1377325680 8054480 3635152
19:12:53 76934 8192000000 10290455696 10748162048 1375169680 14.373 99 12 24 1 1385047200 7646224 2231296
19:15:10 54891 8192000000 10291055216 10731872256 1355611328 14.198 98 31 61 3 1373488736 10988368 6889040
19:17:12 66869 8192000000 10279038576 10730713088 1362580352 14.261 98 16 32 0 1373495360 7619568 3295440
19:19:19 63818 8192000000 10270916912 10717790208 1333992736 14.003 96 14 27 1 1346193920 9314144 2887040
19:21:29 56428 8192000000 10292300544 10747502592 1359937504 14.237 98 16 32 1 1379372672 15769072 3666096
19:23:29 101781 8192000000 10281434736 10721878016 1286581632 13.573 93 18 36 1 1304473040 13495440 4395968
19:26:16 35260 8192000000 10293206064 10728787968 1349086192 14.139 98 22 44 3 1363077344 9112224 4878928
19:27:52 119260 8192000000 10251122080 10708938752 1320332208 13.880 96 22 44 0 1336539648 11039136 5168304
19:31:02 70195 8192000000 10269225392 10725081088 1352783152 14.173 98 18 34 1 1366659104 9810208 4065744
19:33:13 86079 8192000000 10279312176 10734505984 1353289376 14.177 98 11 22 2 1366550512 10706640 2554496
19:35:47 57968 8192000000 10256609264 10707316736 1346742832 14.118 98 15 29 0 1358122288 8328896 3050560
19:37:57 30542 8192000000 9113061872 9357078528 278047248 3.282 21 6 12 0 282640240 3235360 1357632

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 122 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 25-05-2022 19:40:22 to 25-05-2022 19:40:24.

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          236659
MON$OLDEST_ACTIVE               237780
MON$OLDEST_SNAPSHOT             237780
MON$NEXT_TRANSACTION            237780
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                       2030816
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.33596 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33596 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33596 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33596 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Wed May 25 19:38:31 2022
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Wed May 25 19:38:32 2022
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Wed May 25 19:38:32 2022
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Wed May 25 19:38:32 2022
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
--- end of diff output ---

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

25.05.22 19:40:25. 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-05-25 19:40:28 No severe PSQL-related problems occured
25.05.22 19:40:29. End of report.